Note: This is a public test instance of Red Hat Bugzilla. The data contained within is a snapshot of the live data so any changes you make will not be reflected in the production Bugzilla. Email is disabled so feel free to test any aspect of the site that you want. File any problems you find or give feedback at bugzilla.redhat.com.
Bug 1488707 - tracker-extract crashes when processing virtual elements
Summary: tracker-extract crashes when processing virtual elements
Keywords:
Status: CLOSED ERRATA
Alias: None
Product: Fedora
Classification: Fedora
Component: tracker-miners
Version: rawhide
Hardware: x86_64
OS: Unspecified
unspecified
unspecified
Target Milestone: ---
Assignee: Kalev Lember
QA Contact: Fedora Extras Quality Assurance
URL: https://retrace.fedoraproject.org/faf...
Whiteboard: abrt_hash:59e417ef1afa9de8d6dfb80a830...
: 1478628 (view as bug list)
Depends On:
Blocks: F27BetaBlocker F27BetaFreezeException
TreeView+ depends on / blocked
 
Reported: 2017-09-06 03:22 UTC by Mikhail
Modified: 2017-09-25 18:06 UTC (History)
8 users (show)

Fixed In Version: tracker-miners-2.0.0-3.fc27
Doc Type: If docs needed, set a value
Doc Text:
Clone Of:
Environment:
Last Closed: 2017-09-25 18:06:04 UTC
Type: ---
Embargoed:

Attachments (Terms of Use)
File: backtrace (47.75 KB, text/plain)
2017-09-06 03:22 UTC, Mikhail 		no flags Details
File: cgroup (386 bytes, text/plain)
2017-09-06 03:22 UTC, Mikhail 		no flags Details
File: core_backtrace (41.96 KB, text/plain)
2017-09-06 03:22 UTC, Mikhail 		no flags Details
File: cpuinfo (1.22 KB, text/plain)
2017-09-06 03:22 UTC, Mikhail 		no flags Details
File: dso_list (12.75 KB, text/plain)
2017-09-06 03:22 UTC, Mikhail 		no flags Details
File: environ (2.20 KB, text/plain)
2017-09-06 03:22 UTC, Mikhail 		no flags Details
File: exploitable (82 bytes, text/plain)
2017-09-06 03:22 UTC, Mikhail 		no flags Details
File: limits (1.29 KB, text/plain)
2017-09-06 03:22 UTC, Mikhail 		no flags Details
File: maps (61.85 KB, text/plain)
2017-09-06 03:22 UTC, Mikhail 		no flags Details
File: open_fds (1.64 KB, text/plain)
2017-09-06 03:22 UTC, Mikhail 		no flags Details
File: proc_pid_status (1.28 KB, text/plain)
2017-09-06 03:22 UTC, Mikhail 		no flags Details
File: var_log_messages (334 bytes, text/plain)
2017-09-06 03:22 UTC, Mikhail 		no flags Details
htop screenshot (397.89 KB, image/png)
2017-09-24 14:22 UTC, Mikhail 		no flags Details
View All


Links
System ID Private Priority Status Summary Last Updated
GNOME Bugzilla 785853 0 Normal RESOLVED tracker-extract crashes when processing virtual elements 2020-04-03 17:35:11 UTC

Description Mikhail 2017-09-06 03:22:01 UTC 
Description of problem:
Occured every second!!!!

Version-Release number of selected component:
tracker-1.99.0-1.fc27

Additional info:
reporter:       libreport-2.9.2
backtrace_rating: 4
cmdline:        /usr/libexec/tracker-extract
crash_function: g_str_hash
executable:     /usr/libexec/tracker-extract
journald_cursor: s=df6a9f8b489d427d9444424883d03d03;i=1ba14;b=87a3880f14db419aa5a2bfb37874b9ba;m=57941b8154;t=555ff7600134b;x=d0597f0541c6c65c
kernel:         4.13.0-0.rc2.git3.1.fc27.x86_64
rootdir:        /
runlevel:       N 5
type:           CCpp
uid:            1000

