D0227 11:55:12.766363 54532 parameters.go:238] Clock(Monotonic): error: 217 ns, adjusted frequency from 2299774172 Hz to 2299774901 Hz D0227 11:55:12.766666 54532 parameters.go:238] Clock(Realtime): error: 256 ns, adjusted frequency from 2299774169 Hz to 2299775038 Hz D0227 11:55:12.766766 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501014615829125 monotonicBaseRef:2004139941 monotonicFrequency:2299774901 realtimeReady:1 realtimeBaseCycles:8501014616568445 realtimeBaseRef:1582804512766660233 realtimeFrequency:2299775038} D0227 11:55:13.762980 54532 parameters.go:238] Clock(Monotonic): error: 61 ns, adjusted frequency from 2299774901 Hz to 2299774613 Hz D0227 11:55:13.763193 54532 parameters.go:238] Clock(Realtime): error: 31 ns, adjusted frequency from 2299775038 Hz to 2299774566 Hz D0227 11:55:13.763298 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501016907831470 monotonicBaseRef:3000760238 monotonicFrequency:2299774613 realtimeReady:1 realtimeBaseCycles:8501016908359933 realtimeBaseRef:1582804513763188784 realtimeFrequency:2299774566} D0227 11:55:14.763785 54532 parameters.go:238] Clock(Monotonic): error: 70 ns, adjusted frequency from 2299774613 Hz to 2299774679 Hz D0227 11:55:14.764011 54532 parameters.go:238] Clock(Realtime): error: 36 ns, adjusted frequency from 2299774566 Hz to 2299774606 Hz D0227 11:55:14.764147 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501019209373303 monotonicBaseRef:4001528669 monotonicFrequency:2299774679 realtimeReady:1 realtimeBaseCycles:8501019210003869 realtimeBaseRef:1582804514764001633 realtimeFrequency:2299774606} D0227 11:55:15.768826 54532 parameters.go:238] Clock(Monotonic): error: 82 ns, adjusted frequency from 2299774679 Hz to 2299774733 Hz D0227 11:55:15.769059 54532 parameters.go:238] Clock(Realtime): error: 99 ns, adjusted frequency from 2299774606 Hz to 2299774786 Hz D0227 11:55:15.769176 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501021520812929 monotonicBaseRef:5006600881 monotonicFrequency:2299774733 realtimeReady:1 realtimeBaseCycles:8501021521396345 realtimeBaseRef:1582804515769053375 realtimeFrequency:2299774786} D0227 11:55:16.763698 54532 parameters.go:238] Clock(Monotonic): error: 27 ns, adjusted frequency from 2299774733 Hz to 2299774621 Hz D0227 11:55:16.763904 54532 parameters.go:238] Clock(Realtime): error: -13 ns, adjusted frequency from 2299774786 Hz to 2299774532 Hz D0227 11:55:16.764027 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501023808814451 monotonicBaseRef:6001481592 monotonicFrequency:2299774621 realtimeReady:1 realtimeBaseCycles:8501023809319155 realtimeBaseRef:1582804516763899837 realtimeFrequency:2299774532} D0227 11:55:17.763684 54532 parameters.go:238] Clock(Monotonic): error: 14 ns, adjusted frequency from 2299774621 Hz to 2299774597 Hz D0227 11:55:17.763919 54532 parameters.go:238] Clock(Realtime): error: 42 ns, adjusted frequency from 2299774532 Hz to 2299774668 Hz D0227 11:55:17.764048 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501026108528674 monotonicBaseRef:7001455329 monotonicFrequency:2299774597 realtimeReady:1 realtimeBaseCycles:8501026109125150 realtimeBaseRef:1582804517763913517 realtimeFrequency:2299774668} D0227 11:55:18.764561 54532 parameters.go:238] Clock(Monotonic): error: 51 ns, adjusted frequency from 2299774597 Hz to 2299774699 Hz D0227 11:55:18.764750 54532 parameters.go:238] Clock(Realtime): error: 32 ns, adjusted frequency from 2299774668 Hz to 2299774656 Hz D0227 11:55:18.764865 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501028410295287 monotonicBaseRef:8002321507 monotonicFrequency:2299774699 realtimeReady:1 realtimeBaseCycles:8501028410812129 realtimeBaseRef:1582804518764745038 realtimeFrequency:2299774656} D0227 11:55:19.763605 54532 parameters.go:238] Clock(Monotonic): error: 49 ns, adjusted frequency from 2299774699 Hz to 2299774704 Hz D0227 11:55:19.763871 54532 parameters.go:238] Clock(Realtime): error: 40 ns, adjusted frequency from 2299774656 Hz to 2299774682 Hz D0227 11:55:19.764005 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501030707919682 monotonicBaseRef:9001386500 monotonicFrequency:2299774704 realtimeReady:1 realtimeBaseCycles:8501030708565072 realtimeBaseRef:1582804519763865946 realtimeFrequency:2299774682} D0227 11:55:20.763917 54532 parameters.go:238] Clock(Monotonic): error: -18 ns, adjusted frequency from 2299774704 Hz to 2299774593 Hz D0227 11:55:20.764115 54532 parameters.go:238] Clock(Realtime): error: 16 ns, adjusted frequency from 2299774682 Hz to 2299774678 Hz D0227 11:55:20.764254 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501033008414653 monotonicBaseRef:10001699690 monotonicFrequency:2299774593 realtimeReady:1 realtimeBaseCycles:8501033008896783 realtimeBaseRef:1582804520764108156 realtimeFrequency:2299774678} D0227 11:55:21.763295 54532 parameters.go:238] Clock(Monotonic): error: 16 ns, adjusted frequency from 2299774593 Hz to 2299774672 Hz D0227 11:55:21.763505 54532 parameters.go:238] Clock(Realtime): error: 10 ns, adjusted frequency from 2299774678 Hz to 2299774651 Hz D0227 11:55:21.763604 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501035306752305 monotonicBaseRef:11001074871 monotonicFrequency:2299774672 realtimeReady:1 realtimeBaseCycles:8501035307270085 realtimeBaseRef:1582804521763498802 realtimeFrequency:2299774651} D0227 11:55:22.763866 54532 parameters.go:238] Clock(Monotonic): error: 8 ns, adjusted frequency from 2299774672 Hz to 2299774660 Hz D0227 11:55:22.764095 54532 parameters.go:238] Clock(Realtime): error: 16 ns, adjusted frequency from 2299774651 Hz to 2299774677 Hz D0227 11:55:22.764219 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501037607842465 monotonicBaseRef:12001646878 monotonicFrequency:2299774660 realtimeReady:1 realtimeBaseCycles:8501037608402747 realtimeBaseRef:1582804522764089299 realtimeFrequency:2299774677} D0227 11:55:23.764526 54532 parameters.go:238] Clock(Monotonic): error: 16 ns, adjusted frequency from 2299774660 Hz to 2299774675 Hz D0227 11:55:23.764774 54532 parameters.go:238] Clock(Realtime): error: 32 ns, adjusted frequency from 2299774677 Hz to 2299774715 Hz D0227 11:55:23.764956 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501039909116572 monotonicBaseRef:13002298875 monotonicFrequency:2299774675 realtimeReady:1 realtimeBaseCycles:8501039909728961 realtimeBaseRef:1582804523764763946 realtimeFrequency:2299774715} D0227 11:55:24.764327 54532 parameters.go:238] Clock(Monotonic): error: -54 ns, adjusted frequency from 2299774675 Hz to 2299774511 Hz D0227 11:55:24.764569 54532 parameters.go:238] Clock(Realtime): error: -47 ns, adjusted frequency from 2299774715 Hz to 2299774527 Hz D0227 11:55:24.764694 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501042208457908 monotonicBaseRef:14002110448 monotonicFrequency:2299774511 realtimeReady:1 realtimeBaseCycles:8501042209041802 realtimeBaseRef:1582804524764563111 realtimeFrequency:2299774527} D0227 11:55:25.763162 54532 parameters.go:238] Clock(Monotonic): error: 33 ns, adjusted frequency from 2299774511 Hz to 2299774703 Hz D0227 11:55:25.763363 54532 parameters.go:238] Clock(Realtime): error: 35 ns, adjusted frequency from 2299774527 Hz to 2299774727 Hz D0227 11:55:25.763489 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501044505520339 monotonicBaseRef:15000931167 monotonicFrequency:2299774703 realtimeReady:1 realtimeBaseCycles:8501044506045561 realtimeBaseRef:1582804525763358311 realtimeFrequency:2299774727} D0227 11:55:26.764488 54532 parameters.go:238] Clock(Monotonic): error: -21 ns, adjusted frequency from 2299774703 Hz to 2299774591 Hz D0227 11:55:26.764719 54532 sampler.go:168] Time: Adjusting syscall overhead up to 8000 D0227 11:55:26.764850 54532 parameters.go:238] Clock(Realtime): error: 238 ns, adjusted frequency from 2299774727 Hz to 2299775138 Hz D0227 11:55:26.764990 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501046808329649 monotonicBaseRef:16002250690 monotonicFrequency:2299774591 realtimeReady:1 realtimeBaseCycles:8501046809231653 realtimeBaseRef:1582804526764841658 realtimeFrequency:2299775138} D0227 11:55:27.762631 54532 parameters.go:238] Clock(Monotonic): error: 27 ns, adjusted frequency from 2299774591 Hz to 2299774697 Hz D0227 11:55:27.762806 54532 parameters.go:238] Clock(Realtime): error: -7 ns, adjusted frequency from 2299775138 Hz to 2299774617 Hz D0227 11:55:27.764408 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501049103885553 monotonicBaseRef:17000416298 monotonicFrequency:2299774697 realtimeReady:1 realtimeBaseCycles:8501049104313992 realtimeBaseRef:1582804527762801111 realtimeFrequency:2299774617} D0227 11:55:28.763120 54532 parameters.go:238] Clock(Monotonic): error: -91 ns, adjusted frequency from 2299774697 Hz to 2299774418 Hz D0227 11:55:28.763533 54532 parameters.go:238] Clock(Realtime): error: -257 ns, adjusted frequency from 2299774617 Hz to 2299774046 Hz D0227 11:55:28.763633 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501051404789822 monotonicBaseRef:18000907464 monotonicFrequency:2299774418 realtimeReady:1 realtimeBaseCycles:8501051405750624 realtimeBaseRef:1582804528763523797 realtimeFrequency:2299774046} D0227 11:55:29.763961 54532 parameters.go:238] Clock(Monotonic): error: 78 ns, adjusted frequency from 2299774418 Hz to 2299774813 Hz D0227 11:55:29.764197 54532 parameters.go:238] Clock(Realtime): error: 38 ns, adjusted frequency from 2299774046 Hz to 2299774728 Hz D0227 11:55:29.764336 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501053706488707 monotonicBaseRef:19001744270 monotonicFrequency:2299774813 realtimeReady:1 realtimeBaseCycles:8501053707059884 realtimeBaseRef:1582804529764191346 realtimeFrequency:2299774728} D0227 11:55:30.762507 54532 parameters.go:238] Clock(Monotonic): error: 25 ns, adjusted frequency from 2299774813 Hz to 2299774695 Hz D0227 11:55:30.762738 54532 parameters.go:238] Clock(Realtime): error: 274 ns, adjusted frequency from 2299774728 Hz to 2299775204 Hz D0227 11:55:30.762843 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501056002915430 monotonicBaseRef:20000288436 monotonicFrequency:2299774695 realtimeReady:1 realtimeBaseCycles:8501056003481053 realtimeBaseRef:1582804530762733134 realtimeFrequency:2299775204} D0227 11:55:31.764332 54532 parameters.go:238] Clock(Monotonic): error: -40 ns, adjusted frequency from 2299774695 Hz to 2299774539 Hz D0227 11:55:31.764615 54532 parameters.go:238] Clock(Realtime): error: 51 ns, adjusted frequency from 2299775204 Hz to 2299774689 Hz D0227 11:55:31.764766 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501058306893640 monotonicBaseRef:21002116230 monotonicFrequency:2299774539 realtimeReady:1 realtimeBaseCycles:8501058307564576 realtimeBaseRef:1582804531764606499 realtimeFrequency:2299774689} D0227 11:55:32.764259 54532 parameters.go:238] Clock(Monotonic): error: 0 ns, adjusted frequency from 2299774539 Hz to 2299774636 Hz D0227 11:55:32.764444 54532 parameters.go:238] Clock(Realtime): error: 18 ns, adjusted frequency from 2299774689 Hz to 2299774627 Hz D0227 11:55:32.764603 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501060606458511 monotonicBaseRef:22002025061 monotonicFrequency:2299774636 realtimeReady:1 realtimeBaseCycles:8501060606952588 realtimeBaseRef:1582804532764438362 realtimeFrequency:2299774627} D0227 11:55:33.762909 54532 parameters.go:238] Clock(Monotonic): error: 43 ns, adjusted frequency from 2299774636 Hz to 2299774735 Hz D0227 11:55:33.763130 54532 parameters.go:238] Clock(Realtime): error: 67 ns, adjusted frequency from 2299774627 Hz to 2299774737 Hz D0227 11:55:33.763236 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501062903171542 monotonicBaseRef:23000693797 monotonicFrequency:2299774735 realtimeReady:1 realtimeBaseCycles:8501062903686487 realtimeBaseRef:1582804533763116176 realtimeFrequency:2299774737} D0227 11:55:34.764397 54532 parameters.go:238] Clock(Monotonic): error: -30 ns, adjusted frequency from 2299774735 Hz to 2299774573 Hz D0227 11:55:34.764630 54532 parameters.go:238] Clock(Realtime): error: -59 ns, adjusted frequency from 2299774737 Hz to 2299774438 Hz D0227 11:55:34.764776 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501065206354497 monotonicBaseRef:24002175776 monotonicFrequency:2299774573 realtimeReady:1 realtimeBaseCycles:8501065206921065 realtimeBaseRef:1582804534764620602 realtimeFrequency:2299774438} D0227 11:55:35.764888 54532 parameters.go:238] Clock(Monotonic): error: 2 ns, adjusted frequency from 2299774573 Hz to 2299774643 Hz D0227 11:55:35.765148 54532 parameters.go:238] Clock(Realtime): error: 47 ns, adjusted frequency from 2299774438 Hz to 2299774733 Hz D0227 11:55:35.765279 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501067507249847 monotonicBaseRef:25002663118 monotonicFrequency:2299774643 realtimeReady:1 realtimeBaseCycles:8501067507892858 realtimeBaseRef:1582804535765141242 realtimeFrequency:2299774733} D0227 11:55:36.762557 54532 parameters.go:238] Clock(Monotonic): error: -6 ns, adjusted frequency from 2299774643 Hz to 2299774623 Hz D0227 11:55:36.762787 54532 parameters.go:238] Clock(Realtime): error: 8 ns, adjusted frequency from 2299774733 Hz to 2299774615 Hz D0227 11:55:36.762881 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501069801645741 monotonicBaseRef:26000324302 monotonicFrequency:2299774623 realtimeReady:1 realtimeBaseCycles:8501069802238337 realtimeBaseRef:1582804536762780465 realtimeFrequency:2299774615} D0227 11:55:37.763096 54532 parameters.go:238] Clock(Monotonic): error: 9 ns, adjusted frequency from 2299774623 Hz to 2299774664 Hz D0227 11:55:37.763303 54532 parameters.go:238] Clock(Realtime): error: -97 ns, adjusted frequency from 2299774615 Hz to 2299774381 Hz D0227 11:55:37.763428 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501072102694387 monotonicBaseRef:27000878279 monotonicFrequency:2299774664 realtimeReady:1 realtimeBaseCycles:8501072103203525 realtimeBaseRef:1582804537763298156 realtimeFrequency:2299774381} D0227 11:55:38.763966 54532 parameters.go:238] Clock(Monotonic): error: 55 ns, adjusted frequency from 2299774664 Hz to 2299774776 Hz D0227 11:55:38.764248 54532 parameters.go:238] Clock(Realtime): error: 161 ns, adjusted frequency from 2299774381 Hz to 2299774950 Hz D0227 11:55:38.764380 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501074404455357 monotonicBaseRef:28001741974 monotonicFrequency:2299774776 realtimeReady:1 realtimeBaseCycles:8501074405147692 realtimeBaseRef:1582804538764241633 realtimeFrequency:2299774950} D0227 11:55:39.764273 54532 parameters.go:238] Clock(Monotonic): error: -40 ns, adjusted frequency from 2299774776 Hz to 2299774551 Hz D0227 11:55:39.764607 54532 parameters.go:238] Clock(Realtime): error: 131 ns, adjusted frequency from 2299774950 Hz to 2299774883 Hz D0227 11:55:39.764706 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501076704937484 monotonicBaseRef:29002049548 monotonicFrequency:2299774551 realtimeReady:1 realtimeBaseCycles:8501076705746418 realtimeBaseRef:1582804539764599831 realtimeFrequency:2299774883} D0227 11:55:40.763860 54532 parameters.go:238] Clock(Monotonic): error: -22 ns, adjusted frequency from 2299774551 Hz to 2299774595 Hz D0227 11:55:40.764128 54532 parameters.go:238] Clock(Realtime): error: -89 ns, adjusted frequency from 2299774883 Hz to 2299774456 Hz D0227 11:55:40.764260 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501079003743618 monotonicBaseRef:30001628455 monotonicFrequency:2299774595 realtimeReady:1 realtimeBaseCycles:8501079004420145 realtimeBaseRef:1582804540764121020 realtimeFrequency:2299774456} D0227 11:55:41.766441 54532 parameters.go:238] Clock(Monotonic): error: 5 ns, adjusted frequency from 2299774595 Hz to 2299774645 Hz D0227 11:55:41.766615 54532 parameters.go:238] Clock(Realtime): error: -94 ns, adjusted frequency from 2299774456 Hz to 2299774432 Hz D0227 11:55:41.766714 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501081309494697 monotonicBaseRef:31004227180 monotonicFrequency:2299774645 realtimeReady:1 realtimeBaseCycles:8501081309918497 realtimeBaseRef:1582804541766609914 realtimeFrequency:2299774432} D0227 11:55:42.762912 54532 parameters.go:238] Clock(Monotonic): error: 37 ns, adjusted frequency from 2299774645 Hz to 2299774729 Hz D0227 11:55:42.763124 54532 parameters.go:238] Clock(Realtime): error: 34 ns, adjusted frequency from 2299774432 Hz to 2299774728 Hz D0227 11:55:42.763210 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501083601084207 monotonicBaseRef:32000668076 monotonicFrequency:2299774729 realtimeReady:1 realtimeBaseCycles:8501083601662529 realtimeBaseRef:1582804542763118093 realtimeFrequency:2299774728} D0227 11:55:43.764007 54532 parameters.go:238] Clock(Monotonic): error: -41 ns, adjusted frequency from 2299774729 Hz to 2299774553 Hz D0227 11:55:43.764248 54532 parameters.go:238] Clock(Realtime): error: -194 ns, adjusted frequency from 2299774728 Hz to 2299774260 Hz D0227 11:55:43.764372 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501085903437067 monotonicBaseRef:33001789112 monotonicFrequency:2299774553 realtimeReady:1 realtimeBaseCycles:8501085904020538 realtimeBaseRef:1582804543764241368 realtimeFrequency:2299774260} D0227 11:55:44.764322 54532 sampler.go:168] Time: Adjusting syscall overhead up to 8000 D0227 11:55:44.764515 54532 parameters.go:238] Clock(Monotonic): error: 251 ns, adjusted frequency from 2299774553 Hz to 2299775203 Hz D0227 11:55:44.764703 54532 parameters.go:238] Clock(Realtime): error: -9 ns, adjusted frequency from 2299774260 Hz to 2299774679 Hz D0227 11:55:44.764808 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501088204400125 monotonicBaseRef:34002305903 monotonicFrequency:2299775203 realtimeReady:1 realtimeBaseCycles:8501088204843087 realtimeBaseRef:1582804544764697190 realtimeFrequency:2299774679} D0227 11:55:45.354244 54532 urpc.go:577] urpc: unmarshal success. D0227 11:55:45.354498 54532 controller.go:501] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-2 Signo:0 PID:0 Mode:Process} D0227 11:55:45.354777 54532 urpc.go:534] urpc: successfully marshalled 37 bytes. D0227 11:55:45.762544 54532 parameters.go:238] Clock(Monotonic): error: -236 ns, adjusted frequency from 2299775203 Hz to 2299774101 Hz D0227 11:55:45.762776 54532 parameters.go:238] Clock(Realtime): error: 267 ns, adjusted frequency from 2299774679 Hz to 2299775253 Hz D0227 11:55:45.762897 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501090499616781 monotonicBaseRef:35000323732 monotonicFrequency:2299774101 realtimeReady:1 realtimeBaseCycles:8501090500186391 realtimeBaseRef:1582804545762770316 realtimeFrequency:2299775253} D0227 11:55:46.763679 54532 parameters.go:238] Clock(Monotonic): error: 260 ns, adjusted frequency from 2299774101 Hz to 2299775171 Hz D0227 11:55:46.763871 54532 parameters.go:238] Clock(Realtime): error: -80 ns, adjusted frequency from 2299775253 Hz to 2299774431 Hz D0227 11:55:46.764012 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501092801980974 monotonicBaseRef:36001449969 monotonicFrequency:2299775171 realtimeReady:1 realtimeBaseCycles:8501092802469016 realtimeBaseRef:1582804546763860584 realtimeFrequency:2299774431} D0227 11:55:47.763073 54532 parameters.go:238] Clock(Monotonic): error: 148 ns, adjusted frequency from 2299775171 Hz to 2299774921 Hz D0227 11:55:47.763333 54532 parameters.go:238] Clock(Realtime): error: 170 ns, adjusted frequency from 2299774431 Hz to 2299775007 Hz D0227 11:55:47.763489 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501095100397377 monotonicBaseRef:37000859142 monotonicFrequency:2299774921 realtimeReady:1 realtimeBaseCycles:8501095101015354 realtimeBaseRef:1582804547763326578 realtimeFrequency:2299775007} D0227 11:55:48.764591 54532 parameters.go:238] Clock(Monotonic): error: -238 ns, adjusted frequency from 2299774921 Hz to 2299774096 Hz D0227 11:55:48.764887 54532 parameters.go:238] Clock(Realtime): error: -337 ns, adjusted frequency from 2299775007 Hz to 2299773993 Hz D0227 11:55:48.765038 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501097403654587 monotonicBaseRef:38002373328 monotonicFrequency:2299774096 realtimeReady:1 realtimeBaseCycles:8501097404363207 realtimeBaseRef:1582804548764880141 realtimeFrequency:2299773993} D0227 11:55:49.762498 54532 parameters.go:238] Clock(Monotonic): error: -114 ns, adjusted frequency from 2299774096 Hz to 2299774377 Hz D0227 11:55:49.762696 54532 parameters.go:238] Clock(Realtime): error: 128 ns, adjusted frequency from 2299773993 Hz to 2299774928 Hz D0227 11:55:49.762829 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501099698612200 monotonicBaseRef:39000278999 monotonicFrequency:2299774377 realtimeReady:1 realtimeBaseCycles:8501099699099051 realtimeBaseRef:1582804549762689426 realtimeFrequency:2299774928} D0227 11:55:50.763063 54532 parameters.go:238] Clock(Monotonic): error: 327 ns, adjusted frequency from 2299774377 Hz to 2299775353 Hz D0227 11:55:50.763231 54532 parameters.go:238] Clock(Realtime): error: -82 ns, adjusted frequency from 2299774928 Hz to 2299774467 Hz D0227 11:55:50.763325 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501101999697160 monotonicBaseRef:40000848873 monotonicFrequency:2299775353 realtimeReady:1 realtimeBaseCycles:8501102000108019 realtimeBaseRef:1582804550763226017 realtimeFrequency:2299774467} D0227 11:55:51.764051 54532 parameters.go:238] Clock(Monotonic): error: 44 ns, adjusted frequency from 2299775353 Hz to 2299774665 Hz D0227 11:55:51.764225 54532 parameters.go:238] Clock(Realtime): error: -22 ns, adjusted frequency from 2299774467 Hz to 2299774606 Hz D0227 11:55:51.764313 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501104301745305 monotonicBaseRef:41001837139 monotonicFrequency:2299774665 realtimeReady:1 realtimeBaseCycles:8501104302164597 realtimeBaseRef:1582804551764218336 realtimeFrequency:2299774606} D0227 11:55:52.763571 54532 parameters.go:238] Clock(Monotonic): error: -302 ns, adjusted frequency from 2299774665 Hz to 2299773949 Hz D0227 11:55:52.763778 54532 parameters.go:238] Clock(Realtime): error: 672 ns, adjusted frequency from 2299774606 Hz to 2299776190 Hz D0227 11:55:52.763904 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501106600418420 monotonicBaseRef:42001358157 monotonicFrequency:2299773949 realtimeReady:1 realtimeBaseCycles:8501106600914173 realtimeBaseRef:1582804552763772627 realtimeFrequency:2299776190} D0227 11:55:53.762467 54532 parameters.go:238] Clock(Monotonic): error: 314 ns, adjusted frequency from 2299773949 Hz to 2299775259 Hz D0227 11:55:53.762653 54532 parameters.go:238] Clock(Realtime): error: -604 ns, adjusted frequency from 2299776190 Hz to 2299773210 Hz D0227 11:55:53.762789 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501108897654125 monotonicBaseRef:43000254464 monotonicFrequency:2299775259 realtimeReady:1 realtimeBaseCycles:8501108898101845 realtimeBaseRef:1582804553762647074 realtimeFrequency:2299773210} D0227 11:55:54.763561 54532 parameters.go:238] Clock(Monotonic): error: 54 ns, adjusted frequency from 2299775259 Hz to 2299774724 Hz D0227 11:55:54.763764 54532 parameters.go:238] Clock(Realtime): error: 27 ns, adjusted frequency from 2299773210 Hz to 2299774693 Hz D0227 11:55:54.763891 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501111199945878 monotonicBaseRef:44001348698 monotonicFrequency:2299774724 realtimeReady:1 realtimeBaseCycles:8501111200430390 realtimeBaseRef:1582804554763758198 realtimeFrequency:2299774693} D0227 11:55:55.763536 54532 parameters.go:238] Clock(Monotonic): error: -12 ns, adjusted frequency from 2299774724 Hz to 2299774617 Hz D0227 11:55:55.763764 54532 parameters.go:238] Clock(Realtime): error: 10 ns, adjusted frequency from 2299774693 Hz to 2299774689 Hz D0227 11:55:55.763875 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501113499662976 monotonicBaseRef:45001323640 monotonicFrequency:2299774617 realtimeReady:1 realtimeBaseCycles:8501113500205803 realtimeBaseRef:1582804555763758511 realtimeFrequency:2299774689} I0227 11:55:55.963446 54532 watchdog.go:277] Watchdog starting loop, tasks: 16, discount: 0s D0227 11:55:56.763583 54532 parameters.go:238] Clock(Monotonic): error: -354 ns, adjusted frequency from 2299774617 Hz to 2299773865 Hz D0227 11:55:56.763800 54532 parameters.go:238] Clock(Realtime): error: 100 ns, adjusted frequency from 2299774689 Hz to 2299774896 Hz D0227 11:55:56.763924 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501115799533209 monotonicBaseRef:46001365216 monotonicFrequency:2299773865 realtimeReady:1 realtimeBaseCycles:8501115800060738 realtimeBaseRef:1582804556763793403 realtimeFrequency:2299774896} D0227 11:55:57.763376 54532 parameters.go:238] Clock(Monotonic): error: 344 ns, adjusted frequency from 2299773865 Hz to 2299775293 Hz D0227 11:55:57.763562 54532 parameters.go:238] Clock(Realtime): error: -192 ns, adjusted frequency from 2299774896 Hz to 2299774166 Hz D0227 11:55:57.763679 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501118098839109 monotonicBaseRef:47001161732 monotonicFrequency:2299775293 realtimeReady:1 realtimeBaseCycles:8501118099290334 realtimeBaseRef:1582804557763556292 realtimeFrequency:2299774166} D0227 11:55:58.762483 54532 parameters.go:238] Clock(Monotonic): error: 7 ns, adjusted frequency from 2299775293 Hz to 2299774620 Hz D0227 11:55:58.762729 54532 parameters.go:238] Clock(Realtime): error: 289 ns, adjusted frequency from 2299774166 Hz to 2299775313 Hz D0227 11:55:58.762899 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501120396555275 monotonicBaseRef:48000266371 monotonicFrequency:2299774620 realtimeReady:1 realtimeBaseCycles:8501120397143996 realtimeBaseRef:1582804558762721208 realtimeFrequency:2299775313} D0227 11:55:59.762627 54532 parameters.go:238] Clock(Monotonic): error: 234 ns, adjusted frequency from 2299774620 Hz to 2299775163 Hz D0227 11:55:59.763872 54532 parameters.go:238] Clock(Realtime): error: -190 ns, adjusted frequency from 2299775313 Hz to 2299774178 Hz D0227 11:55:59.764009 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501122696664367 monotonicBaseRef:49000411807 monotonicFrequency:2299775163 realtimeReady:1 realtimeBaseCycles:8501122699551117 realtimeBaseRef:1582804559763865584 realtimeFrequency:2299774178} D0227 11:56:00.764155 54532 parameters.go:238] Clock(Monotonic): error: -146 ns, adjusted frequency from 2299775163 Hz to 2299774268 Hz D0227 11:56:00.764397 54532 parameters.go:238] Clock(Realtime): error: 117 ns, adjusted frequency from 2299774178 Hz to 2299774863 Hz D0227 11:56:00.764587 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501124999939329 monotonicBaseRef:50001933607 monotonicFrequency:2299774268 realtimeReady:1 realtimeBaseCycles:8501125000531217 realtimeBaseRef:1582804560764389949 realtimeFrequency:2299774863} D0227 11:56:01.763694 54532 parameters.go:238] Clock(Monotonic): error: -303 ns, adjusted frequency from 2299774268 Hz to 2299773943 Hz D0227 11:56:01.764821 54532 parameters.go:238] Clock(Realtime): error: -92 ns, adjusted frequency from 2299774863 Hz to 2299774351 Hz D0227 11:56:01.764951 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501127298657678 monotonicBaseRef:51001474466 monotonicFrequency:2299773943 realtimeReady:1 realtimeBaseCycles:8501127301273488 realtimeBaseRef:1582804561764810602 realtimeFrequency:2299774351} D0227 11:56:02.763599 54532 parameters.go:238] Clock(Monotonic): error: 6 ns, adjusted frequency from 2299773943 Hz to 2299774778 Hz D0227 11:56:02.763835 54532 parameters.go:238] Clock(Realtime): error: -205 ns, adjusted frequency from 2299774351 Hz to 2299774212 Hz D0227 11:56:02.763974 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501129598214737 monotonicBaseRef:52001380159 monotonicFrequency:2299774778 realtimeReady:1 realtimeBaseCycles:8501129598788015 realtimeBaseRef:1582804562763827973 realtimeFrequency:2299774212} D0227 11:56:03.763539 54532 parameters.go:238] Clock(Monotonic): error: 85 ns, adjusted frequency from 2299774778 Hz to 2299774883 Hz D0227 11:56:03.763776 54532 parameters.go:238] Clock(Realtime): error: 258 ns, adjusted frequency from 2299774212 Hz to 2299775233 Hz D0227 11:56:03.763908 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501131897856830 monotonicBaseRef:53001322464 monotonicFrequency:2299774883 realtimeReady:1 realtimeBaseCycles:8501131898426937 realtimeBaseRef:1582804563763769145 realtimeFrequency:2299775233} D0227 11:56:04.763555 54532 parameters.go:238] Clock(Monotonic): error: 119 ns, adjusted frequency from 2299774883 Hz to 2299774921 Hz D0227 11:56:04.763741 54532 parameters.go:238] Clock(Realtime): error: -117 ns, adjusted frequency from 2299775233 Hz to 2299774372 Hz D0227 11:56:04.763867 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501134197677226 monotonicBaseRef:54001342254 monotonicFrequency:2299774921 realtimeReady:1 realtimeBaseCycles:8501134198126975 realtimeBaseRef:1582804564763736448 realtimeFrequency:2299774372} D0227 11:56:05.763020 54532 parameters.go:238] Clock(Monotonic): error: -13 ns, adjusted frequency from 2299774921 Hz to 2299774551 Hz D0227 11:56:05.763205 54532 parameters.go:238] Clock(Realtime): error: 13 ns, adjusted frequency from 2299774372 Hz to 2299774691 Hz D0227 11:56:05.763312 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501136496213448 monotonicBaseRef:55000803636 monotonicFrequency:2299774551 realtimeReady:1 realtimeBaseCycles:8501136496664804 realtimeBaseRef:1582804565763198767 realtimeFrequency:2299774691} D0227 11:56:06.764010 54532 parameters.go:238] Clock(Monotonic): error: -194 ns, adjusted frequency from 2299774551 Hz to 2299774338 Hz D0227 11:56:06.764243 54532 parameters.go:238] Clock(Realtime): error: -206 ns, adjusted frequency from 2299774691 Hz to 2299774202 Hz D0227 11:56:06.764360 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501138798239078 monotonicBaseRef:56001782461 monotonicFrequency:2299774338 realtimeReady:1 realtimeBaseCycles:8501138798823876 realtimeBaseRef:1582804566764235555 realtimeFrequency:2299774202} D0227 11:56:07.763845 54532 parameters.go:238] Clock(Monotonic): error: -128 ns, adjusted frequency from 2299774338 Hz to 2299774385 Hz D0227 11:56:07.764039 54532 parameters.go:238] Clock(Realtime): error: 193 ns, adjusted frequency from 2299774202 Hz to 2299775084 Hz D0227 11:56:07.764160 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501141097635177 monotonicBaseRef:57001617993 monotonicFrequency:2299774385 realtimeReady:1 realtimeBaseCycles:8501141098134318 realtimeBaseRef:1582804567764033900 realtimeFrequency:2299775084} D0227 11:56:08.764340 54532 parameters.go:238] Clock(Monotonic): error: 45 ns, adjusted frequency from 2299774385 Hz to 2299774819 Hz D0227 11:56:08.764587 54532 parameters.go:238] Clock(Realtime): error: 774 ns, adjusted frequency from 2299775084 Hz to 2299776510 Hz D0227 11:56:08.764723 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501143398564549 monotonicBaseRef:58002120210 monotonicFrequency:2299774819 realtimeReady:1 realtimeBaseCycles:8501143399140044 realtimeBaseRef:1582804568764569014 realtimeFrequency:2299776510} D0227 11:56:09.764083 54532 parameters.go:238] Clock(Monotonic): error: 332 ns, adjusted frequency from 2299774819 Hz to 2299775471 Hz D0227 11:56:09.764330 54532 parameters.go:238] Clock(Realtime): error: -1021 ns, adjusted frequency from 2299776510 Hz to 2299772375 Hz D0227 11:56:09.764459 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501145697744527 monotonicBaseRef:59001861558 monotonicFrequency:2299775471 realtimeReady:1 realtimeBaseCycles:8501145698350053 realtimeBaseRef:1582804569764322685 realtimeFrequency:2299772375} D0227 11:56:10.763464 54532 parameters.go:238] Clock(Monotonic): error: -468 ns, adjusted frequency from 2299775471 Hz to 2299773549 Hz D0227 11:56:10.764561 54532 parameters.go:238] Clock(Realtime): error: 10 ns, adjusted frequency from 2299772375 Hz to 2299774732 Hz D0227 11:56:10.764683 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501147996099059 monotonicBaseRef:60001243698 monotonicFrequency:2299773549 realtimeReady:1 realtimeBaseCycles:8501147998652910 realtimeBaseRef:1582804570764553352 realtimeFrequency:2299774732} D0227 11:56:11.763627 54532 parameters.go:238] Clock(Monotonic): error: 627 ns, adjusted frequency from 2299773549 Hz to 2299776034 Hz D0227 11:56:11.763821 54532 parameters.go:238] Clock(Realtime): error: 254 ns, adjusted frequency from 2299774732 Hz to 2299775210 Hz D0227 11:56:11.764000 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501150296257727 monotonicBaseRef:61001411157 monotonicFrequency:2299776034 realtimeReady:1 realtimeBaseCycles:8501150296718830 realtimeBaseRef:1582804571763810317 realtimeFrequency:2299775210} D0227 11:56:12.762543 54532 parameters.go:238] Clock(Monotonic): error: -587 ns, adjusted frequency from 2299776034 Hz to 2299773290 Hz D0227 11:56:12.762802 54532 parameters.go:238] Clock(Realtime): error: -46 ns, adjusted frequency from 2299775210 Hz to 2299774534 Hz D0227 11:56:12.762900 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501152593533463 monotonicBaseRef:62000323965 monotonicFrequency:2299773290 realtimeReady:1 realtimeBaseCycles:8501152594160158 realtimeBaseRef:1582804572762795486 realtimeFrequency:2299774534} D0227 11:56:13.763872 54532 parameters.go:238] Clock(Monotonic): error: 9 ns, adjusted frequency from 2299773290 Hz to 2299774716 Hz D0227 11:56:13.764089 54532 parameters.go:238] Clock(Realtime): error: 7 ns, adjusted frequency from 2299774534 Hz to 2299774665 Hz D0227 11:56:13.764177 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501154896357372 monotonicBaseRef:63001650451 monotonicFrequency:2299774716 realtimeReady:1 realtimeBaseCycles:8501154896896129 realtimeBaseRef:1582804573764083193 realtimeFrequency:2299774665} D0227 11:56:14.763256 54532 parameters.go:238] Clock(Monotonic): error: -24 ns, adjusted frequency from 2299774716 Hz to 2299774665 Hz D0227 11:56:14.763472 54532 parameters.go:238] Clock(Realtime): error: 360 ns, adjusted frequency from 2299774665 Hz to 2299775523 Hz D0227 11:56:14.763629 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501157194710356 monotonicBaseRef:64001032246 monotonicFrequency:2299774665 realtimeReady:1 realtimeBaseCycles:8501157195250081 realtimeBaseRef:1582804574763465431 realtimeFrequency:2299775523} D0227 11:56:15.764897 54532 sampler.go:197] Time: Adjusting syscall overhead down to 7000 D0227 11:56:15.765129 54532 parameters.go:238] Clock(Monotonic): error: -54 ns, adjusted frequency from 2299774665 Hz to 2299774507 Hz D0227 11:56:15.765295 54532 parameters.go:238] Clock(Realtime): error: -680 ns, adjusted frequency from 2299775523 Hz to 2299773077 Hz D0227 11:56:15.765418 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501159498286690 monotonicBaseRef:65002685307 monotonicFrequency:2299774507 realtimeReady:1 realtimeBaseCycles:8501159499217521 realtimeBaseRef:1582804575765288181 realtimeFrequency:2299773077} D0227 11:56:16.763769 54532 parameters.go:238] Clock(Monotonic): error: 45 ns, adjusted frequency from 2299774507 Hz to 2299774749 Hz D0227 11:56:16.763989 54532 parameters.go:238] Clock(Realtime): error: 174 ns, adjusted frequency from 2299773077 Hz to 2299775043 Hz D0227 11:56:16.764120 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501161795445732 monotonicBaseRef:66001548036 monotonicFrequency:2299774749 realtimeReady:1 realtimeBaseCycles:8501161795989597 realtimeBaseRef:1582804576763983269 realtimeFrequency:2299775043} D0227 11:56:17.763531 54532 parameters.go:238] Clock(Monotonic): error: -32 ns, adjusted frequency from 2299774749 Hz to 2299774542 Hz D0227 11:56:17.763760 54532 parameters.go:238] Clock(Realtime): error: 87 ns, adjusted frequency from 2299775043 Hz to 2299774743 Hz D0227 11:56:17.763894 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501164094683113 monotonicBaseRef:67001314374 monotonicFrequency:2299774542 realtimeReady:1 realtimeBaseCycles:8501164095237044 realtimeBaseRef:1582804577763753856 realtimeFrequency:2299774743} D0227 11:56:18.763760 54532 parameters.go:238] Clock(Monotonic): error: 44 ns, adjusted frequency from 2299774542 Hz to 2299774746 Hz D0227 11:56:18.763989 54532 parameters.go:238] Clock(Realtime): error: 276 ns, adjusted frequency from 2299774743 Hz to 2299775215 Hz D0227 11:56:18.764153 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501166394990354 monotonicBaseRef:68001546004 monotonicFrequency:2299774746 realtimeReady:1 realtimeBaseCycles:8501166395535824 realtimeBaseRef:1582804578763981720 realtimeFrequency:2299775215} D0227 11:56:18.966785 54532 urpc.go:577] urpc: unmarshal success. D0227 11:56:18.966998 54532 controller.go:501] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-2 Signo:0 PID:0 Mode:Process} D0227 11:56:18.967280 54532 urpc.go:534] urpc: successfully marshalled 37 bytes. D0227 11:56:19.762617 54532 parameters.go:238] Clock(Monotonic): error: 358 ns, adjusted frequency from 2299774746 Hz to 2299775443 Hz D0227 11:56:19.762804 54532 parameters.go:238] Clock(Realtime): error: -263 ns, adjusted frequency from 2299775215 Hz to 2299774008 Hz D0227 11:56:19.762933 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501168692136812 monotonicBaseRef:69000403158 monotonicFrequency:2299775443 realtimeReady:1 realtimeBaseCycles:8501168692591815 realtimeBaseRef:1582804579762799333 realtimeFrequency:2299774008} D0227 11:56:20.762823 54532 parameters.go:238] Clock(Monotonic): error: -311 ns, adjusted frequency from 2299775443 Hz to 2299773931 Hz D0227 11:56:20.762990 54532 parameters.go:238] Clock(Realtime): error: 69 ns, adjusted frequency from 2299774008 Hz to 2299774795 Hz D0227 11:56:20.763098 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501170992374889 monotonicBaseRef:70000604322 monotonicFrequency:2299773931 realtimeReady:1 realtimeBaseCycles:8501170992793023 realtimeBaseRef:1582804580762985090 realtimeFrequency:2299774795} D0227 11:56:21.763619 54532 parameters.go:238] Clock(Monotonic): error: 140 ns, adjusted frequency from 2299773931 Hz to 2299774953 Hz D0227 11:56:21.763926 54532 parameters.go:238] Clock(Realtime): error: 326 ns, adjusted frequency from 2299774795 Hz to 2299775441 Hz D0227 11:56:21.764125 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501173293926956 monotonicBaseRef:71001377500 monotonicFrequency:2299774953 realtimeReady:1 realtimeBaseCycles:8501173294710096 realtimeBaseRef:1582804581763916606 realtimeFrequency:2299775441} D0227 11:56:22.762682 54532 parameters.go:238] Clock(Monotonic): error: -290 ns, adjusted frequency from 2299774953 Hz to 2299773969 Hz D0227 11:56:22.762866 54532 parameters.go:238] Clock(Realtime): error: -433 ns, adjusted frequency from 2299775441 Hz to 2299773644 Hz D0227 11:56:22.763001 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501175591612797 monotonicBaseRef:72000469101 monotonicFrequency:2299773969 realtimeReady:1 realtimeBaseCycles:8501175592056283 realtimeBaseRef:1582804582762860305 realtimeFrequency:2299773644} D0227 11:56:23.763799 54532 parameters.go:238] Clock(Monotonic): error: 158 ns, adjusted frequency from 2299773969 Hz to 2299775014 Hz D0227 11:56:23.764046 54532 parameters.go:238] Clock(Realtime): error: 81 ns, adjusted frequency from 2299773644 Hz to 2299774747 Hz D0227 11:56:23.764157 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501177893948133 monotonicBaseRef:73001582848 monotonicFrequency:2299775014 realtimeReady:1 realtimeBaseCycles:8501177894543156 realtimeBaseRef:1582804583764040085 realtimeFrequency:2299774747} D0227 11:56:24.762564 54532 parameters.go:238] Clock(Monotonic): error: -55 ns, adjusted frequency from 2299775014 Hz to 2299774520 Hz D0227 11:56:24.762785 54532 parameters.go:238] Clock(Realtime): error: 48 ns, adjusted frequency from 2299774747 Hz to 2299774694 Hz D0227 11:56:24.762894 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501180190887616 monotonicBaseRef:74000349887 monotonicFrequency:2299774520 realtimeReady:1 realtimeBaseCycles:8501180191417725 realtimeBaseRef:1582804584762779014 realtimeFrequency:2299774694} D0227 11:56:25.763153 54532 parameters.go:238] Clock(Monotonic): error: -2 ns, adjusted frequency from 2299774520 Hz to 2299774635 Hz D0227 11:56:25.763355 54532 parameters.go:238] Clock(Realtime): error: 17 ns, adjusted frequency from 2299774694 Hz to 2299774679 Hz D0227 11:56:25.763455 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501182492007693 monotonicBaseRef:75000934969 monotonicFrequency:2299774635 realtimeReady:1 realtimeBaseCycles:8501182492507340 realtimeBaseRef:1582804585763350774 realtimeFrequency:2299774679} D0227 11:56:26.764058 54532 parameters.go:238] Clock(Monotonic): error: -17 ns, adjusted frequency from 2299774635 Hz to 2299774620 Hz D0227 11:56:26.764274 54532 parameters.go:238] Clock(Realtime): error: -6 ns, adjusted frequency from 2299774679 Hz to 2299774604 Hz D0227 11:56:26.764417 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501184793845075 monotonicBaseRef:76001831903 monotonicFrequency:2299774620 realtimeReady:1 realtimeBaseCycles:8501184794390885 realtimeBaseRef:1582804586764267762 realtimeFrequency:2299774604} D0227 11:56:27.764232 54532 parameters.go:238] Clock(Monotonic): error: 324 ns, adjusted frequency from 2299774620 Hz to 2299775365 Hz D0227 11:56:27.764440 54532 parameters.go:238] Clock(Realtime): error: -254 ns, adjusted frequency from 2299774604 Hz to 2299774116 Hz D0227 11:56:27.764571 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501187094044388 monotonicBaseRef:77002016570 monotonicFrequency:2299775365 realtimeReady:1 realtimeBaseCycles:8501187094523743 realtimeBaseRef:1582804587764423539 realtimeFrequency:2299774116} D0227 11:56:28.764263 54532 parameters.go:238] Clock(Monotonic): error: -291 ns, adjusted frequency from 2299775365 Hz to 2299774015 Hz D0227 11:56:28.764457 54532 parameters.go:238] Clock(Realtime): error: 194 ns, adjusted frequency from 2299774116 Hz to 2299775070 Hz D0227 11:56:28.764581 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501189393888120 monotonicBaseRef:78002046297 monotonicFrequency:2299774015 realtimeReady:1 realtimeBaseCycles:8501189394362768 realtimeBaseRef:1582804588764451763 realtimeFrequency:2299775070} D0227 11:56:29.765472 54532 parameters.go:238] Clock(Monotonic): error: 8 ns, adjusted frequency from 2299774015 Hz to 2299774695 Hz D0227 11:56:29.765760 54532 parameters.go:238] Clock(Realtime): error: -161 ns, adjusted frequency from 2299775070 Hz to 2299774305 Hz D0227 11:56:29.765917 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501191696433529 monotonicBaseRef:79003251369 monotonicFrequency:2299774695 realtimeReady:1 realtimeBaseCycles:8501191697131610 realtimeBaseRef:1582804589765753530 realtimeFrequency:2299774305} D0227 11:56:30.764670 54532 parameters.go:238] Clock(Monotonic): error: -151 ns, adjusted frequency from 2299774695 Hz to 2299774298 Hz D0227 11:56:30.764935 54532 parameters.go:238] Clock(Realtime): error: 325 ns, adjusted frequency from 2299774305 Hz to 2299775358 Hz D0227 11:56:30.765071 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501193994371823 monotonicBaseRef:80002452855 monotonicFrequency:2299774298 realtimeReady:1 realtimeBaseCycles:8501193995006643 realtimeBaseRef:1582804590764927678 realtimeFrequency:2299775358} D0227 11:56:31.762545 54532 parameters.go:238] Clock(Monotonic): error: 132 ns, adjusted frequency from 2299774298 Hz to 2299774960 Hz D0227 11:56:31.762775 54532 parameters.go:238] Clock(Realtime): error: -82 ns, adjusted frequency from 2299775358 Hz to 2299774416 Hz D0227 11:56:31.762867 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501196289227608 monotonicBaseRef:81000314161 monotonicFrequency:2299774960 realtimeReady:1 realtimeBaseCycles:8501196289816049 realtimeBaseRef:1582804591762768357 realtimeFrequency:2299774416} D0227 11:56:32.762475 54532 parameters.go:238] Clock(Monotonic): error: 225 ns, adjusted frequency from 2299774960 Hz to 2299775065 Hz D0227 11:56:32.762692 54532 parameters.go:238] Clock(Realtime): error: 51 ns, adjusted frequency from 2299774416 Hz to 2299774737 Hz D0227 11:56:32.762805 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501198588879082 monotonicBaseRef:82000260466 monotonicFrequency:2299775065 realtimeReady:1 realtimeBaseCycles:8501198589400685 realtimeBaseRef:1582804592762685835 realtimeFrequency:2299774737} D0227 11:56:33.763116 54532 parameters.go:238] Clock(Monotonic): error: -208 ns, adjusted frequency from 2299775065 Hz to 2299774164 Hz D0227 11:56:33.763351 54532 parameters.go:238] Clock(Realtime): error: -312 ns, adjusted frequency from 2299774737 Hz to 2299773983 Hz D0227 11:56:33.763494 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501200890119617 monotonicBaseRef:83000897689 monotonicFrequency:2299774164 realtimeReady:1 realtimeBaseCycles:8501200890688579 realtimeBaseRef:1582804593763343793 realtimeFrequency:2299773983} D0227 11:56:34.764867 54532 parameters.go:238] Clock(Monotonic): error: 5 ns, adjusted frequency from 2299774164 Hz to 2299774654 Hz D0227 11:56:34.765124 54532 parameters.go:238] Clock(Realtime): error: -43 ns, adjusted frequency from 2299773983 Hz to 2299774590 Hz D0227 11:56:34.765283 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501203193916555 monotonicBaseRef:84002646892 monotonicFrequency:2299774654 realtimeReady:1 realtimeBaseCycles:8501203194540982 realtimeBaseRef:1582804594765117193 realtimeFrequency:2299774590} D0227 11:56:35.763690 54532 parameters.go:238] Clock(Monotonic): error: -34 ns, adjusted frequency from 2299774654 Hz to 2299774567 Hz D0227 11:56:35.763926 54532 parameters.go:238] Clock(Realtime): error: 204 ns, adjusted frequency from 2299774590 Hz to 2299775153 Hz D0227 11:56:35.764089 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501205490998451 monotonicBaseRef:85001476012 monotonicFrequency:2299774567 realtimeReady:1 realtimeBaseCycles:8501205491566737 realtimeBaseRef:1582804595763921930 realtimeFrequency:2299775153} D0227 11:56:36.763154 54532 parameters.go:238] Clock(Monotonic): error: 41 ns, adjusted frequency from 2299774567 Hz to 2299774808 Hz D0227 11:56:36.763377 54532 parameters.go:238] Clock(Realtime): error: 58 ns, adjusted frequency from 2299775153 Hz to 2299774726 Hz D0227 11:56:36.763533 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501207789499810 monotonicBaseRef:86000922389 monotonicFrequency:2299774808 realtimeReady:1 realtimeBaseCycles:8501207790073107 realtimeBaseRef:1582804596763370231 realtimeFrequency:2299774726} D0227 11:56:37.763698 54532 parameters.go:238] Clock(Monotonic): error: 198 ns, adjusted frequency from 2299774808 Hz to 2299775118 Hz D0227 11:56:37.764019 54532 parameters.go:238] Clock(Realtime): error: 290 ns, adjusted frequency from 2299774726 Hz to 2299775297 Hz D0227 11:56:37.764290 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501210090557600 monotonicBaseRef:87001480261 monotonicFrequency:2299775118 realtimeReady:1 realtimeBaseCycles:8501210091320555 realtimeBaseRef:1582804597764010607 realtimeFrequency:2299775297} D0227 11:56:38.763244 54532 parameters.go:238] Clock(Monotonic): error: -95 ns, adjusted frequency from 2299775118 Hz to 2299774393 Hz D0227 11:56:38.763510 54532 parameters.go:238] Clock(Realtime): error: -554 ns, adjusted frequency from 2299775297 Hz to 2299773362 Hz D0227 11:56:38.763656 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501212389280271 monotonicBaseRef:88001022630 monotonicFrequency:2299774393 realtimeReady:1 realtimeBaseCycles:8501212389930008 realtimeBaseRef:1582804598763503668 realtimeFrequency:2299773362} D0227 11:56:39.765208 54532 parameters.go:238] Clock(Monotonic): error: -190 ns, adjusted frequency from 2299774393 Hz to 2299774197 Hz D0227 11:56:39.765407 54532 parameters.go:238] Clock(Realtime): error: 184 ns, adjusted frequency from 2299773362 Hz to 2299775079 Hz D0227 11:56:39.765641 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501214693569321 monotonicBaseRef:89002985716 monotonicFrequency:2299774197 realtimeReady:1 realtimeBaseCycles:8501214694071782 realtimeBaseRef:1582804599765403164 realtimeFrequency:2299775079} D0227 11:56:40.763107 54532 parameters.go:238] Clock(Monotonic): error: 30 ns, adjusted frequency from 2299774197 Hz to 2299774691 Hz D0227 11:56:40.763323 54532 parameters.go:238] Clock(Realtime): error: 133 ns, adjusted frequency from 2299775079 Hz to 2299775005 Hz D0227 11:56:40.763435 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501216988520060 monotonicBaseRef:90000888354 monotonicFrequency:2299774691 realtimeReady:1 realtimeBaseCycles:8501216989052478 realtimeBaseRef:1582804600763318445 realtimeFrequency:2299775005} I0227 11:56:40.965129 54532 watchdog.go:277] Watchdog starting loop, tasks: 16, discount: 0s D0227 11:56:41.491184 54532 urpc.go:577] urpc: unmarshal success. D0227 11:56:41.491435 54532 controller.go:501] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-2 Signo:0 PID:0 Mode:Process} D0227 11:56:41.491674 54532 urpc.go:534] urpc: successfully marshalled 37 bytes. D0227 11:56:41.763162 54532 parameters.go:238] Clock(Monotonic): error: 120 ns, adjusted frequency from 2299774691 Hz to 2299775013 Hz D0227 11:56:41.763395 54532 parameters.go:238] Clock(Realtime): error: 42 ns, adjusted frequency from 2299775005 Hz to 2299774793 Hz D0227 11:56:41.763502 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501219288428023 monotonicBaseRef:91000946304 monotonicFrequency:2299775013 realtimeReady:1 realtimeBaseCycles:8501219288989692 realtimeBaseRef:1582804601763388977 realtimeFrequency:2299774793} D0227 11:56:42.763642 54532 parameters.go:238] Clock(Monotonic): error: -150 ns, adjusted frequency from 2299775013 Hz to 2299774293 Hz D0227 11:56:42.763857 54532 parameters.go:238] Clock(Realtime): error: -141 ns, adjusted frequency from 2299774793 Hz to 2299774249 Hz D0227 11:56:42.764005 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501221589305953 monotonicBaseRef:92001425880 monotonicFrequency:2299774293 realtimeReady:1 realtimeBaseCycles:8501221589819568 realtimeBaseRef:1582804602763847753 realtimeFrequency:2299774249} I0227 11:56:43.130183 64173 main.go:296] *************************** I0227 11:56:43.130324 64173 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-2 /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=0 -threaded=true -collide=true -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller787230553] I0227 11:56:43.130710 64173 main.go:298] Version release-20200219.0-47-gde0b2ebf8635 I0227 11:56:43.130800 64173 main.go:299] PID: 64173 I0227 11:56:43.130891 64173 main.go:300] UID: 0, GID: 0 I0227 11:56:43.130984 64173 main.go:301] Configuration: I0227 11:56:43.131066 64173 main.go:302] RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root I0227 11:56:43.131147 64173 main.go:303] Platform: ptrace I0227 11:56:43.131217 64173 main.go:304] FileAccess: shared, overlay: false I0227 11:56:43.131301 64173 main.go:305] Network: sandbox, logging: false I0227 11:56:43.131385 64173 main.go:306] Strace: false, max size: 1024, syscalls: [] I0227 11:56:43.131470 64173 main.go:307] *************************** W0227 11:56:43.131544 64173 main.go:312] Block the TERM signal. This is only safe in tests! D0227 11:56:43.131873 64173 container.go:158] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-2" D0227 11:56:43.140070 64173 container.go:591] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-2": signal 0 D0227 11:56:43.140245 64173 sandbox.go:788] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0227 11:56:43.141440 54532 urpc.go:577] urpc: unmarshal success. D0227 11:56:43.141658 54532 controller.go:501] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-2 Signo:0 PID:0 Mode:Process} D0227 11:56:43.141890 54532 urpc.go:534] urpc: successfully marshalled 37 bytes. D0227 11:56:43.140380 64173 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0227 11:56:43.142008 64173 urpc.go:534] urpc: successfully marshalled 117 bytes. D0227 11:56:43.142214 64173 urpc.go:577] urpc: unmarshal success. D0227 11:56:43.142381 64173 exec.go:120] Exec arguments: /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=0 -threaded=true -collide=true -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller787230553 D0227 11:56:43.142478 64173 exec.go:121] Exec capablities: &{PermittedCaps:274877898751 InheritableCaps:274877898751 EffectiveCaps:274877898751 BoundingCaps:274877898751 AmbientCaps:0} D0227 11:56:43.142710 64173 container.go:532] Execute in container "ci-gvisor-ptrace-proxy-sandbox-race-2", args: /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=0 -threaded=true -collide=true -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller787230553 D0227 11:56:43.142902 64173 sandbox.go:283] Executing new process in container "ci-gvisor-ptrace-proxy-sandbox-race-2" in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0227 11:56:43.143038 64173 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0227 11:56:43.144853 54532 urpc.go:577] urpc: unmarshal success. D0227 11:56:43.147343 54532 controller.go:267] containerManager.ExecuteAsync: /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=0 -threaded=true -collide=true -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller787230553 D0227 11:56:43.147637 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 5, Names: [etc]} D0227 11:56:43.147863 64173 urpc.go:534] urpc: successfully marshalled 634 bytes. D0227 11:56:43.148372 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:43.148603 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0227 11:56:43.149171 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} I0227 11:56:43.150377 54532 kernel.go:795] EXEC: [/syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=0 -threaded=true -collide=true -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller787230553] D0227 11:56:43.152312 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 5, Names: [syz-execprog]} D0227 11:56:43.153908 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] 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: 1582804512, NanoSec: 49055914}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16515331}]} D0227 11:56:43.154126 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syz-execprog, Size: 1} D0227 11:56:43.154593 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:43.154978 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetattr{FID: 5, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0227 11:56:43.155967 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] 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: 1582804512, NanoSec: 49055914}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0227 11:56:43.156150 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalk{FID: 5, NewFID: 6, Names: []} D0227 11:56:43.157092 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rwalk{QIDs: []} D0227 11:56:43.157231 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tlopen{FID: 6, Flags: ReadOnly} D0227 11:56:43.158140 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlopen{QID: QID{Type: 0, Version: 0, Path: 16515331}, IoUnit: 0, File: &{{33}}} D0227 11:56:43.163826 54532 syscalls.go:266] Allocating stack with size of 8388608 bytes D0227 11:56:43.168426 54532 task_block.go:223] [ 22] Interrupt queued D0227 11:56:43.172121 54532 loader.go:832] updated processes: map[{ci-gvisor-ptrace-proxy-sandbox-race-2 0}:0xc0002dee60 {ci-gvisor-ptrace-proxy-sandbox-race-2 22}:0xc000330d40] D0227 11:56:43.172566 54532 urpc.go:534] urpc: successfully marshalled 37 bytes. D0227 11:56:43.172771 64173 urpc.go:577] urpc: unmarshal success. D0227 11:56:43.172917 64173 container.go:579] Wait on PID 22 in container "ci-gvisor-ptrace-proxy-sandbox-race-2" D0227 11:56:43.173097 64173 sandbox.go:743] Waiting for PID 22 in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0227 11:56:43.173210 64173 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0227 11:56:43.173514 64173 urpc.go:534] urpc: successfully marshalled 100 bytes. D0227 11:56:43.174552 54532 urpc.go:577] urpc: unmarshal success. D0227 11:56:43.174896 54532 controller.go:443] containerManager.Wait D0227 11:56:43.406510 54532 task_block.go:223] [ 23] Interrupt queued D0227 11:56:43.451576 54532 task_block.go:223] [ 24] Interrupt queued D0227 11:56:43.462897 54532 task_block.go:223] [ 25] Interrupt queued D0227 11:56:43.531613 54532 task_block.go:223] [ 26] Interrupt queued D0227 11:56:43.532526 54532 task_block.go:223] [ 27] Interrupt queued D0227 11:56:43.540086 54532 task_block.go:223] [ 28] Interrupt queued D0227 11:56:43.589093 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 7, Names: [etc]} D0227 11:56:43.589851 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:43.590054 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0227 11:56:43.590669 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:43.665166 54532 task_block.go:223] [ 29] Interrupt queued D0227 11:56:43.672545 54532 task_block.go:223] [ 30] Interrupt queued D0227 11:56:43.682100 54532 task_block.go:223] [ 31] Interrupt queued D0227 11:56:43.716841 54532 task_block.go:223] [ 32] Interrupt queued D0227 11:56:43.737466 54532 task_block.go:223] [ 33] Interrupt queued D0227 11:56:43.763782 54532 parameters.go:238] Clock(Monotonic): error: 340 ns, adjusted frequency from 2299774293 Hz to 2299775368 Hz D0227 11:56:43.764128 54532 parameters.go:238] Clock(Realtime): error: -201 ns, adjusted frequency from 2299774249 Hz to 2299774183 Hz D0227 11:56:43.764290 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501223889372533 monotonicBaseRef:93001552973 monotonicFrequency:2299775368 realtimeReady:1 realtimeBaseCycles:8501223890223498 realtimeBaseRef:1582804603764121554 realtimeFrequency:2299774183} D0227 11:56:43.774194 54532 task_block.go:223] [ 34] Interrupt queued D0227 11:56:43.785899 54532 task_block.go:223] [ 35] Interrupt queued D0227 11:56:43.815460 54532 task_block.go:223] [ 36] Interrupt queued D0227 11:56:43.824161 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 7, Names: [syzkaller787230553]} D0227 11:56:43.825634 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] 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: 314, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1582804603, NanoSec: 81975487}, MTime: {Sec: 1582804603, NanoSec: 81975487}, CTime: {Sec: 1582804603, NanoSec: 81975487}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16515213}]} D0227 11:56:43.825895 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzkaller787230553, Size: 1} D0227 11:56:43.826564 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:43.826901 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetattr{FID: 7, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0227 11:56:43.827832 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16515213}, Attr: Attr{Mode: 0o100600, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 314, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1582804603, NanoSec: 81975487}, MTime: {Sec: 1582804603, NanoSec: 81975487}, CTime: {Sec: 1582804603, NanoSec: 81975487}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0227 11:56:43.828034 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalk{FID: 7, NewFID: 8, Names: []} D0227 11:56:43.829212 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rwalk{QIDs: []} D0227 11:56:43.829384 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tlopen{FID: 8, Flags: ReadOnly} D0227 11:56:43.830340 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlopen{QID: QID{Type: 0, Version: 0, Path: 16515213}, IoUnit: 0, File: &{{27}}} D0227 11:56:43.834292 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetattr{FID: 8, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0227 11:56:43.835422 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16515213}, Attr: Attr{Mode: 0o100600, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 314, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1582804603, NanoSec: 81975487}, MTime: {Sec: 1582804603, NanoSec: 81975487}, CTime: {Sec: 1582804603, NanoSec: 81975487}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0227 11:56:43.839790 54532 task_block.go:223] [ 37] Interrupt queued 2020/02/27 11:56:43 parsed 1 programs D0227 11:56:43.857569 54532 task_stop.go:118] [ 26] Entering internal stop (*kernel.vforkStop)(nil) D0227 11:56:43.858350 54532 task_block.go:223] [ 38] Interrupt queued D0227 11:56:43.866132 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 9, Names: [syz-executor]} D0227 11:56:43.870000 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] 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: 1582804512, NanoSec: 53056306}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16515335}]} D0227 11:56:43.870351 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syz-executor, Size: 1} D0227 11:56:43.871271 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:43.871617 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0227 11:56:43.872507 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16515335}, 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: 1582804512, NanoSec: 53056306}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0227 11:56:43.872917 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalk{FID: 9, NewFID: 10, Names: []} D0227 11:56:43.873440 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rwalk{QIDs: []} D0227 11:56:43.873640 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tlopen{FID: 10, Flags: ReadOnly} D0227 11:56:43.874298 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlopen{QID: QID{Type: 0, Version: 0, Path: 16515335}, IoUnit: 0, File: &{{28}}} D0227 11:56:43.876092 54532 syscalls.go:266] [ 38] Allocating stack with size of 8388608 bytes D0227 11:56:43.877119 54532 task_stop.go:137] [ 26] Leaving internal stop (*kernel.vforkStop)(nil) D0227 11:56:43.904788 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [etc]} D0227 11:56:43.909743 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:43.909966 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0227 11:56:43.910440 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:43.934017 54532 task_exit.go:221] [ 38] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:43.935251 54532 task_exit.go:221] [ 38] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:43.935422 54532 task_signals.go:446] [ 22] Notified of signal 17 D0227 11:56:43.935542 54532 task_block.go:223] [ 22] Interrupt queued D0227 11:56:43.935858 54532 task_block.go:223] [ 22] Interrupt queued D0227 11:56:43.936079 54532 task_signals.go:179] [ 22] Restarting syscall 202 after errno 512: interrupted by signal 17 D0227 11:56:43.936200 54532 task_signals.go:220] [ 22] Signal 17: delivering to handler D0227 11:56:43.936371 54532 task_exit.go:221] [ 38] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:43.946558 54532 task_block.go:223] [ 39] Interrupt queued D0227 11:56:43.949354 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syz-executor]} D0227 11:56:43.951515 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] 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: 1582804512, NanoSec: 53056306}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16515335}]} D0227 11:56:43.951692 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tclunk{FID: 11} D0227 11:56:43.952205 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rclunk{} D0227 11:56:43.953533 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0227 11:56:43.954745 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16515335}, 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: 1582804512, NanoSec: 53056306}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} 2020/02/27 11:56:43 executed programs: 0 D0227 11:56:43.986814 54532 task_stop.go:118] [ 37] Entering internal stop (*kernel.vforkStop)(nil) D0227 11:56:43.987673 54532 task_block.go:223] [ 40] Interrupt queued D0227 11:56:44.017253 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syz-executor]} D0227 11:56:44.020433 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] 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: 1582804512, NanoSec: 53056306}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16515335}]} D0227 11:56:44.020778 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tclunk{FID: 11} D0227 11:56:44.021620 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rclunk{} D0227 11:56:44.021980 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0227 11:56:44.024570 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16515335}, 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: 1582804512, NanoSec: 53056306}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0227 11:56:44.025890 54532 syscalls.go:266] [ 40] Allocating stack with size of 8388608 bytes D0227 11:56:44.027649 54532 task_stop.go:137] [ 37] Leaving internal stop (*kernel.vforkStop)(nil) D0227 11:56:44.041337 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [etc]} D0227 11:56:44.041946 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:44.042076 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0227 11:56:44.044660 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:44.060518 54532 task_block.go:223] [ 41] Interrupt queued D0227 11:56:44.071282 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:44.071968 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:44.072105 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:44.073273 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:44.073934 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:44.074621 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:44.074760 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:44.075251 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:44.075706 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:44.077696 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:44.077871 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:44.081308 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:44.081761 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:44.082271 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:44.082401 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:44.082843 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:44.083308 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:44.083740 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:44.083875 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:44.084837 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:44.085157 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:44.085603 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:44.085727 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:44.086120 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:44.086339 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:44.086687 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:44.086778 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:44.087094 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:44.087334 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:44.087672 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:44.087782 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:44.088175 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:44.088632 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:44.089147 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:44.089326 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:44.089787 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:44.133152 54532 netfilter.go:54] netfilter: convert to binary: current offset: 0 D0227 11:56:44.133355 54532 netfilter.go:54] netfilter: convert to binary: found hook 0 at offset 0 D0227 11:56:44.133471 54532 netfilter.go:54] netfilter: convert to binary: found underflow 0 at offset 0 D0227 11:56:44.133565 54532 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 11:56:44.133668 54532 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 11:56:44.134206 54532 netfilter.go:54] netfilter: convert to binary: current offset: 152 D0227 11:56:44.134283 54532 netfilter.go:54] netfilter: convert to binary: found hook 3 at offset 152 D0227 11:56:44.134353 54532 netfilter.go:54] netfilter: convert to binary: found underflow 3 at offset 152 D0227 11:56:44.134460 54532 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 11:56:44.134539 54532 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 11:56:44.135018 54532 netfilter.go:54] netfilter: convert to binary: current offset: 304 D0227 11:56:44.135105 54532 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 11:56:44.135616 54532 netfilter.go:54] netfilter: convert to binary: finished with an marshalled size of 0 D0227 11:56:44.135687 54532 netfilter.go:54] netfilter: convert to binary: current offset: 0 D0227 11:56:44.135774 54532 netfilter.go:54] netfilter: convert to binary: found hook 1 at offset 0 D0227 11:56:44.135864 54532 netfilter.go:54] netfilter: convert to binary: found underflow 1 at offset 0 D0227 11:56:44.135982 54532 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 11:56:44.136059 54532 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 11:56:44.136561 54532 netfilter.go:54] netfilter: convert to binary: current offset: 152 D0227 11:56:44.136640 54532 netfilter.go:54] netfilter: convert to binary: found hook 2 at offset 152 D0227 11:56:44.136732 54532 netfilter.go:54] netfilter: convert to binary: found underflow 2 at offset 152 D0227 11:56:44.136826 54532 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 11:56:44.136890 54532 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 11:56:44.137382 54532 netfilter.go:54] netfilter: convert to binary: current offset: 304 D0227 11:56:44.137440 54532 netfilter.go:54] netfilter: convert to binary: found hook 3 at offset 304 D0227 11:56:44.137525 54532 netfilter.go:54] netfilter: convert to binary: found underflow 3 at offset 304 D0227 11:56:44.137606 54532 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 11:56:44.137700 54532 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 11:56:44.138141 54532 netfilter.go:54] netfilter: convert to binary: current offset: 456 D0227 11:56:44.138230 54532 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 11:56:44.138701 54532 netfilter.go:54] netfilter: convert to binary: finished with an marshalled size of 0 D0227 11:56:44.138764 54532 netfilter.go:54] netfilter: convert to binary: current offset: 0 D0227 11:56:44.138828 54532 netfilter.go:54] netfilter: convert to binary: found hook 0 at offset 0 D0227 11:56:44.138897 54532 netfilter.go:54] netfilter: convert to binary: found underflow 0 at offset 0 D0227 11:56:44.138966 54532 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 11:56:44.139062 54532 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 11:56:44.139485 54532 netfilter.go:54] netfilter: convert to binary: current offset: 152 D0227 11:56:44.139576 54532 netfilter.go:54] netfilter: convert to binary: found hook 1 at offset 152 D0227 11:56:44.139648 54532 netfilter.go:54] netfilter: convert to binary: found underflow 1 at offset 152 D0227 11:56:44.139734 54532 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 11:56:44.139794 54532 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 11:56:44.140285 54532 netfilter.go:54] netfilter: convert to binary: current offset: 304 D0227 11:56:44.140379 54532 netfilter.go:54] netfilter: convert to binary: found hook 3 at offset 304 D0227 11:56:44.140451 54532 netfilter.go:54] netfilter: convert to binary: found underflow 3 at offset 304 D0227 11:56:44.140521 54532 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 11:56:44.140623 54532 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 11:56:44.141149 54532 netfilter.go:54] netfilter: convert to binary: current offset: 456 D0227 11:56:44.141237 54532 netfilter.go:54] netfilter: convert to binary: found hook 4 at offset 456 D0227 11:56:44.141315 54532 netfilter.go:54] netfilter: convert to binary: found underflow 4 at offset 456 D0227 11:56:44.141448 54532 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 11:56:44.141528 54532 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 11:56:44.141966 54532 netfilter.go:54] netfilter: convert to binary: current offset: 608 D0227 11:56:44.142058 54532 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 11:56:44.142635 54532 netfilter.go:54] netfilter: convert to binary: finished with an marshalled size of 0 D0227 11:56:44.419936 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:44.425184 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:44.425831 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:44.426354 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:44.426693 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:44.427203 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:44.427317 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:44.427620 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:44.428170 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:44.428717 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:44.428971 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:44.429659 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:44.429890 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:44.430262 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:44.430416 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:44.430851 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:44.431062 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:44.431426 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:44.431564 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:44.431965 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:44.432178 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:44.432658 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:44.433009 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:44.433637 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:44.433978 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:44.440405 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:44.440795 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:44.441373 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:44.441679 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:44.442083 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:44.442200 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:44.442645 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:44.442920 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:44.443284 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:44.443431 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:44.460213 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:44.460922 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:44.464614 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:44.465034 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:44.465592 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:44.517869 54532 task_block.go:223] [ 42] Interrupt queued D0227 11:56:44.530053 54532 task_block.go:223] [ 43] Interrupt queued I0227 11:56:44.535508 54532 compat.go:122] Unsupported syscall: perf_event_open, regs: amd64: D0227 11:56:44.577785 54532 task_block.go:223] [ 44] Interrupt queued D0227 11:56:44.588530 54532 task_block.go:223] [ 43] Interrupt queued D0227 11:56:44.588459 54532 task_block.go:223] [ 43] Interrupt queued D0227 11:56:44.589245 54532 task_block.go:223] [ 44] Interrupt queued D0227 11:56:44.589502 54532 task_block.go:223] [ 44] Interrupt queued D0227 11:56:44.589723 54532 task_exit.go:221] [ 42] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:44.590079 54532 task_signals.go:189] [ 43] Signal 9: terminating thread group D0227 11:56:44.590211 54532 task_signals.go:189] [ 44] Signal 9: terminating thread group I0227 11:56:44.590514 54532 compat.go:129] Uncaught signal: "killed" (9), PID: 42, TID: 43, fault addr: 0x0 D0227 11:56:44.590778 54532 task_exit.go:221] [ 43] Transitioning from exit state TaskExitNone to TaskExitInitiated I0227 11:56:44.591085 54532 compat.go:129] Uncaught signal: "killed" (9), PID: 42, TID: 44, fault addr: 0x0 D0227 11:56:44.591252 54532 task_exit.go:221] [ 42] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:44.591397 54532 task_exit.go:221] [ 43] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:44.591537 54532 task_exit.go:221] [ 43] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:44.591732 54532 task_exit.go:221] [ 44] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:44.594541 54532 task_exit.go:221] [ 44] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:44.594915 54532 task_exit.go:221] [ 44] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:44.595302 54532 task_signals.go:419] [ 41] Discarding ignored signal 17 D0227 11:56:44.605001 54532 task_exit.go:221] [ 42] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:44.613543 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:44.615723 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:44.615969 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:44.616706 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:44.617991 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:44.618717 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:44.618865 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:44.619417 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:44.620765 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:44.621496 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:44.621863 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:44.622502 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:44.630331 54532 task_block.go:223] [ 45] Interrupt queued D0227 11:56:44.652615 54532 task_block.go:223] [ 46] Interrupt queued D0227 11:56:44.684320 54532 task_block.go:223] [ 47] Interrupt queued D0227 11:56:44.692982 54532 task_block.go:223] [ 46] Interrupt queued D0227 11:56:44.693210 54532 task_block.go:223] [ 47] Interrupt queued D0227 11:56:44.693394 54532 task_exit.go:221] [ 45] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:44.693637 54532 task_exit.go:221] [ 45] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:44.693811 54532 task_block.go:223] [ 47] Interrupt queued D0227 11:56:44.693954 54532 task_signals.go:189] [ 47] Signal 9: terminating thread group I0227 11:56:44.694105 54532 compat.go:129] Uncaught signal: "killed" (9), PID: 45, TID: 47, fault addr: 0x0 D0227 11:56:44.694210 54532 task_exit.go:221] [ 47] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:44.694350 54532 task_exit.go:221] [ 47] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:44.694425 54532 task_exit.go:221] [ 47] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:44.694554 54532 task_block.go:223] [ 46] Interrupt queued D0227 11:56:44.694670 54532 task_signals.go:189] [ 46] Signal 9: terminating thread group I0227 11:56:44.694767 54532 compat.go:129] Uncaught signal: "killed" (9), PID: 45, TID: 46, fault addr: 0x0 D0227 11:56:44.694856 54532 task_exit.go:221] [ 46] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:44.696181 54532 task_exit.go:221] [ 46] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:44.696354 54532 task_exit.go:221] [ 46] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:44.696517 54532 task_signals.go:419] [ 41] Discarding ignored signal 17 D0227 11:56:44.697843 54532 task_exit.go:221] [ 45] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:44.705889 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:44.706607 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:44.706921 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:44.707439 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:44.708292 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:44.708973 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:44.709200 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:44.709675 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:44.710295 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:44.710749 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:44.710925 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:44.711290 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:44.726809 54532 task_block.go:223] [ 48] Interrupt queued D0227 11:56:44.752461 54532 task_block.go:223] [ 49] Interrupt queued D0227 11:56:44.764958 54532 parameters.go:238] Clock(Monotonic): error: -44 ns, adjusted frequency from 2299775368 Hz to 2299774481 Hz D0227 11:56:44.765240 54532 parameters.go:238] Clock(Realtime): error: 347 ns, adjusted frequency from 2299774183 Hz to 2299775442 Hz D0227 11:56:44.765371 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501226191878202 monotonicBaseRef:94002740176 monotonicFrequency:2299774481 realtimeReady:1 realtimeBaseCycles:8501226192552625 realtimeBaseRef:1582804604765232508 realtimeFrequency:2299775442} D0227 11:56:44.765899 54532 task_block.go:223] [ 50] Interrupt queued D0227 11:56:44.776657 54532 task_block.go:223] [ 49] Interrupt queued D0227 11:56:44.776978 54532 task_block.go:223] [ 50] Interrupt queued D0227 11:56:44.777120 54532 task_block.go:223] [ 50] Interrupt queued D0227 11:56:44.777102 54532 task_block.go:223] [ 49] Interrupt queued D0227 11:56:44.777181 54532 task_exit.go:221] [ 48] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:44.777583 54532 task_signals.go:189] [ 50] Signal 9: terminating thread group D0227 11:56:44.777732 54532 task_signals.go:189] [ 49] Signal 9: terminating thread group D0227 11:56:44.777679 54532 task_exit.go:221] [ 48] Transitioning from exit state TaskExitInitiated to TaskExitZombie I0227 11:56:44.778216 54532 compat.go:129] Uncaught signal: "killed" (9), PID: 48, TID: 49, fault addr: 0x0 I0227 11:56:44.778477 54532 compat.go:129] Uncaught signal: "killed" (9), PID: 48, TID: 50, fault addr: 0x0 D0227 11:56:44.778601 54532 task_exit.go:221] [ 50] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:44.778781 54532 task_exit.go:221] [ 50] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:44.778870 54532 task_exit.go:221] [ 50] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:44.779078 54532 task_exit.go:221] [ 49] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:44.780667 54532 task_exit.go:221] [ 49] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:44.780812 54532 task_exit.go:221] [ 49] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:44.781000 54532 task_signals.go:419] [ 41] Discarding ignored signal 17 D0227 11:56:44.783170 54532 task_exit.go:221] [ 48] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:44.785837 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:44.786545 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:44.786706 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:44.787195 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:44.788922 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:44.789585 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:44.790391 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:44.791113 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:44.791895 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:44.792466 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:44.792945 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:44.793385 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:44.805464 54532 task_block.go:223] [ 51] Interrupt queued D0227 11:56:44.811827 54532 task_block.go:223] [ 52] Interrupt queued D0227 11:56:44.818497 54532 task_block.go:223] [ 53] Interrupt queued D0227 11:56:44.823625 54532 task_block.go:223] [ 52] Interrupt queued D0227 11:56:44.823915 54532 task_block.go:223] [ 53] Interrupt queued D0227 11:56:44.824172 54532 task_exit.go:221] [ 51] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:44.824572 54532 task_exit.go:221] [ 51] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:44.824795 54532 task_block.go:223] [ 53] Interrupt queued D0227 11:56:44.824972 54532 task_signals.go:189] [ 53] Signal 9: terminating thread group I0227 11:56:44.825100 54532 compat.go:129] Uncaught signal: "killed" (9), PID: 51, TID: 53, fault addr: 0x0 D0227 11:56:44.825247 54532 task_exit.go:221] [ 53] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:44.825439 54532 task_exit.go:221] [ 53] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:44.825548 54532 task_exit.go:221] [ 53] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:44.825581 54532 task_block.go:223] [ 52] Interrupt queued D0227 11:56:44.825987 54532 task_signals.go:189] [ 52] Signal 9: terminating thread group I0227 11:56:44.826236 54532 compat.go:129] Uncaught signal: "killed" (9), PID: 51, TID: 52, fault addr: 0x0 D0227 11:56:44.826416 54532 task_exit.go:221] [ 52] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:44.827729 54532 task_exit.go:221] [ 52] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:44.827872 54532 task_exit.go:221] [ 52] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:44.828062 54532 task_signals.go:419] [ 41] Discarding ignored signal 17 D0227 11:56:44.828327 54532 task_exit.go:221] [ 51] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:44.841985 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:44.842577 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:44.842789 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:44.843205 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:44.844141 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:44.844665 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:44.844821 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:44.845315 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:44.846041 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:44.846466 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:44.846602 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:44.847060 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:44.865045 54532 task_block.go:223] [ 54] Interrupt queued D0227 11:56:44.885657 54532 task_block.go:223] [ 55] Interrupt queued D0227 11:56:44.896225 54532 task_block.go:223] [ 56] Interrupt queued D0227 11:56:44.903985 54532 task_block.go:223] [ 55] Interrupt queued D0227 11:56:44.904058 54532 task_block.go:223] [ 55] Interrupt queued D0227 11:56:44.904184 54532 task_block.go:223] [ 56] Interrupt queued D0227 11:56:44.904376 54532 task_exit.go:221] [ 54] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:44.904575 54532 task_signals.go:189] [ 55] Signal 9: terminating thread group D0227 11:56:44.904606 54532 task_exit.go:221] [ 54] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:44.904335 54532 task_block.go:223] [ 56] Interrupt queued I0227 11:56:44.904894 54532 compat.go:129] Uncaught signal: "killed" (9), PID: 54, TID: 55, fault addr: 0x0 D0227 11:56:44.904955 54532 task_signals.go:189] [ 56] Signal 9: terminating thread group D0227 11:56:44.905113 54532 task_exit.go:221] [ 55] Transitioning from exit state TaskExitNone to TaskExitInitiated I0227 11:56:44.905362 54532 compat.go:129] Uncaught signal: "killed" (9), PID: 54, TID: 56, fault addr: 0x0 D0227 11:56:44.905493 54532 task_exit.go:221] [ 55] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:44.905668 54532 task_exit.go:221] [ 55] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:44.905865 54532 task_exit.go:221] [ 56] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:44.907411 54532 task_exit.go:221] [ 56] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:44.907524 54532 task_exit.go:221] [ 56] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:44.907707 54532 task_signals.go:419] [ 41] Discarding ignored signal 17 D0227 11:56:44.908760 54532 task_exit.go:221] [ 54] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:44.912223 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:44.913518 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:44.913749 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:44.914226 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:44.915543 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:44.916091 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:44.916271 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:44.916960 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:44.917916 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:44.918619 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:44.918807 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:44.924993 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:44.932672 54532 task_block.go:223] [ 57] Interrupt queued D0227 11:56:44.941597 54532 task_block.go:223] [ 58] Interrupt queued D0227 11:56:44.950356 54532 task_block.go:223] [ 59] Interrupt queued D0227 11:56:44.957302 54532 task_block.go:223] [ 58] Interrupt queued D0227 11:56:44.957594 54532 task_block.go:223] [ 59] Interrupt queued D0227 11:56:44.957792 54532 task_exit.go:221] [ 57] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:44.958111 54532 task_block.go:223] [ 58] Interrupt queued D0227 11:56:44.958152 54532 task_block.go:223] [ 59] Interrupt queued D0227 11:56:44.958150 54532 task_exit.go:221] [ 57] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:44.958363 54532 task_signals.go:189] [ 59] Signal 9: terminating thread group D0227 11:56:44.958361 54532 task_signals.go:189] [ 58] Signal 9: terminating thread group I0227 11:56:44.958693 54532 compat.go:129] Uncaught signal: "killed" (9), PID: 57, TID: 59, fault addr: 0x0 D0227 11:56:44.958882 54532 task_exit.go:221] [ 59] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:44.959132 54532 task_exit.go:221] [ 59] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:44.959247 54532 task_exit.go:221] [ 59] Transitioning from exit state TaskExitZombie to TaskExitDead I0227 11:56:44.958919 54532 compat.go:129] Uncaught signal: "killed" (9), PID: 57, TID: 58, fault addr: 0x0 D0227 11:56:44.960359 54532 task_exit.go:221] [ 58] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:44.962292 54532 task_exit.go:221] [ 58] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:44.962536 54532 task_exit.go:221] [ 58] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:44.962858 54532 task_signals.go:419] [ 41] Discarding ignored signal 17 D0227 11:56:44.964268 54532 task_exit.go:221] [ 57] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:44.972009 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:44.979645 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:44.979856 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:44.980536 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:44.983008 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:44.983874 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:44.984138 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:44.985161 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:44.987505 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:44.988168 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:44.988393 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:44.989256 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:45.008643 54532 task_block.go:223] [ 60] Interrupt queued D0227 11:56:45.026635 54532 task_block.go:223] [ 61] Interrupt queued D0227 11:56:45.055669 54532 task_block.go:223] [ 62] Interrupt queued D0227 11:56:45.061002 54532 task_block.go:223] [ 61] Interrupt queued D0227 11:56:45.061361 54532 task_block.go:223] [ 62] Interrupt queued D0227 11:56:45.061431 54532 task_exit.go:221] [ 60] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:45.061350 54532 task_block.go:223] [ 61] Interrupt queued D0227 11:56:45.061585 54532 task_exit.go:221] [ 60] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:45.061434 54532 task_block.go:223] [ 62] Interrupt queued D0227 11:56:45.061700 54532 task_signals.go:189] [ 61] Signal 9: terminating thread group I0227 11:56:45.061997 54532 compat.go:129] Uncaught signal: "killed" (9), PID: 60, TID: 61, fault addr: 0x0 D0227 11:56:45.062354 54532 task_exit.go:221] [ 61] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:45.062565 54532 task_signals.go:189] [ 62] Signal 9: terminating thread group D0227 11:56:45.062779 54532 task_exit.go:221] [ 61] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:45.062950 54532 task_exit.go:221] [ 61] Transitioning from exit state TaskExitZombie to TaskExitDead I0227 11:56:45.063319 54532 compat.go:129] Uncaught signal: "killed" (9), PID: 60, TID: 62, fault addr: 0x0 D0227 11:56:45.063527 54532 task_exit.go:221] [ 62] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:45.065568 54532 task_exit.go:221] [ 62] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:45.065704 54532 task_exit.go:221] [ 62] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:45.066566 54532 task_signals.go:419] [ 41] Discarding ignored signal 17 D0227 11:56:45.066766 54532 task_exit.go:221] [ 60] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:45.070766 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:45.071407 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:45.074821 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:45.078302 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:45.086250 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:45.086835 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:45.086985 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:45.087430 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:45.093672 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:45.094473 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:45.094725 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:45.095268 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:45.108621 54532 task_block.go:223] [ 63] Interrupt queued D0227 11:56:45.124954 54532 task_block.go:223] [ 64] Interrupt queued D0227 11:56:45.139804 54532 task_block.go:223] [ 65] Interrupt queued D0227 11:56:45.149263 54532 task_block.go:223] [ 64] Interrupt queued D0227 11:56:45.149467 54532 task_block.go:223] [ 65] Interrupt queued D0227 11:56:45.149559 54532 task_exit.go:221] [ 63] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:45.149827 54532 task_exit.go:221] [ 63] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:45.150026 54532 task_block.go:223] [ 64] Interrupt queued D0227 11:56:45.150221 54532 task_signals.go:189] [ 64] Signal 9: terminating thread group I0227 11:56:45.150410 54532 compat.go:129] Uncaught signal: "killed" (9), PID: 63, TID: 64, fault addr: 0x0 D0227 11:56:45.150574 54532 task_exit.go:221] [ 64] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:45.150767 54532 task_exit.go:221] [ 64] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:45.150886 54532 task_exit.go:221] [ 64] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:45.151141 54532 task_block.go:223] [ 65] Interrupt queued D0227 11:56:45.151372 54532 task_signals.go:189] [ 65] Signal 9: terminating thread group I0227 11:56:45.151512 54532 compat.go:129] Uncaught signal: "killed" (9), PID: 63, TID: 65, fault addr: 0x0 D0227 11:56:45.151673 54532 task_exit.go:221] [ 65] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:45.152919 54532 task_exit.go:221] [ 65] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:45.153140 54532 task_exit.go:221] [ 65] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:45.153397 54532 task_signals.go:419] [ 41] Discarding ignored signal 17 D0227 11:56:45.153660 54532 task_exit.go:221] [ 63] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:45.166390 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:45.167342 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:45.167535 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:45.168020 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:45.170350 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:45.170912 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:45.171048 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:45.171558 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:45.172100 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:45.172458 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:45.172713 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:45.206645 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:45.215992 54532 task_block.go:223] [ 66] Interrupt queued D0227 11:56:45.225288 54532 task_block.go:223] [ 67] Interrupt queued D0227 11:56:45.236997 54532 task_block.go:223] [ 68] Interrupt queued D0227 11:56:45.247587 54532 task_block.go:223] [ 67] Interrupt queued D0227 11:56:45.248380 54532 task_block.go:223] [ 68] Interrupt queued D0227 11:56:45.248799 54532 task_exit.go:221] [ 66] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:45.249238 54532 task_exit.go:221] [ 66] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:45.247897 54532 task_block.go:223] [ 67] Interrupt queued D0227 11:56:45.249683 54532 task_signals.go:189] [ 67] Signal 9: terminating thread group I0227 11:56:45.249883 54532 compat.go:129] Uncaught signal: "killed" (9), PID: 66, TID: 67, fault addr: 0x0 D0227 11:56:45.250087 54532 task_exit.go:221] [ 67] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:45.250379 54532 task_exit.go:221] [ 67] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:45.248585 54532 task_block.go:223] [ 68] Interrupt queued D0227 11:56:45.250544 54532 task_exit.go:221] [ 67] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:45.250707 54532 task_signals.go:189] [ 68] Signal 9: terminating thread group I0227 11:56:45.250863 54532 compat.go:129] Uncaught signal: "killed" (9), PID: 66, TID: 68, fault addr: 0x0 D0227 11:56:45.250992 54532 task_exit.go:221] [ 68] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:45.252090 54532 task_exit.go:221] [ 68] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:45.252303 54532 task_exit.go:221] [ 68] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:45.252592 54532 task_signals.go:419] [ 41] Discarding ignored signal 17 D0227 11:56:45.253665 54532 task_exit.go:221] [ 66] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:45.276361 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:45.277106 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:45.277271 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:45.277675 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:45.278371 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:45.278958 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:45.279154 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:45.280340 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:45.281421 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:45.282015 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:45.282174 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:45.282712 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:45.294217 54532 task_block.go:223] [ 69] Interrupt queued D0227 11:56:45.309815 54532 task_block.go:223] [ 70] Interrupt queued D0227 11:56:45.334903 54532 task_block.go:223] [ 71] Interrupt queued D0227 11:56:45.345580 54532 task_block.go:223] [ 70] Interrupt queued D0227 11:56:45.345731 54532 task_block.go:223] [ 71] Interrupt queued D0227 11:56:45.345834 54532 task_exit.go:221] [ 69] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:45.346035 54532 task_exit.go:221] [ 69] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:45.346168 54532 task_block.go:223] [ 71] Interrupt queued D0227 11:56:45.346327 54532 task_signals.go:189] [ 71] Signal 9: terminating thread group I0227 11:56:45.346421 54532 compat.go:129] Uncaught signal: "killed" (9), PID: 69, TID: 71, fault addr: 0x0 D0227 11:56:45.346523 54532 task_exit.go:221] [ 71] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:45.346650 54532 task_exit.go:221] [ 71] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:45.346742 54532 task_exit.go:221] [ 71] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:45.346922 54532 task_block.go:223] [ 70] Interrupt queued D0227 11:56:45.347056 54532 task_signals.go:189] [ 70] Signal 9: terminating thread group I0227 11:56:45.347173 54532 compat.go:129] Uncaught signal: "killed" (9), PID: 69, TID: 70, fault addr: 0x0 D0227 11:56:45.347285 54532 task_exit.go:221] [ 70] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:45.348383 54532 task_exit.go:221] [ 70] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:45.348499 54532 task_exit.go:221] [ 70] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:45.348680 54532 task_signals.go:419] [ 41] Discarding ignored signal 17 D0227 11:56:45.350157 54532 task_exit.go:221] [ 69] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:45.354118 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:45.355032 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:45.355172 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:45.355665 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:45.356559 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:45.357085 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:45.357246 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:45.357653 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:45.358351 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:45.358778 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:45.358896 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:45.359292 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:45.364935 54532 task_block.go:223] [ 72] Interrupt queued D0227 11:56:45.374168 54532 task_block.go:223] [ 73] Interrupt queued D0227 11:56:45.403188 54532 task_block.go:223] [ 74] Interrupt queued D0227 11:56:45.410083 54532 task_block.go:223] [ 73] Interrupt queued D0227 11:56:45.410243 54532 task_block.go:223] [ 74] Interrupt queued D0227 11:56:45.410327 54532 task_exit.go:221] [ 72] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:45.410504 54532 task_exit.go:221] [ 72] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:45.410692 54532 task_block.go:223] [ 73] Interrupt queued D0227 11:56:45.410845 54532 task_signals.go:189] [ 73] Signal 9: terminating thread group I0227 11:56:45.410977 54532 compat.go:129] Uncaught signal: "killed" (9), PID: 72, TID: 73, fault addr: 0x0 D0227 11:56:45.411083 54532 task_exit.go:221] [ 73] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:45.411215 54532 task_exit.go:221] [ 73] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:45.411314 54532 task_exit.go:221] [ 73] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:45.411444 54532 task_block.go:223] [ 74] Interrupt queued D0227 11:56:45.411717 54532 task_signals.go:189] [ 74] Signal 9: terminating thread group I0227 11:56:45.411896 54532 compat.go:129] Uncaught signal: "killed" (9), PID: 72, TID: 74, fault addr: 0x0 D0227 11:56:45.412037 54532 task_exit.go:221] [ 74] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:45.416534 54532 task_exit.go:221] [ 74] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:45.416788 54532 task_exit.go:221] [ 74] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:45.416991 54532 task_signals.go:419] [ 41] Discarding ignored signal 17 D0227 11:56:45.418014 54532 task_exit.go:221] [ 72] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:45.421694 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:45.424269 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:45.424453 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:45.427441 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:45.428285 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:45.428999 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:45.429143 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:45.429554 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:45.430401 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:45.430847 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:45.431078 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:45.431570 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:45.442710 54532 task_block.go:223] [ 75] Interrupt queued D0227 11:56:45.450149 54532 task_block.go:223] [ 76] Interrupt queued D0227 11:56:45.480716 54532 task_block.go:223] [ 77] Interrupt queued D0227 11:56:45.486666 54532 task_block.go:223] [ 76] Interrupt queued D0227 11:56:45.486902 54532 task_block.go:223] [ 77] Interrupt queued D0227 11:56:45.487061 54532 task_exit.go:221] [ 75] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:45.487304 54532 task_exit.go:221] [ 75] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:45.487497 54532 task_block.go:223] [ 77] Interrupt queued D0227 11:56:45.487681 54532 task_signals.go:189] [ 77] Signal 9: terminating thread group I0227 11:56:45.487854 54532 compat.go:129] Uncaught signal: "killed" (9), PID: 75, TID: 77, fault addr: 0x0 D0227 11:56:45.488032 54532 task_exit.go:221] [ 77] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:45.488224 54532 task_exit.go:221] [ 77] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:45.488498 54532 task_exit.go:221] [ 77] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:45.488807 54532 task_block.go:223] [ 76] Interrupt queued D0227 11:56:45.489193 54532 task_signals.go:189] [ 76] Signal 9: terminating thread group I0227 11:56:45.489411 54532 compat.go:129] Uncaught signal: "killed" (9), PID: 75, TID: 76, fault addr: 0x0 D0227 11:56:45.489683 54532 task_exit.go:221] [ 76] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:45.490856 54532 task_exit.go:221] [ 76] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:45.490963 54532 task_exit.go:221] [ 76] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:45.491143 54532 task_signals.go:419] [ 41] Discarding ignored signal 17 D0227 11:56:45.491618 54532 task_exit.go:221] [ 75] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:45.501614 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:45.506972 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:45.507142 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:45.507555 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:45.512097 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:45.512568 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:45.512750 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:45.513200 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:45.513910 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:45.514558 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:45.514678 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:45.515014 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:45.527791 54532 task_block.go:223] [ 78] Interrupt queued D0227 11:56:45.544482 54532 task_block.go:223] [ 79] Interrupt queued D0227 11:56:45.555490 54532 task_block.go:223] [ 80] Interrupt queued D0227 11:56:45.561778 54532 task_block.go:223] [ 79] Interrupt queued D0227 11:56:45.561816 54532 task_block.go:223] [ 79] Interrupt queued D0227 11:56:45.561977 54532 task_block.go:223] [ 80] Interrupt queued D0227 11:56:45.562022 54532 task_block.go:223] [ 80] Interrupt queued D0227 11:56:45.562173 54532 task_signals.go:189] [ 79] Signal 9: terminating thread group D0227 11:56:45.562234 54532 task_signals.go:189] [ 80] Signal 9: terminating thread group D0227 11:56:45.562297 54532 task_exit.go:221] [ 78] Transitioning from exit state TaskExitNone to TaskExitInitiated I0227 11:56:45.562479 54532 compat.go:129] Uncaught signal: "killed" (9), PID: 78, TID: 79, fault addr: 0x0 I0227 11:56:45.562744 54532 compat.go:129] Uncaught signal: "killed" (9), PID: 78, TID: 80, fault addr: 0x0 D0227 11:56:45.562909 54532 task_exit.go:221] [ 78] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:45.563300 54532 task_exit.go:221] [ 79] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:45.563562 54532 task_exit.go:221] [ 79] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:45.563722 54532 task_exit.go:221] [ 79] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:45.563991 54532 task_exit.go:221] [ 80] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:45.565161 54532 task_exit.go:221] [ 80] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:45.565294 54532 task_exit.go:221] [ 80] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:45.565480 54532 task_signals.go:419] [ 41] Discarding ignored signal 17 D0227 11:56:45.566520 54532 task_exit.go:221] [ 78] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:45.575278 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:45.576452 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:45.576664 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:45.577105 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:45.578067 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:45.578589 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:45.578785 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:45.579228 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:45.579993 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:45.580545 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:45.580762 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:45.581280 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:45.587143 54532 task_block.go:223] [ 81] Interrupt queued D0227 11:56:45.597228 54532 task_block.go:223] [ 82] Interrupt queued D0227 11:56:45.639563 54532 task_block.go:223] [ 83] Interrupt queued D0227 11:56:45.644428 54532 task_block.go:223] [ 82] Interrupt queued D0227 11:56:45.644601 54532 task_block.go:223] [ 83] Interrupt queued D0227 11:56:45.644761 54532 task_exit.go:221] [ 81] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:45.644999 54532 task_exit.go:221] [ 81] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:45.645226 54532 task_block.go:223] [ 83] Interrupt queued D0227 11:56:45.645426 54532 task_signals.go:189] [ 83] Signal 9: terminating thread group I0227 11:56:45.645589 54532 compat.go:129] Uncaught signal: "killed" (9), PID: 81, TID: 83, fault addr: 0x0 D0227 11:56:45.645763 54532 task_exit.go:221] [ 83] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:45.646027 54532 task_exit.go:221] [ 83] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:45.646190 54532 task_exit.go:221] [ 83] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:45.646165 54532 task_block.go:223] [ 82] Interrupt queued D0227 11:56:45.646731 54532 task_signals.go:189] [ 82] Signal 9: terminating thread group I0227 11:56:45.646850 54532 compat.go:129] Uncaught signal: "killed" (9), PID: 81, TID: 82, fault addr: 0x0 D0227 11:56:45.646966 54532 task_exit.go:221] [ 82] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:45.648057 54532 task_exit.go:221] [ 82] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:45.648212 54532 task_exit.go:221] [ 82] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:45.648431 54532 task_signals.go:419] [ 41] Discarding ignored signal 17 D0227 11:56:45.649100 54532 task_exit.go:221] [ 81] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:45.651066 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:45.651753 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:45.651939 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:45.652439 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:45.653227 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:45.654484 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:45.655352 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:45.655818 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:45.656451 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:45.657053 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:45.657276 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:45.657755 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:45.663311 54532 task_block.go:223] [ 84] Interrupt queued D0227 11:56:45.671204 54532 task_block.go:223] [ 85] Interrupt queued D0227 11:56:45.679137 54532 task_block.go:223] [ 86] Interrupt queued D0227 11:56:45.684047 54532 task_block.go:223] [ 85] Interrupt queued D0227 11:56:45.684203 54532 task_block.go:223] [ 86] Interrupt queued D0227 11:56:45.684299 54532 task_exit.go:221] [ 84] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:45.684473 54532 task_exit.go:221] [ 84] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:45.684636 54532 task_block.go:223] [ 86] Interrupt queued D0227 11:56:45.684678 54532 task_block.go:223] [ 85] Interrupt queued D0227 11:56:45.684941 54532 task_signals.go:189] [ 86] Signal 9: terminating thread group D0227 11:56:45.684967 54532 task_signals.go:189] [ 85] Signal 9: terminating thread group I0227 11:56:45.685161 54532 compat.go:129] Uncaught signal: "killed" (9), PID: 84, TID: 86, fault addr: 0x0 D0227 11:56:45.685321 54532 task_exit.go:221] [ 86] Transitioning from exit state TaskExitNone to TaskExitInitiated I0227 11:56:45.685326 54532 compat.go:129] Uncaught signal: "killed" (9), PID: 84, TID: 85, fault addr: 0x0 D0227 11:56:45.685530 54532 task_exit.go:221] [ 86] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:45.685666 54532 task_exit.go:221] [ 86] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:45.685856 54532 task_exit.go:221] [ 85] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:45.686983 54532 task_exit.go:221] [ 85] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:45.687099 54532 task_exit.go:221] [ 85] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:45.687271 54532 task_signals.go:419] [ 41] Discarding ignored signal 17 D0227 11:56:45.689177 54532 task_exit.go:221] [ 84] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:45.691909 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:45.693235 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:45.693423 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:45.693988 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:45.694906 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:45.695387 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:45.695524 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:45.696332 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:45.697143 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:45.697773 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:45.697992 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:45.698385 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:45.707837 54532 task_block.go:223] [ 87] Interrupt queued D0227 11:56:45.728739 54532 task_block.go:223] [ 88] Interrupt queued D0227 11:56:45.735961 54532 task_block.go:223] [ 89] Interrupt queued D0227 11:56:45.751392 54532 task_block.go:223] [ 88] Interrupt queued D0227 11:56:45.751590 54532 task_block.go:223] [ 89] Interrupt queued D0227 11:56:45.751686 54532 task_block.go:223] [ 88] Interrupt queued D0227 11:56:45.751730 54532 task_exit.go:221] [ 87] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:45.751838 54532 task_block.go:223] [ 89] Interrupt queued D0227 11:56:45.752029 54532 task_exit.go:221] [ 87] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:45.752082 54532 task_signals.go:189] [ 89] Signal 9: terminating thread group I0227 11:56:45.752262 54532 compat.go:129] Uncaught signal: "killed" (9), PID: 87, TID: 89, fault addr: 0x0 D0227 11:56:45.752318 54532 task_signals.go:189] [ 88] Signal 9: terminating thread group D0227 11:56:45.752441 54532 task_exit.go:221] [ 89] Transitioning from exit state TaskExitNone to TaskExitInitiated I0227 11:56:45.752765 54532 compat.go:129] Uncaught signal: "killed" (9), PID: 87, TID: 88, fault addr: 0x0 D0227 11:56:45.752939 54532 task_exit.go:221] [ 89] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:45.753044 54532 task_exit.go:221] [ 89] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:45.753261 54532 task_exit.go:221] [ 88] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:45.754603 54532 task_exit.go:221] [ 88] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:45.754754 54532 task_exit.go:221] [ 88] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:45.754921 54532 task_signals.go:419] [ 41] Discarding ignored signal 17 D0227 11:56:45.756096 54532 task_exit.go:221] [ 87] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:45.758801 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:45.759486 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:45.759633 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:45.760153 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:45.760907 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:45.761375 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:45.761594 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:45.762138 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:45.762402 54532 parameters.go:238] Clock(Monotonic): error: 43 ns, adjusted frequency from 2299774481 Hz to 2299774675 Hz D0227 11:56:45.762662 54532 parameters.go:238] Clock(Realtime): error: -11 ns, adjusted frequency from 2299775442 Hz to 2299774631 Hz D0227 11:56:45.762785 54532 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8501228485793284 monotonicBaseRef:95000192361 monotonicFrequency:2299774675 realtimeReady:1 realtimeBaseCycles:8501228486398870 realtimeBaseRef:1582804605762654344 realtimeFrequency:2299774631} D0227 11:56:45.762948 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:45.763803 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:45.764032 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:45.764570 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:45.770586 54532 task_block.go:223] [ 90] Interrupt queued D0227 11:56:45.778985 54532 task_block.go:223] [ 91] Interrupt queued D0227 11:56:45.786629 54532 task_block.go:223] [ 92] Interrupt queued D0227 11:56:45.791103 54532 task_block.go:223] [ 91] Interrupt queued D0227 11:56:45.791253 54532 task_block.go:223] [ 92] Interrupt queued D0227 11:56:45.791162 54532 task_block.go:223] [ 91] Interrupt queued D0227 11:56:45.791315 54532 task_block.go:223] [ 92] Interrupt queued D0227 11:56:45.791361 54532 task_exit.go:221] [ 90] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:45.791628 54532 task_signals.go:189] [ 92] Signal 9: terminating thread group D0227 11:56:45.791665 54532 task_signals.go:189] [ 91] Signal 9: terminating thread group D0227 11:56:45.791735 54532 task_exit.go:221] [ 90] Transitioning from exit state TaskExitInitiated to TaskExitZombie I0227 11:56:45.791955 54532 compat.go:129] Uncaught signal: "killed" (9), PID: 90, TID: 91, fault addr: 0x0 D0227 11:56:45.792218 54532 task_exit.go:221] [ 91] Transitioning from exit state TaskExitNone to TaskExitInitiated I0227 11:56:45.792231 54532 compat.go:129] Uncaught signal: "killed" (9), PID: 90, TID: 92, fault addr: 0x0 D0227 11:56:45.792430 54532 task_exit.go:221] [ 91] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:45.792562 54532 task_exit.go:221] [ 91] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:45.792893 54532 task_exit.go:221] [ 92] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:45.794130 54532 task_exit.go:221] [ 92] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:45.794257 54532 task_exit.go:221] [ 92] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:45.794435 54532 task_signals.go:419] [ 41] Discarding ignored signal 17 D0227 11:56:45.795756 54532 task_exit.go:221] [ 90] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:45.797713 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:45.798356 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:45.798591 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:45.799658 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:45.800450 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:45.801008 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:45.801151 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:45.801499 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:45.801989 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:45.802636 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:45.802869 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:45.803289 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:45.812664 54532 task_block.go:223] [ 93] Interrupt queued D0227 11:56:45.825886 54532 task_block.go:223] [ 94] Interrupt queued D0227 11:56:45.851889 54532 task_block.go:223] [ 95] Interrupt queued D0227 11:56:45.859548 54532 task_block.go:223] [ 94] Interrupt queued D0227 11:56:45.859579 54532 task_block.go:223] [ 94] Interrupt queued D0227 11:56:45.859746 54532 task_block.go:223] [ 95] Interrupt queued D0227 11:56:45.859875 54532 task_exit.go:221] [ 93] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:45.859908 54532 task_block.go:223] [ 95] Interrupt queued D0227 11:56:45.860081 54532 task_signals.go:189] [ 94] Signal 9: terminating thread group D0227 11:56:45.860326 54532 task_signals.go:189] [ 95] Signal 9: terminating thread group I0227 11:56:45.860387 54532 compat.go:129] Uncaught signal: "killed" (9), PID: 93, TID: 94, fault addr: 0x0 I0227 11:56:45.860652 54532 compat.go:129] Uncaught signal: "killed" (9), PID: 93, TID: 95, fault addr: 0x0 D0227 11:56:45.860771 54532 task_exit.go:221] [ 94] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:45.861066 54532 task_exit.go:221] [ 94] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:45.861190 54532 task_exit.go:221] [ 94] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:45.861410 54532 task_exit.go:221] [ 93] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:45.861621 54532 task_exit.go:221] [ 95] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:45.863078 54532 task_exit.go:221] [ 95] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:45.863241 54532 task_exit.go:221] [ 95] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:45.863475 54532 task_signals.go:419] [ 41] Discarding ignored signal 17 D0227 11:56:45.869256 54532 task_exit.go:221] [ 93] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:45.874382 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:45.874994 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:45.875143 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:45.875592 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:45.876908 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:45.877480 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:45.877689 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:45.878230 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:45.879557 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:45.880148 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:45.880337 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:45.880869 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:45.888318 54532 task_block.go:223] [ 96] Interrupt queued D0227 11:56:45.908712 54532 task_block.go:223] [ 97] Interrupt queued D0227 11:56:45.923925 54532 task_block.go:223] [ 98] Interrupt queued D0227 11:56:45.928799 54532 task_block.go:223] [ 97] Interrupt queued D0227 11:56:45.928989 54532 task_block.go:223] [ 98] Interrupt queued D0227 11:56:45.929099 54532 task_exit.go:221] [ 96] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:45.929267 54532 task_block.go:223] [ 97] Interrupt queued D0227 11:56:45.929466 54532 task_block.go:223] [ 98] Interrupt queued D0227 11:56:45.929551 54532 task_signals.go:189] [ 97] Signal 9: terminating thread group D0227 11:56:45.929355 54532 task_exit.go:221] [ 96] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:45.929619 54532 task_signals.go:189] [ 98] Signal 9: terminating thread group I0227 11:56:45.929762 54532 compat.go:129] Uncaught signal: "killed" (9), PID: 96, TID: 98, fault addr: 0x0 D0227 11:56:45.929950 54532 task_exit.go:221] [ 98] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:45.930126 54532 task_exit.go:221] [ 98] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:45.930205 54532 task_exit.go:221] [ 98] Transitioning from exit state TaskExitZombie to TaskExitDead I0227 11:56:45.930297 54532 compat.go:129] Uncaught signal: "killed" (9), PID: 96, TID: 97, fault addr: 0x0 D0227 11:56:45.930498 54532 task_exit.go:221] [ 97] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:45.931864 54532 task_exit.go:221] [ 97] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:45.932027 54532 task_exit.go:221] [ 97] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:45.932198 54532 task_signals.go:419] [ 41] Discarding ignored signal 17 D0227 11:56:45.933772 54532 task_exit.go:221] [ 96] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:45.936319 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:45.937029 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:45.937237 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:45.937811 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:45.938809 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:45.939344 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:45.939518 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:45.939980 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:45.941320 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:45.941838 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:45.941959 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:45.942333 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:45.948488 54532 task_block.go:223] [ 99] Interrupt queued D0227 11:56:45.959068 54532 task_block.go:223] [ 100] Interrupt queued D0227 11:56:45.970624 54532 task_block.go:223] [ 101] Interrupt queued D0227 11:56:45.972888 54532 task_block.go:223] [ 102] Interrupt queued D0227 11:56:45.978329 54532 task_block.go:223] [ 100] Interrupt queued D0227 11:56:45.978615 54532 task_block.go:223] [ 101] Interrupt queued D0227 11:56:45.978795 54532 task_block.go:223] [ 102] Interrupt queued D0227 11:56:45.978965 54532 task_exit.go:221] [ 99] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:45.979146 54532 task_block.go:223] [ 100] Interrupt queued D0227 11:56:45.979301 54532 task_block.go:223] [ 102] Interrupt queued D0227 11:56:45.979368 54532 task_signals.go:189] [ 100] Signal 9: terminating thread group D0227 11:56:45.979199 54532 task_exit.go:221] [ 99] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:45.979499 54532 task_signals.go:189] [ 102] Signal 9: terminating thread group I0227 11:56:45.979581 54532 compat.go:129] Uncaught signal: "killed" (9), PID: 99, TID: 100, fault addr: 0x0 D0227 11:56:45.979667 54532 task_block.go:223] [ 101] Interrupt queued D0227 11:56:45.979740 54532 task_exit.go:221] [ 100] Transitioning from exit state TaskExitNone to TaskExitInitiated I0227 11:56:45.979903 54532 compat.go:129] Uncaught signal: "killed" (9), PID: 99, TID: 102, fault addr: 0x0 D0227 11:56:45.979959 54532 task_exit.go:221] [ 100] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:45.980082 54532 task_exit.go:221] [ 100] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:45.980231 54532 task_signals.go:189] [ 101] Signal 9: terminating thread group I0227 11:56:45.980698 54532 compat.go:129] Uncaught signal: "killed" (9), PID: 99, TID: 101, fault addr: 0x0 D0227 11:56:45.980897 54532 task_exit.go:221] [ 101] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:45.981090 54532 task_exit.go:221] [ 101] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:45.981216 54532 task_exit.go:221] [ 101] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:45.981492 54532 task_exit.go:221] [ 102] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:56:45.982709 54532 task_exit.go:221] [ 102] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:56:45.982912 54532 task_exit.go:221] [ 102] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:45.983155 54532 task_signals.go:419] [ 41] Discarding ignored signal 17 D0227 11:56:45.994017 54532 task_exit.go:221] [ 99] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:56:45.996774 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:45.997556 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:45.997792 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:45.998823 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:45.999637 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:46.000246 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:46.000405 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:46.000897 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:46.001969 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:56:46.002573 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 2} D0227 11:56:46.002731 54532 transport_flipcall.go:127] send [channel @0xc0003683c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:56:46.003172 54532 transport_flipcall.go:234] recv [channel @0xc0003683c0] Rlerror{Error: 95} D0227 11:56:46.008281 54532 task_block.go:223] [ 103] Interrupt queued D0227 11:56:46.016112 54532 task_block.go:223] [ 104] Interrupt queued D0227 11:56:46.027596 54532 task_block.go:223] [ 105] Interrupt queued ================== WARNING: DATA RACE Read at 0x00c00056c490 by goroutine 352: 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 DIAGNOSIS: I0227 11:56:46.066532 64459 main.go:296] *************************** I0227 11:56:46.066691 64459 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-2] I0227 11:56:46.066930 64459 main.go:298] Version release-20200219.0-47-gde0b2ebf8635 I0227 11:56:46.067029 64459 main.go:299] PID: 64459 I0227 11:56:46.067149 64459 main.go:300] UID: 0, GID: 0 I0227 11:56:46.067256 64459 main.go:301] Configuration: I0227 11:56:46.067337 64459 main.go:302] RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root I0227 11:56:46.067428 64459 main.go:303] Platform: ptrace I0227 11:56:46.067529 64459 main.go:304] FileAccess: shared, overlay: false I0227 11:56:46.067616 64459 main.go:305] Network: sandbox, logging: false I0227 11:56:46.067706 64459 main.go:306] Strace: false, max size: 1024, syscalls: [] I0227 11:56:46.067824 64459 main.go:307] *************************** W0227 11:56:46.067939 64459 main.go:312] Block the TERM signal. This is only safe in tests! D0227 11:56:46.068150 64459 container.go:158] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-2" D0227 11:56:46.075502 64459 container.go:591] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-2": signal 0 D0227 11:56:46.075674 64459 sandbox.go:788] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0227 11:56:46.075862 64459 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0227 11:56:46.076361 64459 urpc.go:534] urpc: successfully marshalled 117 bytes. W0227 11:56:46.137953 64459 error.go:48] FATAL ERROR: container sandbox is not running container sandbox is not running W0227 11:56:46.138280 64459 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-2"]: exit status 128 I0227 11:56:46.066532 64459 main.go:296] *************************** I0227 11:56:46.066691 64459 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-2] I0227 11:56:46.066930 64459 main.go:298] Version release-20200219.0-47-gde0b2ebf8635 I0227 11:56:46.067029 64459 main.go:299] PID: 64459 I0227 11:56:46.067149 64459 main.go:300] UID: 0, GID: 0 I0227 11:56:46.067256 64459 main.go:301] Configuration: I0227 11:56:46.067337 64459 main.go:302] RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root I0227 11:56:46.067428 64459 main.go:303] Platform: ptrace I0227 11:56:46.067529 64459 main.go:304] FileAccess: shared, overlay: false I0227 11:56:46.067616 64459 main.go:305] Network: sandbox, logging: false I0227 11:56:46.067706 64459 main.go:306] Strace: false, max size: 1024, syscalls: [] I0227 11:56:46.067824 64459 main.go:307] *************************** W0227 11:56:46.067939 64459 main.go:312] Block the TERM signal. This is only safe in tests! D0227 11:56:46.068150 64459 container.go:158] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-2" D0227 11:56:46.075502 64459 container.go:591] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-2": signal 0 D0227 11:56:46.075674 64459 sandbox.go:788] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0227 11:56:46.075862 64459 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0227 11:56:46.076361 64459 urpc.go:534] urpc: successfully marshalled 117 bytes. W0227 11:56:46.137953 64459 error.go:48] FATAL ERROR: container sandbox is not running container sandbox is not running W0227 11:56:46.138280 64459 main.go:329] Failure to execute command, err: 1 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 write at 0x00c00056c490 by goroutine 363: 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 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 352 (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 363 (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 11:56:46.130717 64173 error.go:48] FATAL ERROR: waiting on pid 22: waiting on PID 22 in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2": urpc method "containerManager.WaitPID" failed: EOF waiting on pid 22: waiting on PID 22 in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2": urpc method "containerManager.WaitPID" failed: EOF W0227 11:56:46.131122 64173 main.go:329] Failure to execute command, err: 1 W0227 11:56:46.137253 54514 sandbox.go:724] Wait RPC to container "ci-gvisor-ptrace-proxy-sandbox-race-2" failed: urpc method "containerManager.Wait" failed: EOF. Will try waiting on the sandbox process instead. D0227 11:56:46.137857 54514 container.go:713] Destroy container "ci-gvisor-ptrace-proxy-sandbox-race-2" D0227 11:56:46.138832 54514 container.go:800] Destroying container "ci-gvisor-ptrace-proxy-sandbox-race-2" D0227 11:56:46.138927 54514 sandbox.go:1040] Destroying root container "ci-gvisor-ptrace-proxy-sandbox-race-2" by destroying sandbox D0227 11:56:46.139057 54514 sandbox.go:770] Destroy sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0227 11:56:46.139137 54514 container.go:814] Killing gofer for container "ci-gvisor-ptrace-proxy-sandbox-race-2", PID: 54531 I0227 11:56:46.345594 54514 main.go:320] Exiting with status: 16896