Bug#903374: tracker : flaky autopkgtest: ERROR: tracker-monitor-test - Bail out!

Paul Gevers elbrus at debian.org
Mon Jul 9 09:18:24 BST 2018 

Source: tracker
Version: 2.0.3-3
Severity: important
User: debian-ci at lists.debian.org
Usertags: flaky

While inspecting regressions in autopkgtest results¹, I noticed that
your package failed multiple times² without apparent changes and passed
later again. Most (recent) cases had the error that I copied below, but
there were others as well. The last regression was involved in delaying
the migration of glibc.

Could you please investigate and make your autopkgtest more robust?
Please contact me if you need help and you think I can provide that (I
am not subscribed to this bug). Note that we recently added the "flaky"
restriction to autopkgtest. If you can't really fix the test, but still
want it run, you can mark it as not suitable for gating.

Recent discussion of gating migration by autopkgtests on debian-devel³
noted that if this is going to work, and in particular if we are going
to *block* migration when it causes autopkgtest regressions rather than
merely delaying it, intermittent autopkgtest failures are likely to have
to be considered RC due to their impact on the tested package's
dependencies; for now I've filed it as important.

Paul

¹ https://ci.debian.net/packages/t/tracker/
²
https://ci.debian.net/data/autopkgtest/unstable/amd64/t/tracker/463571/log.gz
https://ci.debian.net/data/autopkgtest/unstable/amd64/t/tracker/445513/log.gz
https://ci.debian.net/data/autopkgtest/unstable/amd64/t/tracker/381226/log.gz
https://ci.debian.net/data/autopkgtest/unstable/amd64/t/tracker/555447/log.gz
https://ci.debian.net/data/autopkgtest/unstable/amd64/t/tracker/560505/log.gz
https://ci.debian.net/data/autopkgtest/testing/amd64/t/tracker/580054/log.gz
³ https://lists.debian.org/debian-devel/2018/05/msg00061.html

https://ci.debian.net/data/autopkgtest/testing/amd64/t/tracker/580054/log.gz

ERROR: tracker-monitor-test
===========================

# random seed: R02Sc5faf77e1dc66ff760e4a22ce3f4f6d8
# Testing filesystem monitor
1..15
# Start of libtracker-miner tests
# Start of tracker-monitor tests
# Tracker-DEBUG: Monitor backend is Inotify
# Tracker-DEBUG: Monitor limit is 7692
# Tracker-DEBUG: Added monitor for
path:'file:///tmp/monitor-test-18773', total monitors:1
# Tracker-DEBUG: Removed monitor for
path:'file:///tmp/monitor-test-18773', total monitors:0
# Tracker-DEBUG: Added monitor for
path:'file:///tmp/monitor-test-18773', total monitors:1
# Tracker-DEBUG: Added monitor for path:'file:///tmp', total monitors:2
# Tracker-DEBUG: Removed all monitors recursively for
path:'file:///tmp', total monitors:0
# Tracker-DEBUG: Added monitor for
path:'file:///tmp/monitor-test-18773', total monitors:1
# Tracker-DEBUG: Removed monitor for
path:'file:///tmp/monitor-test-18773', total monitors:0
# Tracker-DEBUG: Added monitor for
path:'file:///tmp/monitor-test-18773', total monitors:1
# Tracker-DEBUG: Added monitor for path:'file:///tmp', total monitors:2
# Tracker-DEBUG: Removed all monitors recursively for
path:'file:///tmp', total monitors:0
ok 1 /libtracker-miner/tracker-monitor/basic
PASS: tracker-monitor-test 1 /libtracker-miner/tracker-monitor/basic
# Start of file-event tests
# Tracker-DEBUG: Monitor backend is Inotify
# Tracker-DEBUG: Monitor limit is 7692
# Tracker-DEBUG: Added monitor for
path:'file:///tmp/monitor-test-18773', total monitors:1
# Tracker-DEBUG: Waiting 5 seconds for monitor events...
# Tracker-DEBUG: Received monitor event:3 (G_FILE_MONITOR_EVENT_CREATED)
for file:'file:///tmp/monitor-test-18773/created.txt'
# Tracker-DEBUG: ***** '/tmp/monitor-test-18773/created.txt' (FILE)
(CREATED)
# Tracker-DEBUG: Received monitor event:0 (G_FILE_MONITOR_EVENT_CHANGED)
for file:'file:///tmp/monitor-test-18773/created.txt'
# Tracker-DEBUG: ***** '/tmp/monitor-test-18773/created.txt' (FILE)
(UPDATED)
# Tracker-DEBUG: Received monitor event:1
(G_FILE_MONITOR_EVENT_CHANGES_DONE_HINT) for
file:'file:///tmp/monitor-test-18773/created.txt'
Signals received for 'file:///tmp/monitor-test-18773/created.txt':
   CREATED:           yes
   UPDATED:           yes
   ATTRIBUTE UPDATED: no
   DELETED:           no
   MOVED_FROM:        no
   MOVED_TO:          no