Truncated backtrace:
Thread no. 1 (7 frames)
 #0 g_str_hash at ghash.c:1882
 #1 g_hash_table_lookup_node at ghash.c:379
 #2 g_hash_table_lookup at ghash.c:1153
 #3 decorator_get_recovery_file at tracker-extract-decorator.c:228
 #4 decorator_next_item_cb at tracker-extract-decorator.c:277
 #5 g_task_return_now at gtask.c:1145
 #6 complete_in_idle_cb at gtask.c:1159

Potential duplicate: bug 1159895
Comment 1 Mikhail 2017-09-06 03:22:08 UTC 
Created attachment 1322474 [details]
File: backtrace
Comment 2 Mikhail 2017-09-06 03:22:10 UTC 
Created attachment 1322475 [details]
File: cgroup
Comment 3 Mikhail 2017-09-06 03:22:12 UTC 
Created attachment 1322476 [details]
File: core_backtrace
Comment 4 Mikhail 2017-09-06 03:22:13 UTC 
Created attachment 1322477 [details]
File: cpuinfo
Comment 5 Mikhail 2017-09-06 03:22:15 UTC 
Created attachment 1322478 [details]
File: dso_list
Comment 6 Mikhail 2017-09-06 03:22:16 UTC 
Created attachment 1322479 [details]
File: environ
Comment 7 Mikhail 2017-09-06 03:22:18 UTC 
Created attachment 1322480 [details]
File: exploitable
Comment 8 Mikhail 2017-09-06 03:22:19 UTC 
Created attachment 1322481 [details]
File: limits
Comment 9 Mikhail 2017-09-06 03:22:22 UTC 
Created attachment 1322482 [details]
File: maps
Comment 10 Mikhail 2017-09-06 03:22:23 UTC 
Created attachment 1322483 [details]
File: open_fds
Comment 11 Mikhail 2017-09-06 03:22:25 UTC 
Created attachment 1322484 [details]
File: proc_pid_status
Comment 12 Mikhail 2017-09-06 03:22:26 UTC 
Created attachment 1322485 [details]
File: var_log_messages
Comment 13 Mikhail 2017-09-08 03:30:25 UTC 
Similar problem has been detected:

Occured every second!!!!

reporter:       libreport-2.9.2
backtrace_rating: 4
cmdline:        /usr/libexec/tracker-extract
crash_function: g_str_hash
executable:     /usr/libexec/tracker-extract
journald_cursor: s=df6a9f8b489d427d9444424883d03d03;i=1ba14;b=87a3880f14db419aa5a2bfb37874b9ba;m=57941b8154;t=555ff7600134b;x=d0597f0541c6c65c
kernel:         4.13.0-0.rc2.git3.1.fc27.x86_64
package:        tracker-1.99.0-1.fc27
reason:         tracker-extract killed by signal 11
rootdir:        /
runlevel:       N 5
type:           CCpp
uid:            1000
Comment 14 Fedora Blocker Bugs Application 2017-09-09 06:55:17 UTC 
Proposed as a Blocker and Freeze Exception for 27-beta by Fedora user mikhail using the blocker tracking app because:

 Very annoying bug because happens every seconds.
Comment 15 Kamil Páral 2017-09-11 17:52:44 UTC 
Discussed during blocker review [1]:

RejectedBlocker (Beta) - for now, there is no indication this affects anyone besides Mikhail. We'd need information indicating this bug will hit a somewhat wider range of folks to accept it as a blocker

punt (delay decision) on FreezeException status - we don't have enough information on the cause, prevalence or fix for this yet to make an FE determination

[1] https://meetbot-raw.fedoraproject.org/fedora-blocker-review/2017-09-11/
Comment 16 Debarshi Ray 2017-09-17 00:08:24 UTC 
*** Bug 1478628 has been marked as a duplicate of this bug. ***
Comment 17 Kamil Páral 2017-09-18 17:59:01 UTC 
Discussed at blocker review meeting [1]:

AcceptedFreezeException - The fix seems to be small and shouldn't affect system much

