D0227 16:08:53.136828 45111 parameters.go:238] Clock(Monotonic): error: 234 ns, adjusted frequency from 2299774027 Hz to 2299774816 Hz D0227 16:08:53.137122 45111 parameters.go:238] Clock(Realtime): error: 289 ns, adjusted frequency from 2299773721 Hz to 2299774748 Hz D0227 16:08:53.137216 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536018036597615 monotonicBaseRef:2001129560 monotonicFrequency:2299774816 realtimeReady:1 realtimeBaseCycles:8536018037302921 realtimeBaseRef:1582819733137113477 realtimeFrequency:2299774748} D0227 16:08:54.136744 45111 sampler.go:168] Time: Adjusting syscall overhead up to 8000 D0227 16:08:54.136918 45111 parameters.go:238] Clock(Monotonic): error: 366 ns, adjusted frequency from 2299774816 Hz to 2299775139 Hz D0227 16:08:54.137083 45111 parameters.go:238] Clock(Realtime): error: 215 ns, adjusted frequency from 2299774748 Hz to 2299774724 Hz D0227 16:08:54.137199 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536020336606736 monotonicBaseRef:3001231441 monotonicFrequency:2299775139 realtimeReady:1 realtimeBaseCycles:8536020336987001 realtimeBaseRef:1582819734137074052 realtimeFrequency:2299774724} D0227 16:08:55.137104 45111 parameters.go:238] Clock(Monotonic): error: -122 ns, adjusted frequency from 2299775139 Hz to 2299774102 Hz D0227 16:08:55.137328 45111 parameters.go:238] Clock(Realtime): error: 141 ns, adjusted frequency from 2299774724 Hz to 2299774632 Hz D0227 16:08:55.137452 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536022636773278 monotonicBaseRef:4001401632 monotonicFrequency:2299774102 realtimeReady:1 realtimeBaseCycles:8536022637333749 realtimeBaseRef:1582819735137322782 realtimeFrequency:2299774632} D0227 16:08:56.137062 45111 parameters.go:238] Clock(Monotonic): error: 22 ns, adjusted frequency from 2299774102 Hz to 2299774446 Hz D0227 16:08:56.137292 45111 parameters.go:238] Clock(Realtime): error: 97 ns, adjusted frequency from 2299774632 Hz to 2299774576 Hz D0227 16:08:56.137421 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536024936476081 monotonicBaseRef:5001370629 monotonicFrequency:2299774446 realtimeReady:1 realtimeBaseCycles:8536024937025817 realtimeBaseRef:1582819736137286881 realtimeFrequency:2299774576} D0227 16:08:57.138064 45111 parameters.go:238] Clock(Monotonic): error: 346 ns, adjusted frequency from 2299774446 Hz to 2299775096 Hz D0227 16:08:57.138289 45111 parameters.go:238] Clock(Realtime): error: 70 ns, adjusted frequency from 2299774576 Hz to 2299774535 Hz D0227 16:08:57.138392 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536027238554497 monotonicBaseRef:6002372453 monotonicFrequency:2299775096 realtimeReady:1 realtimeBaseCycles:8536027239091745 realtimeBaseRef:1582819737138283218 realtimeFrequency:2299774535} D0227 16:08:58.137453 45111 parameters.go:238] Clock(Monotonic): error: -90 ns, adjusted frequency from 2299775096 Hz to 2299774252 Hz D0227 16:08:58.137691 45111 parameters.go:238] Clock(Realtime): error: 28 ns, adjusted frequency from 2299774535 Hz to 2299774459 Hz D0227 16:08:58.137786 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536029536881257 monotonicBaseRef:7001742680 monotonicFrequency:2299774252 realtimeReady:1 realtimeBaseCycles:8536029537491808 realtimeBaseRef:1582819738137685562 realtimeFrequency:2299774459} D0227 16:08:59.136760 45111 parameters.go:238] Clock(Monotonic): error: -15 ns, adjusted frequency from 2299774252 Hz to 2299774430 Hz D0227 16:08:59.136971 45111 parameters.go:238] Clock(Realtime): error: 63 ns, adjusted frequency from 2299774459 Hz to 2299774555 Hz D0227 16:08:59.137111 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536031835050506 monotonicBaseRef:8001044784 monotonicFrequency:2299774430 realtimeReady:1 realtimeBaseCycles:8536031835610420 realtimeBaseRef:1582819739136965557 realtimeFrequency:2299774555} D0227 16:09:00.137091 45111 parameters.go:238] Clock(Monotonic): error: 280 ns, adjusted frequency from 2299774430 Hz to 2299775085 Hz D0227 16:09:00.137337 45111 parameters.go:238] Clock(Realtime): error: 76 ns, adjusted frequency from 2299774555 Hz to 2299774587 Hz D0227 16:09:00.137489 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536034135639430 monotonicBaseRef:9001398946 monotonicFrequency:2299775085 realtimeReady:1 realtimeBaseCycles:8536034136227418 realtimeBaseRef:1582819740137331872 realtimeFrequency:2299774587} D0227 16:09:01.136523 45111 parameters.go:238] Clock(Monotonic): error: -235 ns, adjusted frequency from 2299775085 Hz to 2299773980 Hz D0227 16:09:01.136757 45111 parameters.go:238] Clock(Realtime): error: 16 ns, adjusted frequency from 2299774587 Hz to 2299774535 Hz D0227 16:09:01.136905 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536036434100676 monotonicBaseRef:10000827655 monotonicFrequency:2299773980 realtimeReady:1 realtimeBaseCycles:8536036434665116 realtimeBaseRef:1582819741136750558 realtimeFrequency:2299774535} D0227 16:09:02.136686 45111 parameters.go:238] Clock(Monotonic): error: -61 ns, adjusted frequency from 2299773980 Hz to 2299774378 Hz D0227 16:09:02.136897 45111 parameters.go:238] Clock(Realtime): error: 19 ns, adjusted frequency from 2299774535 Hz to 2299774559 Hz D0227 16:09:02.137042 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536038734230682 monotonicBaseRef:11000982464 monotonicFrequency:2299774378 realtimeReady:1 realtimeBaseCycles:8536038734763459 realtimeBaseRef:1582819742136891357 realtimeFrequency:2299774559} D0227 16:09:03.136853 45111 parameters.go:238] Clock(Monotonic): error: 18 ns, adjusted frequency from 2299774378 Hz to 2299774570 Hz D0227 16:09:03.137096 45111 parameters.go:238] Clock(Realtime): error: -32 ns, adjusted frequency from 2299774559 Hz to 2299774440 Hz D0227 16:09:03.137206 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536041034401302 monotonicBaseRef:12001154760 monotonicFrequency:2299774570 realtimeReady:1 realtimeBaseCycles:8536041034996323 realtimeBaseRef:1582819743137090639 realtimeFrequency:2299774440} D0227 16:09:04.137055 45111 parameters.go:238] Clock(Monotonic): error: 208 ns, adjusted frequency from 2299774570 Hz to 2299775012 Hz D0227 16:09:04.137284 45111 parameters.go:238] Clock(Realtime): error: 40 ns, adjusted frequency from 2299774440 Hz to 2299774609 Hz D0227 16:09:04.137423 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536043334622258 monotonicBaseRef:13001348859 monotonicFrequency:2299775012 realtimeReady:1 realtimeBaseCycles:8536043335201805 realtimeBaseRef:1582819744137278066 realtimeFrequency:2299774609} D0227 16:09:05.136524 45111 parameters.go:238] Clock(Monotonic): error: -248 ns, adjusted frequency from 2299775012 Hz to 2299773960 Hz D0227 16:09:05.136747 45111 parameters.go:238] Clock(Realtime): error: -9 ns, adjusted frequency from 2299774609 Hz to 2299774491 Hz D0227 16:09:05.136864 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536045633201935 monotonicBaseRef:14000829097 monotonicFrequency:2299773960 realtimeReady:1 realtimeBaseCycles:8536045633741826 realtimeBaseRef:1582819745136741236 realtimeFrequency:2299774491} D0227 16:09:06.136777 45111 parameters.go:238] Clock(Monotonic): error: 527 ns, adjusted frequency from 2299773960 Hz to 2299775752 Hz D0227 16:09:06.137019 45111 sampler.go:168] Time: Adjusting syscall overhead up to 8000 D0227 16:09:06.137172 45111 parameters.go:238] Clock(Realtime): error: 471 ns, adjusted frequency from 2299774491 Hz to 2299775580 Hz D0227 16:09:06.137335 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536047933560150 monotonicBaseRef:15001083145 monotonicFrequency:2299775752 realtimeReady:1 realtimeBaseCycles:8536047934491322 realtimeBaseRef:1582819746137165192 realtimeFrequency:2299775580} D0227 16:09:07.136851 45111 parameters.go:238] Clock(Monotonic): error: -257 ns, adjusted frequency from 2299775752 Hz to 2299773799 Hz D0227 16:09:07.137052 45111 parameters.go:238] Clock(Realtime): error: -387 ns, adjusted frequency from 2299775580 Hz to 2299773618 Hz D0227 16:09:07.137185 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536050233452788 monotonicBaseRef:16001133969 monotonicFrequency:2299773799 realtimeReady:1 realtimeBaseCycles:8536050233988116 realtimeBaseRef:1582819747137043968 realtimeFrequency:2299773618} D0227 16:09:08.136215 45111 parameters.go:238] Clock(Monotonic): error: -121 ns, adjusted frequency from 2299773799 Hz to 2299774232 Hz D0227 16:09:08.136425 45111 parameters.go:238] Clock(Realtime): error: 397 ns, adjusted frequency from 2299773618 Hz to 2299775374 Hz D0227 16:09:08.136597 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536052531805305 monotonicBaseRef:17000515959 monotonicFrequency:2299774232 realtimeReady:1 realtimeBaseCycles:8536052532323534 realtimeBaseRef:1582819748136418602 realtimeFrequency:2299775374} D0227 16:09:09.136537 45111 parameters.go:238] Clock(Monotonic): error: -97 ns, adjusted frequency from 2299774232 Hz to 2299774312 Hz D0227 16:09:09.136764 45111 parameters.go:238] Clock(Realtime): error: -401 ns, adjusted frequency from 2299775374 Hz to 2299773607 Hz D0227 16:09:09.136889 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536054832336121 monotonicBaseRef:18000844940 monotonicFrequency:2299774312 realtimeReady:1 realtimeBaseCycles:8536054832879215 realtimeBaseRef:1582819749136757899 realtimeFrequency:2299773607} D0227 16:09:10.136159 45111 parameters.go:238] Clock(Monotonic): error: 422 ns, adjusted frequency from 2299774312 Hz to 2299775400 Hz D0227 16:09:10.136372 45111 parameters.go:238] Clock(Realtime): error: 355 ns, adjusted frequency from 2299773607 Hz to 2299775314 Hz D0227 16:09:10.136526 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536057131241978 monotonicBaseRef:19000467313 monotonicFrequency:2299775400 realtimeReady:1 realtimeBaseCycles:8536057131751005 realtimeBaseRef:1582819750136365766 realtimeFrequency:2299775314} D0227 16:09:11.136748 45111 parameters.go:238] Clock(Monotonic): error: -393 ns, adjusted frequency from 2299775400 Hz to 2299773612 Hz D0227 16:09:11.136963 45111 parameters.go:238] Clock(Realtime): error: -392 ns, adjusted frequency from 2299775314 Hz to 2299773616 Hz D0227 16:09:11.137081 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536059432364157 monotonicBaseRef:20001052926 monotonicFrequency:2299773612 realtimeReady:1 realtimeBaseCycles:8536059432884725 realtimeBaseRef:1582819751136956435 realtimeFrequency:2299773616} D0227 16:09:12.136858 45111 parameters.go:238] Clock(Monotonic): error: -15 ns, adjusted frequency from 2299773612 Hz to 2299774484 Hz D0227 16:09:12.137068 45111 parameters.go:238] Clock(Realtime): error: 3 ns, adjusted frequency from 2299773616 Hz to 2299774524 Hz D0227 16:09:12.137168 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536061732372885 monotonicBaseRef:21001155160 monotonicFrequency:2299774484 realtimeReady:1 realtimeBaseCycles:8536061732899977 realtimeBaseRef:1582819752137061504 realtimeFrequency:2299774524} D0227 16:09:13.136072 45111 parameters.go:238] Clock(Monotonic): error: 17 ns, adjusted frequency from 2299774484 Hz to 2299774542 Hz D0227 16:09:13.136275 45111 parameters.go:238] Clock(Realtime): error: 267 ns, adjusted frequency from 2299774524 Hz to 2299775091 Hz D0227 16:09:13.136363 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536064030365051 monotonicBaseRef:22000380163 monotonicFrequency:2299774542 realtimeReady:1 realtimeBaseCycles:8536064030852285 realtimeBaseRef:1582819753136269158 realtimeFrequency:2299775091} D0227 16:09:14.137032 45111 parameters.go:238] Clock(Monotonic): error: 284 ns, adjusted frequency from 2299774542 Hz to 2299775106 Hz D0227 16:09:14.137230 45111 parameters.go:238] Clock(Realtime): error: -258 ns, adjusted frequency from 2299775091 Hz to 2299773913 Hz D0227 16:09:14.137376 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536066332322361 monotonicBaseRef:23001329285 monotonicFrequency:2299775106 realtimeReady:1 realtimeBaseCycles:8536066332824423 realtimeBaseRef:1582819754137224489 realtimeFrequency:2299773913} D0227 16:09:15.136409 45111 parameters.go:238] Clock(Monotonic): error: 92 ns, adjusted frequency from 2299775106 Hz to 2299774748 Hz D0227 16:09:15.136593 45111 parameters.go:238] Clock(Realtime): error: 186 ns, adjusted frequency from 2299773913 Hz to 2299774921 Hz D0227 16:09:15.136726 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536068630681920 monotonicBaseRef:24000713769 monotonicFrequency:2299774748 realtimeReady:1 realtimeBaseCycles:8536068631132886 realtimeBaseRef:1582819755136587274 realtimeFrequency:2299774921} D0227 16:09:16.137158 45111 parameters.go:238] Clock(Monotonic): error: -152 ns, adjusted frequency from 2299774748 Hz to 2299774271 Hz D0227 16:09:16.137452 45111 parameters.go:238] Clock(Realtime): error: -69 ns, adjusted frequency from 2299774921 Hz to 2299774358 Hz D0227 16:09:16.137636 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536070932168856 monotonicBaseRef:25001458271 monotonicFrequency:2299774271 realtimeReady:1 realtimeBaseCycles:8536070932878624 realtimeBaseRef:1582819756137444234 realtimeFrequency:2299774358} D0227 16:09:17.136788 45111 parameters.go:238] Clock(Monotonic): error: -193 ns, adjusted frequency from 2299774271 Hz to 2299774075 Hz D0227 16:09:17.137026 45111 parameters.go:238] Clock(Realtime): error: 239 ns, adjusted frequency from 2299774358 Hz to 2299775076 Hz D0227 16:09:17.137147 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536073231068124 monotonicBaseRef:26001077797 monotonicFrequency:2299774075 realtimeReady:1 realtimeBaseCycles:8536073231665214 realtimeBaseRef:1582819757137014727 realtimeFrequency:2299775076} D0227 16:09:18.137212 45111 parameters.go:238] Clock(Monotonic): error: -14 ns, adjusted frequency from 2299774075 Hz to 2299774486 Hz D0227 16:09:18.137429 45111 parameters.go:238] Clock(Realtime): error: -97 ns, adjusted frequency from 2299775076 Hz to 2299774186 Hz D0227 16:09:18.137570 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536075531822737 monotonicBaseRef:27001504159 monotonicFrequency:2299774486 realtimeReady:1 realtimeBaseCycles:8536075532378145 realtimeBaseRef:1582819758137422529 realtimeFrequency:2299774186} D0227 16:09:19.136841 45111 parameters.go:238] Clock(Monotonic): error: -65 ns, adjusted frequency from 2299774486 Hz to 2299774446 Hz D0227 16:09:19.137110 45111 parameters.go:238] Clock(Realtime): error: -134 ns, adjusted frequency from 2299774186 Hz to 2299774194 Hz D0227 16:09:19.137276 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536077830768546 monotonicBaseRef:28001143829 monotonicFrequency:2299774446 realtimeReady:1 realtimeBaseCycles:8536077831417296 realtimeBaseRef:1582819759137102917 realtimeFrequency:2299774194} D0227 16:09:20.136412 45111 parameters.go:238] Clock(Monotonic): error: 250 ns, adjusted frequency from 2299774446 Hz to 2299775093 Hz D0227 16:09:20.136629 45111 parameters.go:238] Clock(Realtime): error: 217 ns, adjusted frequency from 2299774194 Hz to 2299774954 Hz D0227 16:09:20.136773 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536080129550861 monotonicBaseRef:29000712425 monotonicFrequency:2299775093 realtimeReady:1 realtimeBaseCycles:8536080130072211 realtimeBaseRef:1582819760136616226 realtimeFrequency:2299774954} D0227 16:09:21.136724 45111 parameters.go:238] Clock(Monotonic): error: -142 ns, adjusted frequency from 2299775093 Hz to 2299774197 Hz D0227 16:09:21.136967 45111 parameters.go:238] Clock(Realtime): error: 201 ns, adjusted frequency from 2299774954 Hz to 2299774931 Hz D0227 16:09:21.137081 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536082430043751 monotonicBaseRef:30001024541 monotonicFrequency:2299774197 realtimeReady:1 realtimeBaseCycles:8536082430634639 realtimeBaseRef:1582819761136958639 realtimeFrequency:2299774931} D0227 16:09:22.137539 45111 parameters.go:238] Clock(Monotonic): error: 190 ns, adjusted frequency from 2299774197 Hz to 2299774920 Hz D0227 16:09:22.137733 45111 parameters.go:238] Clock(Realtime): error: -195 ns, adjusted frequency from 2299774931 Hz to 2299774067 Hz D0227 16:09:22.137884 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536084731709630 monotonicBaseRef:31001847092 monotonicFrequency:2299774920 realtimeReady:1 realtimeBaseCycles:8536084732178954 realtimeBaseRef:1582819762137728011 realtimeFrequency:2299774067} D0227 16:09:23.143533 45111 parameters.go:238] Clock(Monotonic): error: -322 ns, adjusted frequency from 2299774920 Hz to 2299773836 Hz D0227 16:09:23.143781 45111 parameters.go:238] Clock(Realtime): error: 90 ns, adjusted frequency from 2299774067 Hz to 2299774684 Hz D0227 16:09:23.143928 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536087045256376 monotonicBaseRef:32007835428 monotonicFrequency:2299773836 realtimeReady:1 realtimeBaseCycles:8536087045858409 realtimeBaseRef:1582819763143774425 realtimeFrequency:2299774684} D0227 16:09:24.137316 45111 parameters.go:238] Clock(Monotonic): error: 200 ns, adjusted frequency from 2299773836 Hz to 2299775019 Hz D0227 16:09:24.137559 45111 parameters.go:238] Clock(Realtime): error: 433 ns, adjusted frequency from 2299774684 Hz to 2299775518 Hz D0227 16:09:24.137718 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536089330703909 monotonicBaseRef:33001605988 monotonicFrequency:2299775019 realtimeReady:1 realtimeBaseCycles:8536089331322769 realtimeBaseRef:1582819764137551935 realtimeFrequency:2299775518} D0227 16:09:24.438979 45111 urpc.go:577] urpc: unmarshal success. D0227 16:09:24.439231 45111 controller.go:501] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-0 Signo:0 PID:0 Mode:Process} D0227 16:09:24.439476 45111 urpc.go:534] urpc: successfully marshalled 37 bytes. D0227 16:09:25.136279 45111 parameters.go:238] Clock(Monotonic): error: 270 ns, adjusted frequency from 2299775019 Hz to 2299775047 Hz D0227 16:09:25.136512 45111 parameters.go:238] Clock(Realtime): error: -420 ns, adjusted frequency from 2299775518 Hz to 2299773467 Hz D0227 16:09:25.136652 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536091628104758 monotonicBaseRef:34000573639 monotonicFrequency:2299775047 realtimeReady:1 realtimeBaseCycles:8536091628691307 realtimeBaseRef:1582819765136505319 realtimeFrequency:2299773467} D0227 16:09:26.137269 45111 parameters.go:238] Clock(Monotonic): error: -453 ns, adjusted frequency from 2299775047 Hz to 2299773469 Hz D0227 16:09:26.137505 45111 parameters.go:238] Clock(Realtime): error: -307 ns, adjusted frequency from 2299773467 Hz to 2299773848 Hz D0227 16:09:26.137629 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536093930170057 monotonicBaseRef:35001569498 monotonicFrequency:2299773469 realtimeReady:1 realtimeBaseCycles:8536093930749382 realtimeBaseRef:1582819766137498724 realtimeFrequency:2299773848} D0227 16:09:27.136189 45111 parameters.go:238] Clock(Monotonic): error: 1145 ns, adjusted frequency from 2299773469 Hz to 2299777221 Hz D0227 16:09:27.136430 45111 parameters.go:238] Clock(Realtime): error: 4 ns, adjusted frequency from 2299773848 Hz to 2299774620 Hz D0227 16:09:27.136575 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536096227467024 monotonicBaseRef:36000492651 monotonicFrequency:2299777221 realtimeReady:1 realtimeBaseCycles:8536096228049909 realtimeBaseRef:1582819767136423261 realtimeFrequency:2299774620} D0227 16:09:28.136826 45111 parameters.go:238] Clock(Monotonic): error: -1197 ns, adjusted frequency from 2299777221 Hz to 2299771761 Hz D0227 16:09:28.137063 45111 parameters.go:238] Clock(Realtime): error: 218 ns, adjusted frequency from 2299774620 Hz to 2299774988 Hz D0227 16:09:28.137163 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536098528713364 monotonicBaseRef:37001131460 monotonicFrequency:2299771761 realtimeReady:1 realtimeBaseCycles:8536098529281546 realtimeBaseRef:1582819768137056808 realtimeFrequency:2299774988} D0227 16:09:29.137371 45111 parameters.go:238] Clock(Monotonic): error: 338 ns, adjusted frequency from 2299771761 Hz to 2299775143 Hz D0227 16:09:29.137619 45111 parameters.go:238] Clock(Realtime): error: -265 ns, adjusted frequency from 2299774988 Hz to 2299773964 Hz D0227 16:09:29.137759 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536100829708792 monotonicBaseRef:38001663541 monotonicFrequency:2299775143 realtimeReady:1 realtimeBaseCycles:8536100830335772 realtimeBaseRef:1582819769137613052 realtimeFrequency:2299773964} D0227 16:09:30.136690 45111 parameters.go:238] Clock(Monotonic): error: -247 ns, adjusted frequency from 2299775143 Hz to 2299773942 Hz D0227 16:09:30.137319 45111 parameters.go:238] Clock(Realtime): error: 399 ns, adjusted frequency from 2299773964 Hz to 2299775409 Hz D0227 16:09:30.137463 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536103127923318 monotonicBaseRef:39000984945 monotonicFrequency:2299773942 realtimeReady:1 realtimeBaseCycles:8536103129418394 realtimeBaseRef:1582819770137312438 realtimeFrequency:2299775409} D0227 16:09:31.137781 45111 parameters.go:238] Clock(Monotonic): error: 393 ns, adjusted frequency from 2299773942 Hz to 2299775359 Hz D0227 16:09:31.137993 45111 parameters.go:238] Clock(Realtime): error: -240 ns, adjusted frequency from 2299775409 Hz to 2299773954 Hz D0227 16:09:31.138127 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536105430224049 monotonicBaseRef:40002083656 monotonicFrequency:2299775359 realtimeReady:1 realtimeBaseCycles:8536105430747167 realtimeBaseRef:1582819771137987879 realtimeFrequency:2299773954} D0227 16:09:32.136041 45111 parameters.go:238] Clock(Monotonic): error: -50 ns, adjusted frequency from 2299775359 Hz to 2299774271 Hz D0227 16:09:32.136224 45111 parameters.go:238] Clock(Realtime): error: 115 ns, adjusted frequency from 2299773954 Hz to 2299774752 Hz D0227 16:09:32.136327 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536107726004407 monotonicBaseRef:41000346529 monotonicFrequency:2299774271 realtimeReady:1 realtimeBaseCycles:8536107726453007 realtimeBaseRef:1582819772136218959 realtimeFrequency:2299774752} D0227 16:09:33.136078 45111 parameters.go:238] Clock(Monotonic): error: -144 ns, adjusted frequency from 2299774271 Hz to 2299774193 Hz D0227 16:09:33.136307 45111 parameters.go:238] Clock(Realtime): error: -4 ns, adjusted frequency from 2299774752 Hz to 2299774488 Hz D0227 16:09:33.136429 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536110025828081 monotonicBaseRef:42000368010 monotonicFrequency:2299774193 realtimeReady:1 realtimeBaseCycles:8536110026417140 realtimeBaseRef:1582819773136301306 realtimeFrequency:2299774488} D0227 16:09:34.136892 45111 parameters.go:238] Clock(Monotonic): error: -71 ns, adjusted frequency from 2299774193 Hz to 2299774456 Hz D0227 16:09:34.137311 45111 parameters.go:238] Clock(Realtime): error: 209 ns, adjusted frequency from 2299774488 Hz to 2299774979 Hz D0227 16:09:34.137447 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536112327499775 monotonicBaseRef:43001193091 monotonicFrequency:2299774456 realtimeReady:1 realtimeBaseCycles:8536112328494543 realtimeBaseRef:1582819774137302671 realtimeFrequency:2299774979} D0227 16:09:35.136705 45111 parameters.go:238] Clock(Monotonic): error: -50 ns, adjusted frequency from 2299774456 Hz to 2299774373 Hz D0227 16:09:35.136986 45111 parameters.go:238] Clock(Realtime): error: -334 ns, adjusted frequency from 2299774979 Hz to 2299773752 Hz D0227 16:09:35.137128 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536114626832891 monotonicBaseRef:44001001185 monotonicFrequency:2299774373 realtimeReady:1 realtimeBaseCycles:8536114627523111 realtimeBaseRef:1582819775136978112 realtimeFrequency:2299773752} D0227 16:09:36.136191 45111 parameters.go:238] Clock(Monotonic): error: 450 ns, adjusted frequency from 2299774373 Hz to 2299775396 Hz D0227 16:09:36.136449 45111 parameters.go:238] Clock(Realtime): error: 1 ns, adjusted frequency from 2299773752 Hz to 2299774492 Hz D0227 16:09:36.136612 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536116925440240 monotonicBaseRef:45000493733 monotonicFrequency:2299775396 realtimeReady:1 realtimeBaseCycles:8536116926063143 realtimeBaseRef:1582819776136441659 realtimeFrequency:2299774492} I0227 16:09:36.451214 45111 watchdog.go:277] Watchdog starting loop, tasks: 14, discount: 0s D0227 16:09:37.136390 45111 parameters.go:238] Clock(Monotonic): error: -94 ns, adjusted frequency from 2299775396 Hz to 2299774208 Hz D0227 16:09:37.136623 45111 parameters.go:238] Clock(Realtime): error: 220 ns, adjusted frequency from 2299774492 Hz to 2299775011 Hz D0227 16:09:37.136813 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536119225384870 monotonicBaseRef:46000567320 monotonicFrequency:2299774208 realtimeReady:1 realtimeBaseCycles:8536119226237158 realtimeBaseRef:1582819777136615381 realtimeFrequency:2299775011} D0227 16:09:38.137254 45111 parameters.go:238] Clock(Monotonic): error: 110 ns, adjusted frequency from 2299774208 Hz to 2299774806 Hz D0227 16:09:38.137499 45111 parameters.go:238] Clock(Realtime): error: -305 ns, adjusted frequency from 2299775011 Hz to 2299773817 Hz D0227 16:09:38.137791 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536121527424405 monotonicBaseRef:47001552341 monotonicFrequency:2299774806 realtimeReady:1 realtimeBaseCycles:8536121528030248 realtimeBaseRef:1582819778137492892 realtimeFrequency:2299773817} D0227 16:09:39.136045 45111 parameters.go:238] Clock(Monotonic): error: -113 ns, adjusted frequency from 2299774806 Hz to 2299774134 Hz D0227 16:09:39.136253 45111 parameters.go:238] Clock(Realtime): error: 296 ns, adjusted frequency from 2299773817 Hz to 2299775216 Hz D0227 16:09:39.136378 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536123824430425 monotonicBaseRef:48000348403 monotonicFrequency:2299774134 realtimeReady:1 realtimeBaseCycles:8536123824939572 realtimeBaseRef:1582819779136247337 realtimeFrequency:2299775216} D0227 16:09:40.136526 45111 parameters.go:238] Clock(Monotonic): error: -248 ns, adjusted frequency from 2299774134 Hz to 2299774033 Hz D0227 16:09:40.136796 45111 parameters.go:238] Clock(Realtime): error: -47 ns, adjusted frequency from 2299775216 Hz to 2299774377 Hz D0227 16:09:40.136930 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536126125310946 monotonicBaseRef:49000829488 monotonicFrequency:2299774033 realtimeReady:1 realtimeBaseCycles:8536126125962466 realtimeBaseRef:1582819780136789858 realtimeFrequency:2299774377} D0227 16:09:41.137196 45111 parameters.go:238] Clock(Monotonic): error: -16 ns, adjusted frequency from 2299774033 Hz to 2299774612 Hz D0227 16:09:41.137435 45111 parameters.go:238] Clock(Realtime): error: 254 ns, adjusted frequency from 2299774377 Hz to 2299775175 Hz D0227 16:09:41.137544 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536128426631953 monotonicBaseRef:50001502151 monotonicFrequency:2299774612 realtimeReady:1 realtimeBaseCycles:8536128427205045 realtimeBaseRef:1582819781137428269 realtimeFrequency:2299775175} D0227 16:09:42.136154 45111 parameters.go:238] Clock(Monotonic): error: 283 ns, adjusted frequency from 2299774612 Hz to 2299775057 Hz D0227 16:09:42.136410 45111 parameters.go:238] Clock(Realtime): error: -529 ns, adjusted frequency from 2299775175 Hz to 2299773344 Hz D0227 16:09:42.136552 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536130723995839 monotonicBaseRef:51000453906 monotonicFrequency:2299775057 realtimeReady:1 realtimeBaseCycles:8536130724610743 realtimeBaseRef:1582819782136397960 realtimeFrequency:2299773344} D0227 16:09:43.137552 45111 parameters.go:238] Clock(Monotonic): error: -262 ns, adjusted frequency from 2299775057 Hz to 2299773907 Hz D0227 16:09:43.138430 45111 parameters.go:238] Clock(Realtime): error: 234 ns, adjusted frequency from 2299773344 Hz to 2299775083 Hz D0227 16:09:43.138557 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536133026984400 monotonicBaseRef:52001851218 monotonicFrequency:2299773907 realtimeReady:1 realtimeBaseCycles:8536133029042478 realtimeBaseRef:1582819783138423547 realtimeFrequency:2299775083} D0227 16:09:44.136930 45111 parameters.go:238] Clock(Monotonic): error: -69 ns, adjusted frequency from 2299773907 Hz to 2299774382 Hz D0227 16:09:44.137175 45111 parameters.go:238] Clock(Realtime): error: -43 ns, adjusted frequency from 2299775083 Hz to 2299774321 Hz D0227 16:09:44.137351 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536135325327413 monotonicBaseRef:53001229029 monotonicFrequency:2299774382 realtimeReady:1 realtimeBaseCycles:8536135325933707 realtimeBaseRef:1582819784137169574 realtimeFrequency:2299774321} D0227 16:09:45.137499 45111 parameters.go:238] Clock(Monotonic): error: 3 ns, adjusted frequency from 2299774382 Hz to 2299774594 Hz D0227 16:09:45.137804 45111 parameters.go:238] Clock(Realtime): error: -266 ns, adjusted frequency from 2299774321 Hz to 2299773924 Hz D0227 16:09:45.137951 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536137626425679 monotonicBaseRef:54001804687 monotonicFrequency:2299774594 realtimeReady:1 realtimeBaseCycles:8536137627139863 realtimeBaseRef:1582819785137792172 realtimeFrequency:2299773924} D0227 16:09:46.138132 45111 parameters.go:238] Clock(Monotonic): error: -49 ns, adjusted frequency from 2299774594 Hz to 2299774494 Hz D0227 16:09:46.138375 45111 parameters.go:238] Clock(Realtime): error: 293 ns, adjusted frequency from 2299773924 Hz to 2299775214 Hz D0227 16:09:46.138512 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536139927640641 monotonicBaseRef:55002430995 monotonicFrequency:2299774494 realtimeReady:1 realtimeBaseCycles:8536139928241096 realtimeBaseRef:1582819786138369319 realtimeFrequency:2299775214} D0227 16:09:47.137355 45111 sampler.go:197] Time: Adjusting syscall overhead down to 7000 D0227 16:09:47.137528 45111 parameters.go:238] Clock(Monotonic): error: -2 ns, adjusted frequency from 2299774494 Hz to 2299774615 Hz D0227 16:09:47.137696 45111 parameters.go:238] Clock(Realtime): error: 61 ns, adjusted frequency from 2299775214 Hz to 2299774604 Hz D0227 16:09:47.137802 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536142225641410 monotonicBaseRef:56001659734 monotonicFrequency:2299774615 realtimeReady:1 realtimeBaseCycles:8536142226453155 realtimeBaseRef:1582819787137689619 realtimeFrequency:2299774604} D0227 16:09:48.135999 45111 parameters.go:238] Clock(Monotonic): error: 219 ns, adjusted frequency from 2299774615 Hz to 2299775051 Hz D0227 16:09:48.136227 45111 parameters.go:238] Clock(Realtime): error: 40 ns, adjusted frequency from 2299774604 Hz to 2299774562 Hz D0227 16:09:48.136342 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536144522298895 monotonicBaseRef:57000304327 monotonicFrequency:2299775051 realtimeReady:1 realtimeBaseCycles:8536144522830179 realtimeBaseRef:1582819788136212265 realtimeFrequency:2299774562} D0227 16:09:49.137066 45111 parameters.go:238] Clock(Monotonic): error: -176 ns, adjusted frequency from 2299775051 Hz to 2299774101 Hz D0227 16:09:49.137364 45111 parameters.go:238] Clock(Realtime): error: 117 ns, adjusted frequency from 2299774562 Hz to 2299774802 Hz D0227 16:09:49.137524 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536146824494788 monotonicBaseRef:58001356969 monotonicFrequency:2299774101 realtimeReady:1 realtimeBaseCycles:8536146825234667 realtimeBaseRef:1582819789137355823 realtimeFrequency:2299774802} D0227 16:09:50.136919 45111 parameters.go:238] Clock(Monotonic): error: -155 ns, adjusted frequency from 2299774101 Hz to 2299774149 Hz D0227 16:09:50.137147 45111 parameters.go:238] Clock(Realtime): error: -155 ns, adjusted frequency from 2299774802 Hz to 2299774086 Hz D0227 16:09:50.137260 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536149123957064 monotonicBaseRef:59001221379 monotonicFrequency:2299774149 realtimeReady:1 realtimeBaseCycles:8536149124515015 realtimeBaseRef:1582819790137140821 realtimeFrequency:2299774086} D0227 16:09:51.137722 45111 parameters.go:238] Clock(Monotonic): error: 340 ns, adjusted frequency from 2299774149 Hz to 2299775419 Hz D0227 16:09:51.137952 45111 parameters.go:238] Clock(Realtime): error: 154 ns, adjusted frequency from 2299774086 Hz to 2299774774 Hz D0227 16:09:51.138078 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536151425543545 monotonicBaseRef:60002009426 monotonicFrequency:2299775419 realtimeReady:1 realtimeBaseCycles:8536151426138628 realtimeBaseRef:1582819791137945042 realtimeFrequency:2299774774} D0227 16:09:52.136044 45111 parameters.go:238] Clock(Monotonic): error: 216 ns, adjusted frequency from 2299775419 Hz to 2299775016 Hz D0227 16:09:52.136256 45111 parameters.go:238] Clock(Realtime): error: -119 ns, adjusted frequency from 2299774774 Hz to 2299774264 Hz D0227 16:09:52.136397 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536153721495657 monotonicBaseRef:61000346956 monotonicFrequency:2299775016 realtimeReady:1 realtimeBaseCycles:8536153722015337 realtimeBaseRef:1582819792136250065 realtimeFrequency:2299774264} D0227 16:09:53.137964 45111 parameters.go:238] Clock(Monotonic): error: -580 ns, adjusted frequency from 2299775016 Hz to 2299773180 Hz D0227 16:09:53.138179 45111 parameters.go:238] Clock(Realtime): error: 16 ns, adjusted frequency from 2299774264 Hz to 2299774595 Hz D0227 16:09:53.138311 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536156025675237 monotonicBaseRef:62002262171 monotonicFrequency:2299773180 realtimeReady:1 realtimeBaseCycles:8536156026212092 realtimeBaseRef:1582819793138173075 realtimeFrequency:2299774595} D0227 16:09:54.136582 45111 parameters.go:238] Clock(Monotonic): error: -6 ns, adjusted frequency from 2299773180 Hz to 2299774502 Hz D0227 16:09:54.136877 45111 parameters.go:238] Clock(Realtime): error: 31 ns, adjusted frequency from 2299774595 Hz to 2299774519 Hz D0227 16:09:54.136994 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536158322280710 monotonicBaseRef:63000884771 monotonicFrequency:2299774502 realtimeReady:1 realtimeBaseCycles:8536158322971590 realtimeBaseRef:1582819794136862034 realtimeFrequency:2299774519} D0227 16:09:55.136757 45111 parameters.go:238] Clock(Monotonic): error: -4 ns, adjusted frequency from 2299774502 Hz to 2299774492 Hz D0227 16:09:55.136954 45111 parameters.go:238] Clock(Realtime): error: -23 ns, adjusted frequency from 2299774519 Hz to 2299774473 Hz D0227 16:09:55.137265 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536160622430433 monotonicBaseRef:64001047926 monotonicFrequency:2299774492 realtimeReady:1 realtimeBaseCycles:8536160622944324 realtimeBaseRef:1582819795136948222 realtimeFrequency:2299774473} D0227 16:09:56.137325 45111 parameters.go:238] Clock(Monotonic): error: 6 ns, adjusted frequency from 2299774492 Hz to 2299774524 Hz D0227 16:09:56.137564 45111 parameters.go:238] Clock(Realtime): error: -99 ns, adjusted frequency from 2299774473 Hz to 2299774348 Hz D0227 16:09:56.137736 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536162923547573 monotonicBaseRef:65001631743 monotonicFrequency:2299774524 realtimeReady:1 realtimeBaseCycles:8536162924118959 realtimeBaseRef:1582819796137557047 realtimeFrequency:2299774348} D0227 16:09:57.137753 45111 parameters.go:238] Clock(Monotonic): error: 56 ns, adjusted frequency from 2299774524 Hz to 2299774718 Hz D0227 16:09:57.137958 45111 parameters.go:238] Clock(Realtime): error: 77 ns, adjusted frequency from 2299774348 Hz to 2299774737 Hz D0227 16:09:57.139248 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536165223149050 monotonicBaseRef:66001556497 monotonicFrequency:2299774718 realtimeReady:1 realtimeBaseCycles:8536165224803250 realtimeBaseRef:1582819797137952713 realtimeFrequency:2299774737} D0227 16:09:58.137401 45111 parameters.go:238] Clock(Monotonic): error: -75 ns, adjusted frequency from 2299774718 Hz to 2299774355 Hz D0227 16:09:58.137635 45111 parameters.go:238] Clock(Realtime): error: -264 ns, adjusted frequency from 2299774737 Hz to 2299773950 Hz D0227 16:09:58.137765 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536167523265729 monotonicBaseRef:67001705190 monotonicFrequency:2299774355 realtimeReady:1 realtimeBaseCycles:8536167523831775 realtimeBaseRef:1582819798137628241 realtimeFrequency:2299773950} D0227 16:09:59.136430 45111 parameters.go:238] Clock(Monotonic): error: -41 ns, adjusted frequency from 2299774355 Hz to 2299774421 Hz D0227 16:09:59.136646 45111 parameters.go:238] Clock(Realtime): error: -50 ns, adjusted frequency from 2299773950 Hz to 2299774471 Hz D0227 16:09:59.136744 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536169820809561 monotonicBaseRef:68000735302 monotonicFrequency:2299774421 realtimeReady:1 realtimeBaseCycles:8536169821322445 realtimeBaseRef:1582819799136635412 realtimeFrequency:2299774471} D0227 16:09:59.794056 45111 urpc.go:577] urpc: unmarshal success. D0227 16:09:59.794290 45111 controller.go:501] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-0 Signo:0 PID:0 Mode:Process} D0227 16:09:59.796443 45111 urpc.go:534] urpc: successfully marshalled 37 bytes. D0227 16:10:00.138180 45111 parameters.go:238] Clock(Monotonic): error: 341 ns, adjusted frequency from 2299774421 Hz to 2299775230 Hz D0227 16:10:00.138407 45111 parameters.go:238] Clock(Realtime): error: 245 ns, adjusted frequency from 2299774471 Hz to 2299775112 Hz D0227 16:10:00.138514 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536172124612663 monotonicBaseRef:69002487074 monotonicFrequency:2299775230 realtimeReady:1 realtimeBaseCycles:8536172125158073 realtimeBaseRef:1582819800138401305 realtimeFrequency:2299775112} D0227 16:10:01.136664 45111 parameters.go:238] Clock(Monotonic): error: -259 ns, adjusted frequency from 2299775230 Hz to 2299773904 Hz D0227 16:10:01.136899 45111 parameters.go:238] Clock(Realtime): error: -255 ns, adjusted frequency from 2299775112 Hz to 2299773985 Hz D0227 16:10:01.137007 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536174420878487 monotonicBaseRef:70000961096 monotonicFrequency:2299773904 realtimeReady:1 realtimeBaseCycles:8536174421463752 realtimeBaseRef:1582819801136892708 realtimeFrequency:2299773985} D0227 16:10:02.136506 45111 parameters.go:238] Clock(Monotonic): error: -36 ns, adjusted frequency from 2299773904 Hz to 2299774423 Hz D0227 16:10:02.136904 45111 parameters.go:238] Clock(Realtime): error: -63 ns, adjusted frequency from 2299773985 Hz to 2299774419 Hz D0227 16:10:02.137063 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536176720300873 monotonicBaseRef:71000808247 monotonicFrequency:2299774423 realtimeReady:1 realtimeBaseCycles:8536176721246881 realtimeBaseRef:1582819802136896684 realtimeFrequency:2299774419} D0227 16:10:03.136501 45111 parameters.go:238] Clock(Monotonic): error: -9 ns, adjusted frequency from 2299774423 Hz to 2299774485 Hz D0227 16:10:03.136724 45111 parameters.go:238] Clock(Realtime): error: 290 ns, adjusted frequency from 2299774419 Hz to 2299775213 Hz D0227 16:10:03.136897 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536179020068877 monotonicBaseRef:72000805455 monotonicFrequency:2299774485 realtimeReady:1 realtimeBaseCycles:8536179020610520 realtimeBaseRef:1582819803136718066 realtimeFrequency:2299775213} D0227 16:10:04.137426 45111 parameters.go:238] Clock(Monotonic): error: 65 ns, adjusted frequency from 2299774485 Hz to 2299774683 Hz D0227 16:10:04.137612 45111 parameters.go:238] Clock(Realtime): error: -34 ns, adjusted frequency from 2299775213 Hz to 2299774447 Hz D0227 16:10:04.137775 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536181321979258 monotonicBaseRef:73001734196 monotonicFrequency:2299774683 realtimeReady:1 realtimeBaseCycles:8536181322402624 realtimeBaseRef:1582819804137595060 realtimeFrequency:2299774447} D0227 16:10:05.137037 45111 parameters.go:238] Clock(Monotonic): error: -17 ns, adjusted frequency from 2299774683 Hz to 2299774480 Hz D0227 16:10:05.137283 45111 parameters.go:238] Clock(Realtime): error: -43 ns, adjusted frequency from 2299774447 Hz to 2299774303 Hz D0227 16:10:05.137408 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536183620855896 monotonicBaseRef:74001343703 monotonicFrequency:2299774480 realtimeReady:1 realtimeBaseCycles:8536183621435573 realtimeBaseRef:1582819805137272637 realtimeFrequency:2299774303} D0227 16:10:06.136699 45111 parameters.go:238] Clock(Monotonic): error: 295 ns, adjusted frequency from 2299774480 Hz to 2299775123 Hz D0227 16:10:06.136928 45111 parameters.go:238] Clock(Realtime): error: -164 ns, adjusted frequency from 2299774303 Hz to 2299774185 Hz D0227 16:10:06.137039 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536185919855106 monotonicBaseRef:75001006596 monotonicFrequency:2299775123 realtimeReady:1 realtimeBaseCycles:8536185920402234 realtimeBaseRef:1582819806136921453 realtimeFrequency:2299774185} D0227 16:10:07.136894 45111 parameters.go:238] Clock(Monotonic): error: -307 ns, adjusted frequency from 2299775123 Hz to 2299773802 Hz D0227 16:10:07.137116 45111 parameters.go:238] Clock(Realtime): error: 241 ns, adjusted frequency from 2299774185 Hz to 2299775012 Hz D0227 16:10:07.137242 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536188220074603 monotonicBaseRef:76001199820 monotonicFrequency:2299773802 realtimeReady:1 realtimeBaseCycles:8536188220594506 realtimeBaseRef:1582819807137103247 realtimeFrequency:2299775012} D0227 16:10:08.136745 45111 parameters.go:238] Clock(Monotonic): error: 193 ns, adjusted frequency from 2299773802 Hz to 2299774962 Hz D0227 16:10:08.136978 45111 parameters.go:238] Clock(Realtime): error: -137 ns, adjusted frequency from 2299775012 Hz to 2299774200 Hz D0227 16:10:08.137137 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536190519464342 monotonicBaseRef:77001032819 monotonicFrequency:2299774962 realtimeReady:1 realtimeBaseCycles:8536190520067446 realtimeBaseRef:1582819808136971898 realtimeFrequency:2299774200} D0227 16:10:09.136408 45111 parameters.go:238] Clock(Monotonic): error: 90 ns, adjusted frequency from 2299774962 Hz to 2299774717 Hz D0227 16:10:09.136628 45111 parameters.go:238] Clock(Realtime): error: 147 ns, adjusted frequency from 2299774200 Hz to 2299774874 Hz D0227 16:10:09.136771 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536192818512790 monotonicBaseRef:78000716912 monotonicFrequency:2299774717 realtimeReady:1 realtimeBaseCycles:8536192819028224 realtimeBaseRef:1582819809136618201 realtimeFrequency:2299774874} D0227 16:10:10.137311 45111 parameters.go:238] Clock(Monotonic): error: -178 ns, adjusted frequency from 2299774717 Hz to 2299774090 Hz D0227 16:10:10.137544 45111 parameters.go:238] Clock(Realtime): error: 95 ns, adjusted frequency from 2299774874 Hz to 2299774644 Hz D0227 16:10:10.137694 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536195120338847 monotonicBaseRef:79001608886 monotonicFrequency:2299774090 realtimeReady:1 realtimeBaseCycles:8536195120915684 realtimeBaseRef:1582819810137536806 realtimeFrequency:2299774644} D0227 16:10:11.136811 45111 parameters.go:238] Clock(Monotonic): error: -49 ns, adjusted frequency from 2299774090 Hz to 2299774409 Hz D0227 16:10:11.137051 45111 parameters.go:238] Clock(Realtime): error: -349 ns, adjusted frequency from 2299774644 Hz to 2299773712 Hz D0227 16:10:11.137206 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536197418976180 monotonicBaseRef:80001114595 monotonicFrequency:2299774409 realtimeReady:1 realtimeBaseCycles:8536197419558168 realtimeBaseRef:1582819811137044514 realtimeFrequency:2299773712} D0227 16:10:12.136506 45111 parameters.go:238] Clock(Monotonic): error: 155 ns, adjusted frequency from 2299774409 Hz to 2299774891 Hz D0227 16:10:12.136723 45111 parameters.go:238] Clock(Realtime): error: 515 ns, adjusted frequency from 2299773712 Hz to 2299775686 Hz D0227 16:10:12.136896 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536199718046237 monotonicBaseRef:81000808324 monotonicFrequency:2299774891 realtimeReady:1 realtimeBaseCycles:8536199718575086 realtimeBaseRef:1582819812136715440 realtimeFrequency:2299775686} D0227 16:10:13.138191 45111 parameters.go:238] Clock(Monotonic): error: -54 ns, adjusted frequency from 2299774891 Hz to 2299774369 Hz D0227 16:10:13.138395 45111 parameters.go:238] Clock(Realtime): error: -351 ns, adjusted frequency from 2299775686 Hz to 2299773676 Hz D0227 16:10:13.138503 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536202021707567 monotonicBaseRef:82002498245 monotonicFrequency:2299774369 realtimeReady:1 realtimeBaseCycles:8536202022200959 realtimeBaseRef:1582819813138389597 realtimeFrequency:2299773676} D0227 16:10:14.138401 45111 parameters.go:238] Clock(Monotonic): error: -118 ns, adjusted frequency from 2299774369 Hz to 2299774244 Hz D0227 16:10:14.138553 45111 parameters.go:238] Clock(Realtime): error: -57 ns, adjusted frequency from 2299773676 Hz to 2299774420 Hz D0227 16:10:14.138700 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536204321960407 monotonicBaseRef:83002706296 monotonicFrequency:2299774244 realtimeReady:1 realtimeBaseCycles:8536204322338573 realtimeBaseRef:1582819814138547846 realtimeFrequency:2299774420} D0227 16:10:15.137812 45111 parameters.go:238] Clock(Monotonic): error: 92 ns, adjusted frequency from 2299774244 Hz to 2299774809 Hz D0227 16:10:15.138063 45111 parameters.go:238] Clock(Realtime): error: 162 ns, adjusted frequency from 2299774420 Hz to 2299774775 Hz D0227 16:10:15.138155 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536206620373519 monotonicBaseRef:84002114441 monotonicFrequency:2299774809 realtimeReady:1 realtimeBaseCycles:8536206620981832 realtimeBaseRef:1582819815138055988 realtimeFrequency:2299774775} D0227 16:10:16.136683 45111 parameters.go:238] Clock(Monotonic): error: -139 ns, adjusted frequency from 2299774809 Hz to 2299774183 Hz D0227 16:10:16.136876 45111 parameters.go:238] Clock(Realtime): error: 69 ns, adjusted frequency from 2299774775 Hz to 2299774643 Hz D0227 16:10:16.137003 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536208917560179 monotonicBaseRef:85000989048 monotonicFrequency:2299774183 realtimeReady:1 realtimeBaseCycles:8536208918026806 realtimeBaseRef:1582819816136869001 realtimeFrequency:2299774643} D0227 16:10:17.136047 45111 parameters.go:238] Clock(Monotonic): error: -24 ns, adjusted frequency from 2299774183 Hz to 2299774455 Hz D0227 16:10:17.136248 45111 parameters.go:238] Clock(Realtime): error: -300 ns, adjusted frequency from 2299774643 Hz to 2299773821 Hz D0227 16:10:17.136373 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536211215807431 monotonicBaseRef:86000325099 monotonicFrequency:2299774455 realtimeReady:1 realtimeBaseCycles:8536211216357156 realtimeBaseRef:1582819817136240985 realtimeFrequency:2299773821} D0227 16:10:18.136729 45111 parameters.go:238] Clock(Monotonic): error: 190 ns, adjusted frequency from 2299774455 Hz to 2299775004 Hz D0227 16:10:18.137001 45111 parameters.go:238] Clock(Realtime): error: 563 ns, adjusted frequency from 2299773821 Hz to 2299775794 Hz D0227 16:10:18.137641 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536213517213224 monotonicBaseRef:87001034445 monotonicFrequency:2299775004 realtimeReady:1 realtimeBaseCycles:8536213517863149 realtimeBaseRef:1582819818136994177 realtimeFrequency:2299775794} D0227 16:10:19.137129 45111 parameters.go:238] Clock(Monotonic): error: -211 ns, adjusted frequency from 2299775004 Hz to 2299774052 Hz D0227 16:10:19.137337 45111 parameters.go:238] Clock(Realtime): error: -510 ns, adjusted frequency from 2299775794 Hz to 2299773427 Hz D0227 16:10:19.137462 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536215817910734 monotonicBaseRef:88001435573 monotonicFrequency:2299774052 realtimeReady:1 realtimeBaseCycles:8536215818405947 realtimeBaseRef:1582819819137327689 realtimeFrequency:2299773427} D0227 16:10:20.136362 45111 parameters.go:238] Clock(Monotonic): error: 52 ns, adjusted frequency from 2299774052 Hz to 2299774625 Hz D0227 16:10:20.136586 45111 parameters.go:238] Clock(Realtime): error: -110 ns, adjusted frequency from 2299773427 Hz to 2299774253 Hz D0227 16:10:20.136708 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536218115919065 monotonicBaseRef:89000667792 monotonicFrequency:2299774625 realtimeReady:1 realtimeBaseCycles:8536218116457863 realtimeBaseRef:1582819820136579132 realtimeFrequency:2299774253} D0227 16:10:21.135983 45111 parameters.go:238] Clock(Monotonic): error: 299 ns, adjusted frequency from 2299774625 Hz to 2299775116 Hz D0227 16:10:21.136136 45111 parameters.go:238] Clock(Realtime): error: 209 ns, adjusted frequency from 2299774253 Hz to 2299775004 Hz D0227 16:10:21.136237 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536220414823864 monotonicBaseRef:90000289569 monotonicFrequency:2299775116 realtimeReady:1 realtimeBaseCycles:8536220415202664 realtimeBaseRef:1582819821136131500 realtimeFrequency:2299775004} I0227 16:10:21.452969 45111 watchdog.go:277] Watchdog starting loop, tasks: 14, discount: 0s D0227 16:10:22.136137 45111 parameters.go:238] Clock(Monotonic): error: 94 ns, adjusted frequency from 2299775116 Hz to 2299774684 Hz D0227 16:10:22.136410 45111 parameters.go:238] Clock(Realtime): error: 133 ns, adjusted frequency from 2299775004 Hz to 2299774826 Hz D0227 16:10:22.136540 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536222714951561 monotonicBaseRef:91000442880 monotonicFrequency:2299774684 realtimeReady:1 realtimeBaseCycles:8536222715602192 realtimeBaseRef:1582819822136403058 realtimeFrequency:2299774826} D0227 16:10:23.137259 45111 parameters.go:238] Clock(Monotonic): error: -383 ns, adjusted frequency from 2299774684 Hz to 2299773622 Hz D0227 16:10:23.137455 45111 parameters.go:238] Clock(Realtime): error: -217 ns, adjusted frequency from 2299774826 Hz to 2299774043 Hz D0227 16:10:23.137588 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536225017311309 monotonicBaseRef:92001566930 monotonicFrequency:2299773622 realtimeReady:1 realtimeBaseCycles:8536225017780649 realtimeBaseRef:1582819823137448217 realtimeFrequency:2299774043} D0227 16:10:24.136027 45111 parameters.go:238] Clock(Monotonic): error: 33 ns, adjusted frequency from 2299773622 Hz to 2299774590 Hz D0227 16:10:24.136298 45111 parameters.go:238] Clock(Realtime): error: -90 ns, adjusted frequency from 2299774043 Hz to 2299774408 Hz D0227 16:10:24.136420 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536227314240363 monotonicBaseRef:93000330039 monotonicFrequency:2299774590 realtimeReady:1 realtimeBaseCycles:8536227314894747 realtimeBaseRef:1582819824136291605 realtimeFrequency:2299774408} D0227 16:10:25.136295 45111 parameters.go:238] Clock(Monotonic): error: -15 ns, adjusted frequency from 2299774590 Hz to 2299774492 Hz D0227 16:10:25.137271 45111 parameters.go:238] Clock(Realtime): error: 293 ns, adjusted frequency from 2299774408 Hz to 2299775225 Hz D0227 16:10:25.137407 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536229614634100 monotonicBaseRef:94000599259 monotonicFrequency:2299774492 realtimeReady:1 realtimeBaseCycles:8536229616905827 realtimeBaseRef:1582819825137264166 realtimeFrequency:2299775225} D0227 16:10:26.137334 45111 parameters.go:238] Clock(Monotonic): error: 62 ns, adjusted frequency from 2299774492 Hz to 2299774654 Hz D0227 16:10:26.137556 45111 parameters.go:238] Clock(Realtime): error: -357 ns, adjusted frequency from 2299775225 Hz to 2299773696 Hz D0227 16:10:26.137713 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536231916799848 monotonicBaseRef:95001639037 monotonicFrequency:2299774654 realtimeReady:1 realtimeBaseCycles:8536231917338357 realtimeBaseRef:1582819826137549978 realtimeFrequency:2299773696} D0227 16:10:27.136167 45111 parameters.go:238] Clock(Monotonic): error: -36 ns, adjusted frequency from 2299774654 Hz to 2299774434 Hz D0227 16:10:27.136467 45111 parameters.go:238] Clock(Realtime): error: -14 ns, adjusted frequency from 2299773696 Hz to 2299774542 Hz D0227 16:10:27.136598 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536234213757792 monotonicBaseRef:96000414260 monotonicFrequency:2299774434 realtimeReady:1 realtimeBaseCycles:8536234214602540 realtimeBaseRef:1582819827136458778 realtimeFrequency:2299774542} D0227 16:10:28.137440 45111 parameters.go:238] Clock(Monotonic): error: 13 ns, adjusted frequency from 2299774434 Hz to 2299774553 Hz D0227 16:10:28.137718 45111 parameters.go:238] Clock(Realtime): error: 286 ns, adjusted frequency from 2299774542 Hz to 2299775090 Hz D0227 16:10:28.137858 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536236516595635 monotonicBaseRef:97001746307 monotonicFrequency:2299774553 realtimeReady:1 realtimeBaseCycles:8536236517259799 realtimeBaseRef:1582819828137712256 realtimeFrequency:2299775090} D0227 16:10:29.143848 45111 parameters.go:238] Clock(Monotonic): error: 110 ns, adjusted frequency from 2299774553 Hz to 2299774799 Hz D0227 16:10:29.144103 45111 parameters.go:238] Clock(Realtime): error: 85 ns, adjusted frequency from 2299775090 Hz to 2299774668 Hz D0227 16:10:29.144210 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536238831098695 monotonicBaseRef:98008150633 monotonicFrequency:2299774799 realtimeReady:1 realtimeBaseCycles:8536238831716200 realtimeBaseRef:1582819829144096058 realtimeFrequency:2299774668} D0227 16:10:30.137272 45111 parameters.go:238] Clock(Monotonic): error: -165 ns, adjusted frequency from 2299774799 Hz to 2299774215 Hz D0227 16:10:30.137513 45111 parameters.go:238] Clock(Realtime): error: -93 ns, adjusted frequency from 2299774668 Hz to 2299774305 Hz D0227 16:10:30.137643 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536241115747715 monotonicBaseRef:99001573563 monotonicFrequency:2299774215 realtimeReady:1 realtimeBaseCycles:8536241116328360 realtimeBaseRef:1582819830137503017 realtimeFrequency:2299774305} D0227 16:10:31.137099 45111 parameters.go:238] Clock(Monotonic): error: -11 ns, adjusted frequency from 2299774215 Hz to 2299774550 Hz D0227 16:10:31.137339 45111 parameters.go:238] Clock(Realtime): error: 11 ns, adjusted frequency from 2299774305 Hz to 2299774543 Hz D0227 16:10:31.137455 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536243415124295 monotonicBaseRef:100001400661 monotonicFrequency:2299774550 realtimeReady:1 realtimeBaseCycles:8536243415680909 realtimeBaseRef:1582819831137319626 realtimeFrequency:2299774543} D0227 16:10:32.136675 45111 parameters.go:238] Clock(Monotonic): error: -59 ns, adjusted frequency from 2299774550 Hz to 2299774389 Hz D0227 16:10:32.136934 45111 parameters.go:238] Clock(Realtime): error: 47 ns, adjusted frequency from 2299774543 Hz to 2299774586 Hz D0227 16:10:32.137077 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536245713925781 monotonicBaseRef:101000977548 monotonicFrequency:2299774389 realtimeReady:1 realtimeBaseCycles:8536245714545117 realtimeBaseRef:1582819832136923789 realtimeFrequency:2299774586} D0227 16:10:32.317262 45111 urpc.go:577] urpc: unmarshal success. D0227 16:10:32.317549 45111 controller.go:501] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-0 Signo:0 PID:0 Mode:Process} D0227 16:10:32.317801 45111 urpc.go:534] urpc: successfully marshalled 37 bytes. D0227 16:10:33.137050 45111 parameters.go:238] Clock(Monotonic): error: -27 ns, adjusted frequency from 2299774389 Hz to 2299774447 Hz D0227 16:10:33.137597 45111 parameters.go:238] Clock(Realtime): error: -181 ns, adjusted frequency from 2299774586 Hz to 2299774096 Hz D0227 16:10:33.137784 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536248014577392 monotonicBaseRef:102001358986 monotonicFrequency:2299774447 realtimeReady:1 realtimeBaseCycles:8536248015818914 realtimeBaseRef:1582819833137575683 realtimeFrequency:2299774096} I0227 16:10:33.846027 55750 main.go:296] *************************** I0227 16:10:33.846200 55750 main.go:297] 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-0 /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=1 -threaded=true -collide=true -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller107821376] I0227 16:10:33.846645 55750 main.go:298] Version release-20200219.0-47-gde0b2ebf8635 I0227 16:10:33.846735 55750 main.go:299] PID: 55750 I0227 16:10:33.846841 55750 main.go:300] UID: 0, GID: 0 I0227 16:10:33.846940 55750 main.go:301] Configuration: I0227 16:10:33.847032 55750 main.go:302] RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root I0227 16:10:33.847134 55750 main.go:303] Platform: ptrace I0227 16:10:33.847205 55750 main.go:304] FileAccess: shared, overlay: false I0227 16:10:33.847301 55750 main.go:305] Network: sandbox, logging: false I0227 16:10:33.847387 55750 main.go:306] Strace: false, max size: 1024, syscalls: [] I0227 16:10:33.847520 55750 main.go:307] *************************** W0227 16:10:33.847613 55750 main.go:312] Block the TERM signal. This is only safe in tests! D0227 16:10:33.848597 55750 container.go:158] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-0" D0227 16:10:33.856179 55750 container.go:591] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-0": signal 0 D0227 16:10:33.856404 55750 sandbox.go:788] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" D0227 16:10:33.856536 55750 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" D0227 16:10:33.857037 55750 urpc.go:534] urpc: successfully marshalled 117 bytes. D0227 16:10:33.858308 45111 urpc.go:577] urpc: unmarshal success. D0227 16:10:33.858540 45111 controller.go:501] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-0 Signo:0 PID:0 Mode:Process} D0227 16:10:33.858818 45111 urpc.go:534] urpc: successfully marshalled 37 bytes. D0227 16:10:33.859048 55750 urpc.go:577] urpc: unmarshal success. D0227 16:10:33.859179 55750 exec.go:120] Exec arguments: /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=1 -threaded=true -collide=true -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller107821376 D0227 16:10:33.859308 55750 exec.go:121] Exec capablities: &{PermittedCaps:274877898751 InheritableCaps:274877898751 EffectiveCaps:274877898751 BoundingCaps:274877898751 AmbientCaps:0} D0227 16:10:33.859433 55750 container.go:532] Execute in container "ci-gvisor-ptrace-proxy-sandbox-race-0", args: /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=1 -threaded=true -collide=true -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller107821376 D0227 16:10:33.859621 55750 sandbox.go:283] Executing new process in container "ci-gvisor-ptrace-proxy-sandbox-race-0" in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" D0227 16:10:33.859754 55750 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" D0227 16:10:33.860727 55750 urpc.go:534] urpc: successfully marshalled 634 bytes. D0227 16:10:33.861109 45111 urpc.go:577] urpc: unmarshal success. D0227 16:10:33.862914 45111 controller.go:267] containerManager.ExecuteAsync: /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=1 -threaded=true -collide=true -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller107821376 D0227 16:10:33.863211 45111 transport_flipcall.go:127] send [channel @0xc000390300] Twalkgetattr{FID: 1, NewFID: 5, Names: [etc]} D0227 16:10:33.866581 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 2} D0227 16:10:33.866720 45111 transport_flipcall.go:127] send [channel @0xc000390300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0227 16:10:33.868514 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 95} I0227 16:10:33.868964 45111 kernel.go:795] EXEC: [/syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=1 -threaded=true -collide=true -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller107821376] D0227 16:10:33.871090 45111 transport_flipcall.go:127] send [channel @0xc000390300] Twalkgetattr{FID: 1, NewFID: 5, Names: [syz-execprog]} D0227 16:10:33.873939 45111 transport_flipcall.go:234] recv [channel @0xc000390300] 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: 24119712, BlockSize: 4096, Blocks: 47112, ATime: {Sec: 1582792705, NanoSec: 196725805}, MTime: {Sec: 1582792705, NanoSec: 196725805}, CTime: {Sec: 1582819732, NanoSec: 684956585}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16515331}]} D0227 16:10:33.874891 45111 transport_flipcall.go:127] send [channel @0xc000390300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syz-execprog, Size: 1} D0227 16:10:33.875394 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 95} D0227 16:10:33.876431 45111 transport_flipcall.go:127] send [channel @0xc000390300] Tgetattr{FID: 5, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0227 16:10:33.877281 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16515331}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 24119712, BlockSize: 4096, Blocks: 47112, ATime: {Sec: 1582792705, NanoSec: 196725805}, MTime: {Sec: 1582792705, NanoSec: 196725805}, CTime: {Sec: 1582819732, NanoSec: 684956585}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0227 16:10:33.877427 45111 transport_flipcall.go:127] send [channel @0xc000390300] Twalk{FID: 5, NewFID: 6, Names: []} D0227 16:10:33.877901 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rwalk{QIDs: []} D0227 16:10:33.878012 45111 transport_flipcall.go:127] send [channel @0xc000390300] Tlopen{FID: 6, Flags: ReadOnly} D0227 16:10:33.878662 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlopen{QID: QID{Type: 0, Version: 0, Path: 16515331}, IoUnit: 0, File: &{{33}}} D0227 16:10:33.885263 45111 syscalls.go:266] Allocating stack with size of 8388608 bytes D0227 16:10:33.886458 45111 loader.go:832] updated processes: map[{ci-gvisor-ptrace-proxy-sandbox-race-0 0}:0xc00030aa00 {ci-gvisor-ptrace-proxy-sandbox-race-0 20}:0xc00000eb60] D0227 16:10:33.886792 45111 urpc.go:534] urpc: successfully marshalled 37 bytes. D0227 16:10:33.886955 55750 urpc.go:577] urpc: unmarshal success. D0227 16:10:33.887134 55750 container.go:579] Wait on PID 20 in container "ci-gvisor-ptrace-proxy-sandbox-race-0" D0227 16:10:33.887248 55750 sandbox.go:743] Waiting for PID 20 in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" D0227 16:10:33.887362 55750 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" D0227 16:10:33.887745 55750 urpc.go:534] urpc: successfully marshalled 100 bytes. D0227 16:10:33.888349 45111 task_block.go:223] [ 20] Interrupt queued D0227 16:10:33.888884 45111 urpc.go:577] urpc: unmarshal success. D0227 16:10:33.889269 45111 controller.go:443] containerManager.Wait D0227 16:10:34.129046 45111 task_block.go:223] [ 21] Interrupt queued D0227 16:10:34.129736 45111 task_block.go:223] [ 22] Interrupt queued D0227 16:10:34.137079 45111 parameters.go:238] Clock(Monotonic): error: 369 ns, adjusted frequency from 2299774447 Hz to 2299775308 Hz D0227 16:10:34.137310 45111 parameters.go:238] Clock(Realtime): error: 266 ns, adjusted frequency from 2299774096 Hz to 2299775076 Hz D0227 16:10:34.137433 45111 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8536250314418333 monotonicBaseRef:103001387899 monotonicFrequency:2299775308 realtimeReady:1 realtimeBaseCycles:8536250314957313 realtimeBaseRef:1582819834137299265 realtimeFrequency:2299775076} D0227 16:10:34.140784 45111 task_block.go:223] [ 23] Interrupt queued D0227 16:10:34.141704 45111 task_block.go:223] [ 24] Interrupt queued D0227 16:10:34.174297 45111 task_block.go:223] [ 25] Interrupt queued D0227 16:10:34.185886 45111 task_block.go:223] [ 26] Interrupt queued D0227 16:10:34.201927 45111 task_block.go:223] [ 27] Interrupt queued D0227 16:10:34.231632 45111 transport_flipcall.go:127] send [channel @0xc000390300] Twalkgetattr{FID: 1, NewFID: 7, Names: [etc]} D0227 16:10:34.232384 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 2} D0227 16:10:34.232564 45111 transport_flipcall.go:127] send [channel @0xc000390300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0227 16:10:34.233054 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 95} D0227 16:10:34.259594 45111 task_block.go:223] [ 28] Interrupt queued D0227 16:10:34.288177 45111 task_block.go:223] [ 29] Interrupt queued D0227 16:10:34.289744 45111 task_block.go:223] [ 30] Interrupt queued D0227 16:10:34.294586 45111 task_block.go:223] [ 31] Interrupt queued D0227 16:10:34.328858 45111 task_block.go:223] [ 32] Interrupt queued D0227 16:10:34.347474 45111 task_block.go:223] [ 33] Interrupt queued D0227 16:10:34.414686 45111 task_block.go:223] [ 34] Interrupt queued D0227 16:10:34.421166 45111 transport_flipcall.go:127] send [channel @0xc000390300] Twalkgetattr{FID: 1, NewFID: 7, Names: [syzkaller107821376]} D0227 16:10:34.421473 45111 task_block.go:223] [ 35] Interrupt queued D0227 16:10:34.423335 45111 transport_flipcall.go:234] recv [channel @0xc000390300] 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: 401, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1582819833, NanoSec: 810870637}, MTime: {Sec: 1582819833, NanoSec: 810870637}, CTime: {Sec: 1582819833, NanoSec: 810870637}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16515220}]} D0227 16:10:34.423559 45111 transport_flipcall.go:127] send [channel @0xc000390300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzkaller107821376, Size: 1} D0227 16:10:34.424125 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 95} D0227 16:10:34.424424 45111 transport_flipcall.go:127] send [channel @0xc000390300] Tgetattr{FID: 7, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0227 16:10:34.425296 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16515220}, Attr: Attr{Mode: 0o100600, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 401, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1582819833, NanoSec: 810870637}, MTime: {Sec: 1582819833, NanoSec: 810870637}, CTime: {Sec: 1582819833, NanoSec: 810870637}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0227 16:10:34.425470 45111 transport_flipcall.go:127] send [channel @0xc000390300] Twalk{FID: 7, NewFID: 8, Names: []} D0227 16:10:34.426214 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rwalk{QIDs: []} D0227 16:10:34.426412 45111 transport_flipcall.go:127] send [channel @0xc000390300] Tlopen{FID: 8, Flags: ReadOnly} D0227 16:10:34.427194 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlopen{QID: QID{Type: 0, Version: 0, Path: 16515220}, IoUnit: 0, File: &{{27}}} D0227 16:10:34.427962 45111 transport_flipcall.go:127] send [channel @0xc000390300] Tgetattr{FID: 8, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0227 16:10:34.428857 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16515220}, Attr: Attr{Mode: 0o100600, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 401, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1582819833, NanoSec: 810870637}, MTime: {Sec: 1582819833, NanoSec: 810870637}, CTime: {Sec: 1582819833, NanoSec: 810870637}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} 2020/02/27 16:10:34 parsed 1 programs D0227 16:10:34.439849 45111 task_stop.go:118] [ 27] Entering internal stop (*kernel.vforkStop)(nil) D0227 16:10:34.440570 45111 task_block.go:223] [ 36] Interrupt queued D0227 16:10:34.448565 45111 transport_flipcall.go:127] send [channel @0xc000390300] Twalkgetattr{FID: 1, NewFID: 9, Names: [syz-executor]} D0227 16:10:34.450798 45111 transport_flipcall.go:234] recv [channel @0xc000390300] 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: 1579448, BlockSize: 4096, Blocks: 3088, ATime: {Sec: 1582792705, NanoSec: 424748149}, MTime: {Sec: 1582792705, NanoSec: 424748149}, CTime: {Sec: 1582819732, NanoSec: 688956978}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16515338}]} D0227 16:10:34.451138 45111 transport_flipcall.go:127] send [channel @0xc000390300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syz-executor, Size: 1} D0227 16:10:34.451757 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 95} D0227 16:10:34.452070 45111 transport_flipcall.go:127] send [channel @0xc000390300] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0227 16:10:34.453082 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16515338}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 1579448, BlockSize: 4096, Blocks: 3088, ATime: {Sec: 1582792705, NanoSec: 424748149}, MTime: {Sec: 1582792705, NanoSec: 424748149}, CTime: {Sec: 1582819732, NanoSec: 688956978}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0227 16:10:34.453306 45111 transport_flipcall.go:127] send [channel @0xc000390300] Twalk{FID: 9, NewFID: 10, Names: []} D0227 16:10:34.454062 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rwalk{QIDs: []} D0227 16:10:34.454256 45111 transport_flipcall.go:127] send [channel @0xc000390300] Tlopen{FID: 10, Flags: ReadOnly} D0227 16:10:34.454992 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlopen{QID: QID{Type: 0, Version: 0, Path: 16515338}, IoUnit: 0, File: &{{28}}} D0227 16:10:34.456935 45111 syscalls.go:266] [ 36] Allocating stack with size of 8388608 bytes D0227 16:10:34.458059 45111 task_stop.go:137] [ 27] Leaving internal stop (*kernel.vforkStop)(nil) D0227 16:10:34.480384 45111 transport_flipcall.go:127] send [channel @0xc000390300] Twalkgetattr{FID: 1, NewFID: 11, Names: [etc]} D0227 16:10:34.481067 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 2} D0227 16:10:34.481217 45111 transport_flipcall.go:127] send [channel @0xc000390300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0227 16:10:34.482660 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 95} D0227 16:10:34.486328 45111 task_exit.go:221] [ 36] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 16:10:34.487536 45111 task_exit.go:221] [ 36] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 16:10:34.487705 45111 task_signals.go:446] [ 20] Notified of signal 17 D0227 16:10:34.487825 45111 task_block.go:223] [ 20] Interrupt queued D0227 16:10:34.487971 45111 task_block.go:223] [ 20] Interrupt queued D0227 16:10:34.488187 45111 task_signals.go:179] [ 20] Restarting syscall 202 after errno 512: interrupted by signal 17 D0227 16:10:34.488243 45111 task_exit.go:221] [ 36] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 16:10:34.488302 45111 task_signals.go:220] [ 20] Signal 17: delivering to handler D0227 16:10:34.489663 45111 task_block.go:223] [ 37] Interrupt queued D0227 16:10:34.503301 45111 transport_flipcall.go:127] send [channel @0xc000390300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syz-executor]} D0227 16:10:34.505147 45111 transport_flipcall.go:234] recv [channel @0xc000390300] 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: 1579448, BlockSize: 4096, Blocks: 3088, ATime: {Sec: 1582792705, NanoSec: 424748149}, MTime: {Sec: 1582792705, NanoSec: 424748149}, CTime: {Sec: 1582819732, NanoSec: 688956978}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16515338}]} D0227 16:10:34.505371 45111 transport_flipcall.go:127] send [channel @0xc000390300] Tclunk{FID: 11} D0227 16:10:34.505878 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rclunk{} D0227 16:10:34.506147 45111 transport_flipcall.go:127] send [channel @0xc000390300] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0227 16:10:34.507002 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16515338}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 1579448, BlockSize: 4096, Blocks: 3088, ATime: {Sec: 1582792705, NanoSec: 424748149}, MTime: {Sec: 1582792705, NanoSec: 424748149}, CTime: {Sec: 1582819732, NanoSec: 688956978}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} 2020/02/27 16:10:34 executed programs: 0 D0227 16:10:34.517343 45111 task_stop.go:118] [ 35] Entering internal stop (*kernel.vforkStop)(nil) D0227 16:10:34.518019 45111 task_block.go:223] [ 38] Interrupt queued D0227 16:10:34.523438 45111 transport_flipcall.go:127] send [channel @0xc000390300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syz-executor]} D0227 16:10:34.524498 45111 transport_flipcall.go:234] recv [channel @0xc000390300] 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: 1579448, BlockSize: 4096, Blocks: 3088, ATime: {Sec: 1582792705, NanoSec: 424748149}, MTime: {Sec: 1582792705, NanoSec: 424748149}, CTime: {Sec: 1582819732, NanoSec: 688956978}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16515338}]} D0227 16:10:34.525380 45111 transport_flipcall.go:127] send [channel @0xc000390300] Tclunk{FID: 11} D0227 16:10:34.525912 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rclunk{} D0227 16:10:34.526177 45111 transport_flipcall.go:127] send [channel @0xc000390300] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0227 16:10:34.527148 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16515338}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 1579448, BlockSize: 4096, Blocks: 3088, ATime: {Sec: 1582792705, NanoSec: 424748149}, MTime: {Sec: 1582792705, NanoSec: 424748149}, CTime: {Sec: 1582819732, NanoSec: 688956978}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0227 16:10:34.531800 45111 syscalls.go:266] [ 38] Allocating stack with size of 8388608 bytes D0227 16:10:34.533223 45111 task_stop.go:137] [ 35] Leaving internal stop (*kernel.vforkStop)(nil) D0227 16:10:34.542425 45111 transport_flipcall.go:127] send [channel @0xc000390300] Twalkgetattr{FID: 1, NewFID: 11, Names: [etc]} D0227 16:10:34.542998 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 2} D0227 16:10:34.543208 45111 transport_flipcall.go:127] send [channel @0xc000390300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0227 16:10:34.543604 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 95} D0227 16:10:34.566739 45111 task_block.go:223] [ 39] Interrupt queued D0227 16:10:34.571628 45111 transport_flipcall.go:127] send [channel @0xc000390300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 16:10:34.572615 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 2} D0227 16:10:34.572837 45111 transport_flipcall.go:127] send [channel @0xc000390300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 16:10:34.573442 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 95} D0227 16:10:34.573992 45111 transport_flipcall.go:127] send [channel @0xc000390300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 16:10:34.574575 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 2} D0227 16:10:34.574741 45111 transport_flipcall.go:127] send [channel @0xc000390300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 16:10:34.575200 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 95} D0227 16:10:34.575658 45111 transport_flipcall.go:127] send [channel @0xc000390300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 16:10:34.576285 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 2} D0227 16:10:34.576582 45111 transport_flipcall.go:127] send [channel @0xc000390300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 16:10:34.577164 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 95} D0227 16:10:34.577614 45111 transport_flipcall.go:127] send [channel @0xc000390300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 16:10:34.578067 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 2} D0227 16:10:34.578203 45111 transport_flipcall.go:127] send [channel @0xc000390300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 16:10:34.578695 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 95} D0227 16:10:34.578987 45111 transport_flipcall.go:127] send [channel @0xc000390300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 16:10:34.579466 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 2} D0227 16:10:34.579603 45111 transport_flipcall.go:127] send [channel @0xc000390300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 16:10:34.579991 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 95} D0227 16:10:34.580538 45111 transport_flipcall.go:127] send [channel @0xc000390300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 16:10:34.581228 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 2} D0227 16:10:34.581454 45111 transport_flipcall.go:127] send [channel @0xc000390300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 16:10:34.582057 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 95} D0227 16:10:34.582378 45111 transport_flipcall.go:127] send [channel @0xc000390300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 16:10:34.582826 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 2} D0227 16:10:34.582984 45111 transport_flipcall.go:127] send [channel @0xc000390300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 16:10:34.587723 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 95} D0227 16:10:34.588308 45111 transport_flipcall.go:127] send [channel @0xc000390300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 16:10:34.588914 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 2} D0227 16:10:34.589068 45111 transport_flipcall.go:127] send [channel @0xc000390300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 16:10:34.589561 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 95} D0227 16:10:34.590190 45111 transport_flipcall.go:127] send [channel @0xc000390300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 16:10:34.590781 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 2} D0227 16:10:34.591004 45111 transport_flipcall.go:127] send [channel @0xc000390300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 16:10:34.591373 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 95} D0227 16:10:34.615285 45111 netfilter.go:54] netfilter: convert to binary: current offset: 0 D0227 16:10:34.615453 45111 netfilter.go:54] netfilter: convert to binary: found hook 0 at offset 0 D0227 16:10:34.615555 45111 netfilter.go:54] netfilter: convert to binary: found underflow 0 at offset 0 D0227 16:10:34.615631 45111 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 16:10:34.615705 45111 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:152 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[40 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 254 255 255 255 0 0 0 0]} D0227 16:10:34.616291 45111 netfilter.go:54] netfilter: convert to binary: current offset: 152 D0227 16:10:34.616361 45111 netfilter.go:54] netfilter: convert to binary: found hook 1 at offset 152 D0227 16:10:34.616459 45111 netfilter.go:54] netfilter: convert to binary: found underflow 1 at offset 152 D0227 16:10:34.616546 45111 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 16:10:34.616647 45111 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:152 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[40 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 254 255 255 255 0 0 0 0]} D0227 16:10:34.617252 45111 netfilter.go:54] netfilter: convert to binary: current offset: 304 D0227 16:10:34.617361 45111 netfilter.go:54] netfilter: convert to binary: found hook 3 at offset 304 D0227 16:10:34.617436 45111 netfilter.go:54] netfilter: convert to binary: found underflow 3 at offset 304 D0227 16:10:34.617519 45111 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 16:10:34.617603 45111 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:152 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[40 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 254 255 255 255 0 0 0 0]} D0227 16:10:34.618082 45111 netfilter.go:54] netfilter: convert to binary: current offset: 456 D0227 16:10:34.618160 45111 netfilter.go:54] netfilter: convert to binary: found hook 4 at offset 456 D0227 16:10:34.618225 45111 netfilter.go:54] netfilter: convert to binary: found underflow 4 at offset 456 D0227 16:10:34.618294 45111 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 16:10:34.618360 45111 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:152 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[40 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 254 255 255 255 0 0 0 0]} D0227 16:10:34.618825 45111 netfilter.go:54] netfilter: convert to binary: current offset: 608 D0227 16:10:34.618921 45111 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:176 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[64 0 69 82 82 79 82 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 69 82 82 79 82 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0]} D0227 16:10:34.619424 45111 netfilter.go:54] netfilter: convert to binary: finished with an marshalled size of 0 D0227 16:10:34.619490 45111 netfilter.go:54] netfilter: convert to binary: current offset: 0 D0227 16:10:34.619579 45111 netfilter.go:54] netfilter: convert to binary: found hook 0 at offset 0 D0227 16:10:34.619654 45111 netfilter.go:54] netfilter: convert to binary: found underflow 0 at offset 0 D0227 16:10:34.619736 45111 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 16:10:34.619802 45111 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:152 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[40 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 254 255 255 255 0 0 0 0]} D0227 16:10:34.620280 45111 netfilter.go:54] netfilter: convert to binary: current offset: 152 D0227 16:10:34.620812 45111 netfilter.go:54] netfilter: convert to binary: found hook 3 at offset 152 D0227 16:10:34.620902 45111 netfilter.go:54] netfilter: convert to binary: found underflow 3 at offset 152 D0227 16:10:34.620993 45111 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 16:10:34.621081 45111 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:152 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[40 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 254 255 255 255 0 0 0 0]} D0227 16:10:34.621609 45111 netfilter.go:54] netfilter: convert to binary: current offset: 304 D0227 16:10:34.621713 45111 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:176 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[64 0 69 82 82 79 82 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 69 82 82 79 82 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0]} D0227 16:10:34.622250 45111 netfilter.go:54] netfilter: convert to binary: finished with an marshalled size of 0 D0227 16:10:34.622335 45111 netfilter.go:54] netfilter: convert to binary: current offset: 0 D0227 16:10:34.622415 45111 netfilter.go:54] netfilter: convert to binary: found hook 1 at offset 0 D0227 16:10:34.622490 45111 netfilter.go:54] netfilter: convert to binary: found underflow 1 at offset 0 D0227 16:10:34.622566 45111 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 16:10:34.622648 45111 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:152 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[40 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 254 255 255 255 0 0 0 0]} D0227 16:10:34.623128 45111 netfilter.go:54] netfilter: convert to binary: current offset: 152 D0227 16:10:34.623361 45111 netfilter.go:54] netfilter: convert to binary: found hook 2 at offset 152 D0227 16:10:34.623451 45111 netfilter.go:54] netfilter: convert to binary: found underflow 2 at offset 152 D0227 16:10:34.623532 45111 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 16:10:34.623661 45111 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:152 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[40 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 254 255 255 255 0 0 0 0]} D0227 16:10:34.624280 45111 netfilter.go:54] netfilter: convert to binary: current offset: 304 D0227 16:10:34.624344 45111 netfilter.go:54] netfilter: convert to binary: found hook 3 at offset 304 D0227 16:10:34.624419 45111 netfilter.go:54] netfilter: convert to binary: found underflow 3 at offset 304 D0227 16:10:34.624531 45111 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 16:10:34.624627 45111 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:152 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[40 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 254 255 255 255 0 0 0 0]} D0227 16:10:34.625226 45111 netfilter.go:54] netfilter: convert to binary: current offset: 456 D0227 16:10:34.625318 45111 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:176 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[64 0 69 82 82 79 82 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 69 82 82 79 82 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0]} D0227 16:10:34.625862 45111 netfilter.go:54] netfilter: convert to binary: finished with an marshalled size of 0 D0227 16:10:34.848502 45111 transport_flipcall.go:127] send [channel @0xc000390300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 16:10:34.849253 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 2} D0227 16:10:34.849458 45111 transport_flipcall.go:127] send [channel @0xc000390300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 16:10:34.849940 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 95} D0227 16:10:34.850330 45111 transport_flipcall.go:127] send [channel @0xc000390300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 16:10:34.850950 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 2} D0227 16:10:34.851093 45111 transport_flipcall.go:127] send [channel @0xc000390300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 16:10:34.855965 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 95} D0227 16:10:34.856317 45111 transport_flipcall.go:127] send [channel @0xc000390300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 16:10:34.856976 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 2} D0227 16:10:34.857148 45111 transport_flipcall.go:127] send [channel @0xc000390300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 16:10:34.857658 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 95} D0227 16:10:34.858029 45111 transport_flipcall.go:127] send [channel @0xc000390300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 16:10:34.858553 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 2} D0227 16:10:34.858722 45111 transport_flipcall.go:127] send [channel @0xc000390300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 16:10:34.859131 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 95} D0227 16:10:34.859345 45111 transport_flipcall.go:127] send [channel @0xc000390300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 16:10:34.859972 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 2} D0227 16:10:34.860108 45111 transport_flipcall.go:127] send [channel @0xc000390300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 16:10:34.860524 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 95} D0227 16:10:34.860799 45111 transport_flipcall.go:127] send [channel @0xc000390300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 16:10:34.861289 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 2} D0227 16:10:34.861480 45111 transport_flipcall.go:127] send [channel @0xc000390300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 16:10:34.861980 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 95} D0227 16:10:34.862274 45111 transport_flipcall.go:127] send [channel @0xc000390300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 16:10:34.864854 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 2} D0227 16:10:34.865008 45111 transport_flipcall.go:127] send [channel @0xc000390300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 16:10:34.866454 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 95} D0227 16:10:34.866831 45111 transport_flipcall.go:127] send [channel @0xc000390300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 16:10:34.867495 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 2} D0227 16:10:34.867657 45111 transport_flipcall.go:127] send [channel @0xc000390300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 16:10:34.868205 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 95} D0227 16:10:34.868454 45111 transport_flipcall.go:127] send [channel @0xc000390300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 16:10:34.869044 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 2} D0227 16:10:34.869246 45111 transport_flipcall.go:127] send [channel @0xc000390300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 16:10:34.869872 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 95} D0227 16:10:34.870279 45111 transport_flipcall.go:127] send [channel @0xc000390300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 16:10:34.870839 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 2} D0227 16:10:34.870968 45111 transport_flipcall.go:127] send [channel @0xc000390300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 16:10:34.871444 45111 transport_flipcall.go:234] recv [channel @0xc000390300] Rlerror{Error: 95} D0227 16:10:34.902720 45111 task_block.go:223] [ 40] Interrupt queued D0227 16:10:34.918811 45111 task_block.go:223] [ 41] Interrupt queued D0227 16:10:34.947953 45111 task_block.go:223] [ 42] Interrupt queued ================== WARNING: DATA RACE Write at 0x00c0002cd210 by goroutine 334: gvisor.dev/gvisor/pkg/tcpip/transport/udp.(*endpoint).Connect() pkg/tcpip/transport/udp/endpoint.go:1053 +0x46a gvisor.dev/gvisor/pkg/sentry/socket/netstack.(*SocketOperations).Connect() pkg/sentry/socket/netstack/netstack.go:698 +0x4c5 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Connect() pkg/sentry/syscalls/linux/sys_socket.go:280 +0x251 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall() pkg/sentry/kernel/task_syscall.go:170 +0x455 DIAGNOSIS: I0227 16:10:34.984283 55878 main.go:296] *************************** I0227 16:10:34.984411 55878 main.go:297] 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-0] I0227 16:10:34.984666 55878 main.go:298] Version release-20200219.0-47-gde0b2ebf8635 I0227 16:10:34.984786 55878 main.go:299] PID: 55878 I0227 16:10:34.984908 55878 main.go:300] UID: 0, GID: 0 I0227 16:10:34.985027 55878 main.go:301] Configuration: I0227 16:10:34.985123 55878 main.go:302] RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root I0227 16:10:34.985230 55878 main.go:303] Platform: ptrace I0227 16:10:34.985308 55878 main.go:304] FileAccess: shared, overlay: false I0227 16:10:34.985435 55878 main.go:305] Network: sandbox, logging: false I0227 16:10:34.985518 55878 main.go:306] Strace: false, max size: 1024, syscalls: [] I0227 16:10:34.985587 55878 main.go:307] *************************** W0227 16:10:34.985693 55878 main.go:312] Block the TERM signal. This is only safe in tests! D0227 16:10:34.985881 55878 container.go:158] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-0" D0227 16:10:34.991988 55878 container.go:591] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-0": signal 0 D0227 16:10:34.992153 55878 sandbox.go:788] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" D0227 16:10:34.992261 55878 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" D0227 16:10:34.992722 55878 urpc.go:534] urpc: successfully marshalled 117 bytes. I0227 16:10:35.105847 55878 debug.go:122] Found sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0", PID: 45111 I0227 16:10:35.105986 55878 debug.go:131] Retrieving sandbox stacks D0227 16:10:35.106085 55878 sandbox.go:906] Stacks sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" D0227 16:10:35.106205 55878 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" W0227 16:10:35.106335 55878 error.go:48] FATAL ERROR: retrieving stacks: connecting to control server at PID 45111: connection refused retrieving stacks: connecting to control server at PID 45111: connection refused W0227 16:10:35.106609 55878 main.go:329] 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-0"]: exit status 128 I0227 16:10:34.984283 55878 main.go:296] *************************** I0227 16:10:34.984411 55878 main.go:297] 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-0] I0227 16:10:34.984666 55878 main.go:298] Version release-20200219.0-47-gde0b2ebf8635 I0227 16:10:34.984786 55878 main.go:299] PID: 55878 I0227 16:10:34.984908 55878 main.go:300] UID: 0, GID: 0 I0227 16:10:34.985027 55878 main.go:301] Configuration: I0227 16:10:34.985123 55878 main.go:302] RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root I0227 16:10:34.985230 55878 main.go:303] Platform: ptrace I0227 16:10:34.985308 55878 main.go:304] FileAccess: shared, overlay: false I0227 16:10:34.985435 55878 main.go:305] Network: sandbox, logging: false I0227 16:10:34.985518 55878 main.go:306] Strace: false, max size: 1024, syscalls: [] I0227 16:10:34.985587 55878 main.go:307] *************************** W0227 16:10:34.985693 55878 main.go:312] Block the TERM signal. This is only safe in tests! D0227 16:10:34.985881 55878 container.go:158] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-0" D0227 16:10:34.991988 55878 container.go:591] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-0": signal 0 D0227 16:10:34.992153 55878 sandbox.go:788] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" D0227 16:10:34.992261 55878 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" D0227 16:10:34.992722 55878 urpc.go:534] urpc: successfully marshalled 117 bytes. I0227 16:10:35.105847 55878 debug.go:122] Found sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0", PID: 45111 I0227 16:10:35.105986 55878 debug.go:131] Retrieving sandbox stacks D0227 16:10:35.106085 55878 sandbox.go:906] Stacks sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" D0227 16:10:35.106205 55878 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" W0227 16:10:35.106335 55878 error.go:48] FATAL ERROR: retrieving stacks: connecting to control server at PID 45111: connection refused retrieving stacks: connecting to control server at PID 45111: connection refused W0227 16:10:35.106609 55878 main.go:329] Failure to execute command, err: 1 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:232 +0x1aaf gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run() pkg/sentry/kernel/task_run.go:92 +0x308 Previous read at 0x00c0002cd210 by goroutine 347: gvisor.dev/gvisor/pkg/tcpip/stack.(*TransportEndpointInfo).AddrNetProto() pkg/tcpip/stack/stack.go:573 +0x79 gvisor.dev/gvisor/pkg/tcpip/transport/udp.(*endpoint).checkV4Mapped() pkg/tcpip/transport/udp/endpoint.go:931 +0xee gvisor.dev/gvisor/pkg/tcpip/transport/udp.(*endpoint).Connect() pkg/tcpip/transport/udp/endpoint.go:984 +0x6c gvisor.dev/gvisor/pkg/sentry/socket/netstack.(*SocketOperations).Connect() pkg/sentry/socket/netstack/netstack.go:698 +0x4c5 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Connect() pkg/sentry/syscalls/linux/sys_socket.go:280 +0x251 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:232 +0x1aaf gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run() pkg/sentry/kernel/task_run.go:92 +0x308 Goroutine 334 (running) created at: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).Start() pkg/sentry/kernel/task_start.go:315 +0x19d gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).Clone() pkg/sentry/kernel/task_clone.go:361 +0x12af gvisor.dev/gvisor/pkg/sentry/syscalls/linux.clone() pkg/sentry/syscalls/linux/sys_thread.go:226 +0x1fe gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Clone() pkg/sentry/syscalls/linux/sys_clone_amd64.go:34 +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:232 +0x1aaf gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run() pkg/sentry/kernel/task_run.go:92 +0x308 Goroutine 347 (running) created at: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).Start() pkg/sentry/kernel/task_start.go:315 +0x19d gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).Clone() pkg/sentry/kernel/task_clone.go:361 +0x12af gvisor.dev/gvisor/pkg/sentry/syscalls/linux.clone() pkg/sentry/syscalls/linux/sys_thread.go:226 +0x1fe gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Clone() pkg/sentry/syscalls/linux/sys_clone_amd64.go:34 +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:232 +0x1aaf gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run() pkg/sentry/kernel/task_run.go:92 +0x308 ================== W0227 16:10:35.097553 55750 error.go:48] FATAL ERROR: waiting on pid 20: waiting on PID 20 in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0": urpc method "containerManager.WaitPID" failed: EOF waiting on pid 20: waiting on PID 20 in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0": urpc method "containerManager.WaitPID" failed: EOF W0227 16:10:35.097961 55750 main.go:329] Failure to execute command, err: 1 W0227 16:10:35.105722 45091 sandbox.go:724] Wait RPC to container "ci-gvisor-ptrace-proxy-sandbox-race-0" failed: urpc method "containerManager.Wait" failed: EOF. Will try waiting on the sandbox process instead. D0227 16:10:35.106285 45091 container.go:713] Destroy container "ci-gvisor-ptrace-proxy-sandbox-race-0" D0227 16:10:35.106447 45091 container.go:800] Destroying container "ci-gvisor-ptrace-proxy-sandbox-race-0" D0227 16:10:35.106577 45091 sandbox.go:1040] Destroying root container "ci-gvisor-ptrace-proxy-sandbox-race-0" by destroying sandbox D0227 16:10:35.106685 45091 sandbox.go:770] Destroy sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" D0227 16:10:35.107725 45091 container.go:814] Killing gofer for container "ci-gvisor-ptrace-proxy-sandbox-race-0", PID: 45107 I0227 16:10:35.483372 45091 main.go:320] Exiting with status: 16896