# Tracker-DEBUG: Removed monitor for
path:'file:///tmp/monitor-test-18773', total monitors:0
ok 2 /libtracker-miner/tracker-monitor/file-event/created
PASS: tracker-monitor-test 2
/libtracker-miner/tracker-monitor/file-event/created
# Tracker-DEBUG: Monitor backend is Inotify
# Tracker-DEBUG: Monitor limit is 7692
# Tracker-DEBUG: Added monitor for
path:'file:///tmp/monitor-test-18773', total monitors:1
# Tracker-DEBUG: Waiting 5 seconds for monitor events...
# Tracker-DEBUG: Received monitor event:0 (G_FILE_MONITOR_EVENT_CHANGED)
for file:'file:///tmp/monitor-test-18773/created.txt'
# Tracker-DEBUG: ***** '/tmp/monitor-test-18773/created.txt' (FILE)
(UPDATED)
# Tracker-DEBUG: Received monitor event:1
(G_FILE_MONITOR_EVENT_CHANGES_DONE_HINT) for
file:'file:///tmp/monitor-test-18773/created.txt'
Signals received for 'file:///tmp/monitor-test-18773/created.txt':
   CREATED:           no
   UPDATED:           yes
   ATTRIBUTE UPDATED: no
   DELETED:           no
   MOVED_FROM:        no
   MOVED_TO:          no
# Tracker-DEBUG: Removed monitor for
path:'file:///tmp/monitor-test-18773', total monitors:0
ok 3 /libtracker-miner/tracker-monitor/file-event/updated
PASS: tracker-monitor-test 3
/libtracker-miner/tracker-monitor/file-event/updated
# Tracker-DEBUG: Monitor backend is Inotify
# Tracker-DEBUG: Monitor limit is 7692
# Tracker-DEBUG: Added monitor for
path:'file:///tmp/monitor-test-18773', total monitors:1
# Tracker-DEBUG: Waiting 5 seconds for monitor events...
# Tracker-DEBUG: Received monitor event:4
(G_FILE_MONITOR_EVENT_ATTRIBUTE_CHANGED) for
file:'file:///tmp/monitor-test-18773/created.txt'
# Tracker-DEBUG: ***** '/tmp/monitor-test-18773/created.txt' (FILE)
(ATRIBUTE UPDATED)
Signals received for 'file:///tmp/monitor-test-18773/created.txt':
   CREATED:           no
   UPDATED:           no
   ATTRIBUTE UPDATED: yes
   DELETED:           no
   MOVED_FROM:        no
   MOVED_TO:          no
