Here are logs from not working version and working. (I added JK in front of some the prints) Looks like difference is that in the working version there are logs: "new input from vm-0 for syscall openat (signal=589, cover=535)" would the timeout increment have effect on this? I will try to find the line where to increase the timeout.
Does not work:
2019/12/18 15:14:44 loading corpus...
2019/12/18 15:14:44 serving rpc on tcp://[::]:38835
2019/12/18 15:14:44 booting test machines...
2019/12/18 15:14:44 wait for the connection from test machine...
2019/12/18 15:16:16 machine check:
2019/12/18 15:16:16 syscalls : 1124/2961
2019/12/18 15:16:16 code coverage : enabled
2019/12/18 15:16:16 comparison tracing : ioctl(KCOV_TRACE_CMP) failed: invalid argument
2019/12/18 15:16:16 extra coverage : extra coverage is not supported by the kernel
2019/12/18 15:16:16 setuid sandbox : enabled
2019/12/18 15:16:16 namespace sandbox : enabled
2019/12/18 15:16:16 Android sandbox : /sys/fs/selinux/policy does not exist
2019/12/18 15:16:16 fault injection : enabled
2019/12/18 15:16:16 leak checking : enabled
2019/12/18 15:16:16 net packet injection : enabled
2019/12/18 15:16:16 net device setup : enabled
2019/12/18 15:16:16 concurrency sanitizer : /sys/kernel/debug/kcsan does not exist
2019/12/18 15:16:16 devlink PCI setup : PCI device 0000:00:10.0 is not available
2019/12/18 15:16:16 corpus : 0 (0 deleted)
JK poll from vm-0: candidates=0 inputs=0 maxsignal=0
JK poll from vm-0: candidates=0 inputs=0 maxsignal=0
2019/12/18 15:16:24 VMs 1, executed 3, cover 0, crashes 0, repro 0
2019/12/18 15:16:34 VMs 1, executed 3, cover 0, crashes 0, repro 0
JK poll from vm-0: candidates=0 inputs=0 maxsignal=0
2019/12/18 15:16:44 VMs 1, executed 10, cover 0, crashes 0, repro 0
JK poll from vm-0: candidates=0 inputs=0 maxsignal=0
2019/12/18 15:16:54 VMs 1, executed 10, cover 0, crashes 0, repro 0
JK poll from vm-0: candidates=0 inputs=0 maxsignal=0
2019/12/18 15:17:04 VMs 1, executed 10, cover 0, crashes 0, repro 0
JK poll from vm-0: candidates=0 inputs=0 maxsignal=0
2019/12/18 15:17:14 VMs 1, executed 10, cover 0, crashes 0, repro 0
JK poll from vm-0: candidates=0 inputs=0 maxsignal=0
2019/12/18 15:17:24 VMs 1, executed 10, cover 0, crashes 0, repro 0
2019/12/18 15:17:34 VMs 1, executed 10, cover 0, crashes 0, repro 0
JK poll from vm-0: candidates=0 inputs=0 maxsignal=0
2019/12/18 15:17:44 VMs 1, executed 18, cover 0, crashes 0, repro 0
JK poll from vm-0: candidates=0 inputs=0 maxsignal=0
2019/12/18 15:17:54 VMs 1, executed 18, cover 0, crashes 0, repro 0
JK poll from vm-0: candidates=0 inputs=0 maxsignal=0
2019/12/18 15:18:04 VMs 1, executed 18, cover 0, crashes 0, repro 0
JK poll from vm-0: candidates=0 inputs=0 maxsignal=0
2019/12/18 15:18:14 VMs 1, executed 18, cover 0, crashes 0, repro 0
Here it works:
2019/12/18 15:14:53 loading corpus...
2019/12/18 15:14:53 serving rpc on tcp://[::]:43639
2019/12/18 15:14:53 booting test machines...
2019/12/18 15:14:53 wait for the connection from test machine...
2019/12/18 15:16:43 machine check:
2019/12/18 15:16:43 syscalls : 1124/2961
2019/12/18 15:16:43 code coverage : enabled
2019/12/18 15:16:43 comparison tracing : ioctl(KCOV_TRACE_CMP) failed: invalid argument
2019/12/18 15:16:43 extra coverage : extra coverage is not supported by the kernel
2019/12/18 15:16:43 setuid sandbox : enabled
2019/12/18 15:16:43 namespace sandbox : enabled
2019/12/18 15:16:43 Android sandbox : /sys/fs/selinux/policy does not exist
2019/12/18 15:16:43 fault injection : enabled
2019/12/18 15:16:43 leak checking : enabled
2019/12/18 15:16:43 net packet injection : enabled
2019/12/18 15:16:43 net device setup : enabled
2019/12/18 15:16:43 concurrency sanitizer : /sys/kernel/debug/kcsan does not exist
2019/12/18 15:16:43 devlink PCI setup : PCI device 0000:00:10.0 is not available
2019/12/18 15:16:43 corpus : 156 (0 deleted)
JK poll from vm-0: candidates=5 inputs=0 maxsignal=0
JK poll from vm-0: candidates=0 inputs=0 maxsignal=0
2019/12/18 15:16:53 VMs 1, executed 0, cover 0, crashes 0, repro 0
JK poll from vm-0: candidates=0 inputs=0 maxsignal=0
2019/12/18 15:17:03 VMs 1, executed 2, cover 0, crashes 0, repro 0
JK poll from vm-0: candidates=0 inputs=0 maxsignal=0
2019/12/18 15:17:13 VMs 1, executed 2, cover 0, crashes 0, repro 0
JK poll from vm-0: candidates=0 inputs=0 maxsignal=0
2019/12/18 15:17:23 VMs 1, executed 2, cover 0, crashes 0, repro 0
JK poll from vm-0: candidates=0 inputs=0 maxsignal=0
2019/12/18 15:17:33 VMs 1, executed 2, cover 0, crashes 0, repro 0
JK new input from vm-0 for syscall openat (signal=589, cover=535)
JK skipped cover vm-0 vs. vm-0
JK new input from vm-0 for syscall syz_open_dev (signal=615, cover=612)
JK skipped cover vm-0 vs. vm-0
JK poll from vm-0: candidates=5 inputs=0 maxsignal=0
JK poll from vm-0: candidates=0 inputs=0 maxsignal=0
2019/12/18 15:17:43 VMs 1, executed 10, cover 831, crashes 0, repro 0
JK new input from vm-0 for syscall ftruncate (signal=80, cover=21)