syzkaller: testing failed: failed to run ["go" "test" "-short" "./..."]: exit status 1 (14)

[syzbot]

Hello,

syzbot found the following issue on:

HEAD commit:
git tree: https://github.com/google/syzkaller.git master
console output: https://syzkaller.appspot.com/x/log.txt?x=125f5d4f900000
dashboard link: https://syzkaller.appspot.com/bug?extid=4ab4893f934611694ce3
compiler: go version go1.15.2 openbsd/amd64

IMPORTANT: if you fix the issue, please add the following tag to the commit:
Reported-by: syzbot+4ab489...@syzkaller.appspotmail.com



---
This report is generated by a bot. It may contain errors.
See https://goo.gl/tpsmEJ for more information about syzbot.
syzbot engineers can be reached at syzk...@googlegroups.com.

syzbot will keep track of this issue. See:
https://goo.gl/tpsmEJ#status for how to communicate with syzbot.

[Greg Steuck]

Does this look like a deadlock or a timeout? I was unable to reproduce on the same system using `gmake test`. Curious what else I could try.

panic: test timed out after 10m0s

goroutine 583 [running]:
testing.(*M).startAlarm.func1()
/usr/local/go/src/testing/testing.go:1628 +0xe5
created by time.goFunc
/usr/local/go/src/time/sleep.go:167 +0x45

goroutine 1 [chan receive, 9 minutes]:
testing.tRunner.func1(0xc000082480)
/usr/local/go/src/testing/testing.go:1092 +0x24d
testing.tRunner(0xc000082480, 0xc0000a2de0)
/usr/local/go/src/testing/testing.go:1131 +0x125
testing.runTests(0xc0000be160, 0x35eed80, 0x3e, 0x3e, 0xbfd7d8d827bfe2a7, 0x8bb40ce324, 0x3b849a0, 0x40d050)
/usr/local/go/src/testing/testing.go:1447 +0x2e8
testing.(*M).Run(0xc0000ee300, 0x0)
/usr/local/go/src/testing/testing.go:1357 +0x245
main.main()
_testmain.go:175 +0x138

goroutine 65 [chan receive, 9 minutes]:
testing.tRunner.func1(0xc00013f380)
/usr/local/go/src/testing/testing.go:1092 +0x24d
testing.tRunner(0xc00013f380, 0x77bd20)
/usr/local/go/src/testing/testing.go:1131 +0x125
created by testing.(*T).Run
/usr/local/go/src/testing/testing.go:1178 +0x386

goroutine 72 [chan send, 9 minutes]:
testing.tRunner.func1(0xc00016a300)
/usr/local/go/src/testing/testing.go:1117 +0x373
testing.tRunner(0xc00016a300, 0x77bcb8)
/usr/local/go/src/testing/testing.go:1131 +0x125
created by testing.(*T).Run
/usr/local/go/src/testing/testing.go:1178 +0x386

goroutine 74 [chan send, 9 minutes]:
testing.tRunner.func1(0xc00016a600)
/usr/local/go/src/testing/testing.go:1117 +0x373
testing.tRunner(0xc00016a600, 0x77bb60)
/usr/local/go/src/testing/testing.go:1131 +0x125
created by testing.(*T).Run
/usr/local/go/src/testing/testing.go:1178 +0x386

goroutine 75 [chan send, 9 minutes]:
testing.tRunner.func1(0xc00016a780)
/usr/local/go/src/testing/testing.go:1117 +0x373
testing.tRunner(0xc00016a780, 0x77bb68)
/usr/local/go/src/testing/testing.go:1131 +0x125
created by testing.(*T).Run
/usr/local/go/src/testing/testing.go:1178 +0x386

goroutine 76 [chan send, 9 minutes]:
testing.tRunner.func1(0xc00016aa80)
/usr/local/go/src/testing/testing.go:1117 +0x373
testing.tRunner(0xc00016aa80, 0x77bd08)
/usr/local/go/src/testing/testing.go:1131 +0x125
created by testing.(*T).Run
/usr/local/go/src/testing/testing.go:1178 +0x386

