[pkg-go] Bug#929717: golang-fsnotify: FTBFS: dh_auto_test: cd obj-x86_64-linux-gnu && go test -vet=off -v -p 4 github.com/fsnotify/fsnotify returned exit code 1

Wed May 29 15:23:18 BST 2019

Source: golang-fsnotify
Version: 1.4.7-2
Severity: serious
Tags: buster sid
User: debian-qa at lists.debian.org
Usertags: qa-ftbfs-20190529 qa-ftbfs
Justification: FTBFS in buster on amd64

Hi,

During a rebuild of all packages in buster (in a buster chroot, not a
sid chroot), your package failed to build on amd64.

Relevant part (hopefully):
>  debian/rules build
> dh build --buildsystem=golang --with=golang
>    dh_update_autotools_config -O--buildsystem=golang
>    dh_autoreconf -O--buildsystem=golang
>    dh_auto_configure -O--buildsystem=golang
>    dh_auto_build -O--buildsystem=golang
> 	cd obj-x86_64-linux-gnu && go install -gcflags=all=\"-trimpath=/<<PKGBUILDDIR>>/obj-x86_64-linux-gnu/src\" -asmflags=all=\"-trimpath=/<<PKGBUILDDIR>>/obj-x86_64-linux-gnu/src\" -v -p 4 github.com/fsnotify/fsnotify
> internal/race
> errors
> internal/cpu
> runtime/internal/atomic
> runtime/internal/sys
> sync/atomic
> unicode
> unicode/utf8
> internal/testlog
> internal/bytealg
> math
> math/bits
> runtime
> vendor/golang_org/x/net/dns/dnsmessage
> internal/nettrace
> runtime/cgo
> strconv
> sync
> internal/singleflight
> io
> reflect
> syscall
> math/rand
> bytes
> strings
> internal/syscall/unix
> time
> internal/poll
> encoding/binary
> sort
> os
> path/filepath
> fmt
> context
> net
> golang.org/x/sys/unix
> github.com/fsnotify/fsnotify
>    dh_auto_test -O--buildsystem=golang
> 	cd obj-x86_64-linux-gnu && go test -vet=off -v -p 4 github.com/fsnotify/fsnotify
> === RUN   TestEventStringWithValue
> --- PASS: TestEventStringWithValue (0.00s)
> === RUN   TestEventOpStringWithValue
> --- PASS: TestEventOpStringWithValue (0.00s)
> === RUN   TestEventOpStringWithNoValue
> --- PASS: TestEventOpStringWithNoValue (0.00s)
> === RUN   TestWatcherClose
> === PAUSE TestWatcherClose
> === RUN   TestPollerWithBadFd
> --- PASS: TestPollerWithBadFd (0.00s)
> === RUN   TestPollerWithData
> --- PASS: TestPollerWithData (0.00s)
> === RUN   TestPollerWithWakeup
> --- PASS: TestPollerWithWakeup (0.00s)
> === RUN   TestPollerWithClose
> --- PASS: TestPollerWithClose (0.00s)
> === RUN   TestPollerWithWakeupAndData
> --- PASS: TestPollerWithWakeupAndData (0.00s)
> === RUN   TestPollerConcurrent
> --- PASS: TestPollerConcurrent (0.15s)
> === RUN   TestInotifyCloseRightAway
> --- PASS: TestInotifyCloseRightAway (0.05s)
> === RUN   TestInotifyCloseSlightlyLater
> --- PASS: TestInotifyCloseSlightlyLater (0.10s)
> === RUN   TestInotifyCloseSlightlyLaterWithWatch
> --- PASS: TestInotifyCloseSlightlyLaterWithWatch (0.10s)
> === RUN   TestInotifyCloseAfterRead
> --- PASS: TestInotifyCloseAfterRead (0.10s)
> === RUN   TestInotifyCloseCreate
> --- PASS: TestInotifyCloseCreate (0.05s)
> === RUN   TestInotifyStress
> --- PASS: TestInotifyStress (0.14s)
> === RUN   TestInotifyRemoveTwice
> --- PASS: TestInotifyRemoveTwice (0.00s)
> === RUN   TestInotifyInnerMapLength
> --- PASS: TestInotifyInnerMapLength (0.05s)
> === RUN   TestInotifyOverflow
> --- FAIL: TestInotifyOverflow (4.69s)
>     inotify_test.go:442: Could not trigger overflow
> === RUN   TestFsnotifyMultipleOperations
> --- PASS: TestFsnotifyMultipleOperations (0.67s)
>     integration_test.go:104: event received: "/tmp/fsnotify244767993/TestFsnotifySeq.testfile": CREATE
>     integration_test.go:104: event received: "/tmp/fsnotify244767993/TestFsnotifySeq.testfile": WRITE
>     integration_test.go:104: event received: "/tmp/fsnotify244767993/TestFsnotifySeq.testfile": RENAME
>     integration_test.go:104: event received: "/tmp/fsnotify244767993/TestFsnotifySeq.testfile": CREATE
>     integration_test.go:180: calling Close()
>     integration_test.go:182: waiting for the event channel to become closed...
>     integration_test.go:185: event channel closed
> === RUN   TestFsnotifyMultipleCreates
> --- PASS: TestFsnotifyMultipleCreates (0.80s)
>     integration_test.go:217: event received: "/tmp/fsnotify693647763/TestFsnotifySeq.testfile": CREATE
>     integration_test.go:217: event received: "/tmp/fsnotify693647763/TestFsnotifySeq.testfile": WRITE
>     integration_test.go:217: event received: "/tmp/fsnotify693647763/TestFsnotifySeq.testfile": REMOVE
>     integration_test.go:217: event received: "/tmp/fsnotify693647763/TestFsnotifySeq.testfile": CREATE
>     integration_test.go:217: event received: "/tmp/fsnotify693647763/TestFsnotifySeq.testfile": WRITE
>     integration_test.go:217: event received: "/tmp/fsnotify693647763/TestFsnotifySeq.testfile": WRITE
>     integration_test.go:306: calling Close()
>     integration_test.go:308: waiting for the event channel to become closed...
>     integration_test.go:311: event channel closed
> === RUN   TestFsnotifyDirOnly
> --- PASS: TestFsnotifyDirOnly (0.56s)
>     integration_test.go:356: event received: "/tmp/fsnotify473264598/TestFsnotifyDirOnly.testfile": CREATE
>     integration_test.go:356: event received: "/tmp/fsnotify473264598/TestFsnotifyDirOnly.testfile": WRITE
>     integration_test.go:356: event received: "/tmp/fsnotify473264598/TestFsnotifyDirOnly.testfile": REMOVE
>     integration_test.go:356: event received: "/tmp/fsnotify473264598/TestFsnotifyEventsExisting.testfile": REMOVE
>     integration_test.go:408: calling Close()
>     integration_test.go:410: waiting for the event channel to become closed...
>     integration_test.go:413: event channel closed
> === RUN   TestFsnotifyDeleteWatchedDir
> --- PASS: TestFsnotifyDeleteWatchedDir (0.50s)
>     integration_test.go:458: event received: "/tmp/fsnotify342294333/TestFsnotifyEventsExisting.testfile": REMOVE
>     integration_test.go:458: event received: "/tmp/fsnotify342294333/TestFsnotifyEventsExisting.testfile": REMOVE
>     integration_test.go:458: event received: "/tmp/fsnotify342294333": REMOVE
> === RUN   TestFsnotifySubDir
> --- PASS: TestFsnotifySubDir (0.70s)
>     integration_test.go:504: event received: "/tmp/fsnotify011955576/sub": CREATE
>     integration_test.go:504: event received: "/tmp/fsnotify011955576/TestFsnotifyFile1.testfile": CREATE
>     integration_test.go:504: event received: "/tmp/fsnotify011955576/sub": REMOVE
>     integration_test.go:504: event received: "/tmp/fsnotify011955576/TestFsnotifyFile1.testfile": REMOVE
>     integration_test.go:561: calling Close()
>     integration_test.go:563: waiting for the event channel to become closed...
>     integration_test.go:566: event channel closed
> === RUN   TestFsnotifyRename
> --- PASS: TestFsnotifyRename (0.50s)
>     integration_test.go:602: event received: "/tmp/fsnotify325096567/TestFsnotifyEvents.testfile": CREATE
>     integration_test.go:602: event received: "/tmp/fsnotify325096567/TestFsnotifyEvents.testfile": WRITE
>     integration_test.go:602: event received: "/tmp/fsnotify325096567/TestFsnotifyEvents.testfile": RENAME
>     integration_test.go:602: event received: "/tmp/fsnotify325096567/TestFsnotifyEvents.testfileRenamed": CREATE
>     integration_test.go:602: event received: "/tmp/fsnotify325096567/TestFsnotifyEvents.testfile": RENAME
>     integration_test.go:637: calling Close()
>     integration_test.go:639: waiting for the event channel to become closed...
>     integration_test.go:642: event channel closed
> === RUN   TestFsnotifyRenameToCreate
> --- PASS: TestFsnotifyRenameToCreate (0.50s)
>     integration_test.go:684: event received: "/tmp/fsnotify857532266/TestFsnotifyEvents.testfileRenamed": CREATE
>     integration_test.go:713: calling Close()
>     integration_test.go:715: waiting for the event channel to become closed...
>     integration_test.go:718: event channel closed
> === RUN   TestFsnotifyRenameToOverwrite
> --- PASS: TestFsnotifyRenameToOverwrite (0.50s)
>     integration_test.go:772: event received: "/tmp/fsnotify378106412/TestFsnotifyEvents.testfileRenamed": CREATE
>     integration_test.go:801: calling Close()
>     integration_test.go:803: waiting for the event channel to become closed...
>     integration_test.go:806: event channel closed
> === RUN   TestRemovalOfWatch
> --- PASS: TestRemovalOfWatch (0.60s)
>     integration_test.go:844: No event received, as expected.
> === RUN   TestFsnotifyAttrib
> --- PASS: TestFsnotifyAttrib (1.51s)
>     integration_test.go:901: event received: "/tmp/fsnotify663728773/TestFsnotifyAttrib.testfile": CHMOD
>     integration_test.go:901: event received: "/tmp/fsnotify663728773/TestFsnotifyAttrib.testfile": WRITE
>     integration_test.go:901: event received: "/tmp/fsnotify663728773/TestFsnotifyAttrib.testfile": CHMOD
>     integration_test.go:979: calling Close()
>     integration_test.go:981: waiting for the event channel to become closed...
>     integration_test.go:984: event channel closed
> === RUN   TestFsnotifyClose
> --- PASS: TestFsnotifyClose (0.05s)
> === RUN   TestFsnotifyFakeSymlink
> --- PASS: TestFsnotifyFakeSymlink (0.50s)
>     integration_test.go:1053: Created bogus symlink
>     integration_test.go:1039: event received: "/tmp/fsnotify559986943/zzznew": CREATE
>     integration_test.go:1075: calling Close()
> === RUN   TestCyclicSymlink
> --- PASS: TestCyclicSymlink (0.50s)
> === RUN   TestConcurrentRemovalOfWatch
> --- SKIP: TestConcurrentRemovalOfWatch (0.00s)
>     integration_test.go:1131: regression test for race only present on darwin
> === RUN   TestClose
> --- PASS: TestClose (0.00s)
> === RUN   TestRemoveWithClose
> --- PASS: TestRemoveWithClose (0.00s)
> === CONT  TestWatcherClose
> --- PASS: TestWatcherClose (0.10s)
> FAIL
> FAIL	github.com/fsnotify/fsnotify	13.458s
> dh_auto_test: cd obj-x86_64-linux-gnu && go test -vet=off -v -p 4 github.com/fsnotify/fsnotify returned exit code 1

The full build log is available from:
   http://qa-logs.debian.net/2019/05/29/golang-fsnotify_1.4.7-2_testing.log

A list of current common problems and possible solutions is available at
http://wiki.debian.org/qa.debian.org/FTBFS . You're welcome to contribute!

About the archive rebuild: The rebuild was done on EC2 VM instances from
Amazon Web Services, using a clean, minimal and up-to-date chroot. Every
failed build was retried once to eliminate random failures.