[1] https://meetbot-raw.fedoraproject.org/fedora-blocker-review/2017-09-18
Comment 18 Fedora Update System 2017-09-19 09:42:49 UTC 
tracker-miners-2.0.0-3.fc27 has been submitted as an update to Fedora 27. https://bodhi.fedoraproject.org/updates/FEDORA-2017-2d2fb4e7d2
Comment 19 Fedora Update System 2017-09-19 23:29:36 UTC 
tracker-miners-2.0.0-3.fc27 has been pushed to the Fedora 27 testing repository. If problems still persist, please make note of it in this bug report.
See https://fedoraproject.org/wiki/QA:Updates_Testing for
instructions on how to install test updates.
You can provide feedback for this update here: https://bodhi.fedoraproject.org/updates/FEDORA-2017-2d2fb4e7d2
Comment 20 Mikhail 2017-09-24 14:21:18 UTC 
With latest tracker-miners package I don't see crashes, but now happens another problem:

Sep 24 19:08:29 localhost.localdomain systemd[3230]: Starting Tracker metadata database store and lookup manager...
Sep 24 19:08:29 localhost.localdomain systemd[3230]: Started Tracker metadata database store and lookup manager.
Sep 24 19:08:48 localhost.localdomain tracker-store[32589]: Unable to insert multiple values for subject `http://www.tracker-project.org/temp/nmm#albumTitle' and single valued property `rdfs:comment' (old_value: 'nmm:albumTitle is deprecated, use nie:title instead, extractors still need updating', new value: 'nmm:albumTitle is deprecated, use nie:title instead')
Sep 24 19:08:48 localhost.localdomain tracker-store[32589]: Could not insert FTS text: constraint failed
Sep 24 19:08:48 localhost.localdomain tracker-store[32589]: Could not insert FTS text: constraint failed
Sep 24 19:08:48 localhost.localdomain tracker-store[32589]: Could not insert FTS text: constraint failed
Sep 24 19:08:48 localhost.localdomain tracker-store[32589]: Could not insert FTS text: constraint failed
Sep 24 19:08:48 localhost.localdomain tracker-store[32589]: Could not insert FTS text: constraint failed
Sep 24 19:08:48 localhost.localdomain tracker-store[32589]: Could not insert FTS text: constraint failed
Sep 24 19:08:48 localhost.localdomain tracker-store[32589]: Could not insert FTS text: constraint failed
Sep 24 19:08:48 localhost.localdomain tracker-store[32589]: Could not insert FTS text: constraint failed
Sep 24 19:08:48 localhost.localdomain tracker-store[32589]: Could not insert FTS text: constraint failed
Sep 24 19:08:48 localhost.localdomain tracker-store[32589]: Could not insert FTS text: constraint failed
Sep 24 19:08:48 localhost.localdomain tracker-store[32589]: Could not insert FTS text: constraint failed
Sep 24 19:08:48 localhost.localdomain tracker-store[32589]: Could not insert FTS text: constraint failed
Sep 24 19:08:48 localhost.localdomain tracker-store[32589]: Could not insert FTS text: constraint failed
Sep 24 19:08:49 localhost.localdomain tracker-store[32589]: Transaction rollback failed: cannot rollback - no transaction is active
Sep 24 19:08:49 localhost.localdomain tracker-store[32589]: Cannot initialize database: constraint failed
Sep 24 19:08:49 localhost.localdomain systemd[3230]: tracker-store.service: Main process exited, code=exited, status=1/FAILURE
Sep 24 19:08:49 localhost.localdomain systemd[3230]: tracker-store.service: Unit entered failed state.
Sep 24 19:08:49 localhost.localdomain systemd[3230]: tracker-store.service: Failed with result 'exit-code'.
Sep 24 19:08:49 localhost.localdomain systemd[3230]: tracker-store.service: Service hold-off time over, scheduling restart.
Sep 24 19:08:49 localhost.localdomain systemd[3230]: Stopped Tracker metadata database store and lookup manager.
Sep 24 19:08:49 localhost.localdomain systemd[3230]: Starting Tracker metadata database store and lookup manager...
Sep 24 19:08:49 localhost.localdomain systemd[3230]: Started Tracker metadata database store and lookup manager.
Sep 24 19:09:08 localhost.localdomain tracker-store[32659]: Unable to insert multiple values for subject `http://www.tracker-project.org/temp/nmm#albumTitle' and single valued property `rdfs:comment' (old_value: 'nmm:albumTitle is deprecated, use nie:title instead, extractors still need updating', new value: 'nmm:albumTitle is deprecated, use nie:title instead')
Sep 24 19:09:08 localhost.localdomain tracker-store[32659]: Could not insert FTS text: constraint failed
Sep 24 19:09:08 localhost.localdomain tracker-store[32659]: Could not insert FTS text: constraint failed
Sep 24 19:09:08 localhost.localdomain tracker-store[32659]: Could not insert FTS text: constraint failed
Sep 24 19:09:08 localhost.localdomain tracker-store[32659]: Could not insert FTS text: constraint failed
Sep 24 19:09:08 localhost.localdomain tracker-store[32659]: Could not insert FTS text: constraint failed
Sep 24 19:09:08 localhost.localdomain tracker-store[32659]: Could not insert FTS text: constraint failed
Sep 24 19:09:08 localhost.localdomain tracker-store[32659]: Could not insert FTS text: constraint failed
Sep 24 19:09:08 localhost.localdomain tracker-store[32659]: Could not insert FTS text: constraint failed
Sep 24 19:09:08 localhost.localdomain tracker-store[32659]: Could not insert FTS text: constraint failed
Sep 24 19:09:08 localhost.localdomain tracker-store[32659]: Could not insert FTS text: constraint failed
Sep 24 19:09:08 localhost.localdomain tracker-store[32659]: Could not insert FTS text: constraint failed
Sep 24 19:09:08 localhost.localdomain tracker-store[32659]: Could not insert FTS text: constraint failed
Sep 24 19:09:08 localhost.localdomain tracker-store[32659]: Could not insert FTS text: constraint failed
Sep 24 19:09:09 localhost.localdomain tracker-store[32659]: Transaction rollback failed: cannot rollback - no transaction is active
Sep 24 19:09:09 localhost.localdomain tracker-store[32659]: Cannot initialize database: constraint failed
Sep 24 19:09:09 localhost.localdomain systemd[3230]: tracker-store.service: Main process exited, code=exited, status=1/FAILURE
Sep 24 19:09:09 localhost.localdomain systemd[3230]: tracker-store.service: Unit entered failed state.
Sep 24 19:09:09 localhost.localdomain systemd[3230]: tracker-store.service: Failed with result 'exit-code'.
Sep 24 19:09:09 localhost.localdomain systemd[3230]: tracker-store.service: Service hold-off time over, scheduling restart.
Sep 24 19:09:09 localhost.localdomain systemd[3230]: Stopped Tracker metadata database store and lookup manager.