goroutine 77 [chan send, 9 minutes]:
testing.tRunner.func1(0xc00016ad80)
/usr/local/go/src/testing/testing.go:1117 +0x373
testing.tRunner(0xc00016ad80, 0x77bb80)
/usr/local/go/src/testing/testing.go:1131 +0x125
created by testing.(*T).Run
/usr/local/go/src/testing/testing.go:1178 +0x386

goroutine 87 [chan send, 9 minutes]:
testing.tRunner.func1(0xc00017c180)
/usr/local/go/src/testing/testing.go:1117 +0x373
testing.tRunner(0xc00017c180, 0x77bca8)
/usr/local/go/src/testing/testing.go:1131 +0x125
created by testing.(*T).Run
/usr/local/go/src/testing/testing.go:1178 +0x386

goroutine 88 [chan send, 9 minutes]:
testing.tRunner.func1(0xc00017c300)
/usr/local/go/src/testing/testing.go:1117 +0x373
testing.tRunner(0xc00017c300, 0x77bca0)
/usr/local/go/src/testing/testing.go:1131 +0x125
created by testing.(*T).Run
/usr/local/go/src/testing/testing.go:1178 +0x386

goroutine 89 [chan send, 9 minutes]:
testing.tRunner.func1(0xc00017c480)
/usr/local/go/src/testing/testing.go:1117 +0x373
testing.tRunner(0xc00017c480, 0x77baf8)
/usr/local/go/src/testing/testing.go:1131 +0x125
created by testing.(*T).Run
/usr/local/go/src/testing/testing.go:1178 +0x386

goroutine 90 [chan send, 9 minutes]:
testing.tRunner.func1(0xc00017c780)
/usr/local/go/src/testing/testing.go:1117 +0x373
testing.tRunner(0xc00017c780, 0x77bb00)
/usr/local/go/src/testing/testing.go:1131 +0x125
created by testing.(*T).Run
/usr/local/go/src/testing/testing.go:1178 +0x386

goroutine 91 [chan send, 9 minutes]:
testing.tRunner.func1(0xc00017ca80)
/usr/local/go/src/testing/testing.go:1117 +0x373
testing.tRunner(0xc00017ca80, 0x77bdc8)
/usr/local/go/src/testing/testing.go:1131 +0x125
created by testing.(*T).Run
/usr/local/go/src/testing/testing.go:1178 +0x386

goroutine 92 [chan receive, 9 minutes]:
testing.runTests.func1.1(0xc000082480)
/usr/local/go/src/testing/testing.go:1454 +0x3b
created by testing.runTests.func1
/usr/local/go/src/testing/testing.go:1454 +0xac