# Tracker-DEBUG: Removed monitor for
path:'file:///tmp/monitor-test-18773', total monitors:0
ok 4 /libtracker-miner/tracker-monitor/file-event/attribute-updated
PASS: tracker-monitor-test 4
/libtracker-miner/tracker-monitor/file-event/attribute-updated
# Tracker-DEBUG: Monitor backend is Inotify
# Tracker-DEBUG: Monitor limit is 7692
# Tracker-DEBUG: Added monitor for
path:'file:///tmp/monitor-test-18773', total monitors:1
# Tracker-DEBUG: Waiting 5 seconds for monitor events...
# Tracker-DEBUG: Received monitor event:2 (G_FILE_MONITOR_EVENT_DELETED)
for file:'file:///tmp/monitor-test-18773/created.txt'
# Tracker-DEBUG: ***** '/tmp/monitor-test-18773/created.txt' (FILE)
(DELETED)
Signals received for 'file:///tmp/monitor-test-18773/created.txt':
   CREATED:           no
   UPDATED:           no
   ATTRIBUTE UPDATED: no
   DELETED:           yes
   MOVED_FROM:        no
   MOVED_TO:          no
# Tracker-DEBUG: Removed monitor for
path:'file:///tmp/monitor-test-18773', total monitors:0
ok 5 /libtracker-miner/tracker-monitor/file-event/deleted
PASS: tracker-monitor-test 5
/libtracker-miner/tracker-monitor/file-event/deleted
# Start of moved tests
# Tracker-DEBUG: Monitor backend is Inotify
# Tracker-DEBUG: Monitor limit is 7692
# Tracker-DEBUG: Added monitor for
path:'file:///tmp/monitor-test-18773', total monitors:1
# Tracker-DEBUG: Waiting 5 seconds for monitor events...
# Tracker-DEBUG: Received monitor event:8 (G_FILE_MONITOR_EVENT_RENAMED)
for files
'file:///tmp/monitor-test-18773/created.txt'->'file:///tmp/monitor-test-18773/renamed.txt'
# Tracker-DEBUG: *****
'/tmp/monitor-test-18773/renamed.txt'->'/tmp/monitor-test-18773/renamed.txt'
(FILE) (MOVED) (source monitored)
Signals received for 'file:///tmp/monitor-test-18773/created.txt':
   CREATED:           no
   UPDATED:           no
   ATTRIBUTE UPDATED: no
   DELETED:           no
   MOVED_FROM:        yes
   MOVED_TO:          no
Signals received for 'file:///tmp/monitor-test-18773/renamed.txt':
   CREATED:           no
   UPDATED:           no
   ATTRIBUTE UPDATED: no
   DELETED:           no
   MOVED_FROM:        no
   MOVED_TO:          yes
# Tracker-DEBUG: Removed monitor for
path:'file:///tmp/monitor-test-18773', total monitors:0
ok 6 /libtracker-miner/tracker-monitor/file-event/moved/to-monitored
PASS: tracker-monitor-test 6
/libtracker-miner/tracker-monitor/file-event/moved/to-monitored
# Tracker-DEBUG: Monitor backend is Inotify
# Tracker-DEBUG: Monitor limit is 7692
# Tracker-DEBUG: Added monitor for
path:'file:///tmp/monitor-test-18773', total monitors:1
# Tracker-DEBUG: Waiting 5 seconds for monitor events...
# Tracker-DEBUG: Received monitor event:10
(G_FILE_MONITOR_EVENT_MOVED_OUT) for
file:'file:///tmp/monitor-test-18773/created.txt'
# Tracker-DEBUG: ***** '/tmp/monitor-test-18773/created.txt' (FILE)
(DELETED)
Signals received for 'file:///tmp/out.txt':
   CREATED:           no
   UPDATED:           no
   ATTRIBUTE UPDATED: no
   DELETED:           no
   MOVED_FROM:        no
   MOVED_TO:          no
Signals received for 'file:///tmp/monitor-test-18773/created.txt':
   CREATED:           no
   UPDATED:           no
   ATTRIBUTE UPDATED: no
   DELETED:           yes
   MOVED_FROM:        no
   MOVED_TO:          no