The whole log is full of these messages and tracker-store process continue consume 100% CPU
Comment 21 Mikhail 2017-09-24 14:22:06 UTC 
Created attachment 1330167 [details]
htop screenshot
Comment 22 Debarshi Ray 2017-09-24 16:20:59 UTC 
(In reply to Mikhail from comment #20)

Thanks for reporting back. These are different issues.

> Sep 24 19:08:29 localhost.localdomain systemd[3230]: Starting Tracker
> metadata database store and lookup manager...
> Sep 24 19:08:29 localhost.localdomain systemd[3230]: Started Tracker
> metadata database store and lookup manager.
> Sep 24 19:08:48 localhost.localdomain tracker-store[32589]: Unable to insert
> multiple values for subject
> `http://www.tracker-project.org/temp/nmm#albumTitle' and single valued
> property `rdfs:comment' (old_value: 'nmm:albumTitle is deprecated, use
> nie:title instead, extractors still need updating', new value:
> 'nmm:albumTitle is deprecated, use nie:title instead')

This bit is indicative of a bug in the miners that's worth filing upstream. Not sure about the rest of the logs.
Comment 23 Fedora Update System 2017-09-25 18:06:04 UTC 
tracker-miners-2.0.0-3.fc27 has been pushed to the Fedora 27 stable repository. If problems still persist, please make note of it in this bug report.
Note You need to log in before you can comment on or make changes to this bug.