goroutine 393 [runnable]:
math/rand.(*Rand).Intn(0xc073fa5dd0, 0x14, 0x0)
/usr/local/go/src/math/rand/rand.go:167 +0xa5
github.com/google/syzkaller/prog.(*randGen).oneOf(...)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/rand.go:55
github.com/google/syzkaller/prog.(*randGen).flags(0xc073fba2e0, 0x3bb5d90, 0x2, 0x2, 0xc011bfa600, 0x0, 0x5da740)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/rand.go:236 +0x225
github.com/google/syzkaller/prog.(*FlagsType).generate(0x1bc2420, 0xc073fba2e0, 0xc0737706e0, 0xc00e4ff700, 0x8655c0, 0xc073fbe670, 0x0, 0x0, 0x0)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/rand.go:743 +0x65
github.com/google/syzkaller/prog.(*randGen).generateArgImpl(0xc073fba2e0, 0xc0737706e0, 0x867ca0, 0x1bc2420, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/rand.go:652 +0x40b
github.com/google/syzkaller/prog.(*randGen).generateArg(...)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/rand.go:602
github.com/google/syzkaller/prog.(*randGen).generateArgs(0xc073fba2e0, 0xc0737706e0, 0x301af60, 0x6, 0x6, 0x0, 0x20301c, 0x20301c, 0x20301c, 0x0, ...)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/rand.go:590 +0x179
github.com/google/syzkaller/prog.(*StructType).generate(0x1578fc0, 0xc073fba2e0, 0xc0737706e0, 0x0, 0x3bb66a0, 0xc073fba6e0, 0xc000300000, 0x22f516080, 0x0)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/rand.go:782 +0x7c
github.com/google/syzkaller/prog.(*randGen).generateArgImpl(0xc073fba2e0, 0xc0737706e0, 0x8681e0, 0x1578fc0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/rand.go:652 +0x40b
github.com/google/syzkaller/prog.(*randGen).generateArg(...)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/rand.go:602
github.com/google/syzkaller/prog.(*UnionType).generate(0xee98e0, 0xc073fba2e0, 0xc0737706e0, 0x0, 0x3bb66a0, 0xc073fba700, 0xc011bfaaf8, 0x44b765, 0xc0732cfd00)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/rand.go:790 +0xd4
github.com/google/syzkaller/prog.(*randGen).generateArgImpl(0xc073fba2e0, 0xc0737706e0, 0x8682c0, 0xee98e0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/rand.go:652 +0x40b
github.com/google/syzkaller/prog.(*randGen).generateArg(...)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/rand.go:602
github.com/google/syzkaller/prog.(*ArrayType).generate(0x1815ea0, 0xc073fba2e0, 0xc0737706e0, 0xc073fba600, 0x216bebc58, 0x2, 0xc000300000, 0x22f516080, 0x0)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/rand.go:774 +0x177
github.com/google/syzkaller/prog.(*randGen).generateArgImpl(0xc073fba2e0, 0xc0737706e0, 0x867920, 0x1815ea0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/rand.go:652 +0x40b
github.com/google/syzkaller/prog.(*randGen).generateArg(...)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/rand.go:602
github.com/google/syzkaller/prog.(*randGen).generateArgs(0xc073fba2e0, 0xc0737706e0, 0x1817280, 0x2, 0x2, 0x1578d00, 0x200000, 0x865640, 0xc073fba5e0, 0x0, ...)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/rand.go:590 +0x179
github.com/google/syzkaller/prog.(*StructType).generate(0x1578f00, 0xc073fba2e0, 0xc0737706e0, 0x0, 0x3bb66a0, 0xc073fba600, 0x0, 0x0, 0x0)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/rand.go:782 +0x7c
github.com/google/syzkaller/prog.(*randGen).generateArgImpl(0xc073fba2e0, 0xc0737706e0, 0x8681e0, 0x1578f00, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/rand.go:652 +0x40b
github.com/google/syzkaller/prog.(*randGen).generateArg(...)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/rand.go:602
github.com/google/syzkaller/prog.(*randGen).generateArgs(0xc073fba2e0, 0xc0737706e0, 0x33d74a0, 0x9, 0x9, 0x27c8c73fbe300, 0x20301c, 0xc07c477088, 0x40d9b8, 0x10, ...)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/rand.go:590 +0x179
github.com/google/syzkaller/prog.(*StructType).generate(0x1578f60, 0xc073fba2e0, 0xc0737706e0, 0x0, 0x3bb66a0, 0xc073fbe350, 0x0, 0x0, 0x0)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/rand.go:782 +0x7c
github.com/google/syzkaller/prog.(*randGen).generateArgImpl(0xc073fba2e0, 0xc0737706e0, 0x8681e0, 0x1578f60, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/rand.go:652 +0x40b
github.com/google/syzkaller/prog.(*randGen).generateArg(...)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/rand.go:602
github.com/google/syzkaller/prog.(*randGen).generateArgs(0xc073fba2e0, 0xc0737706e0, 0x1816fe0, 0x2, 0x2, 0x0, 0xc073fc0f80, 0x0, 0xc07c477370, 0x54e1cb, ...)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/rand.go:590 +0x179
github.com/google/syzkaller/prog.(*StructType).generate(0x1577700, 0xc073fba2e0, 0xc0737706e0, 0x0, 0x3bb66a0, 0x0, 0x20301c, 0x0, 0xc07c477488)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/rand.go:782 +0x7c
github.com/google/syzkaller/prog.(*randGen).generateArgImpl(0xc073fba2e0, 0xc0737706e0, 0x8681e0, 0x1577700, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/rand.go:652 +0x40b
github.com/google/syzkaller/prog.(*randGen).generateArg(...)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/rand.go:602
github.com/google/syzkaller/prog.(*UnionType).generate(0xee96e0, 0xc073fba2e0, 0xc0737706e0, 0x0, 0x3bb66a0, 0x8, 0xc000300000, 0x22f516080, 0x0)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/rand.go:790 +0xd4
github.com/google/syzkaller/prog.(*randGen).generateArgImpl(0xc073fba2e0, 0xc0737706e0, 0x8682c0, 0xee96e0, 0xc000300000, 0x0, 0x0, 0x0, 0x0, 0x0)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/rand.go:652 +0x40b
github.com/google/syzkaller/prog.(*randGen).generateArg(...)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/rand.go:602
github.com/google/syzkaller/prog.(*randGen).generateArgs(0xc073fba2e0, 0xc0737706e0, 0xc85d20, 0x1, 0x1, 0x0, 0x0, 0x1, 0x0, 0x0, ...)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/rand.go:590 +0x179
github.com/google/syzkaller/prog.(*StructType).generate(0x15777c0, 0xc073fba2e0, 0xc0737706e0, 0x0, 0x3bb66a0, 0xc073fba4e0, 0x0, 0x0, 0x0)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/rand.go:782 +0x7c
github.com/google/syzkaller/prog.(*randGen).generateArgImpl(0xc073fba2e0, 0xc0737706e0, 0x8681e0, 0x15777c0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/rand.go:652 +0x40b
github.com/google/syzkaller/prog.(*randGen).generateArg(...)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/rand.go:602
github.com/google/syzkaller/prog.(*randGen).generateArgs(0xc073fba2e0, 0xc0737706e0, 0x246f2c0, 0x4, 0x4, 0x157c900, 0x400000, 0x0, 0x0, 0x0, ...)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/rand.go:590 +0x179
github.com/google/syzkaller/prog.(*StructType).generate(0x1577760, 0xc073fba2e0, 0xc0737706e0, 0x0, 0x3bb66a0, 0x40d9b8, 0x40d050, 0x5e51c0, 0x867a01)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/rand.go:782 +0x7c
github.com/google/syzkaller/prog.(*randGen).generateArgImpl(0xc073fba2e0, 0xc0737706e0, 0x8681e0, 0x1577760, 0xc000320000, 0x0, 0x0, 0x0, 0x0, 0x0)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/rand.go:652 +0x40b
github.com/google/syzkaller/prog.(*randGen).generateArg(...)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/rand.go:602
github.com/google/syzkaller/prog.(*PtrType).generate(0xee85e0, 0xc073fba2e0, 0xc0737706e0, 0xc073fba300, 0x8655c0, 0xc073fbe240, 0x0, 0x0, 0x0)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/rand.go:799 +0x127
github.com/google/syzkaller/prog.(*randGen).generateArgImpl(0xc073fba2e0, 0xc0737706e0, 0x868020, 0xee85e0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/rand.go:652 +0x40b
github.com/google/syzkaller/prog.(*randGen).generateArg(...)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/rand.go:602
github.com/google/syzkaller/prog.(*randGen).generateArgs(0xc073fba2e0, 0xc0737706e0, 0x1815300, 0x2, 0x2, 0x0, 0x5ebb40, 0x5ef701, 0x41379b, 0xc00032acf0, ...)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/rand.go:590 +0x179
github.com/google/syzkaller/prog.(*randGen).generateParticularCall(0xc073fba2e0, 0xc0737706e0, 0x2a6aae0, 0x11, 0xc00032ae50, 0xc0737706e0)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/rand.go:545 +0xda
github.com/google/syzkaller/prog.(*Target).GenerateAllSyzProg(0xc0000fe820, 0x863c40, 0xc001816a00, 0x2780cb48)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/rand.go:563 +0x2ce
github.com/google/syzkaller/prog.TestSpecialStructs.func1(0xc000a83b00, 0xc0000fe820, 0x863c40, 0xc001816a00, 0x5)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/prog_test.go:217 +0x5a
github.com/google/syzkaller/prog.testEachTargetRandom.func1(0xc000a83b00)
/syzkaller/gopath/src/github.com/google/syzkaller/prog/export_test.go:82 +0x9a
testing.tRunner(0xc000a83b00, 0xc000384030)
/usr/local/go/src/testing/testing.go:1127 +0xef
created by testing.(*T).Run
/usr/local/go/src/testing/testing.go:1178 +0x386

goroutine 394 [chan send, 9 minutes]:
testing.tRunner.func1(0xc000a83e00)
/usr/local/go/src/testing/testing.go:1117 +0x373
testing.tRunner(0xc000a83e00, 0xc000384090)
/usr/local/go/src/testing/testing.go:1131 +0x125
created by testing.(*T).Run
/usr/local/go/src/testing/testing.go:1178 +0x386

goroutine 395 [chan send, 9 minutes]:
testing.tRunner.func1(0xc000b82900)
/usr/local/go/src/testing/testing.go:1117 +0x373
testing.tRunner(0xc000b82900, 0xc0003840f0)
/usr/local/go/src/testing/testing.go:1131 +0x125
created by testing.(*T).Run
/usr/local/go/src/testing/testing.go:1178 +0x386

goroutine 396 [chan send, 9 minutes]:
testing.tRunner.func1(0xc000b82c00)
/usr/local/go/src/testing/testing.go:1117 +0x373
testing.tRunner(0xc000b82c00, 0xc000384150)
/usr/local/go/src/testing/testing.go:1131 +0x125
created by testing.(*T).Run
/usr/local/go/src/testing/testing.go:1178 +0x386

goroutine 397 [chan send, 9 minutes]:
testing.tRunner.func1(0xc000b82f00)
/usr/local/go/src/testing/testing.go:1117 +0x373
testing.tRunner(0xc000b82f00, 0xc0003841b0)
/usr/local/go/src/testing/testing.go:1131 +0x125
created by testing.(*T).Run
/usr/local/go/src/testing/testing.go:1178 +0x386

goroutine 398 [chan send, 9 minutes]:
testing.tRunner.func1(0xc000b83200)
/usr/local/go/src/testing/testing.go:1117 +0x373
testing.tRunner(0xc000b83200, 0xc000384210)
/usr/local/go/src/testing/testing.go:1131 +0x125
created by testing.(*T).Run
/usr/local/go/src/testing/testing.go:1178 +0x386
FAIL github.com/google/syzkaller/prog 602.424s

--
You received this message because you are subscribed to the Google Groups "syzkaller-openbsd-bugs" group.
To unsubscribe from this group and stop receiving emails from it, send an email to syzkaller-openbsd...@googlegroups.com.
To view this discussion on the web visit https://groups.google.com/d/msgid/syzkaller-openbsd-bugs/00000000000023f21905b1266e9e%40google.com.

--

nest.cx is Gmail hosted, use PGP: https://pgp.key-server.io/0x0B1542BD8DF5A1B0
Fingerprint: 5E2B 2D0E 1E03 2046 BEC3  4D50 0B15 42BD 8DF5 A1B0

[Dmitry Vyukov]

I think it's just a timeout flake.
On my laptop:

syzkaller$ go test -short ./prog
ok github.com/google/syzkaller/prog 9.450s

but as far as I remember we already observed similar flakes on OpenBSD
in the past.
I think we need to reduce the size of prog tests in short mode and/or
speed up execution.

> To view this discussion on the web visit https://groups.google.com/d/msgid/syzkaller-openbsd-bugs/CANMcDo_TWPduCBHoTF8VWeXi9XXMrF7VTEv5-5GhMBqMe2N9QQ%40mail.gmail.com.

[Greg Steuck]

The surprising part is the same machine ran the tests quickly with a manual `gmake test`. I don't understand what is different between my run and syz-bot run.

#syz invalid

To view this discussion on the web visit https://groups.google.com/d/msgid/syzkaller-openbsd-bugs/CACT4Y%2BbHQZmxGvjYzo-Z6yKAdsUEdpsQ7RyEBAqGm1%2BE9%3D9YPg%40mail.gmail.com.