# Tracker-DEBUG: Removed monitor for
path:'file:///tmp/monitor-test-18773', total monitors:0
ok 7 /libtracker-miner/tracker-monitor/file-event/moved/to-not-monitored
PASS: tracker-monitor-test 7
/libtracker-miner/tracker-monitor/file-event/moved/to-not-monitored
# Tracker-DEBUG: Monitor backend is Inotify
# Tracker-DEBUG: Monitor limit is 7692
# Tracker-DEBUG: Added monitor for
path:'file:///tmp/monitor-test-18773', total monitors:1
# Tracker-DEBUG: Waiting 5 seconds for monitor events...
# Tracker-DEBUG: Received monitor event:9
(G_FILE_MONITOR_EVENT_MOVED_IN) for
file:'file:///tmp/monitor-test-18773/in.txt'
# Tracker-DEBUG: ***** '/tmp/monitor-test-18773/in.txt' (FILE) (CREATED)
Signals received for 'file:///tmp/monitor-test-18773/in.txt':
   CREATED:           yes
   UPDATED:           no
   ATTRIBUTE UPDATED: no
   DELETED:           no
   MOVED_FROM:        no
   MOVED_TO:          no
Signals received for 'file:///tmp/created.txt':
   CREATED:           no
   UPDATED:           no
   ATTRIBUTE UPDATED: no
   DELETED:           no
   MOVED_FROM:        no
   MOVED_TO:          no
# Tracker-DEBUG: Removed monitor for
path:'file:///tmp/monitor-test-18773', total monitors:0
ok 8 /libtracker-miner/tracker-monitor/file-event/moved/from-not-monitored
PASS: tracker-monitor-test 8
/libtracker-miner/tracker-monitor/file-event/moved/from-not-monitored
# End of moved tests
# End of file-event tests
# Start of directory-event tests
# Tracker-DEBUG: Monitor backend is Inotify
# Tracker-DEBUG: Monitor limit is 7692
# Tracker-DEBUG: Added monitor for
path:'file:///tmp/monitor-test-18773', total monitors:1
# Tracker-DEBUG: Waiting 5 seconds for monitor events...
# Tracker-DEBUG: Received monitor event:3 (G_FILE_MONITOR_EVENT_CREATED)
for directory:'file:///tmp/monitor-test-18773/foo'
# Tracker-DEBUG: ***** '/tmp/monitor-test-18773/foo' (DIR) (CREATED)
# Tracker-DEBUG: Received monitor event:1
(G_FILE_MONITOR_EVENT_CHANGES_DONE_HINT) for
directory:'file:///tmp/monitor-test-18773/foo'
Signals received for 'file:///tmp/monitor-test-18773/foo':
   CREATED:           yes
   UPDATED:           no
   ATTRIBUTE UPDATED: no
   DELETED:           no
   MOVED_FROM:        no
   MOVED_TO:          no
# Tracker-DEBUG: Removed monitor for
path:'file:///tmp/monitor-test-18773', total monitors:0
ok 9 /libtracker-miner/tracker-monitor/directory-event/created
PASS: tracker-monitor-test 9
/libtracker-miner/tracker-monitor/directory-event/created
# Tracker-DEBUG: Monitor backend is Inotify
# Tracker-DEBUG: Monitor limit is 7692
# Tracker-DEBUG: Added monitor for
path:'file:///tmp/monitor-test-18773', total monitors:1
# Tracker-DEBUG: Added monitor for
path:'file:///tmp/monitor-test-18773/foo', total monitors:2
# Tracker-DEBUG: Waiting 5 seconds for monitor events...
# Tracker-DEBUG: Received monitor event:2 (G_FILE_MONITOR_EVENT_DELETED)
for directory:'file:///tmp/monitor-test-18773/foo'
# Tracker-DEBUG: Cancelled monitor for
path:'file:///tmp/monitor-test-18773/foo'
# Tracker-DEBUG: ***** '/tmp/monitor-test-18773/foo' (DIR) (DELETED)
Signals received for 'file:///tmp/monitor-test-18773/foo':
   CREATED:           no
   UPDATED:           no
   ATTRIBUTE UPDATED: no
   DELETED:           yes
   MOVED_FROM:        no
   MOVED_TO:          no
