D1219 21:08:59.844471 18639 x:0] Clock(Monotonic): error: 218 ns, adjusted frequency from 2299798834 Hz to 2299799712 Hz D1219 21:08:59.844868 18639 x:0] Clock(Realtime): error: 266 ns, adjusted frequency from 2299798840 Hz to 2299799760 Hz D1219 21:08:59.844998 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836065509669683 monotonicBaseRef:2000599470 monotonicFrequency:2299799712 realtimeReady:1 realtimeBaseCycles:42836065510619900 realtimeBaseRef:1576789739844861983 realtimeFrequency:2299799760} D1219 21:09:00.844190 18639 x:0] Clock(Monotonic): error: 218 ns, adjusted frequency from 2299799712 Hz to 2299799805 Hz D1219 21:09:00.844435 18639 x:0] Clock(Realtime): error: 256 ns, adjusted frequency from 2299799760 Hz to 2299799830 Hz D1219 21:09:00.844541 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836067808822324 monotonicBaseRef:3000318110 monotonicFrequency:2299799805 realtimeReady:1 realtimeBaseCycles:42836067809423926 realtimeBaseRef:1576789740844429017 realtimeFrequency:2299799830} D1219 21:09:01.844311 18639 x:0] Clock(Monotonic): error: 76 ns, adjusted frequency from 2299799805 Hz to 2299799503 Hz D1219 21:09:01.844585 18639 x:0] Clock(Realtime): error: 67 ns, adjusted frequency from 2299799830 Hz to 2299799466 Hz D1219 21:09:01.844724 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836070108760361 monotonicBaseRef:4000378216 monotonicFrequency:2299799503 realtimeReady:1 realtimeBaseCycles:42836070109566233 realtimeBaseRef:1576789741844577933 realtimeFrequency:2299799466} D1219 21:09:02.844486 18639 x:0] Clock(Monotonic): error: -9 ns, adjusted frequency from 2299799503 Hz to 2299799345 Hz D1219 21:09:02.844711 18639 x:0] Clock(Realtime): error: -7 ns, adjusted frequency from 2299799466 Hz to 2299799268 Hz D1219 21:09:02.844829 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836072409012725 monotonicBaseRef:5000575129 monotonicFrequency:2299799345 realtimeReady:1 realtimeBaseCycles:42836072409658669 realtimeBaseRef:1576789742844705322 realtimeFrequency:2299799268} D1219 21:09:03.844180 18639 x:0] Clock(Monotonic): error: 88 ns, adjusted frequency from 2299799345 Hz to 2299799583 Hz D1219 21:09:03.844364 18639 x:0] Clock(Realtime): error: 136 ns, adjusted frequency from 2299799268 Hz to 2299799661 Hz D1219 21:09:03.844469 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836074708200627 monotonicBaseRef:6000309261 monotonicFrequency:2299799583 realtimeReady:1 realtimeBaseCycles:42836074708660062 realtimeBaseRef:1576789743844358389 realtimeFrequency:2299799661} D1219 21:09:04.844222 18639 x:0] Clock(Monotonic): error: 81 ns, adjusted frequency from 2299799583 Hz to 2299799581 Hz D1219 21:09:04.844400 18639 x:0] Clock(Realtime): error: 87 ns, adjusted frequency from 2299799661 Hz to 2299799570 Hz D1219 21:09:04.844577 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836077008064592 monotonicBaseRef:7000337255 monotonicFrequency:2299799581 realtimeReady:1 realtimeBaseCycles:42836077008543864 realtimeBaseRef:1576789744844394975 realtimeFrequency:2299799570} D1219 21:09:05.844163 18639 x:0] Clock(Monotonic): error: -118 ns, adjusted frequency from 2299799581 Hz to 2299799121 Hz D1219 21:09:05.844359 18639 x:0] Clock(Realtime): error: -117 ns, adjusted frequency from 2299799570 Hz to 2299799098 Hz D1219 21:09:05.844424 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836079307746526 monotonicBaseRef:8000286099 monotonicFrequency:2299799121 realtimeReady:1 realtimeBaseCycles:42836079308233547 realtimeBaseRef:1576789745844347193 realtimeFrequency:2299799098} D1219 21:09:06.844648 18639 x:0] Clock(Monotonic): error: 83 ns, adjusted frequency from 2299799121 Hz to 2299799586 Hz D1219 21:09:06.844842 18639 x:0] Clock(Realtime): error: 136 ns, adjusted frequency from 2299799098 Hz to 2299799692 Hz D1219 21:09:06.844938 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836081608672724 monotonicBaseRef:9000776175 monotonicFrequency:2299799586 realtimeReady:1 realtimeBaseCycles:42836081609157528 realtimeBaseRef:1576789746844836315 realtimeFrequency:2299799692} D1219 21:09:07.844137 18639 x:0] Clock(Monotonic): error: -40 ns, adjusted frequency from 2299799586 Hz to 2299799358 Hz D1219 21:09:07.844284 18639 x:0] Clock(Realtime): error: -77 ns, adjusted frequency from 2299799692 Hz to 2299799266 Hz D1219 21:09:07.844367 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836083907314356 monotonicBaseRef:10000272672 monotonicFrequency:2299799358 realtimeReady:1 realtimeBaseCycles:42836083907674536 realtimeBaseRef:1576789747844278577 realtimeFrequency:2299799266} D1219 21:09:08.844306 18639 x:0] Clock(Monotonic): error: 59 ns, adjusted frequency from 2299799358 Hz to 2299799582 Hz D1219 21:09:08.844504 18639 x:0] Time: Adjusting syscall overhead up to 8000 D1219 21:09:08.844573 18639 x:0] Clock(Realtime): error: 798 ns, adjusted frequency from 2299799266 Hz to 2299801265 Hz D1219 21:09:08.844677 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836086207473188 monotonicBaseRef:11000428978 monotonicFrequency:2299799582 realtimeReady:1 realtimeBaseCycles:42836086208126459 realtimeBaseRef:1576789748844562365 realtimeFrequency:2299801265} D1219 21:09:09.844223 18639 x:0] Clock(Monotonic): error: 20 ns, adjusted frequency from 2299799582 Hz to 2299799484 Hz D1219 21:09:09.844415 18639 x:0] Clock(Realtime): error: -182 ns, adjusted frequency from 2299801265 Hz to 2299799012 Hz D1219 21:09:09.844513 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836088507106926 monotonicBaseRef:12000356865 monotonicFrequency:2299799484 realtimeReady:1 realtimeBaseCycles:42836088507572484 realtimeBaseRef:1576789749844407899 realtimeFrequency:2299799012} D1219 21:09:10.844302 18639 x:0] Time: Adjusting syscall overhead up to 8000 D1219 21:09:10.844429 18639 x:0] Clock(Monotonic): error: 320 ns, adjusted frequency from 2299799484 Hz to 2299800113 Hz D1219 21:09:10.844544 18639 x:0] Clock(Realtime): error: -162 ns, adjusted frequency from 2299799012 Hz to 2299799027 Hz D1219 21:09:10.844624 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836090807396165 monotonicBaseRef:13000569820 monotonicFrequency:2299800113 realtimeReady:1 realtimeBaseCycles:42836090807668076 realtimeBaseRef:1576789750844536858 realtimeFrequency:2299799027} D1219 21:09:11.844145 18639 x:0] Clock(Monotonic): error: 98 ns, adjusted frequency from 2299800113 Hz to 2299799539 Hz D1219 21:09:11.844301 18639 x:0] Clock(Realtime): error: -74 ns, adjusted frequency from 2299799027 Hz to 2299799232 Hz D1219 21:09:11.844411 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836093106528364 monotonicBaseRef:14000279397 monotonicFrequency:2299799539 realtimeReady:1 realtimeBaseCycles:42836093106909147 realtimeBaseRef:1576789751844294247 realtimeFrequency:2299799232} D1219 21:09:12.844254 18639 x:0] Clock(Monotonic): error: -446 ns, adjusted frequency from 2299799539 Hz to 2299798397 Hz D1219 21:09:12.844434 18639 x:0] Clock(Realtime): error: 26 ns, adjusted frequency from 2299799232 Hz to 2299799442 Hz D1219 21:09:12.844515 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836095406543526 monotonicBaseRef:15000373154 monotonicFrequency:2299798397 realtimeReady:1 realtimeBaseCycles:42836095407014906 realtimeBaseRef:1576789752844427531 realtimeFrequency:2299799442} D1219 21:09:13.844122 18639 x:0] Clock(Monotonic): error: 206 ns, adjusted frequency from 2299798397 Hz to 2299799917 Hz D1219 21:09:13.844290 18639 x:0] Clock(Realtime): error: 77 ns, adjusted frequency from 2299799442 Hz to 2299799561 Hz D1219 21:09:13.844368 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836097706041537 monotonicBaseRef:16000242539 monotonicFrequency:2299799917 realtimeReady:1 realtimeBaseCycles:42836097706480102 realtimeBaseRef:1576789753844282193 realtimeFrequency:2299799561} D1219 21:09:14.844595 18639 x:0] Clock(Monotonic): error: 150 ns, adjusted frequency from 2299799917 Hz to 2299799697 Hz D1219 21:09:14.844752 18639 x:0] Clock(Realtime): error: -111 ns, adjusted frequency from 2299799561 Hz to 2299799135 Hz D1219 21:09:14.844835 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836100006960628 monotonicBaseRef:17000729178 monotonicFrequency:2299799697 realtimeReady:1 realtimeBaseCycles:42836100007346466 realtimeBaseRef:1576789754844746060 realtimeFrequency:2299799135} D1219 21:09:15.844174 18639 x:0] Clock(Monotonic): error: -271 ns, adjusted frequency from 2299799697 Hz to 2299798823 Hz D1219 21:09:15.844347 18639 x:0] Clock(Realtime): error: 40 ns, adjusted frequency from 2299799135 Hz to 2299799490 Hz D1219 21:09:15.844430 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836102305786783 monotonicBaseRef:18000305862 monotonicFrequency:2299798823 realtimeReady:1 realtimeBaseCycles:42836102306214749 realtimeBaseRef:1576789755844341306 realtimeFrequency:2299799490} D1219 21:09:16.844160 18639 x:0] Clock(Monotonic): error: 120 ns, adjusted frequency from 2299798823 Hz to 2299799722 Hz D1219 21:09:16.844355 18639 x:0] Clock(Realtime): error: 162 ns, adjusted frequency from 2299799490 Hz to 2299799733 Hz D1219 21:09:16.844465 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836104605552327 monotonicBaseRef:19000291391 monotonicFrequency:2299799722 realtimeReady:1 realtimeBaseCycles:42836104606025764 realtimeBaseRef:1576789756844346317 realtimeFrequency:2299799733} D1219 21:09:17.844137 18639 x:0] Clock(Monotonic): error: 258 ns, adjusted frequency from 2299799722 Hz to 2299799947 Hz D1219 21:09:17.844264 18639 x:0] Clock(Realtime): error: -208 ns, adjusted frequency from 2299799733 Hz to 2299798935 Hz D1219 21:09:17.844342 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836106905306307 monotonicBaseRef:20000271501 monotonicFrequency:2299799947 realtimeReady:1 realtimeBaseCycles:42836106905623754 realtimeBaseRef:1576789757844258595 realtimeFrequency:2299798935} D1219 21:09:18.844139 18639 x:0] Clock(Monotonic): error: -22 ns, adjusted frequency from 2299799947 Hz to 2299799286 Hz D1219 21:09:18.844274 18639 x:0] Clock(Realtime): error: 73 ns, adjusted frequency from 2299798935 Hz to 2299799582 Hz D1219 21:09:18.844336 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836109205106172 monotonicBaseRef:21000271465 monotonicFrequency:2299799286 realtimeReady:1 realtimeBaseCycles:42836109205446917 realtimeBaseRef:1576789758844269129 realtimeFrequency:2299799582} D1219 21:09:19.844123 18639 x:0] Clock(Monotonic): error: -110 ns, adjusted frequency from 2299799286 Hz to 2299799259 Hz D1219 21:09:19.844260 18639 x:0] Clock(Realtime): error: -231 ns, adjusted frequency from 2299799582 Hz to 2299798947 Hz D1219 21:09:19.844360 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836111504860155 monotonicBaseRef:22000251766 monotonicFrequency:2299799259 realtimeReady:1 realtimeBaseCycles:42836111505213517 realtimeBaseRef:1576789759844254787 realtimeFrequency:2299798947} D1219 21:09:20.844165 18639 x:0] Clock(Monotonic): error: 289 ns, adjusted frequency from 2299799259 Hz to 2299800114 Hz D1219 21:09:20.844327 18639 x:0] Clock(Realtime): error: 451 ns, adjusted frequency from 2299798947 Hz to 2299800458 Hz D1219 21:09:20.844416 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836113804769407 monotonicBaseRef:23000299593 monotonicFrequency:2299800114 realtimeReady:1 realtimeBaseCycles:42836113805162613 realtimeBaseRef:1576789760844320074 realtimeFrequency:2299800458} D1219 21:09:21.844446 18639 x:0] Clock(Monotonic): error: -337 ns, adjusted frequency from 2299800114 Hz to 2299798691 Hz D1219 21:09:21.844627 18639 x:0] Clock(Realtime): error: -29 ns, adjusted frequency from 2299800458 Hz to 2299799325 Hz D1219 21:09:21.844748 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836116105204134 monotonicBaseRef:24000575535 monotonicFrequency:2299798691 realtimeReady:1 realtimeBaseCycles:42836116105651938 realtimeBaseRef:1576789761844619607 realtimeFrequency:2299799325} D1219 21:09:22.844740 18639 x:0] Clock(Monotonic): error: -65 ns, adjusted frequency from 2299798691 Hz to 2299799280 Hz D1219 21:09:22.844924 18639 x:0] Clock(Realtime): error: -249 ns, adjusted frequency from 2299799325 Hz to 2299798929 Hz D1219 21:09:22.845020 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836118405658851 monotonicBaseRef:25000860788 monotonicFrequency:2299799280 realtimeReady:1 realtimeBaseCycles:42836118406132682 realtimeBaseRef:1576789762844915901 realtimeFrequency:2299798929} D1219 21:09:23.844671 18639 x:0] Clock(Monotonic): error: 384 ns, adjusted frequency from 2299799280 Hz to 2299800365 Hz D1219 21:09:23.844850 18639 x:0] Clock(Realtime): error: 20 ns, adjusted frequency from 2299798929 Hz to 2299799551 Hz D1219 21:09:23.844962 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836120705261273 monotonicBaseRef:26000775190 monotonicFrequency:2299800365 realtimeReady:1 realtimeBaseCycles:42836120705753700 realtimeBaseRef:1576789763844838541 realtimeFrequency:2299799551} D1219 21:09:24.844103 18639 x:0] Clock(Monotonic): error: -73 ns, adjusted frequency from 2299800365 Hz to 2299799167 Hz D1219 21:09:24.844250 18639 x:0] Clock(Realtime): error: 184 ns, adjusted frequency from 2299799551 Hz to 2299799857 Hz D1219 21:09:24.844350 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836123003823347 monotonicBaseRef:27000236755 monotonicFrequency:2299799167 realtimeReady:1 realtimeBaseCycles:42836123004187110 realtimeBaseRef:1576789764844244514 realtimeFrequency:2299799857} D1219 21:09:25.844090 18639 x:0] Clock(Monotonic): error: 252 ns, adjusted frequency from 2299799167 Hz to 2299799946 Hz D1219 21:09:25.844243 18639 x:0] Clock(Realtime): error: -132 ns, adjusted frequency from 2299799857 Hz to 2299799238 Hz D1219 21:09:25.844336 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836125303598496 monotonicBaseRef:28000226311 monotonicFrequency:2299799946 realtimeReady:1 realtimeBaseCycles:42836125303969160 realtimeBaseRef:1576789765844236771 realtimeFrequency:2299799238} D1219 21:09:26.844204 18639 x:0] Clock(Monotonic): error: -466 ns, adjusted frequency from 2299799946 Hz to 2299798459 Hz D1219 21:09:26.844420 18639 x:0] Clock(Realtime): error: 246 ns, adjusted frequency from 2299799238 Hz to 2299799990 Hz D1219 21:09:26.844529 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836127603645904 monotonicBaseRef:29000333912 monotonicFrequency:2299798459 realtimeReady:1 realtimeBaseCycles:42836127604163330 realtimeBaseRef:1576789766844408495 realtimeFrequency:2299799990} D1219 21:09:27.844135 18639 x:0] Clock(Monotonic): error: 291 ns, adjusted frequency from 2299798459 Hz to 2299800099 Hz D1219 21:09:27.844305 18639 x:0] Clock(Realtime): error: -226 ns, adjusted frequency from 2299799990 Hz to 2299798917 Hz D1219 21:09:27.844430 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836129903283162 monotonicBaseRef:30000263818 monotonicFrequency:2299800099 realtimeReady:1 realtimeBaseCycles:42836129903708433 realtimeBaseRef:1576789767844297664 realtimeFrequency:2299798917} D1219 21:09:28.844171 18639 x:0] Clock(Monotonic): error: -53 ns, adjusted frequency from 2299800099 Hz to 2299799231 Hz D1219 21:09:28.844317 18639 x:0] Clock(Realtime): error: 44 ns, adjusted frequency from 2299798917 Hz to 2299799481 Hz D1219 21:09:28.844406 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836132203172597 monotonicBaseRef:31000302663 monotonicFrequency:2299799231 realtimeReady:1 realtimeBaseCycles:42836132203530283 realtimeBaseRef:1576789768844307635 realtimeFrequency:2299799481} D1219 21:09:29.845059 18639 x:0] Clock(Monotonic): error: -331 ns, adjusted frequency from 2299799231 Hz to 2299798809 Hz D1219 21:09:29.845225 18639 x:0] Clock(Realtime): error: 16 ns, adjusted frequency from 2299799481 Hz to 2299799494 Hz D1219 21:09:29.845341 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836134505014694 monotonicBaseRef:32001190943 monotonicFrequency:2299798809 realtimeReady:1 realtimeBaseCycles:42836134505424888 realtimeBaseRef:1576789769845218637 realtimeFrequency:2299799494} D1219 21:09:30.844424 18639 x:0] Clock(Monotonic): error: 82 ns, adjusted frequency from 2299798809 Hz to 2299799613 Hz D1219 21:09:30.844581 18639 x:0] Clock(Realtime): error: -348 ns, adjusted frequency from 2299799494 Hz to 2299798760 Hz D1219 21:09:30.844715 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836136803349445 monotonicBaseRef:33000554340 monotonicFrequency:2299799613 realtimeReady:1 realtimeBaseCycles:42836136803742983 realtimeBaseRef:1576789770844574494 realtimeFrequency:2299798760} D1219 21:09:31.844071 18639 x:0] Clock(Monotonic): error: -157 ns, adjusted frequency from 2299799613 Hz to 2299799086 Hz D1219 21:09:31.844220 18639 x:0] Clock(Realtime): error: -60 ns, adjusted frequency from 2299798760 Hz to 2299799300 Hz D1219 21:09:31.844324 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836139102346280 monotonicBaseRef:34000205275 monotonicFrequency:2299799086 realtimeReady:1 realtimeBaseCycles:42836139102713993 realtimeBaseRef:1576789771844214571 realtimeFrequency:2299799300} D1219 21:09:32.043442 18639 x:0] urpc: unmarshal success. D1219 21:09:32.043643 18639 x:0] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-2 Signo:0 PID:0 Mode:Process} D1219 21:09:32.043928 18639 x:0] urpc: successfully marshalled 37 bytes. D1219 21:09:32.844137 18639 x:0] Clock(Monotonic): error: 536 ns, adjusted frequency from 2299799086 Hz to 2299800778 Hz D1219 21:09:32.844295 18639 x:0] Clock(Realtime): error: 1208 ns, adjusted frequency from 2299799300 Hz to 2299802268 Hz D1219 21:09:32.844399 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836141402301891 monotonicBaseRef:35000273335 monotonicFrequency:2299800778 realtimeReady:1 realtimeBaseCycles:42836141402685531 realtimeBaseRef:1576789772844289463 realtimeFrequency:2299802268} D1219 21:09:33.844557 18639 x:0] Clock(Monotonic): error: -565 ns, adjusted frequency from 2299800778 Hz to 2299798255 Hz D1219 21:09:33.844731 18639 x:0] Clock(Realtime): error: -867 ns, adjusted frequency from 2299802268 Hz to 2299797388 Hz D1219 21:09:33.844818 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836143703022820 monotonicBaseRef:36000673435 monotonicFrequency:2299798255 realtimeReady:1 realtimeBaseCycles:42836143703485255 realtimeBaseRef:1576789773844723176 realtimeFrequency:2299797388} D1219 21:09:34.844123 18639 x:0] Clock(Monotonic): error: 40 ns, adjusted frequency from 2299798255 Hz to 2299799631 Hz D1219 21:09:34.844279 18639 x:0] Clock(Realtime): error: 69 ns, adjusted frequency from 2299797388 Hz to 2299799548 Hz D1219 21:09:34.844370 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836146001869930 monotonicBaseRef:37000259857 monotonicFrequency:2299799631 realtimeReady:1 realtimeBaseCycles:42836146002238173 realtimeBaseRef:1576789774844269018 realtimeFrequency:2299799548} D1219 21:09:35.844704 18639 x:0] Clock(Monotonic): error: 269 ns, adjusted frequency from 2299799631 Hz to 2299799943 Hz D1219 21:09:35.844870 18639 x:0] Clock(Realtime): error: -21 ns, adjusted frequency from 2299799548 Hz to 2299799425 Hz D1219 21:09:35.844943 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836148302967908 monotonicBaseRef:38000824404 monotonicFrequency:2299799943 realtimeReady:1 realtimeBaseCycles:42836148303404650 realtimeBaseRef:1576789775844863386 realtimeFrequency:2299799425} D1219 21:09:36.844481 18639 x:0] Clock(Monotonic): error: -179 ns, adjusted frequency from 2299799943 Hz to 2299799165 Hz D1219 21:09:36.844636 18639 x:0] Clock(Realtime): error: 130 ns, adjusted frequency from 2299799425 Hz to 2299799733 Hz D1219 21:09:36.844753 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836150602283801 monotonicBaseRef:39000613929 monotonicFrequency:2299799165 realtimeReady:1 realtimeBaseCycles:42836150602666433 realtimeBaseRef:1576789776844629608 realtimeFrequency:2299799733} D1219 21:09:37.844223 18639 x:0] Clock(Monotonic): error: -80 ns, adjusted frequency from 2299799165 Hz to 2299799361 Hz D1219 21:09:37.844372 18639 x:0] Clock(Realtime): error: -165 ns, adjusted frequency from 2299799733 Hz to 2299799070 Hz D1219 21:09:37.844485 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836152901491362 monotonicBaseRef:40000356687 monotonicFrequency:2299799361 realtimeReady:1 realtimeBaseCycles:42836152901849694 realtimeBaseRef:1576789777844361553 realtimeFrequency:2299799070} D1219 21:09:38.844171 18639 x:0] Clock(Monotonic): error: -121 ns, adjusted frequency from 2299799361 Hz to 2299799162 Hz D1219 21:09:38.844368 18639 x:0] Clock(Realtime): error: 238 ns, adjusted frequency from 2299799070 Hz to 2299799979 Hz D1219 21:09:38.844453 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836155201164431 monotonicBaseRef:41000301772 monotonicFrequency:2299799162 realtimeReady:1 realtimeBaseCycles:42836155201634187 realtimeBaseRef:1576789778844355214 realtimeFrequency:2299799979} D1219 21:09:39.846458 18639 x:0] Clock(Monotonic): error: -87 ns, adjusted frequency from 2299799162 Hz to 2299799248 Hz D1219 21:09:39.846580 18639 x:0] Clock(Realtime): error: -404 ns, adjusted frequency from 2299799979 Hz to 2299798468 Hz D1219 21:09:39.846659 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836157506214576 monotonicBaseRef:42002585007 monotonicFrequency:2299799248 realtimeReady:1 realtimeBaseCycles:42836157506537908 realtimeBaseRef:1576789779846574425 realtimeFrequency:2299798468} D1219 21:09:40.845070 18639 x:0] Clock(Monotonic): error: 218 ns, adjusted frequency from 2299799248 Hz to 2299799958 Hz D1219 21:09:40.845288 18639 x:0] Clock(Realtime): error: 134 ns, adjusted frequency from 2299798468 Hz to 2299799717 Hz D1219 21:09:40.845376 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836159802808423 monotonicBaseRef:43001191232 monotonicFrequency:2299799958 realtimeReady:1 realtimeBaseCycles:42836159803365762 realtimeBaseRef:1576789780845282740 realtimeFrequency:2299799717} D1219 21:09:41.844533 18639 x:0] Clock(Monotonic): error: -136 ns, adjusted frequency from 2299799958 Hz to 2299799083 Hz D1219 21:09:41.844739 18639 x:0] Clock(Realtime): error: -114 ns, adjusted frequency from 2299799717 Hz to 2299799121 Hz D1219 21:09:41.844841 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836162101389053 monotonicBaseRef:44000661043 monotonicFrequency:2299799083 realtimeReady:1 realtimeBaseCycles:42836162101898505 realtimeBaseRef:1576789781844731833 realtimeFrequency:2299799121} D1219 21:09:42.845531 18639 x:0] Clock(Monotonic): error: 183 ns, adjusted frequency from 2299799083 Hz to 2299799866 Hz D1219 21:09:42.845690 18639 x:0] Clock(Realtime): error: 337 ns, adjusted frequency from 2299799121 Hz to 2299800267 Hz D1219 21:09:42.845788 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836164403485962 monotonicBaseRef:45001660185 monotonicFrequency:2299799866 realtimeReady:1 realtimeBaseCycles:42836164403882121 realtimeBaseRef:1576789782845681696 realtimeFrequency:2299800267} I1219 21:09:43.287614 18639 x:0] Watchdog starting loop, tasks: 11, discount: 0s D1219 21:09:43.845325 18639 x:0] Clock(Monotonic): error: -65 ns, adjusted frequency from 2299799866 Hz to 2299799265 Hz D1219 21:09:43.845490 18639 x:0] Clock(Realtime): error: -11 ns, adjusted frequency from 2299800267 Hz to 2299799387 Hz D1219 21:09:43.845569 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836166702768790 monotonicBaseRef:46001435366 monotonicFrequency:2299799265 realtimeReady:1 realtimeBaseCycles:42836166703223526 realtimeBaseRef:1576789783845482173 realtimeFrequency:2299799387} D1219 21:09:44.844129 18639 x:0] Clock(Monotonic): error: -62 ns, adjusted frequency from 2299799265 Hz to 2299799430 Hz D1219 21:09:44.844292 18639 x:0] Clock(Realtime): error: 348 ns, adjusted frequency from 2299799387 Hz to 2299800242 Hz D1219 21:09:44.844389 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836168999855663 monotonicBaseRef:47000255962 monotonicFrequency:2299799430 realtimeReady:1 realtimeBaseCycles:42836169000259081 realtimeBaseRef:1576789784844280402 realtimeFrequency:2299800242} D1219 21:09:45.844149 18639 x:0] Clock(Monotonic): error: 31 ns, adjusted frequency from 2299799430 Hz to 2299799518 Hz D1219 21:09:45.844300 18639 x:0] Clock(Realtime): error: -656 ns, adjusted frequency from 2299800242 Hz to 2299797996 Hz D1219 21:09:45.844396 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836171299722497 monotonicBaseRef:48000285270 monotonicFrequency:2299799518 realtimeReady:1 realtimeBaseCycles:42836171300086597 realtimeBaseRef:1576789785844292261 realtimeFrequency:2299797996} D1219 21:09:46.844089 18639 x:0] Clock(Monotonic): error: -76 ns, adjusted frequency from 2299799518 Hz to 2299799266 Hz D1219 21:09:46.844241 18639 x:0] Clock(Realtime): error: 321 ns, adjusted frequency from 2299797996 Hz to 2299800200 Hz D1219 21:09:46.844346 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836173599380261 monotonicBaseRef:49000223632 monotonicFrequency:2299799266 realtimeReady:1 realtimeBaseCycles:42836173599751557 realtimeBaseRef:1576789786844234414 realtimeFrequency:2299800200} D1219 21:09:47.844612 18639 x:0] Clock(Monotonic): error: 506 ns, adjusted frequency from 2299799266 Hz to 2299800527 Hz D1219 21:09:47.844785 18639 x:0] Clock(Realtime): error: -294 ns, adjusted frequency from 2299800200 Hz to 2299798850 Hz D1219 21:09:47.844904 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836175900383748 monotonicBaseRef:50000747252 monotonicFrequency:2299800527 realtimeReady:1 realtimeBaseCycles:42836175900787990 realtimeBaseRef:1576789787844771953 realtimeFrequency:2299798850} D1219 21:09:48.844199 18639 x:0] Clock(Monotonic): error: -486 ns, adjusted frequency from 2299800527 Hz to 2299798340 Hz D1219 21:09:48.844374 18639 x:0] Clock(Realtime): error: 75 ns, adjusted frequency from 2299798850 Hz to 2299799613 Hz D1219 21:09:48.844473 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836178199231500 monotonicBaseRef:51000332966 monotonicFrequency:2299798340 realtimeReady:1 realtimeBaseCycles:42836178199655881 realtimeBaseRef:1576789788844367152 realtimeFrequency:2299799613} D1219 21:09:49.844160 18639 x:0] Clock(Monotonic): error: 262 ns, adjusted frequency from 2299798340 Hz to 2299799904 Hz D1219 21:09:49.844329 18639 x:0] Clock(Realtime): error: 82 ns, adjusted frequency from 2299799613 Hz to 2299799613 Hz D1219 21:09:49.844388 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836180498938841 monotonicBaseRef:52000293397 monotonicFrequency:2299799904 realtimeReady:1 realtimeBaseCycles:42836180499352797 realtimeBaseRef:1576789789844322497 realtimeFrequency:2299799613} D1219 21:09:50.668853 18639 x:0] urpc: unmarshal success. D1219 21:09:50.669048 18639 x:0] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-2 Signo:0 PID:0 Mode:Process} D1219 21:09:50.669249 18639 x:0] urpc: successfully marshalled 37 bytes. D1219 21:09:50.844212 18639 x:0] Clock(Monotonic): error: -182 ns, adjusted frequency from 2299799904 Hz to 2299798980 Hz D1219 21:09:50.844388 18639 x:0] Clock(Realtime): error: -167 ns, adjusted frequency from 2299799613 Hz to 2299799043 Hz D1219 21:09:50.844474 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836182798848896 monotonicBaseRef:53000341292 monotonicFrequency:2299798980 realtimeReady:1 realtimeBaseCycles:42836182799290387 realtimeBaseRef:1576789790844382492 realtimeFrequency:2299799043} D1219 21:09:51.844262 18639 x:0] Clock(Monotonic): error: 393 ns, adjusted frequency from 2299798980 Hz to 2299800258 Hz D1219 21:09:51.844442 18639 x:0] Clock(Realtime): error: 187 ns, adjusted frequency from 2299799043 Hz to 2299799906 Hz D1219 21:09:51.844553 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836185098772381 monotonicBaseRef:54000395429 monotonicFrequency:2299800258 realtimeReady:1 realtimeBaseCycles:42836185099212127 realtimeBaseRef:1576789791844435843 realtimeFrequency:2299799906} D1219 21:09:52.844646 18639 x:0] Clock(Monotonic): error: -314 ns, adjusted frequency from 2299800258 Hz to 2299798754 Hz D1219 21:09:52.844842 18639 x:0] Clock(Realtime): error: 81 ns, adjusted frequency from 2299799906 Hz to 2299799667 Hz D1219 21:09:52.844955 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836187399455929 monotonicBaseRef:55000779501 monotonicFrequency:2299798754 realtimeReady:1 realtimeBaseCycles:42836187399930818 realtimeBaseRef:1576789792844835349 realtimeFrequency:2299799667} D1219 21:09:53.844859 18639 x:0] Clock(Monotonic): error: -71 ns, adjusted frequency from 2299798754 Hz to 2299799268 Hz D1219 21:09:53.844999 18639 x:0] Clock(Realtime): error: -458 ns, adjusted frequency from 2299799667 Hz to 2299798436 Hz D1219 21:09:53.845069 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836189699701162 monotonicBaseRef:56000973639 monotonicFrequency:2299799268 realtimeReady:1 realtimeBaseCycles:42836189700087478 realtimeBaseRef:1576789793844990576 realtimeFrequency:2299798436} D1219 21:09:54.844342 18639 x:0] Clock(Monotonic): error: -67 ns, adjusted frequency from 2299799268 Hz to 2299799288 Hz D1219 21:09:54.844493 18639 x:0] Clock(Realtime): error: 205 ns, adjusted frequency from 2299798436 Hz to 2299799865 Hz D1219 21:09:54.844562 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836191998357745 monotonicBaseRef:57000476776 monotonicFrequency:2299799288 realtimeReady:1 realtimeBaseCycles:42836191998731129 realtimeBaseRef:1576789794844488451 realtimeFrequency:2299799865} D1219 21:09:55.844153 18639 x:0] Time: Adjusting syscall overhead down to 7000 D1219 21:09:55.844297 18639 x:0] Clock(Monotonic): error: 197 ns, adjusted frequency from 2299799288 Hz to 2299799916 Hz D1219 21:09:55.844393 18639 x:0] Clock(Realtime): error: 190 ns, adjusted frequency from 2299799865 Hz to 2299799855 Hz D1219 21:09:55.844454 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836194297731483 monotonicBaseRef:58000291738 monotonicFrequency:2299799916 realtimeReady:1 realtimeBaseCycles:42836194298298585 realtimeBaseRef:1576789795844387394 realtimeFrequency:2299799855} D1219 21:09:56.844161 18639 x:0] Clock(Monotonic): error: -161 ns, adjusted frequency from 2299799916 Hz to 2299799158 Hz D1219 21:09:56.844318 18639 x:0] Clock(Realtime): error: -162 ns, adjusted frequency from 2299799855 Hz to 2299799030 Hz D1219 21:09:56.844403 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836196597541836 monotonicBaseRef:59000296276 monotonicFrequency:2299799158 realtimeReady:1 realtimeBaseCycles:42836196597925722 realtimeBaseRef:1576789796844312292 realtimeFrequency:2299799030} D1219 21:09:57.844157 18639 x:0] Clock(Monotonic): error: -58 ns, adjusted frequency from 2299799158 Hz to 2299799313 Hz D1219 21:09:57.844419 18639 x:0] Clock(Realtime): error: 226 ns, adjusted frequency from 2299799030 Hz to 2299799900 Hz D1219 21:09:57.844487 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836198897332853 monotonicBaseRef:60000292736 monotonicFrequency:2299799313 realtimeReady:1 realtimeBaseCycles:42836198897952965 realtimeBaseRef:1576789797844411523 realtimeFrequency:2299799900} D1219 21:09:58.844163 18639 x:0] Clock(Monotonic): error: 148 ns, adjusted frequency from 2299799313 Hz to 2299799924 Hz D1219 21:09:58.844323 18639 x:0] Clock(Realtime): error: -13 ns, adjusted frequency from 2299799900 Hz to 2299799428 Hz D1219 21:09:58.844426 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836201197133867 monotonicBaseRef:61000293475 monotonicFrequency:2299799924 realtimeReady:1 realtimeBaseCycles:42836201197536487 realtimeBaseRef:1576789798844317437 realtimeFrequency:2299799428} D1219 21:09:59.844094 18639 x:0] Clock(Monotonic): error: 4 ns, adjusted frequency from 2299799924 Hz to 2299799456 Hz D1219 21:09:59.844262 18639 x:0] Clock(Realtime): error: -278 ns, adjusted frequency from 2299799428 Hz to 2299798836 Hz D1219 21:09:59.844358 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836203496781183 monotonicBaseRef:62000227117 monotonicFrequency:2299799456 realtimeReady:1 realtimeBaseCycles:42836203497192259 realtimeBaseRef:1576789799844254972 realtimeFrequency:2299798836} D1219 21:10:00.844139 18639 x:0] Clock(Monotonic): error: -121 ns, adjusted frequency from 2299799456 Hz to 2299799251 Hz D1219 21:10:00.844288 18639 x:0] Clock(Realtime): error: 287 ns, adjusted frequency from 2299798836 Hz to 2299800105 Hz D1219 21:10:00.844401 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836205796655682 monotonicBaseRef:63000259747 monotonicFrequency:2299799251 realtimeReady:1 realtimeBaseCycles:42836205797051476 realtimeBaseRef:1576789800844281226 realtimeFrequency:2299800105} D1219 21:10:01.844100 18639 x:0] Clock(Monotonic): error: 3 ns, adjusted frequency from 2299799251 Hz to 2299799449 Hz D1219 21:10:01.844267 18639 x:0] Clock(Realtime): error: 2 ns, adjusted frequency from 2299800105 Hz to 2299799443 Hz D1219 21:10:01.844351 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836208096395239 monotonicBaseRef:64000233790 monotonicFrequency:2299799449 realtimeReady:1 realtimeBaseCycles:42836208096804551 realtimeBaseRef:1576789801844260776 realtimeFrequency:2299799443} D1219 21:10:02.844134 18639 x:0] Clock(Monotonic): error: -134 ns, adjusted frequency from 2299799449 Hz to 2299799152 Hz D1219 21:10:02.844325 18639 x:0] Clock(Realtime): error: -152 ns, adjusted frequency from 2299799443 Hz to 2299799015 Hz D1219 21:10:02.844408 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836210396258821 monotonicBaseRef:65000261676 monotonicFrequency:2299799152 realtimeReady:1 realtimeBaseCycles:42836210396736215 realtimeBaseRef:1576789802844318268 realtimeFrequency:2299799015} D1219 21:10:03.844584 18639 x:0] Clock(Monotonic): error: 119 ns, adjusted frequency from 2299799152 Hz to 2299799715 Hz D1219 21:10:03.844781 18639 x:0] Clock(Realtime): error: 287 ns, adjusted frequency from 2299799015 Hz to 2299800128 Hz D1219 21:10:03.844898 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836212697090670 monotonicBaseRef:66000710713 monotonicFrequency:2299799715 realtimeReady:1 realtimeBaseCycles:42836212697580050 realtimeBaseRef:1576789803844772577 realtimeFrequency:2299800128} D1219 21:10:04.844170 18639 x:0] Clock(Monotonic): error: 36 ns, adjusted frequency from 2299799715 Hz to 2299799520 Hz D1219 21:10:04.844351 18639 x:0] Clock(Realtime): error: -184 ns, adjusted frequency from 2299800128 Hz to 2299799134 Hz D1219 21:10:04.844446 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836214995938024 monotonicBaseRef:67000296606 monotonicFrequency:2299799520 realtimeReady:1 realtimeBaseCycles:42836214996391430 realtimeBaseRef:1576789804844342649 realtimeFrequency:2299799134} D1219 21:10:05.844239 18639 x:0] Clock(Monotonic): error: -43 ns, adjusted frequency from 2299799520 Hz to 2299799352 Hz D1219 21:10:05.844431 18639 x:0] Clock(Realtime): error: -337 ns, adjusted frequency from 2299799134 Hz to 2299798718 Hz D1219 21:10:05.844536 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836217295900576 monotonicBaseRef:68000367495 monotonicFrequency:2299799352 realtimeReady:1 realtimeBaseCycles:42836217296378252 realtimeBaseRef:1576789805844424259 realtimeFrequency:2299798718} D1219 21:10:06.845167 18639 x:0] Clock(Monotonic): error: -153 ns, adjusted frequency from 2299799352 Hz to 2299799088 Hz D1219 21:10:06.845312 18639 x:0] Clock(Realtime): error: 140 ns, adjusted frequency from 2299798718 Hz to 2299799837 Hz D1219 21:10:06.845378 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836219597843904 monotonicBaseRef:69001299739 monotonicFrequency:2299799088 realtimeReady:1 realtimeBaseCycles:42836219598205560 realtimeBaseRef:1576789806845306331 realtimeFrequency:2299799837} D1219 21:10:07.844630 18639 x:0] Clock(Monotonic): error: 128 ns, adjusted frequency from 2299799088 Hz to 2299799730 Hz D1219 21:10:07.844830 18639 x:0] Clock(Realtime): error: 238 ns, adjusted frequency from 2299799837 Hz to 2299800002 Hz D1219 21:10:07.844945 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836221896401802 monotonicBaseRef:70000760044 monotonicFrequency:2299799730 realtimeReady:1 realtimeBaseCycles:42836221896894545 realtimeBaseRef:1576789807844823309 realtimeFrequency:2299800002} D1219 21:10:08.844135 18639 x:0] Clock(Monotonic): error: 70 ns, adjusted frequency from 2299799730 Hz to 2299799667 Hz D1219 21:10:08.844282 18639 x:0] Clock(Realtime): error: -174 ns, adjusted frequency from 2299800002 Hz to 2299799006 Hz D1219 21:10:08.844375 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836224195073509 monotonicBaseRef:71000269556 monotonicFrequency:2299799667 realtimeReady:1 realtimeBaseCycles:42836224195433881 realtimeBaseRef:1576789808844275145 realtimeFrequency:2299799006} D1219 21:10:09.845028 18639 x:0] Clock(Monotonic): error: -78 ns, adjusted frequency from 2299799667 Hz to 2299799271 Hz D1219 21:10:09.845212 18639 x:0] Clock(Realtime): error: 199 ns, adjusted frequency from 2299799006 Hz to 2299799857 Hz D1219 21:10:09.845282 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836226496918808 monotonicBaseRef:72001159038 monotonicFrequency:2299799271 realtimeReady:1 realtimeBaseCycles:42836226497369174 realtimeBaseRef:1576789809845204046 realtimeFrequency:2299799857} D1219 21:10:10.844155 18639 x:0] Clock(Monotonic): error: 88 ns, adjusted frequency from 2299799271 Hz to 2299799642 Hz D1219 21:10:10.844293 18639 x:0] Clock(Realtime): error: -267 ns, adjusted frequency from 2299799857 Hz to 2299798810 Hz D1219 21:10:10.844410 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836228794716050 monotonicBaseRef:73000288514 monotonicFrequency:2299799642 realtimeReady:1 realtimeBaseCycles:42836228795060347 realtimeBaseRef:1576789810844287147 realtimeFrequency:2299798810} D1219 21:10:11.844134 18639 x:0] Clock(Monotonic): error: -61 ns, adjusted frequency from 2299799642 Hz to 2299799307 Hz D1219 21:10:11.844299 18639 x:0] Clock(Realtime): error: 250 ns, adjusted frequency from 2299798810 Hz to 2299800051 Hz D1219 21:10:11.844421 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836231094467474 monotonicBaseRef:74000267547 monotonicFrequency:2299799307 realtimeReady:1 realtimeBaseCycles:42836231094869328 realtimeBaseRef:1576789811844291569 realtimeFrequency:2299800051} D1219 21:10:12.844208 18639 x:0] Clock(Monotonic): error: 37 ns, adjusted frequency from 2299799307 Hz to 2299799534 Hz D1219 21:10:12.844369 18639 x:0] Clock(Realtime): error: 83 ns, adjusted frequency from 2299800051 Hz to 2299799651 Hz D1219 21:10:12.844466 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836233394426468 monotonicBaseRef:75000336982 monotonicFrequency:2299799534 realtimeReady:1 realtimeBaseCycles:42836233394830183 realtimeBaseRef:1576789812844361489 realtimeFrequency:2299799651} D1219 21:10:13.844092 18639 x:0] Clock(Monotonic): error: -163 ns, adjusted frequency from 2299799534 Hz to 2299799055 Hz D1219 21:10:13.844257 18639 x:0] Clock(Realtime): error: 419 ns, adjusted frequency from 2299799651 Hz to 2299800419 Hz D1219 21:10:13.844356 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836235693967915 monotonicBaseRef:76000224760 monotonicFrequency:2299799055 realtimeReady:1 realtimeBaseCycles:42836235694371981 realtimeBaseRef:1576789813844249369 realtimeFrequency:2299800419} D1219 21:10:14.844170 18639 x:0] Clock(Monotonic): error: 94 ns, adjusted frequency from 2299799055 Hz to 2299799656 Hz D1219 21:10:14.844339 18639 x:0] Clock(Realtime): error: -799 ns, adjusted frequency from 2299800419 Hz to 2299797614 Hz D1219 21:10:14.844412 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836237993913117 monotonicBaseRef:77000288307 monotonicFrequency:2299799656 realtimeReady:1 realtimeBaseCycles:42836237994352992 realtimeBaseRef:1576789814844327894 realtimeFrequency:2299797614} D1219 21:10:15.844196 18639 x:0] Clock(Monotonic): error: -96 ns, adjusted frequency from 2299799656 Hz to 2299799211 Hz D1219 21:10:15.844342 18639 x:0] Clock(Realtime): error: 45 ns, adjusted frequency from 2299797614 Hz to 2299799541 Hz D1219 21:10:15.844442 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836240293792849 monotonicBaseRef:78000323125 monotonicFrequency:2299799211 realtimeReady:1 realtimeBaseCycles:42836240294172620 realtimeBaseRef:1576789815844337466 realtimeFrequency:2299799541} D1219 21:10:16.844333 18639 x:0] Clock(Monotonic): error: 115 ns, adjusted frequency from 2299799211 Hz to 2299799653 Hz D1219 21:10:16.844487 18639 x:0] Clock(Realtime): error: 48 ns, adjusted frequency from 2299799541 Hz to 2299799504 Hz D1219 21:10:16.844612 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836242593916627 monotonicBaseRef:79000464253 monotonicFrequency:2299799653 realtimeReady:1 realtimeBaseCycles:42836242594303895 realtimeBaseRef:1576789816844481710 realtimeFrequency:2299799504} D1219 21:10:17.844769 18639 x:0] Clock(Monotonic): error: 50 ns, adjusted frequency from 2299799653 Hz to 2299799545 Hz D1219 21:10:17.844947 18639 x:0] Clock(Realtime): error: -77 ns, adjusted frequency from 2299799504 Hz to 2299799316 Hz D1219 21:10:17.845030 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836244894715321 monotonicBaseRef:80000898656 monotonicFrequency:2299799545 realtimeReady:1 realtimeBaseCycles:42836244895156624 realtimeBaseRef:1576789817844939673 realtimeFrequency:2299799316} D1219 21:10:18.844210 18639 x:0] Clock(Monotonic): error: 22 ns, adjusted frequency from 2299799545 Hz to 2299799480 Hz D1219 21:10:18.844375 18639 x:0] Clock(Realtime): error: 214 ns, adjusted frequency from 2299799316 Hz to 2299799969 Hz D1219 21:10:18.844500 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836247193228760 monotonicBaseRef:81000339430 monotonicFrequency:2299799480 realtimeReady:1 realtimeBaseCycles:42836247193632336 realtimeBaseRef:1576789818844364142 realtimeFrequency:2299799969} D1219 21:10:19.844097 18639 x:0] Clock(Monotonic): error: -2 ns, adjusted frequency from 2299799480 Hz to 2299799449 Hz D1219 21:10:19.844296 18639 x:0] Clock(Realtime): error: 50 ns, adjusted frequency from 2299799969 Hz to 2299799592 Hz D1219 21:10:19.844395 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836249492780270 monotonicBaseRef:82000231607 monotonicFrequency:2299799449 realtimeReady:1 realtimeBaseCycles:42836249493244477 realtimeBaseRef:1576789819844282470 realtimeFrequency:2299799592} D1219 21:10:20.844341 18639 x:0] Clock(Monotonic): error: 292 ns, adjusted frequency from 2299799449 Hz to 2299800025 Hz D1219 21:10:20.844500 18639 x:0] Clock(Realtime): error: -79 ns, adjusted frequency from 2299799592 Hz to 2299799224 Hz D1219 21:10:20.844576 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836251793112870 monotonicBaseRef:83000463431 monotonicFrequency:2299800025 realtimeReady:1 realtimeBaseCycles:42836251793519255 realtimeBaseRef:1576789820844489090 realtimeFrequency:2299799224} D1219 21:10:21.844173 18639 x:0] Clock(Monotonic): error: -249 ns, adjusted frequency from 2299800025 Hz to 2299798870 Hz D1219 21:10:21.844325 18639 x:0] Clock(Realtime): error: 108 ns, adjusted frequency from 2299799224 Hz to 2299799643 Hz D1219 21:10:21.844460 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836254092552408 monotonicBaseRef:84000306683 monotonicFrequency:2299798870 realtimeReady:1 realtimeBaseCycles:42836254092926193 realtimeBaseRef:1576789821844318515 realtimeFrequency:2299799643} D1219 21:10:22.844469 18639 x:0] Clock(Monotonic): error: 136 ns, adjusted frequency from 2299798870 Hz to 2299799664 Hz D1219 21:10:22.844618 18639 x:0] Clock(Realtime): error: -475 ns, adjusted frequency from 2299799643 Hz to 2299798307 Hz D1219 21:10:22.844738 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836256393027975 monotonicBaseRef:85000600924 monotonicFrequency:2299799664 realtimeReady:1 realtimeBaseCycles:42836256393400321 realtimeBaseRef:1576789822844611794 realtimeFrequency:2299798307} D1219 21:10:23.844185 18639 x:0] Clock(Monotonic): error: -140 ns, adjusted frequency from 2299799664 Hz to 2299799125 Hz D1219 21:10:23.844340 18639 x:0] Clock(Realtime): error: 36 ns, adjusted frequency from 2299798307 Hz to 2299799524 Hz D1219 21:10:23.844423 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836258692168819 monotonicBaseRef:86000314455 monotonicFrequency:2299799125 realtimeReady:1 realtimeBaseCycles:42836258692560363 realtimeBaseRef:1576789823844334263 realtimeFrequency:2299799524} D1219 21:10:24.844157 18639 x:0] Clock(Monotonic): error: 2 ns, adjusted frequency from 2299799125 Hz to 2299799458 Hz D1219 21:10:24.844333 18639 x:0] Clock(Realtime): error: 443 ns, adjusted frequency from 2299799524 Hz to 2299800452 Hz D1219 21:10:24.844419 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836260991911413 monotonicBaseRef:87000289874 monotonicFrequency:2299799458 realtimeReady:1 realtimeBaseCycles:42836260992341205 realtimeBaseRef:1576789824844326139 realtimeFrequency:2299800452} D1219 21:10:25.858419 18639 x:0] Clock(Monotonic): error: -13 ns, adjusted frequency from 2299799458 Hz to 2299799417 Hz D1219 21:10:25.858573 18639 x:0] Clock(Realtime): error: -145 ns, adjusted frequency from 2299800452 Hz to 2299799181 Hz D1219 21:10:25.858662 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836263324513580 monotonicBaseRef:88014553165 monotonicFrequency:2299799417 realtimeReady:1 realtimeBaseCycles:42836263324893455 realtimeBaseRef:1576789825858567286 realtimeFrequency:2299799181} D1219 21:10:26.141547 18639 x:0] urpc: unmarshal success. D1219 21:10:26.141734 18639 x:0] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-2 Signo:0 PID:0 Mode:Process} D1219 21:10:26.141923 18639 x:0] urpc: successfully marshalled 37 bytes. D1219 21:10:26.844182 18639 x:0] Clock(Monotonic): error: -22 ns, adjusted frequency from 2299799417 Hz to 2299799402 Hz D1219 21:10:26.844350 18639 x:0] Clock(Realtime): error: 68 ns, adjusted frequency from 2299799181 Hz to 2299799524 Hz D1219 21:10:26.844447 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836265591575456 monotonicBaseRef:89000318210 monotonicFrequency:2299799402 realtimeReady:1 realtimeBaseCycles:42836265591982027 realtimeBaseRef:1576789826844344040 realtimeFrequency:2299799524} I1219 21:10:27.525225 20735 x:0] *************************** I1219 21:10:27.525312 20735 x:0] Args: [/syzkaller/managers/ptrace-proxy-sandbox-race/current/image -root /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root -watchdog-action=panic -network=none -debug -alsologtostderr -platform=ptrace -file-access=shared -network=sandbox -TESTONLY-unsafe-nonroot exec -user=0:0 -cap CAP_CHOWN -cap CAP_DAC_OVERRIDE -cap CAP_DAC_READ_SEARCH -cap CAP_FOWNER -cap CAP_FSETID -cap CAP_KILL -cap CAP_SETGID -cap CAP_SETUID -cap CAP_SETPCAP -cap CAP_LINUX_IMMUTABLE -cap CAP_NET_BIND_SERVICE -cap CAP_NET_BROADCAST -cap CAP_NET_ADMIN -cap CAP_NET_RAW -cap CAP_IPC_LOCK -cap CAP_IPC_OWNER -cap CAP_SYS_MODULE -cap CAP_SYS_RAWIO -cap CAP_SYS_CHROOT -cap CAP_SYS_PTRACE -cap CAP_SYS_PACCT -cap CAP_SYS_ADMIN -cap CAP_SYS_BOOT -cap CAP_SYS_NICE -cap CAP_SYS_RESOURCE -cap CAP_SYS_TIME -cap CAP_SYS_TTY_CONFIG -cap CAP_MKNOD -cap CAP_LEASE -cap CAP_AUDIT_WRITE -cap CAP_AUDIT_CONTROL -cap CAP_SETFCAP -cap CAP_MAC_OVERRIDE -cap CAP_MAC_ADMIN -cap CAP_SYSLOG -cap CAP_WAKE_ALARM -cap CAP_BLOCK_SUSPEND -cap CAP_AUDIT_READ ci-gvisor-ptrace-proxy-sandbox-race-2 /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=4 -repeat=0 -threaded=false -collide=false -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller703468100] I1219 21:10:27.525811 20735 x:0] Version release-20191213.0-32-g57ce26c0b465 I1219 21:10:27.525861 20735 x:0] PID: 20735 I1219 21:10:27.525904 20735 x:0] UID: 0, GID: 0 I1219 21:10:27.525975 20735 x:0] Configuration: I1219 21:10:27.526002 20735 x:0] RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root I1219 21:10:27.526066 20735 x:0] Platform: ptrace I1219 21:10:27.526111 20735 x:0] FileAccess: shared, overlay: false I1219 21:10:27.526186 20735 x:0] Network: sandbox, logging: false I1219 21:10:27.526250 20735 x:0] Strace: false, max size: 1024, syscalls: [] I1219 21:10:27.526304 20735 x:0] *************************** W1219 21:10:27.526335 20735 x:0] Block the TERM signal. This is only safe in tests! D1219 21:10:27.526827 20735 x:0] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-2" D1219 21:10:27.535501 20735 x:0] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-2": signal 0 D1219 21:10:27.535636 20735 x:0] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D1219 21:10:27.535708 20735 x:0] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D1219 21:10:27.536235 20735 x:0] urpc: successfully marshalled 117 bytes. D1219 21:10:27.536620 18639 x:0] urpc: unmarshal success. D1219 21:10:27.536866 18639 x:0] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-2 Signo:0 PID:0 Mode:Process} D1219 21:10:27.537089 18639 x:0] urpc: successfully marshalled 37 bytes. D1219 21:10:27.538552 20735 x:0] urpc: unmarshal success. D1219 21:10:27.538776 20735 x:0] Exec arguments: /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=4 -repeat=0 -threaded=false -collide=false -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller703468100 D1219 21:10:27.538920 20735 x:0] Exec capablities: &{PermittedCaps:274877898751 InheritableCaps:274877898751 EffectiveCaps:274877898751 BoundingCaps:274877898751 AmbientCaps:0} D1219 21:10:27.539085 20735 x:0] Execute in container "ci-gvisor-ptrace-proxy-sandbox-race-2", args: /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=4 -repeat=0 -threaded=false -collide=false -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller703468100 D1219 21:10:27.539220 20735 x:0] Executing new process in container "ci-gvisor-ptrace-proxy-sandbox-race-2" in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D1219 21:10:27.539351 20735 x:0] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D1219 21:10:27.540794 18639 x:0] urpc: unmarshal success. D1219 21:10:27.542121 18639 x:0] containerManager.ExecuteAsync: /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=4 -repeat=0 -threaded=false -collide=false -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller703468100 D1219 21:10:27.542699 18639 x:0] send [channel @0xc000288540] Twalkgetattr{FID: 1, NewFID: 5, Names: [etc]} D1219 21:10:27.542856 20735 x:0] urpc: successfully marshalled 610 bytes. D1219 21:10:27.543359 18639 x:0] recv [channel @0xc000288540] Rlerror{Error: 2} I1219 21:10:27.543876 18639 x:0] EXEC: [/syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=4 -repeat=0 -threaded=false -collide=false -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller703468100] D1219 21:10:27.544407 18639 x:0] send [channel @0xc000288540] Twalkgetattr{FID: 1, NewFID: 5, Names: [syz-execprog]} D1219 21:10:27.545771 18639 x:0] recv [channel @0xc000288540] Rwalkgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 18144800, BlockSize: 4096, Blocks: 35440, ATime: {Sec: 1576762150, NanoSec: 651049272}, MTime: {Sec: 1576762150, NanoSec: 651049272}, CTime: {Sec: 1576789739, NanoSec: 344233225}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16255697}]} D1219 21:10:27.546046 18639 x:0] send [channel @0xc000288540] Tgetattr{FID: 5, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D1219 21:10:27.546594 18639 x:0] recv [channel @0xc000288540] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16255697}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 18144800, BlockSize: 4096, Blocks: 35440, ATime: {Sec: 1576762150, NanoSec: 651049272}, MTime: {Sec: 1576762150, NanoSec: 651049272}, CTime: {Sec: 1576789739, NanoSec: 344233225}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D1219 21:10:27.546679 18639 x:0] send [channel @0xc000288540] Twalk{FID: 5, NewFID: 6, Names: []} D1219 21:10:27.547021 18639 x:0] recv [channel @0xc000288540] Rwalk{QIDs: []} D1219 21:10:27.547108 18639 x:0] send [channel @0xc000288540] Tlopen{FID: 6, Flags: ReadOnly} D1219 21:10:27.547591 18639 x:0] recv [channel @0xc000288540] Rlopen{QID: QID{Type: 0, Version: 0, Path: 16255697}, IoUnit: 0, File: &{{33}}} D1219 21:10:27.551037 18639 x:0] Allocating stack with size of 8388608 bytes D1219 21:10:27.552407 18639 x:0] updated processes: map[{ci-gvisor-ptrace-proxy-sandbox-race-2 0}:0xc00000e340 {ci-gvisor-ptrace-proxy-sandbox-race-2 17}:0xc0002a6d60] D1219 21:10:27.552661 18639 x:0] urpc: successfully marshalled 37 bytes. D1219 21:10:27.552867 20735 x:0] urpc: unmarshal success. D1219 21:10:27.552997 20735 x:0] Wait on PID 17 in container "ci-gvisor-ptrace-proxy-sandbox-race-2" D1219 21:10:27.553103 20735 x:0] Waiting for PID 17 in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D1219 21:10:27.553169 20735 x:0] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D1219 21:10:27.553928 20735 x:0] urpc: successfully marshalled 100 bytes. D1219 21:10:27.554349 18639 x:0] urpc: unmarshal success. D1219 21:10:27.554676 18639 x:0] containerManager.Wait D1219 21:10:27.562062 18639 x:0] [ 17] Interrupt queued D1219 21:10:27.753274 18639 x:0] [ 18] Interrupt queued D1219 21:10:27.755642 18639 x:0] [ 19] Interrupt queued D1219 21:10:27.762817 18639 x:0] [ 20] Interrupt queued D1219 21:10:27.763358 18639 x:0] [ 21] Interrupt queued D1219 21:10:27.801473 18639 x:0] [ 22] Interrupt queued D1219 21:10:27.824137 18639 x:0] [ 23] Interrupt queued D1219 21:10:27.829638 18639 x:0] [ 24] Interrupt queued D1219 21:10:27.844132 18639 x:0] Clock(Monotonic): error: 84 ns, adjusted frequency from 2299799402 Hz to 2299799645 Hz D1219 21:10:27.844422 18639 x:0] Clock(Realtime): error: -269 ns, adjusted frequency from 2299799524 Hz to 2299798876 Hz D1219 21:10:27.844539 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836267891255022 monotonicBaseRef:90000266102 monotonicFrequency:2299799645 realtimeReady:1 realtimeBaseCycles:42836267891940076 realtimeBaseRef:1576789827844412969 realtimeFrequency:2299798876} D1219 21:10:27.884387 18639 x:0] send [channel @0xc000288540] Twalkgetattr{FID: 1, NewFID: 7, Names: [etc]} D1219 21:10:27.884971 18639 x:0] recv [channel @0xc000288540] Rlerror{Error: 2} D1219 21:10:27.931467 18639 x:0] [ 25] Interrupt queued D1219 21:10:27.950313 18639 x:0] [ 26] Interrupt queued D1219 21:10:27.950514 18639 x:0] [ 27] Interrupt queued D1219 21:10:27.956562 18639 x:0] [ 28] Interrupt queued D1219 21:10:27.986584 18639 x:0] send [channel @0xc000288540] Twalkgetattr{FID: 1, NewFID: 7, Names: [syzkaller703468100]} D1219 21:10:27.987545 18639 x:0] recv [channel @0xc000288540] Rwalkgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, Attr: Attr{Mode: 0o100600, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 207, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1576789827, NanoSec: 443915285}, MTime: {Sec: 1576789827, NanoSec: 443915285}, CTime: {Sec: 1576789827, NanoSec: 447915635}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16255306}]} D1219 21:10:27.987914 18639 x:0] send [channel @0xc000288540] Tgetattr{FID: 7, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D1219 21:10:27.988678 18639 x:0] recv [channel @0xc000288540] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16255306}, Attr: Attr{Mode: 0o100600, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 207, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1576789827, NanoSec: 443915285}, MTime: {Sec: 1576789827, NanoSec: 443915285}, CTime: {Sec: 1576789827, NanoSec: 447915635}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D1219 21:10:27.988861 18639 x:0] send [channel @0xc000288540] Twalk{FID: 7, NewFID: 8, Names: []} D1219 21:10:27.989328 18639 x:0] recv [channel @0xc000288540] Rwalk{QIDs: []} D1219 21:10:27.989458 18639 x:0] send [channel @0xc000288540] Tlopen{FID: 8, Flags: ReadOnly} D1219 21:10:27.989983 18639 x:0] recv [channel @0xc000288540] Rlopen{QID: QID{Type: 0, Version: 0, Path: 16255306}, IoUnit: 0, File: &{{25}}} D1219 21:10:27.990989 18639 x:0] send [channel @0xc000288540] Tgetattr{FID: 8, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D1219 21:10:27.991565 18639 x:0] recv [channel @0xc000288540] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16255306}, Attr: Attr{Mode: 0o100600, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 207, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1576789827, NanoSec: 443915285}, MTime: {Sec: 1576789827, NanoSec: 443915285}, CTime: {Sec: 1576789827, NanoSec: 447915635}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} 2019/12/19 21:10:27 parsed 1 programs D1219 21:10:28.007556 18639 x:0] [ 22] Entering internal stop (*kernel.vforkStop)(nil) D1219 21:10:28.008274 18639 x:0] [ 29] Interrupt queued D1219 21:10:28.019585 18639 x:0] send [channel @0xc000288540] Twalkgetattr{FID: 1, NewFID: 9, Names: [syz-executor]} D1219 21:10:28.020561 18639 x:0] recv [channel @0xc000288540] Rwalkgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 1554384, BlockSize: 4096, Blocks: 3040, ATime: {Sec: 1576762151, NanoSec: 987165771}, MTime: {Sec: 1576762151, NanoSec: 987165771}, CTime: {Sec: 1576789739, NanoSec: 348233574}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16255702}]} D1219 21:10:28.021038 18639 x:0] send [channel @0xc000288540] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D1219 21:10:28.021680 18639 x:0] recv [channel @0xc000288540] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16255702}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 1554384, BlockSize: 4096, Blocks: 3040, ATime: {Sec: 1576762151, NanoSec: 987165771}, MTime: {Sec: 1576762151, NanoSec: 987165771}, CTime: {Sec: 1576789739, NanoSec: 348233574}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D1219 21:10:28.021820 18639 x:0] send [channel @0xc000288540] Twalk{FID: 9, NewFID: 10, Names: []} D1219 21:10:28.022732 18639 x:0] recv [channel @0xc000288540] Rwalk{QIDs: []} D1219 21:10:28.022827 18639 x:0] send [channel @0xc000288540] Tlopen{FID: 10, Flags: ReadOnly} D1219 21:10:28.024436 18639 x:0] recv [channel @0xc000288540] Rlopen{QID: QID{Type: 0, Version: 0, Path: 16255702}, IoUnit: 0, File: &{{26}}} D1219 21:10:28.025752 18639 x:0] [ 29] Allocating stack with size of 8388608 bytes D1219 21:10:28.026679 18639 x:0] [ 22] Leaving internal stop (*kernel.vforkStop)(nil) D1219 21:10:28.121753 18639 x:0] send [channel @0xc000288540] Twalkgetattr{FID: 1, NewFID: 11, Names: [etc]} D1219 21:10:28.122285 18639 x:0] recv [channel @0xc000288540] Rlerror{Error: 2} D1219 21:10:28.123935 18639 x:0] [ 29] Transitioning from exit state TaskExitNone to TaskExitInitiated D1219 21:10:28.125490 18639 x:0] [ 29] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1219 21:10:28.125610 18639 x:0] [ 17] Notified of signal 17 D1219 21:10:28.125686 18639 x:0] [ 17] Interrupt queued D1219 21:10:28.125743 18639 x:0] [ 17] Interrupt queued D1219 21:10:28.125858 18639 x:0] [ 17] Restarting syscall 202 after errno 512: interrupted by signal 17 D1219 21:10:28.125931 18639 x:0] [ 17] Signal 17: delivering to handler D1219 21:10:28.126036 18639 x:0] [ 29] Transitioning from exit state TaskExitZombie to TaskExitDead D1219 21:10:28.130312 18639 x:0] [ 30] Interrupt queued D1219 21:10:28.171522 18639 x:0] send [channel @0xc000288540] Twalkgetattr{FID: 1, NewFID: 11, Names: [syz-executor]} D1219 21:10:28.172538 18639 x:0] recv [channel @0xc000288540] Rwalkgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 1554384, BlockSize: 4096, Blocks: 3040, ATime: {Sec: 1576762151, NanoSec: 987165771}, MTime: {Sec: 1576762151, NanoSec: 987165771}, CTime: {Sec: 1576789739, NanoSec: 348233574}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16255702}]} D1219 21:10:28.172660 18639 x:0] send [channel @0xc000288540] Tclunk{FID: 11} D1219 21:10:28.174058 18639 x:0] recv [channel @0xc000288540] Rclunk{} D1219 21:10:28.174280 18639 x:0] send [channel @0xc000288540] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D1219 21:10:28.180001 18639 x:0] recv [channel @0xc000288540] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16255702}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 1554384, BlockSize: 4096, Blocks: 3040, ATime: {Sec: 1576762151, NanoSec: 987165771}, MTime: {Sec: 1576762151, NanoSec: 987165771}, CTime: {Sec: 1576789739, NanoSec: 348233574}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} 2019/12/19 21:10:28 executed programs: 0 D1219 21:10:28.184087 18639 x:0] send [channel @0xc000288540] Twalkgetattr{FID: 1, NewFID: 11, Names: [syz-executor]} D1219 21:10:28.185498 18639 x:0] recv [channel @0xc000288540] Rwalkgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 1554384, BlockSize: 4096, Blocks: 3040, ATime: {Sec: 1576762151, NanoSec: 987165771}, MTime: {Sec: 1576762151, NanoSec: 987165771}, CTime: {Sec: 1576789739, NanoSec: 348233574}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16255702}]} D1219 21:10:28.185611 18639 x:0] send [channel @0xc000288540] Tclunk{FID: 11} D1219 21:10:28.186303 18639 x:0] recv [channel @0xc000288540] Rclunk{} D1219 21:10:28.186510 18639 x:0] send [channel @0xc000288540] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D1219 21:10:28.187306 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syz-executor]} D1219 21:10:28.187380 18639 x:0] recv [channel @0xc000288540] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16255702}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 1554384, BlockSize: 4096, Blocks: 3040, ATime: {Sec: 1576762151, NanoSec: 987165771}, MTime: {Sec: 1576762151, NanoSec: 987165771}, CTime: {Sec: 1576789739, NanoSec: 348233574}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D1219 21:10:28.188539 18639 x:0] recv [channel @0xc000288480] Rwalkgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 1554384, BlockSize: 4096, Blocks: 3040, ATime: {Sec: 1576762151, NanoSec: 987165771}, MTime: {Sec: 1576762151, NanoSec: 987165771}, CTime: {Sec: 1576789739, NanoSec: 348233574}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16255702}]} D1219 21:10:28.188651 18639 x:0] send [channel @0xc000288480] Tclunk{FID: 11} D1219 21:10:28.188907 18639 x:0] recv [channel @0xc000288480] Rclunk{} D1219 21:10:28.189314 18639 x:0] send [channel @0xc000288480] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D1219 21:10:28.189420 18639 x:0] send [channel @0xc000288540] Twalkgetattr{FID: 1, NewFID: 11, Names: [syz-executor]} D1219 21:10:28.190180 18639 x:0] recv [channel @0xc000288480] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16255702}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 1554384, BlockSize: 4096, Blocks: 3040, ATime: {Sec: 1576762151, NanoSec: 987165771}, MTime: {Sec: 1576762151, NanoSec: 987165771}, CTime: {Sec: 1576789739, NanoSec: 348233574}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D1219 21:10:28.190663 18639 x:0] recv [channel @0xc000288540] Rwalkgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 1554384, BlockSize: 4096, Blocks: 3040, ATime: {Sec: 1576762151, NanoSec: 987165771}, MTime: {Sec: 1576762151, NanoSec: 987165771}, CTime: {Sec: 1576789739, NanoSec: 348233574}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16255702}]} D1219 21:10:28.190768 18639 x:0] send [channel @0xc000288540] Tclunk{FID: 11} D1219 21:10:28.191603 18639 x:0] recv [channel @0xc000288540] Rclunk{} D1219 21:10:28.191807 18639 x:0] send [channel @0xc000288540] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D1219 21:10:28.192741 18639 x:0] recv [channel @0xc000288540] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16255702}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 1554384, BlockSize: 4096, Blocks: 3040, ATime: {Sec: 1576762151, NanoSec: 987165771}, MTime: {Sec: 1576762151, NanoSec: 987165771}, CTime: {Sec: 1576789739, NanoSec: 348233574}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D1219 21:10:28.220759 18639 x:0] [ 19] Entering internal stop (*kernel.vforkStop)(nil) D1219 21:10:28.221387 18639 x:0] [ 31] Interrupt queued D1219 21:10:28.228578 18639 x:0] send [channel @0xc000288540] Twalkgetattr{FID: 1, NewFID: 11, Names: [syz-executor]} D1219 21:10:28.229640 18639 x:0] recv [channel @0xc000288540] Rwalkgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 1554384, BlockSize: 4096, Blocks: 3040, ATime: {Sec: 1576762151, NanoSec: 987165771}, MTime: {Sec: 1576762151, NanoSec: 987165771}, CTime: {Sec: 1576789739, NanoSec: 348233574}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16255702}]} D1219 21:10:28.229807 18639 x:0] send [channel @0xc000288540] Tclunk{FID: 11} D1219 21:10:28.230191 18639 x:0] recv [channel @0xc000288540] Rclunk{} D1219 21:10:28.230385 18639 x:0] send [channel @0xc000288540] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D1219 21:10:28.236962 18639 x:0] recv [channel @0xc000288540] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16255702}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 1554384, BlockSize: 4096, Blocks: 3040, ATime: {Sec: 1576762151, NanoSec: 987165771}, MTime: {Sec: 1576762151, NanoSec: 987165771}, CTime: {Sec: 1576789739, NanoSec: 348233574}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D1219 21:10:28.238078 18639 x:0] [ 31] Allocating stack with size of 8388608 bytes D1219 21:10:28.239215 18639 x:0] [ 19] Leaving internal stop (*kernel.vforkStop)(nil) D1219 21:10:28.245169 18639 x:0] [ 32] Interrupt queued D1219 21:10:28.247713 18639 x:0] [ 33] Interrupt queued D1219 21:10:28.261003 18639 x:0] [ 34] Interrupt queued D1219 21:10:28.267121 18639 x:0] [ 21] Entering internal stop (*kernel.vforkStop)(nil) D1219 21:10:28.279782 18639 x:0] [ 35] Interrupt queued I1219 21:10:28.288355 18639 x:0] Watchdog starting loop, tasks: 29, discount: 0s D1219 21:10:28.295257 18639 x:0] send [channel @0xc000288540] Twalkgetattr{FID: 1, NewFID: 11, Names: [syz-executor]} D1219 21:10:28.296406 18639 x:0] recv [channel @0xc000288540] Rwalkgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 1554384, BlockSize: 4096, Blocks: 3040, ATime: {Sec: 1576762151, NanoSec: 987165771}, MTime: {Sec: 1576762151, NanoSec: 987165771}, CTime: {Sec: 1576789739, NanoSec: 348233574}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16255702}]} D1219 21:10:28.296529 18639 x:0] send [channel @0xc000288540] Tclunk{FID: 11} D1219 21:10:28.296872 18639 x:0] recv [channel @0xc000288540] Rclunk{} D1219 21:10:28.297046 18639 x:0] send [channel @0xc000288540] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D1219 21:10:28.297596 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [etc]} D1219 21:10:28.297656 18639 x:0] recv [channel @0xc000288540] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16255702}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 1554384, BlockSize: 4096, Blocks: 3040, ATime: {Sec: 1576762151, NanoSec: 987165771}, MTime: {Sec: 1576762151, NanoSec: 987165771}, CTime: {Sec: 1576789739, NanoSec: 348233574}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D1219 21:10:28.298083 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.298924 18639 x:0] [ 35] Allocating stack with size of 8388608 bytes D1219 21:10:28.300849 18639 x:0] [ 21] Leaving internal stop (*kernel.vforkStop)(nil) D1219 21:10:28.314058 18639 x:0] [ 27] Entering internal stop (*kernel.vforkStop)(nil) D1219 21:10:28.314976 18639 x:0] [ 36] Interrupt queued D1219 21:10:28.336218 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syz-executor]} D1219 21:10:28.337277 18639 x:0] recv [channel @0xc000288480] Rwalkgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 1554384, BlockSize: 4096, Blocks: 3040, ATime: {Sec: 1576762151, NanoSec: 987165771}, MTime: {Sec: 1576762151, NanoSec: 987165771}, CTime: {Sec: 1576789739, NanoSec: 348233574}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16255702}]} D1219 21:10:28.337487 18639 x:0] send [channel @0xc000288480] Tclunk{FID: 11} D1219 21:10:28.337773 18639 x:0] recv [channel @0xc000288480] Rclunk{} D1219 21:10:28.337954 18639 x:0] send [channel @0xc000288480] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D1219 21:10:28.338626 18639 x:0] recv [channel @0xc000288480] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16255702}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 1554384, BlockSize: 4096, Blocks: 3040, ATime: {Sec: 1576762151, NanoSec: 987165771}, MTime: {Sec: 1576762151, NanoSec: 987165771}, CTime: {Sec: 1576789739, NanoSec: 348233574}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D1219 21:10:28.339749 18639 x:0] [ 36] Allocating stack with size of 8388608 bytes D1219 21:10:28.342913 18639 x:0] [ 27] Leaving internal stop (*kernel.vforkStop)(nil) D1219 21:10:28.345825 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [etc]} D1219 21:10:28.346360 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.349091 18639 x:0] [ 21] Entering internal stop (*kernel.vforkStop)(nil) D1219 21:10:28.349777 18639 x:0] [ 38] Interrupt queued D1219 21:10:28.376556 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syz-executor]} D1219 21:10:28.376708 18639 x:0] [ 37] Interrupt queued D1219 21:10:28.377876 18639 x:0] recv [channel @0xc000288480] Rwalkgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 1554384, BlockSize: 4096, Blocks: 3040, ATime: {Sec: 1576762151, NanoSec: 987165771}, MTime: {Sec: 1576762151, NanoSec: 987165771}, CTime: {Sec: 1576789739, NanoSec: 348233574}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16255702}]} D1219 21:10:28.378055 18639 x:0] send [channel @0xc000288480] Tclunk{FID: 11} D1219 21:10:28.378394 18639 x:0] recv [channel @0xc000288480] Rclunk{} D1219 21:10:28.378639 18639 x:0] send [channel @0xc000288480] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D1219 21:10:28.379278 18639 x:0] recv [channel @0xc000288480] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16255702}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 1554384, BlockSize: 4096, Blocks: 3040, ATime: {Sec: 1576762151, NanoSec: 987165771}, MTime: {Sec: 1576762151, NanoSec: 987165771}, CTime: {Sec: 1576789739, NanoSec: 348233574}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D1219 21:10:28.380217 18639 x:0] [ 38] Allocating stack with size of 8388608 bytes D1219 21:10:28.381716 18639 x:0] [ 21] Leaving internal stop (*kernel.vforkStop)(nil) D1219 21:10:28.395881 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [etc]} D1219 21:10:28.396109 18639 x:0] [ 39] Interrupt queued D1219 21:10:28.396454 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.413509 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.413562 18639 x:0] send [channel @0xc000288540] Twalkgetattr{FID: 1, NewFID: 12, Names: [syzcgroup]} D1219 21:10:28.414042 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.414085 18639 x:0] recv [channel @0xc000288540] Rlerror{Error: 2} D1219 21:10:28.415586 18639 x:0] send [channel @0xc000288540] Twalkgetattr{FID: 1, NewFID: 12, Names: [syzcgroup]} D1219 21:10:28.415665 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.416087 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.417746 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.418072 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.419044 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.419483 18639 x:0] recv [channel @0xc000288540] Rlerror{Error: 2} D1219 21:10:28.419638 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.421004 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.421453 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.421926 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.422420 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.423232 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.423725 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.424161 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.424491 18639 x:0] send [channel @0xc000288540] Twalkgetattr{FID: 1, NewFID: 12, Names: [syzcgroup]} D1219 21:10:28.424784 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.425031 18639 x:0] recv [channel @0xc000288540] Rlerror{Error: 2} D1219 21:10:28.425208 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.425650 18639 x:0] send [channel @0xc000288540] Twalkgetattr{FID: 1, NewFID: 12, Names: [syzcgroup]} D1219 21:10:28.428598 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.431089 18639 x:0] recv [channel @0xc000288540] Rlerror{Error: 2} D1219 21:10:28.462613 18639 x:0] send [channel @0xc000288540] Twalkgetattr{FID: 1, NewFID: 12, Names: [syzcgroup]} D1219 21:10:28.463039 18639 x:0] recv [channel @0xc000288540] Rlerror{Error: 2} D1219 21:10:28.467692 18639 x:0] send [channel @0xc000288540] Twalkgetattr{FID: 1, NewFID: 12, Names: [syzcgroup]} D1219 21:10:28.468091 18639 x:0] recv [channel @0xc000288540] Rlerror{Error: 2} D1219 21:10:28.468613 18639 x:0] send [channel @0xc000288540] Twalkgetattr{FID: 1, NewFID: 12, Names: [syzcgroup]} D1219 21:10:28.468988 18639 x:0] recv [channel @0xc000288540] Rlerror{Error: 2} D1219 21:10:28.469316 18639 x:0] send [channel @0xc000288540] Twalkgetattr{FID: 1, NewFID: 12, Names: [syzcgroup]} D1219 21:10:28.470194 18639 x:0] recv [channel @0xc000288540] Rlerror{Error: 2} D1219 21:10:28.470721 18639 x:0] send [channel @0xc000288540] Twalkgetattr{FID: 1, NewFID: 12, Names: [syzcgroup]} D1219 21:10:28.471163 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [etc]} D1219 21:10:28.471176 18639 x:0] recv [channel @0xc000288540] Rlerror{Error: 2} D1219 21:10:28.471836 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.477347 18639 x:0] [ 40] Interrupt queued D1219 21:10:28.503129 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.503606 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.503932 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.504266 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.504608 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.505867 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.506272 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.506622 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.506914 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.508582 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.508577 18639 x:0] [ 41] Interrupt queued D1219 21:10:28.509573 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.510206 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.510679 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.511176 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.512777 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.513169 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.513823 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.514158 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.527644 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.528228 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.528630 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.529007 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.529541 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.531353 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.531827 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.532323 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.532603 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.533010 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.533565 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.534412 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.534718 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.535197 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.535521 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.535911 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.536373 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.536828 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.699514 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.700090 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.700357 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.700970 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.701233 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.701662 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.701896 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.702215 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.702444 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.702741 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.703033 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.703442 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.704770 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.705213 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.705506 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.705814 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.705998 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.706251 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.707053 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.707612 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.712575 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.713100 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.713466 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.713822 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.714099 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.714459 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.714656 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.714909 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.715082 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.715396 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.715609 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.715862 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.716030 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.716071 18639 x:0] send [channel @0xc000288540] Twalkgetattr{FID: 1, NewFID: 12, Names: [syzcgroup]} D1219 21:10:28.716988 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.717273 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.717828 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.720625 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.720921 18639 x:0] recv [channel @0xc000288540] Rlerror{Error: 2} D1219 21:10:28.721059 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.721196 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.723773 18639 x:0] send [channel @0xc000288540] Twalkgetattr{FID: 1, NewFID: 12, Names: [syzcgroup]} D1219 21:10:28.724381 18639 x:0] recv [channel @0xc000288540] Rlerror{Error: 2} D1219 21:10:28.724658 18639 x:0] send [channel @0xc000288540] Twalkgetattr{FID: 1, NewFID: 12, Names: [syzcgroup]} D1219 21:10:28.725110 18639 x:0] recv [channel @0xc000288540] Rlerror{Error: 2} D1219 21:10:28.725639 18639 x:0] send [channel @0xc000288540] Twalkgetattr{FID: 1, NewFID: 12, Names: [syzcgroup]} D1219 21:10:28.725985 18639 x:0] recv [channel @0xc000288540] Rlerror{Error: 2} D1219 21:10:28.726238 18639 x:0] send [channel @0xc000288540] Twalkgetattr{FID: 1, NewFID: 12, Names: [syzcgroup]} D1219 21:10:28.726566 18639 x:0] recv [channel @0xc000288540] Rlerror{Error: 2} D1219 21:10:28.727922 18639 x:0] send [channel @0xc000288540] Twalkgetattr{FID: 1, NewFID: 12, Names: [syzcgroup]} D1219 21:10:28.728165 18639 x:0] recv [channel @0xc000288540] Rlerror{Error: 2} D1219 21:10:28.730855 18639 x:0] send [channel @0xc000288540] Twalkgetattr{FID: 1, NewFID: 12, Names: [syzcgroup]} D1219 21:10:28.731256 18639 x:0] recv [channel @0xc000288540] Rlerror{Error: 2} D1219 21:10:28.723806 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.733652 18639 x:0] send [channel @0xc000288480] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D1219 21:10:28.733859 18639 x:0] send [channel @0xc000288540] Twalkgetattr{FID: 1, NewFID: 12, Names: [syzcgroup]} D1219 21:10:28.734051 18639 x:0] recv [channel @0xc000288480] Rlerror{Error: 2} D1219 21:10:28.734576 18639 x:0] recv [channel @0xc000288540] Rlerror{Error: 2} D1219 21:10:28.734860 18639 x:0] send [channel @0xc000288540] Twalkgetattr{FID: 1, NewFID: 12, Names: [syzcgroup]} D1219 21:10:28.735421 18639 x:0] recv [channel @0xc000288540] Rlerror{Error: 2} D1219 21:10:28.743993 18639 x:0] send [channel @0xc000288540] Twalkgetattr{FID: 1, NewFID: 12, Names: [syzcgroup]} D1219 21:10:28.744513 18639 x:0] recv [channel @0xc000288540] Rlerror{Error: 2} D1219 21:10:28.744872 18639 x:0] send [channel @0xc000288540] Twalkgetattr{FID: 1, NewFID: 12, Names: [syzcgroup]} D1219 21:10:28.745234 18639 x:0] recv [channel @0xc000288540] Rlerror{Error: 2} D1219 21:10:28.745513 18639 x:0] send [channel @0xc000288540] Twalkgetattr{FID: 1, NewFID: 12, Names: [syzcgroup]} D1219 21:10:28.745862 18639 x:0] recv [channel @0xc000288540] Rlerror{Error: 2} D1219 21:10:28.746072 18639 x:0] send [channel @0xc000288540] Twalkgetattr{FID: 1, NewFID: 12, Names: [syzcgroup]} D1219 21:10:28.746431 18639 x:0] recv [channel @0xc000288540] Rlerror{Error: 2} D1219 21:10:28.746660 18639 x:0] send [channel @0xc000288540] Twalkgetattr{FID: 1, NewFID: 12, Names: [syzcgroup]} D1219 21:10:28.746928 18639 x:0] recv [channel @0xc000288540] Rlerror{Error: 2} D1219 21:10:28.747109 18639 x:0] send [channel @0xc000288540] Twalkgetattr{FID: 1, NewFID: 12, Names: [syzcgroup]} D1219 21:10:28.748462 18639 x:0] recv [channel @0xc000288540] Rlerror{Error: 2} D1219 21:10:28.748846 18639 x:0] send [channel @0xc000288540] Twalkgetattr{FID: 1, NewFID: 12, Names: [syzcgroup]} D1219 21:10:28.749282 18639 x:0] recv [channel @0xc000288540] Rlerror{Error: 2} D1219 21:10:28.749698 18639 x:0] send [channel @0xc000288540] Twalkgetattr{FID: 1, NewFID: 12, Names: [syzcgroup]} D1219 21:10:28.750141 18639 x:0] recv [channel @0xc000288540] Rlerror{Error: 2} D1219 21:10:28.751654 18639 x:0] send [channel @0xc000288540] Twalkgetattr{FID: 1, NewFID: 12, Names: [syzcgroup]} D1219 21:10:28.752037 18639 x:0] recv [channel @0xc000288540] Rlerror{Error: 2} D1219 21:10:28.752596 18639 x:0] send [channel @0xc000288540] Twalkgetattr{FID: 1, NewFID: 12, Names: [syzcgroup]} D1219 21:10:28.752887 18639 x:0] recv [channel @0xc000288540] Rlerror{Error: 2} D1219 21:10:28.816053 18639 x:0] [ 42] Interrupt queued D1219 21:10:28.817557 18639 x:0] [ 43] Interrupt queued D1219 21:10:28.828775 18639 x:0] [ 44] Interrupt queued D1219 21:10:28.840215 18639 x:0] [ 45] Interrupt queued D1219 21:10:28.844073 18639 x:0] Clock(Monotonic): error: -147 ns, adjusted frequency from 2299799645 Hz to 2299799101 Hz D1219 21:10:28.844318 18639 x:0] Clock(Realtime): error: 292 ns, adjusted frequency from 2299798876 Hz to 2299800111 Hz D1219 21:10:28.844403 18639 x:0] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:42836270190928242 monotonicBaseRef:91000211129 monotonicFrequency:2299799101 realtimeReady:1 realtimeBaseCycles:42836270191505390 realtimeBaseRef:1576789828844311411 realtimeFrequency:2299800111} D1219 21:10:28.949416 18639 x:0] [ 43] Transitioning from exit state TaskExitNone to TaskExitInitiated D1219 21:10:28.950923 18639 x:0] [ 43] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1219 21:10:28.951045 18639 x:0] [ 37] Discarding ignored signal 17 D1219 21:10:28.952019 18639 x:0] [ 43] Transitioning from exit state TaskExitZombie to TaskExitDead ================== WARNING: DATA RACE Write at 0x00c000254180 by goroutine 219: gvisor.dev/gvisor/pkg/sentry/fs/fsutil.(*InodeSimpleAttributes).SetPermissions() pkg/sentry/fs/attr.go:266 +0x74 gvisor.dev/gvisor/pkg/sentry/fs/tty.(*masterInodeOperations).SetPermissions() :1 +0x90 gvisor.dev/gvisor/pkg/sentry/fs.(*Inode).SetPermissions() pkg/sentry/fs/inode.go:334 +0x15f gvisor.dev/gvisor/pkg/sentry/syscalls/linux.chmod() pkg/sentry/syscalls/linux/sys_file.go:1769 +0x105 DIAGNOSIS: I1219 21:10:29.037174 20961 x:0] *************************** I1219 21:10:29.037266 20961 x:0] Args: [/syzkaller/managers/ptrace-proxy-sandbox-race/current/image -root /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root -watchdog-action=panic -network=none -debug -alsologtostderr -platform=ptrace -file-access=shared -network=sandbox -TESTONLY-unsafe-nonroot debug -stacks --ps ci-gvisor-ptrace-proxy-sandbox-race-2] I1219 21:10:29.037440 20961 x:0] Version release-20191213.0-32-g57ce26c0b465 I1219 21:10:29.037479 20961 x:0] PID: 20961 I1219 21:10:29.037541 20961 x:0] UID: 0, GID: 0 I1219 21:10:29.037581 20961 x:0] Configuration: I1219 21:10:29.037633 20961 x:0] RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root I1219 21:10:29.037661 20961 x:0] Platform: ptrace I1219 21:10:29.037715 20961 x:0] FileAccess: shared, overlay: false I1219 21:10:29.037755 20961 x:0] Network: sandbox, logging: false I1219 21:10:29.037810 20961 x:0] Strace: false, max size: 1024, syscalls: [] I1219 21:10:29.037865 20961 x:0] *************************** W1219 21:10:29.037927 20961 x:0] Block the TERM signal. This is only safe in tests! D1219 21:10:29.038075 20961 x:0] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-2" D1219 21:10:29.045647 20961 x:0] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-2": signal 0 D1219 21:10:29.045762 20961 x:0] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D1219 21:10:29.045848 20961 x:0] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D1219 21:10:29.046248 20961 x:0] urpc: successfully marshalled 117 bytes. W1219 21:10:29.144838 20961 x:0] FATAL ERROR: container sandbox is not running container sandbox is not running W1219 21:10:29.145140 20961 x:0] Failure to execute command, err: 1 Error collecting stacks: failed to run ["/syzkaller/managers/ptrace-proxy-sandbox-race/current/image" "-root" "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "-watchdog-action=panic" "-network=none" "-debug" "-alsologtostderr" "-platform=ptrace" "-file-access=shared" "-network=sandbox" "-TESTONLY-unsafe-nonroot" "debug" "-stacks" "--ps" "ci-gvisor-ptrace-proxy-sandbox-race-2"]: exit status 128 I1219 21:10:29.037174 20961 x:0] *************************** I1219 21:10:29.037266 20961 x:0] Args: [/syzkaller/managers/ptrace-proxy-sandbox-race/current/image -root /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root -watchdog-action=panic -network=none -debug -alsologtostderr -platform=ptrace -file-access=shared -network=sandbox -TESTONLY-unsafe-nonroot debug -stacks --ps ci-gvisor-ptrace-proxy-sandbox-race-2] I1219 21:10:29.037440 20961 x:0] Version release-20191213.0-32-g57ce26c0b465 I1219 21:10:29.037479 20961 x:0] PID: 20961 I1219 21:10:29.037541 20961 x:0] UID: 0, GID: 0 I1219 21:10:29.037581 20961 x:0] Configuration: I1219 21:10:29.037633 20961 x:0] RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root I1219 21:10:29.037661 20961 x:0] Platform: ptrace I1219 21:10:29.037715 20961 x:0] FileAccess: shared, overlay: false I1219 21:10:29.037755 20961 x:0] Network: sandbox, logging: false I1219 21:10:29.037810 20961 x:0] Strace: false, max size: 1024, syscalls: [] I1219 21:10:29.037865 20961 x:0] *************************** W1219 21:10:29.037927 20961 x:0] Block the TERM signal. This is only safe in tests! D1219 21:10:29.038075 20961 x:0] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-2" D1219 21:10:29.045647 20961 x:0] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-2": signal 0 D1219 21:10:29.045762 20961 x:0] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D1219 21:10:29.045848 20961 x:0] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D1219 21:10:29.046248 20961 x:0] urpc: successfully marshalled 117 bytes. W1219 21:10:29.144838 20961 x:0] FATAL ERROR: container sandbox is not running container sandbox is not running W1219 21:10:29.145140 20961 x:0] Failure to execute command, err: 1 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Fchmod() pkg/sentry/syscalls/linux/sys_file.go:1809 +0x127 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall() pkg/sentry/kernel/task_syscall.go:170 +0x455 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke() pkg/sentry/kernel/task_syscall.go:291 +0xb4 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter() pkg/sentry/kernel/task_syscall.go:252 +0x109 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall() pkg/sentry/kernel/task_syscall.go:227 +0x1b3 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute() pkg/sentry/kernel/task_run.go:222 +0x1819 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run() pkg/sentry/kernel/task_run.go:92 +0x308 Previous read at 0x00c000254180 by goroutine 231: gvisor.dev/gvisor/pkg/sentry/fs/tty.(*masterInodeOperations).Truncate() :1 +0x4f gvisor.dev/gvisor/pkg/sentry/fs.(*Inode).Truncate() pkg/sentry/fs/inode.go:364 +0x187 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.openAt.func1() pkg/sentry/syscalls/linux/sys_file.go:178 +0x601 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.fileOpOn() pkg/sentry/syscalls/linux/sys_file.go:111 +0x23f gvisor.dev/gvisor/pkg/sentry/syscalls/linux.openAt() pkg/sentry/syscalls/linux/sys_file.go:140 +0x141 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Openat() pkg/sentry/syscalls/linux/sys_file.go:484 +0xfa gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall() pkg/sentry/kernel/task_syscall.go:170 +0x455 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke() pkg/sentry/kernel/task_syscall.go:291 +0xb4 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter() pkg/sentry/kernel/task_syscall.go:252 +0x109 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall() pkg/sentry/kernel/task_syscall.go:227 +0x1b3 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute() pkg/sentry/kernel/task_run.go:222 +0x1819 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run() pkg/sentry/kernel/task_run.go:92 +0x308 Goroutine 219 (running) created at: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).Start() pkg/sentry/kernel/task_start.go:300 +0x19d gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).Clone() pkg/sentry/kernel/task_clone.go:343 +0x11ea gvisor.dev/gvisor/pkg/sentry/syscalls/linux.clone() pkg/sentry/syscalls/linux/sys_thread.go:219 +0x1fe gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Clone() pkg/sentry/syscalls/linux/sys_thread.go:233 +0x80 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall() pkg/sentry/kernel/task_syscall.go:170 +0x455 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke() pkg/sentry/kernel/task_syscall.go:291 +0xb4 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter() pkg/sentry/kernel/task_syscall.go:252 +0x109 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall() pkg/sentry/kernel/task_syscall.go:227 +0x1b3 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute() pkg/sentry/kernel/task_run.go:222 +0x1819 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run() pkg/sentry/kernel/task_run.go:92 +0x308 Goroutine 231 (running) created at: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).Start() pkg/sentry/kernel/task_start.go:300 +0x19d gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).Clone() pkg/sentry/kernel/task_clone.go:343 +0x11ea gvisor.dev/gvisor/pkg/sentry/syscalls/linux.clone() pkg/sentry/syscalls/linux/sys_thread.go:219 +0x1fe gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Clone() pkg/sentry/syscalls/linux/sys_thread.go:233 +0x80 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall() pkg/sentry/kernel/task_syscall.go:170 +0x455 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke() pkg/sentry/kernel/task_syscall.go:291 +0xb4 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter() pkg/sentry/kernel/task_syscall.go:252 +0x109 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall() pkg/sentry/kernel/task_syscall.go:227 +0x1b3 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute() pkg/sentry/kernel/task_run.go:222 +0x1819 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run() pkg/sentry/kernel/task_run.go:92 +0x308 ================== W1219 21:10:29.133044 20735 x:0] FATAL ERROR: waiting on pid 17: waiting on PID 17 in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2": urpc method "containerManager.WaitPID" failed: EOF waiting on pid 17: waiting on PID 17 in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2": urpc method "containerManager.WaitPID" failed: EOF W1219 21:10:29.133493 20735 x:0] Failure to execute command, err: 1 W1219 21:10:29.141931 18631 x:0] Wait RPC to container "ci-gvisor-ptrace-proxy-sandbox-race-2" failed: urpc method "containerManager.Wait" failed: EOF. Will try waiting on the sandbox process instead. D1219 21:10:29.143211 18631 x:0] Destroy container "ci-gvisor-ptrace-proxy-sandbox-race-2" D1219 21:10:29.143407 18631 x:0] Destroying container "ci-gvisor-ptrace-proxy-sandbox-race-2" D1219 21:10:29.143497 18631 x:0] Destroying root container "ci-gvisor-ptrace-proxy-sandbox-race-2" by destroying sandbox D1219 21:10:29.143585 18631 x:0] Destroy sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D1219 21:10:29.143659 18631 x:0] Killing gofer for container "ci-gvisor-ptrace-proxy-sandbox-race-2", PID: 18638 I1219 21:10:29.345957 18631 x:0] Exiting with status: 16896