# Tracker-DEBUG: Removed monitor for
path:'file:///tmp/monitor-test-18773/foo', total monitors:1
# Tracker-DEBUG: Removed monitor for
path:'file:///tmp/monitor-test-18773', total monitors:0
ok 10 /libtracker-miner/tracker-monitor/directory-event/deleted
PASS: tracker-monitor-test 10
/libtracker-miner/tracker-monitor/directory-event/deleted
# Start of moved tests
# Tracker-DEBUG: Monitor backend is Inotify
# Tracker-DEBUG: Monitor limit is 7692
# Tracker-DEBUG: Added monitor for
path:'file:///tmp/monitor-test-18773', total monitors:1
# Tracker-DEBUG: Added monitor for
path:'file:///tmp/monitor-test-18773/foo', total monitors:2
# Tracker-DEBUG: Waiting 5 seconds for monitor events...
# Tracker-DEBUG: Received monitor event:8 (G_FILE_MONITOR_EVENT_RENAMED)
for files
'file:///tmp/monitor-test-18773/foo'->'file:///tmp/monitor-test-18773/renamed'
# Tracker-DEBUG: Cancelled monitor for
path:'file:///tmp/monitor-test-18773/foo'
# Tracker-DEBUG: *****
'/tmp/monitor-test-18773/renamed'->'/tmp/monitor-test-18773/renamed'
(DIR) (MOVED) (source monitored)
Signals received for 'file:///tmp/monitor-test-18773/renamed':
   CREATED:           no
   UPDATED:           no
   ATTRIBUTE UPDATED: no
   DELETED:           no
   MOVED_FROM:        no
   MOVED_TO:          yes
Signals received for 'file:///tmp/monitor-test-18773/foo':
   CREATED:           no
   UPDATED:           no
   ATTRIBUTE UPDATED: no
   DELETED:           no
   MOVED_FROM:        yes
   MOVED_TO:          no
Signals received for 'file:///tmp/monitor-test-18773/renamed/lalala.txt':
   CREATED:          **
Tracker:ERROR:tracker-monitor-test.c:1043:test_monitor_directory_event_moved_to_monitored_after_file_create:
assertion failed ((file_events & MONITOR_SIGNAL_ITEM_DELETED) == 0): (8
== 0)
Aborted
 no
   UPDATED:           no
   ATTRIBUTE UPDATED: no
   DELETED:           no
   MOVED_FROM:        no
   MOVED_TO:          no
Signals received for 'file:///tmp/monitor-test-18773/foo/lalala.txt':
   CREATED:           no
   UPDATED:           no
   ATTRIBUTE UPDATED: no
   DELETED:           no
   MOVED_FROM:        no
   MOVED_TO:          no
# Tracker-DEBUG: Removed monitor for
path:'file:///tmp/monitor-test-18773/foo', total monitors:1
# Tracker-DEBUG: Removed monitor for
path:'file:///tmp/monitor-test-18773', total monitors:0
ok 11 /libtracker-miner/tracker-monitor/directory-event/moved/to-monitored
PASS: tracker-monitor-test 11
/libtracker-miner/tracker-monitor/directory-event/moved/to-monitored
# Tracker-DEBUG: Monitor backend is Inotify
# Tracker-DEBUG: Monitor limit is 7692
# Tracker-DEBUG: Added monitor for
path:'file:///tmp/monitor-test-18773', total monitors:1
# Tracker-DEBUG: Added monitor for
path:'file:///tmp/monitor-test-18773/foo', total monitors:2
# Tracker-DEBUG: Waiting 5 seconds for monitor events...
# Tracker-DEBUG: Received monitor event:3 (G_FILE_MONITOR_EVENT_CREATED)
for file:'file:///tmp/monitor-test-18773/foo/lalala.txt'
# Tracker-DEBUG: ***** '/tmp/monitor-test-18773/foo/lalala.txt' (FILE)
(CREATED)
# Tracker-DEBUG: Received monitor event:1
(G_FILE_MONITOR_EVENT_CHANGES_DONE_HINT) for
file:'file:///tmp/monitor-test-18773/foo/lalala.txt'
# Tracker-DEBUG: Received monitor event:0 (G_FILE_MONITOR_EVENT_CHANGED)
for file:'file:///tmp/monitor-test-18773/foo/lalala.txt'
# Tracker-DEBUG: ***** '/tmp/monitor-test-18773/foo/lalala.txt' (FILE)
(UPDATED)
# Tracker-DEBUG: Received monitor event:1
(G_FILE_MONITOR_EVENT_CHANGES_DONE_HINT) for
file:'file:///tmp/monitor-test-18773/foo/lalala.txt'
# Tracker-DEBUG: Received monitor event:2 (G_FILE_MONITOR_EVENT_DELETED)
for directory:'file:///tmp/monitor-test-18773/foo'
# Tracker-DEBUG: Cancelled monitor for
path:'file:///tmp/monitor-test-18773/foo'
# Tracker-DEBUG: ***** '/tmp/monitor-test-18773/foo' (DIR) (DELETED)
# Tracker-DEBUG: Received monitor event:8 (G_FILE_MONITOR_EVENT_RENAMED)
for files
'file:///tmp/monitor-test-18773/foo'->'file:///tmp/monitor-test-18773/renamed'
# Tracker-DEBUG: Cancelled monitor for
path:'file:///tmp/monitor-test-18773/foo'
# Tracker-DEBUG: *****
'/tmp/monitor-test-18773/renamed'->'/tmp/monitor-test-18773/renamed'
(DIR) (MOVED) (source monitored)
Signals received for 'file:///tmp/monitor-test-18773/renamed':
   CREATED:           no
   UPDATED:           no
   ATTRIBUTE UPDATED: no
   DELETED:           no
   MOVED_FROM:        no
   MOVED_TO:          yes
Signals received for 'file:///tmp/monitor-test-18773/foo':
   CREATED:           no
   UPDATED:           no
   ATTRIBUTE UPDATED: no
   DELETED:           yes
   MOVED_FROM:        yes
   MOVED_TO:          no
Signals received for 'file:///tmp/monitor-test-18773/renamed/lalala.txt':
   CREATED:           no
   UPDATED:           no
   ATTRIBUTE UPDATED: no
   DELETED:           no
   MOVED_FROM:        no
   MOVED_TO:          no
Signals received for 'file:///tmp/monitor-test-18773/foo/lalala.txt':
   CREATED:           yes
   UPDATED:           yes
   ATTRIBUTE UPDATED: no
   DELETED:           no
   MOVED_FROM:        no
   MOVED_TO:          no
Bail out!
Tracker:ERROR:tracker-monitor-test.c:1043:test_monitor_directory_event_moved_to_monitored_after_file_create:
assertion failed ((file_events & MONITOR_SIGNAL_ITEM_DELETED) == 0): (8
== 0)
ERROR: tracker-monitor-test - Bail out!
Tracker:ERROR:tracker-monitor-test.c:1043:test_monitor_directory_event_moved_to_monitored_after_file_create:
assertion failed ((file_events & MONITOR_SIGNAL_ITEM_DELETED) == 0): (8
== 0)

============================================================================
Testsuite summary for tracker 2.0.3
============================================================================
# TOTAL: 76
# PASS:  74
# SKIP:  1
# XFAIL: 0
# FAIL:  0
# XPASS: 0
# ERROR: 1



-------------- next part --------------
A non-text attachment was scrubbed...
Name: signature.asc
Type: application/pgp-signature
Size: 488 bytes
Desc: OpenPGP digital signature
URL: <http://alioth-lists.debian.net/pipermail/pkg-gnome-maintainers/attachments/20180709/5ca6a683/attachment-0001.sig>

More information about the pkg-gnome-maintainers mailing list