search

Philantrop / calibre-marvin-manager

A calibre User Interface Action plugin supporting Marvin
6 stars 6 forks source link

Unable to update metadata for some books #25

Closed WyndhamMisio closed 10 years ago

WyndhamMisio commented 10 years ago

Using 1.0.95 of MXD, I am still getting a failed update, with the following message.

screenshot 1027

Note that this was a Calibre to Marvin metadata update, and only about 6 or 8 books were selected.

The failure noted above was when connected to my iPad. Now running a similar job connected to my iPhone using the same library.

The books noted above are not showing as having metadata differences on the iPhone, so I selected all 133 books which do show metadata differences.

The job completed without any error messages which would imply there is a problem with the library on my iPad. So, I set Marvin to Clear Caches and Optimize the database and re-ran the job - update metadata calibre to Marvin.

These books were selected.

screenshot 1028

Result. image

Debug.txt

image

Will email separately

GRiker commented 10 years ago

This error report indicates that the Marvin is unable to find the listed books when trying to do the metadata update. In order to determine where the failure is occurring, I need you to do some detective work with Marvin on your iPad.

First, send me a copy of your mainDb.sqlite file from Marvin:

Next, look in the Documents folder to see if you can find the missing files:

GRiker commented 10 years ago

(Edited version of debug log from email) calibre 1.14 [64bit] isfrozen: True is64bit: True Windows-7-6.1.7601-SP1 Windows ('64bit', 'WindowsPE') ('Windows', '7', '6.1.7601') Python 2.7.5 Windows: ('7', '6.1.7601', 'SP1', 'Multiprocessor Free') Starting up... DEBUG: 0.0 MarvinManagerAction:genesis(v1.0.95) DEBUG: 0.0 MarvinManagerAction:init_options() DEBUG: 0.0 AnnotationsDB:connect(db_version: 1) DEBUG: 0.0 MarvinManagerAction:init_prefs(Updating prefs from 1.0.95 to 1.1.0) DEBUG: 0.0 MarvinManagerAction:inflate_dialog_resources() DEBUG: 0.1 MarvinManagerAction:compile_ui() DEBUG: 1.7 MarvinManagerAction:rebuild_menus() DEBUG: 1.7 Marvin not connected Started up in 4.52 seconds with 1913 books DEBUG: 30.6 MarvinManagerAction:library_changed(FanFiction Downloads) DEBUG: 52.8 MarvinManagerAction:library_changed(Gay eBooks) DEBUG: 77.2 MarvinManagerAction:library_changed(General) DEBUG: 499.7 MarvinManagerAction:on_device_connection_changed(iOS reader applications) DEBUG: 499.7 mounting com.appstafarian.MarvinIP DEBUG: 500.9 MarvinManagerAction:on_device_connection_changed(iPad3,3 running iOS 7.0.4) DEBUG: 500.9 has_password: False DEBUG: 500.9 MarvinManagerAction:rebuild_menus() DEBUG: 500.9 Marvin connected Job: 1 Get device information started DeviceJob: 1 Get device information done, calling callback Job: 1 Get device information finished No details available. DeviceJob: 1 Get device information callback returned Job: 2 Get list of books on device started Job: 1 Get device information finished No details available. DEBUG: 545.5 libiMobileDevice:_afc_get_file_info( ERROR: Object not found (8) path:u'/Library/Caches/com.appstafarian.marvin.covers/968389b2edc7957b5e60ee9bc74f71b3.jpg') DEBUG: 550.3 libiMobileDevice:_afc_get_file_info( ERROR: Object not found (8) path:u'/Library/Caches/com.appstafarian.marvin.covers/80c04535ea76e6722590f15f12a54779.jpg') DEBUG: 561.5 libiMobileDevice:_afc_get_file_info( ERROR: Object not found (8) path:u'/Library/Caches/com.appstafarian.marvin.covers/8cadbb493748db23a056d12108a7f4c6.jpg') DEBUG: 561.6 libiMobileDevice:_afc_get_file_info( ERROR: Object not found (8) path:u'/Library/Caches/com.appstafarian.marvin.covers/7e9695d3b66fb439a2b614bb0a6f6f7e.jpg') DEBUG: 575.1 libiMobileDevice:_afc_get_file_info( ERROR: Object not found (8) path:u'/Library/Caches/com.appstafarian.marvin.covers/fe34f482ddeb1199c5f70e6e7315dab8.jpg') DeviceJob: 2 Get list of books on device done, calling callback DeviceJob: 2 Get list of books on device callback returned Job: 2 Get list of books on device finished No details available. DEBUG: 696.4 MarvinManagerAction:main_menu_button_clicked() DEBUG: 696.4 MarvinManagerAction:show_installed_books() DEBUG: 696.4 MarvinManagerAction:launch_library_scanner(updating library index for virtual library u'') DEBUG: 696.4 MarvinManagerAction:start_library_indexing() DEBUG: 696.4 MarvinManagerAction:_busy_panel_setup() DEBUG: 698.1 MarvinManagerAction:library_index_complete() DEBUG: 698.1 MarvinManagerAction:_busy_panel_teardown() DEBUG: 698.6 BookStatusDialog:initialize() DEBUG: 698.6 BookStatusDialog:_generate_booklist() DEBUG: 698.9 BookStatusDialog:_scan_library_books(1907) DEBUG: 705.6 BookStatusDialog:_get_installed_books() DEBUG: 705.6 BookStatusDialog:_scan_marvin_books(3214 books) DEBUG: 705.6 BookStatusDialog:_localize_hash_cache() DEBUG: 705.7 remote hash cache: v1, 3214 books in cache DEBUG: 705.7 BookStatusDialog:_purge_cached_orphans() DEBUG: 705.9 BookStatusDialog:_update_remote_hash_cache() DEBUG: 724.0 BookStatusDialog:_purge_cover_hash_orphans() DEBUG: 724.0 BookStatusDialog:_busy_panel_setup(Removing obsolete cover hashes) DEBUG: 724.3 BookStatusDialog:_busy_panel_teardown() DEBUG: 724.3 BookStatusDialog:_generate_marvin_hash_map() DEBUG: 724.3 BookStatusDialog:_find_fuzzy_matches() DEBUG: 724.3 BookStatusDialog:_busy_panel_setup(Preparing Marvin library viewÖ) DEBUG: 724.6 BookStatusDialog:_construct_table_data() DEBUG: 727.9 BookStatusDialog:_construct_table_view() DEBUG: 738.1 BookStatusDialog:_update_refresh_button() DEBUG: 738.1 BookStatusDialog:_busy_panel_teardown() DEBUG: 738.2 MarvinManagerAction:show_installed_books(3214 books) DEBUG: 934.0 BookStatusDialog:dispatch_context_menu_event(u'export_metadata' row: 1474) DEBUG: 934.0 BookStatusDialog:_update_metadata(export_metadata) DEBUG: 934.0 BookStatusDialog:_busy_status_setup() DEBUG: 934.0 BookStatusDialog:_busy_status_msg(Updating metadata: 1 of 18) DEBUG: 934.1 BookStatusDialog:_update_marvin_metadata(Thirteen Reasons Why) DEBUG: 934.1 BookStatusDialog:_build_metadata_update() DEBUG: 934.2 BookStatusDialog:_issue_command() DEBUG: 934.2 BookStatusDialog:_stage_command_file(update_metadata) DEBUG: 934.2 BookStatusDialog:_wait_for_command_completion() DEBUG: 934.2 17:05:08.987000: waiting for '/Library/calibre/status.xml' DEBUG: 934.2 libiMobileDevice:_afc_get_file_info( ERROR: Object not found (8) path:u'/Library/calibre/status.xml') DEBUG: 934.6 libiMobileDevice:_afc_get_file_info( ERROR: Object not found (8) path:u'/Library/calibre/status.xml') DEBUG: 934.8 libiMobileDevice:_afc_get_file_info( ERROR: Object not found (8) path:u'/Library/calibre/status.xml') DEBUG: 935.1 libiMobileDevice:_afc_get_file_info( ERROR: Object not found (8) path:u'/Library/calibre/status.xml') DEBUG: 935.3 libiMobileDevice:_afc_get_file_info( ERROR: Object not found (8) path:u'/Library/calibre/status.xml') DEBUG: 935.6 libiMobileDevice:_afc_get_file_info( ERROR: Object not found (8) path:u'/Library/calibre/status.xml') DEBUG: 935.8 17:05:10.632000: monitoring progress of update_metadata DEBUG: 935.9 17:05:10.650000: -1 50% DEBUG: 936.1 17:05:10.917000: 0 100% DEBUG: 936.4 17:05:11.183000: 'update_metadata' complete DEBUG: 936.4 BookStatusDialog:_localize_marvin_database(starting) DEBUG: 936.4 BookStatusDialog:_busy_status_msg(Refreshing database) DEBUG: 936.5 mainDb: 12,469,248 bytes DEBUG: 938.0 BookStatusDialog:_localize_marvin_database(finished) DEBUG: 938.1 BookStatusDialog:_clear_selected_rows() DEBUG: 938.1 BookStatusDialog:_busy_status_msg(Updating metadata: 2 of 18) DEBUG: 938.1 BookStatusDialog:_update_marvin_metadata(Unknown) DEBUG: 938.1 BookStatusDialog:_build_metadata_update() DEBUG: 938.1 'Unknown': cover is up to date DEBUG: 938.1 BookStatusDialog:_issue_command() DEBUG: 938.1 BookStatusDialog:_stage_command_file(update_metadata) DEBUG: 938.1 BookStatusDialog:_wait_for_command_completion() DEBUG: 938.1 17:05:12.922000: waiting for '/Library/calibre/status.xml' DEBUG: 938.1 libiMobileDevice:_afc_get_file_info( ERROR: Object not found (8) path:u'/Library/calibre/status.xml') DEBUG: 938.5 libiMobileDevice:_afc_get_file_info( ERROR: Object not found (8) path:u'/Library/calibre/status.xml') DEBUG: 938.8 libiMobileDevice:_afc_get_file_info( ERROR: Object not found (8) path:u'/Library/calibre/status.xml') DEBUG: 939.0 17:05:13.817000: monitoring progress of update_metadata DEBUG: 939.0 17:05:13.833000: -1 50% DEBUG: 939.3 17:05:14.096000: 1 100% DEBUG: 939.5 code: 1 status: completed with warnings[State Of Siege - Tom Clancy.epub] Cannot locate book to update metadata - skipping. DEBUG: 939.6 17:05:14.353000: 'update_metadata' complete with errors DEBUG: 939.6 libiMobileDevice:remove( ERROR: Object not found (8) path:u'/Library/calibre/status.xml') DEBUG: 939.6 17:05:14.358000: 'update_metadata' complete DEBUG: 939.6 BookStatusDialog:_busy_status_msg(Updating metadata: 3 of 18) DEBUG: 939.6 BookStatusDialog:_update_marvin_metadata(Unknown) DEBUG: 939.6 BookStatusDialog:_build_metadata_update() DEBUG: 939.6 'Unknown': cover is up to date DEBUG: 939.6 BookStatusDialog:_issue_command() DEBUG: 939.6 BookStatusDialog:_stage_command_file(update_metadata) DEBUG: 939.6 BookStatusDialog:_wait_for_command_completion() DEBUG: 939.6 17:05:14.385000: waiting for '/Library/calibre/status.xml' DEBUG: 942.2 17:05:16.965000: monitoring progress of update_metadata DEBUG: 942.2 17:05:16.981000: -1 50% DEBUG: 942.4 17:05:17.244000: 1 100% DEBUG: 942.7 code: 1 status: completed with warnings[Springboard - Tom Clancy.epub] Cannot locate book to update metadata - skipping. DEBUG: 942.7 17:05:17.502000: 'update_metadata' complete with errors DEBUG: 942.7 libiMobileDevice:remove( ERROR: Object not found (8) path:u'/Library/calibre/status.xml') DEBUG: 942.7 17:05:17.506000: 'update_metadata' complete DEBUG: 942.7 BookStatusDialog:_busy_status_msg(Updating metadata: 4 of 18) DEBUG: 942.7 BookStatusDialog:_update_marvin_metadata(Unknown) DEBUG: 942.7 BookStatusDialog:_build_metadata_update() DEBUG: 942.7 'Unknown': cover is up to date DEBUG: 942.7 BookStatusDialog:_issue_command() DEBUG: 942.7 BookStatusDialog:_stage_command_file(update_metadata) DEBUG: 942.7 BookStatusDialog:_wait_for_command_completion() DEBUG: 942.7 17:05:17.524000: waiting for '/Library/calibre/status.xml' DEBUG: 945.3 17:05:20.086000: monitoring progress of update_metadata DEBUG: 945.3 17:05:20.104000: -1 50% DEBUG: 945.6 17:05:20.374000: 1 100% DEBUG: 945.8 code: 1 status: completed with warnings[Splinter Cell (2004) - Tom Clancy.epub] Cannot locate book to update metadata - skipping. DEBUG: 945.8 17:05:20.632000: 'update_metadata' complete with errors DEBUG: 945.8 libiMobileDevice:remove( ERROR: Object not found (8) path:u'/Library/calibre/status.xml') DEBUG: 945.8 17:05:20.638000: 'update_metadata' complete DEBUG: 945.8 BookStatusDialog:_busy_status_msg(Updating metadata: 5 of 18) DEBUG: 945.8 BookStatusDialog:_update_marvin_metadata(Unknown) DEBUG: 945.8 BookStatusDialog:_build_metadata_update() DEBUG: 945.8 'Unknown': cover is up to date DEBUG: 945.8 BookStatusDialog:_issue_command() DEBUG: 945.8 BookStatusDialog:_stage_command_file(update_metadata) DEBUG: 945.9 BookStatusDialog:_wait_for_command_completion() DEBUG: 945.9 17:05:20.659000: waiting for '/Library/calibre/status.xml' DEBUG: 948.4 17:05:23.217000: monitoring progress of update_metadata DEBUG: 948.4 17:05:23.235000: -1 50% DEBUG: 948.7 17:05:23.496000: 1 100% DEBUG: 948.9 code: 1 status: completed with warnings[Sea of Fire - Tom Clancy.epub] Cannot locate book to update metadata - skipping. DEBUG: 949.0 17:05:23.754000: 'update_metadata' complete with errors DEBUG: 949.0 libiMobileDevice:remove( ERROR: Object not found (8) path:u'/Library/calibre/status.xml') DEBUG: 949.0 17:05:23.759000: 'update_metadata' complete DEBUG: 949.0 BookStatusDialog:_busy_status_msg(Updating metadata: 6 of 18) DEBUG: 949.0 BookStatusDialog:_update_marvin_metadata(Unknown) DEBUG: 949.0 BookStatusDialog:_build_metadata_update() DEBUG: 949.0 'Unknown': cover is up to date DEBUG: 949.0 BookStatusDialog:_issue_command() DEBUG: 949.0 BookStatusDialog:_stage_command_file(update_metadata) DEBUG: 949.0 BookStatusDialog:_wait_for_command_completion() DEBUG: 949.0 17:05:23.784000: waiting for '/Library/calibre/status.xml' DEBUG: 951.6 17:05:26.351000: monitoring progress of update_metadata DEBUG: 951.6 17:05:26.362000: -1 50% DEBUG: 951.8 17:05:26.626000: 1 100% DEBUG: 952.1 code: 1 status: completed with warnings[Fallout (2007) - Tom Clancy.epub] Cannot locate book to update metadata - skipping. DEBUG: 952.1 17:05:26.883000: 'update_metadata' complete with errors DEBUG: 952.1 libiMobileDevice:remove( ERROR: Object not found (8) path:u'/Library/calibre/status.xml') DEBUG: 952.1 17:05:26.887000: 'update_metadata' complete DEBUG: 952.1 BookStatusDialog:_busy_status_msg(Updating metadata: 7 of 18) DEBUG: 952.1 BookStatusDialog:_update_marvin_metadata(Unknown) DEBUG: 952.1 BookStatusDialog:_build_metadata_update() DEBUG: 952.1 'Unknown': cover is up to date DEBUG: 952.1 BookStatusDialog:_issue_command() DEBUG: 952.1 BookStatusDialog:_stage_command_file(update_metadata) DEBUG: 952.1 BookStatusDialog:_wait_for_command_completion() DEBUG: 952.1 17:05:26.906000: waiting for '/Library/calibre/status.xml' DEBUG: 954.7 17:05:29.468000: monitoring progress of update_metadata DEBUG: 954.7 17:05:29.487000: -1 50% DEBUG: 955.0 17:05:29.822000: 1 100% DEBUG: 955.4 code: 1 status: completed with warnings[The Exile - Andrew Britton.epub] Cannot locate book to update metadata - skipping. DEBUG: 955.4 17:05:30.188000: 'update_metadata' complete with errors DEBUG: 955.4 libiMobileDevice:remove( ERROR: Object not found (8) path:u'/Library/calibre/status.xml') DEBUG: 955.4 17:05:30.193000: 'update_metadata' complete DEBUG: 955.4 BookStatusDialog:_busy_status_msg(Updating metadata: 8 of 18) DEBUG: 955.4 BookStatusDialog:_update_marvin_metadata(Unknown) DEBUG: 955.4 BookStatusDialog:_build_metadata_update() DEBUG: 955.4 'Unknown': cover is up to date DEBUG: 955.4 BookStatusDialog:_issue_command() DEBUG: 955.4 BookStatusDialog:_stage_command_file(update_metadata) DEBUG: 955.4 BookStatusDialog:_wait_for_command_completion() DEBUG: 955.4 17:05:30.214000: waiting for '/Library/calibre/status.xml' DEBUG: 957.8 17:05:32.577000: monitoring progress of update_metadata DEBUG: 957.8 17:05:32.594000: -1 50% DEBUG: 958.1 17:05:32.863000: 1 100% DEBUG: 958.3 code: 1 status: completed with warnings[Executive Intent - Dale Brown.epub] Cannot locate book to update metadata - skipping. DEBUG: 958.3 17:05:33.127000: 'update_metadata' complete with errors DEBUG: 958.3 libiMobileDevice:remove( ERROR: Object not found (8) path:u'/Library/calibre/status.xml') DEBUG: 958.3 17:05:33.131000: 'update_metadata' complete DEBUG: 958.3 BookStatusDialog:_busy_status_msg(Updating metadata: 9 of 18) DEBUG: 958.3 BookStatusDialog:_update_marvin_metadata(Unknown) DEBUG: 958.3 BookStatusDialog:_build_metadata_update() DEBUG: 958.3 'Unknown': cover is up to date DEBUG: 958.3 BookStatusDialog:_issue_command() DEBUG: 958.3 BookStatusDialog:_stage_command_file(update_metadata) DEBUG: 958.4 BookStatusDialog:_wait_for_command_completion() DEBUG: 958.4 17:05:33.159000: waiting for '/Library/calibre/status.xml' DEBUG: 961.0 17:05:35.842000: monitoring progress of update_metadata DEBUG: 961.1 17:05:35.852000: 1 100% DEBUG: 961.3 code: 1 status: completed with warnings[Divide and Conquer - Tom Clancy.epub] Cannot locate book to update metadata - skipping. DEBUG: 961.3 17:05:36.110000: 'update_metadata' complete with errors DEBUG: 961.3 libiMobileDevice:remove( ERROR: Object not found (8) path:u'/Library/calibre/status.xml') DEBUG: 961.3 17:05:36.114000: 'update_metadata' complete DEBUG: 961.3 BookStatusDialog:_busy_status_msg(Updating metadata: 10 of 18) DEBUG: 961.3 BookStatusDialog:_update_marvin_metadata(Unknown) DEBUG: 961.3 BookStatusDialog:_build_metadata_update() DEBUG: 961.3 'Unknown': cover is up to date DEBUG: 961.3 BookStatusDialog:_issue_command() DEBUG: 961.3 BookStatusDialog:_stage_command_file(update_metadata) DEBUG: 961.3 BookStatusDialog:_wait_for_command_completion() DEBUG: 961.3 17:05:36.134000: waiting for '/Library/calibre/status.xml' DEBUG: 964.1 17:05:38.924000: monitoring progress of update_metadata DEBUG: 964.1 17:05:38.937000: 1 100% DEBUG: 964.4 code: 1 status: completed with warnings[Cutting Edge (2002) - Tom Clancy.epub] Cannot locate book to update metadata - skipping. DEBUG: 964.4 17:05:39.195000: 'update_metadata' complete with errors DEBUG: 964.4 libiMobileDevice:remove( ERROR: Object not found (8) path:u'/Library/calibre/status.xml') DEBUG: 964.4 17:05:39.198000: 'update_metadata' complete DEBUG: 964.4 BookStatusDialog:_busy_status_msg(Updating metadata: 11 of 18) DEBUG: 964.4 BookStatusDialog:_update_marvin_metadata(Unknown) DEBUG: 964.4 BookStatusDialog:_build_metadata_update() DEBUG: 964.4 'Unknown': cover is up to date DEBUG: 964.4 BookStatusDialog:_issue_command() DEBUG: 964.4 BookStatusDialog:_stage_command_file(update_metadata) DEBUG: 964.4 BookStatusDialog:_wait_for_command_completion() DEBUG: 964.4 17:05:39.218000: waiting for '/Library/calibre/status.xml' DEBUG: 967.1 17:05:41.915000: monitoring progress of update_metadata DEBUG: 967.1 17:05:41.927000: -1 50% DEBUG: 967.4 17:05:42.189000: 1 100% DEBUG: 967.6 code: 1 status: completed with warnings[Conviction (2009) - Tom Clancy.epub] Cannot locate book to update metadata - skipping. DEBUG: 967.6 17:05:42.445000: 'update_metadata' complete with errors DEBUG: 967.6 libiMobileDevice:remove( ERROR: Object not found (8) path:u'/Library/calibre/status.xml') DEBUG: 967.6 17:05:42.449000: 'update_metadata' complete DEBUG: 967.6 BookStatusDialog:_busy_status_msg(Updating metadata: 12 of 18) DEBUG: 967.7 BookStatusDialog:_update_marvin_metadata(A Conspiracy of Paper) DEBUG: 967.7 BookStatusDialog:_build_metadata_update() DEBUG: 967.7 BookStatusDialog:_issue_command() DEBUG: 968.7 BookStatusDialog:_stage_command_file(update_metadata) DEBUG: 968.8 BookStatusDialog:_wait_for_command_completion() DEBUG: 968.8 17:05:43.573000: waiting for '/Library/calibre/status.xml' DEBUG: 970.3 17:05:45.103000: monitoring progress of update_metadata DEBUG: 970.3 17:05:45.122000: -1 50% DEBUG: 970.6 17:05:45.386000: 0 100% DEBUG: 970.8 17:05:45.644000: 'update_metadata' complete DEBUG: 970.8 BookStatusDialog:_localize_marvin_database(starting) DEBUG: 970.8 BookStatusDialog:_busy_status_msg(Refreshing database) DEBUG: 970.8 mainDb: 12,469,248 bytes DEBUG: 971.9 BookStatusDialog:_localize_marvin_database(finished) DEBUG: 971.9 BookStatusDialog:_clear_selected_rows() DEBUG: 972.0 BookStatusDialog:_busy_status_msg(Updating metadata: 13 of 18) DEBUG: 972.0 BookStatusDialog:_update_marvin_metadata(Conspiracy in Death) DEBUG: 972.0 BookStatusDialog:_build_metadata_update() DEBUG: 972.0 BookStatusDialog:_issue_command() DEBUG: 972.0 BookStatusDialog:_stage_command_file(update_metadata) DEBUG: 972.0 BookStatusDialog:_wait_for_command_completion() DEBUG: 972.0 17:05:46.815000: waiting for '/Library/calibre/status.xml' DEBUG: 973.6 17:05:48.351000: monitoring progress of update_metadata DEBUG: 973.6 17:05:48.361000: -1 50% DEBUG: 974.1 17:05:48.888000: 0 100% DEBUG: 974.3 17:05:49.145000: 'update_metadata' complete DEBUG: 974.3 BookStatusDialog:_localize_marvin_database(starting) DEBUG: 974.3 BookStatusDialog:_busy_status_msg(Refreshing database) DEBUG: 974.4 mainDb: 12,472,320 bytes DEBUG: 975.3 BookStatusDialog:_localize_marvin_database(finished) DEBUG: 975.4 BookStatusDialog:_clear_selected_rows() DEBUG: 975.4 BookStatusDialog:_busy_status_msg(Updating metadata: 14 of 18) DEBUG: 975.4 BookStatusDialog:_update_marvin_metadata(Consider the Lobster and Other Essays) DEBUG: 975.4 BookStatusDialog:_build_metadata_update() DEBUG: 975.4 BookStatusDialog:_issue_command() DEBUG: 975.4 BookStatusDialog:_stage_command_file(update_metadata) DEBUG: 975.5 BookStatusDialog:_wait_for_command_completion() DEBUG: 975.5 17:05:50.266000: waiting for '/Library/calibre/status.xml' DEBUG: 977.0 17:05:51.801000: monitoring progress of update_metadata DEBUG: 977.0 17:05:51.817000: -1 50% DEBUG: 977.3 17:05:52.082000: 0 100% DEBUG: 977.5 17:05:52.340000: 'update_metadata' complete DEBUG: 977.5 BookStatusDialog:_localize_marvin_database(starting) DEBUG: 977.5 BookStatusDialog:_busy_status_msg(Refreshing database) DEBUG: 977.5 mainDb: 12,472,320 bytes DEBUG: 978.6 BookStatusDialog:_localize_marvin_database(finished) DEBUG: 978.7 BookStatusDialog:_clear_selected_rows() DEBUG: 978.7 BookStatusDialog:_busy_status_msg(Updating metadata: 15 of 18) DEBUG: 978.7 BookStatusDialog:_update_marvin_metadata(Consider Phlebas) DEBUG: 978.7 BookStatusDialog:_build_metadata_update() DEBUG: 978.7 BookStatusDialog:_issue_command() DEBUG: 978.7 BookStatusDialog:_stage_command_file(update_metadata) DEBUG: 978.7 BookStatusDialog:_wait_for_command_completion() DEBUG: 978.7 17:05:53.536000: waiting for '/Library/calibre/status.xml' DEBUG: 980.3 17:05:55.076000: monitoring progress of update_metadata DEBUG: 980.3 17:05:55.089000: -1 50% DEBUG: 980.6 17:05:55.352000: 0 100% DEBUG: 980.8 17:05:55.609000: 'update_metadata' complete DEBUG: 980.8 BookStatusDialog:_localize_marvin_database(starting) DEBUG: 980.8 BookStatusDialog:_busy_status_msg(Refreshing database) DEBUG: 980.8 mainDb: 12,472,320 bytes DEBUG: 982.2 BookStatusDialog:_localize_marvin_database(finished) DEBUG: 982.2 BookStatusDialog:_clear_selected_rows() DEBUG: 982.3 BookStatusDialog:_busy_status_msg(Updating metadata: 16 of 18) DEBUG: 982.3 BookStatusDialog:_update_marvin_metadata(Consent to Kill) DEBUG: 982.3 BookStatusDialog:_build_metadata_update() DEBUG: 982.3 BookStatusDialog:_issue_command() DEBUG: 982.3 BookStatusDialog:_stage_command_file(update_metadata) DEBUG: 982.3 BookStatusDialog:_wait_for_command_completion() DEBUG: 982.3 17:05:57.109000: waiting for '/Library/calibre/status.xml' DEBUG: 983.6 17:05:58.401000: monitoring progress of update_metadata DEBUG: 983.6 17:05:58.412000: -1 50% DEBUG: 984.1 17:05:58.947000: 0 100% DEBUG: 984.4 17:05:59.204000: 'update_metadata' complete DEBUG: 984.4 BookStatusDialog:_localize_marvin_database(starting) DEBUG: 984.4 BookStatusDialog:_busy_status_msg(Refreshing database) DEBUG: 984.4 mainDb: 12,472,320 bytes DEBUG: 985.4 BookStatusDialog:_localize_marvin_database(finished) DEBUG: 985.5 BookStatusDialog:_clear_selected_rows() DEBUG: 985.5 BookStatusDialog:_busy_status_msg(Updating metadata: 17 of 18) DEBUG: 985.5 BookStatusDialog:_update_marvin_metadata(Congo) DEBUG: 985.5 BookStatusDialog:_build_metadata_update() DEBUG: 985.5 BookStatusDialog:_issue_command() DEBUG: 985.5 BookStatusDialog:_stage_command_file(update_metadata) DEBUG: 985.6 BookStatusDialog:_wait_for_command_completion() DEBUG: 985.6 17:06:00.352000: waiting for '/Library/calibre/status.xml' DEBUG: 987.1 17:06:01.885000: monitoring progress of update_metadata DEBUG: 987.1 17:06:01.896000: -1 50% DEBUG: 987.4 17:06:02.159000: 0 100% DEBUG: 987.6 17:06:02.418000: 'update_metadata' complete DEBUG: 987.6 BookStatusDialog:_localize_marvin_database(starting) DEBUG: 987.6 BookStatusDialog:_busy_status_msg(Refreshing database) DEBUG: 987.6 mainDb: 12,472,320 bytes DEBUG: 988.6 BookStatusDialog:_localize_marvin_database(finished) DEBUG: 988.7 BookStatusDialog:_clear_selected_rows() DEBUG: 988.7 BookStatusDialog:_clear_selected_rows() DEBUG: 988.7 BookStatusDialog:_busy_status_msg(Updating metadata: 18 of 18) DEBUG: 988.7 BookStatusDialog:_update_marvin_metadata(The Confusion) DEBUG: 988.7 BookStatusDialog:_build_metadata_update() DEBUG: 988.7 BookStatusDialog:_issue_command() DEBUG: 988.7 BookStatusDialog:_stage_command_file(update_metadata) DEBUG: 988.8 BookStatusDialog:_wait_for_command_completion() DEBUG: 988.8 17:06:03.564000: waiting for '/Library/calibre/status.xml' DEBUG: 990.3 17:06:05.098000: monitoring progress of update_metadata DEBUG: 990.3 17:06:05.109000: -1 50% DEBUG: 990.8 17:06:05.638000: 0 100% DEBUG: 991.1 17:06:05.894000: 'update_metadata' complete DEBUG: 991.1 BookStatusDialog:_localize_marvin_database(starting) DEBUG: 991.1 BookStatusDialog:_busy_status_msg(Refreshing database) DEBUG: 991.1 mainDb: 12,476,416 bytes DEBUG: 992.2 BookStatusDialog:_localize_marvin_database(finished) DEBUG: 992.2 BookStatusDialog:_clear_selected_rows() DEBUG: 992.3 BookStatusDialog:_clear_selected_rows() DEBUG: 992.3 BookStatusDialog:_busy_status_teardown() DEBUG: 992.3 BookStatusDialog:_flash_affected_rows([1457, 1468, 1469, 1470, 1471, 1472, 1473, 1474]) DEBUG: 992.3 collected errors: [{u'status': u'completed with warnings', u'code': 1, u'details': u'[State Of Siege - Tom Clancy.epub] Cannot locate book to update metadata - skipping.'}, {u'status': u'completed with warnings', u'code': 1, u'details': u'[Springboard - Tom Clancy.epub] Cannot locate book to update metadata - skipping.'}, {u'status': u'completed with warnings', u'code': 1, u'details': u'[Splinter Cell (2004) - Tom Clancy.epub] Cannot locate book to update metadata - skipping.'}, {u'status': u'completed with warnings', u'code': 1, u'details': u'[Sea of Fire - Tom Clancy.epub] Cannot locate book to update metadata - skipping.'}, {u'status': u'completed with warnings', u'code': 1, u'details': u'[Fallout (2007) - Tom Clancy.epub] Cannot locate book to update metadata - skipping.'}, {u'status': u'completed with warnings', u'code': 1, u'details': u'[The Exile - Andrew Britton.epub] Cannot locate book to update metadata - skipping.'}, {u'status': u'completed with warnings', u'code': 1, u'details': u'[Executive Intent - Dale Brown.epub] Cannot locate book to update metadata - skipping.'}, {u'status': u'completed with warnings', u'code': 1, u'details': u'[Divide and Conquer - Tom Clancy.epub] Cannot locate book to update metadata - skipping.'}, {u'status': u'completed with warnings', u'code': 1, u'details': u'[Cutting Edge (2002) - Tom Clancy.epub] Cannot locate book to update metadata - skipping.'}, {u'status': u'completed with warnings', u'code': 1, u'details': u'[Conviction (2009) - Tom Clancy.epub] Cannot locate book to update metadata - skipping.'}] DEBUG: 992.3 BookStatusDialog:_show_command_error({u'details': u'[State Of Siege - Tom Clancy.epub] Cannot locate book to update metadata - skipping.\n[Springboard - Tom Clancy.epub] Cannot locate book to update metadata - skipping.\n[Splinter Cell (2004) - Tom Clancy.epub] Cannot locate book to update metadata - skipping.\n[Sea of Fire - Tom Clancy.epub] Cannot locate book to update metadata - skipping.\n[Fallout (2007) - Tom Clancy.epub] Cannot locate book to update metadata - skipping.\n[The Exile - Andrew Britton.epub] Cannot locate book to update metadata - skipping.\n[Executive Intent - Dale Brown.epub] Cannot locate book to update metadata - skipping.\n[Divide and Conquer - Tom Clancy.epub] Cannot locate book to update metadata - skipping.\n[Cutting Edge (2002) - Tom Clancy.epub] Cannot locate book to update metadata - skipping.\n[Conviction (2009) - Tom Clancy.epub] Cannot locate book to update metadata - skipping.\n'}) DEBUG: 995.1 BookStatusDialog:_flasher_complete() DEBUG: 1087.8 libiMobileDevice:_afc_get_file_info( ERROR: Object not found (8) path:u'/Library/calibre/connected.xml') DEBUG: 1088.8 libiMobileDevice:_afc_get_file_info( ERROR: Object not found (8) path:u'/Library/calibre/connected.xml') DEBUG: 1088.8 MarvinManagerAction:marvin_status_changed(disconnected) DEBUG: 1088.8 BookStatusDialog:marvin_status_changed(disconnected) DEBUG: 1088.8 closing dialog: disconnected DEBUG: 1088.8 BookStatusDialog:close() DEBUG: 1088.8 BookStatusDialog:_save_column_widths() DEBUG: 1089.2 MarvinManagerAction:marvin_status_changed(yanked) DEBUG: 1089.2 BookStatusDialog:marvin_status_changed(yanked) DEBUG: 1089.2 closing dialog: yanked DEBUG: 1089.2 BookStatusDialog:close() DEBUG: 1089.2 BookStatusDialog:_save_column_widths() DEBUG: 1089.3 MarvinManagerAction:on_device_connection_changed(device disconnected) DEBUG: 1089.3 MarvinManagerAction:rebuild_menus() DEBUG: 1089.3 Marvin not connected DEBUG: 1092.3 libiMobileDevice:_afc_get_file_info( ERROR: Object not found (8) path:u'/Library/calibre/connected.xml') DEBUG: 1092.5 MarvinManagerAction:shutting_down()

WyndhamMisio commented 10 years ago

Viewing the Marvin library I notice that some books are stored with the normal name as stored in the Calibre library, but many (most…) others have a computer generated name.

screenshot 1032

I haven’t screenshot all of them, but all the books are there, and have the same title.

screenshot 1033

screenshot 1034

screenshot 1035

GRiker commented 10 years ago

The filename depends on where the book was sourced from. If it came from calibre while connected via USB, it will be the computer-generated filename. If you downloaded the book via OPDS or some other source, it will be the more friendly filename.

Please send me the mainDb.sqlite file I requested above, I need to check the filename as it's listed in the database.

GRiker commented 10 years ago

One more experiment. We are focusing on one particular book, 'State of Siege (1999)' by Tom Clancy. Please do the following:

WyndhamMisio commented 10 years ago

I have removed all books with the computer generated name and will reload from Calibre.

WyndhamMisio commented 10 years ago

Since removing the books with computer generated names, MXD is very much faster when generating the MXD window.

calibre 1.14 [64bit] isfrozen: True is64bit: True Windows-7-6.1.7601-SP1 Windows ('64bit', 'WindowsPE') ('Windows', '7', '6.1.7601') Python 2.7.5 Windows: ('7', '6.1.7601', 'SP1', 'Multiprocessor Free') Starting up... DEBUG: 0.0 MarvinManagerAction:genesis(v1.0.95) DEBUG: 0.0 MarvinManagerAction:init_options() DEBUG: 0.0 AnnotationsDB:connect(db_version: 1) DEBUG: 0.0 MarvinManagerAction:init_prefs(Updating prefs from 1.0.95 to 1.1.0) DEBUG: 0.0 MarvinManagerAction:inflate_dialog_resources() DEBUG: 0.1 MarvinManagerAction:compile_ui() DEBUG: 1.6 MarvinManagerAction:rebuild_menus() DEBUG: 1.6 Marvin not connected Started up in 4.30 seconds with 1913 books DEBUG: 2.9 libiMobileDevice:_afc_get_file_info( ERROR: Object not found (8) path:u'/Library/calibre/connected.xml') DEBUG: 3.9 libiMobileDevice:_afc_get_file_info( ERROR: Object not found (8) path:u'/Library/calibre/connected.xml') DEBUG: 6.9 libiMobileDevice:_afc_get_file_info( ERROR: Object not found (8) path:u'/Library/calibre/connected.xml') DEBUG: 7.9 libiMobileDevice:_afc_get_file_info( ERROR: Object not found (8) path:u'/Library/calibre/connected.xml') DEBUG: 10.9 libiMobileDevice:_afc_get_file_info( ERROR: Object not found (8) path:u'/Library/calibre/connected.xml') DEBUG: 11.9 libiMobileDevice:_afc_get_file_info( ERROR: Object not found (8) path:u'/Library/calibre/connected.xml') DEBUG: 15.0 libiMobileDevice:_afc_get_file_info( ERROR: Object not found (8) path:u'/Library/calibre/connected.xml') DEBUG: 16.0 libiMobileDevice:_afc_get_file_info( ERROR: Object not found (8) path:u'/Library/calibre/connected.xml') DEBUG: 19.0 libiMobileDevice:_afc_get_file_info( ERROR: Object not found (8) path:u'/Library/calibre/connected.xml') DEBUG: 20.0 libiMobileDevice:_afc_get_file_info( ERROR: Object not found (8) path:u'/Library/calibre/connected.xml') DEBUG: 23.0 libiMobileDevice:_afc_get_file_info( ERROR: Object not found (8) path:u'/Library/calibre/connected.xml') DEBUG: 24.0 libiMobileDevice:_afc_get_file_info( ERROR: Object not found (8) path:u'/Library/calibre/connected.xml') DEBUG: 28.1 MarvinManagerAction:on_device_connection_changed(iOS reader applications) DEBUG: 28.1 mounting com.appstafarian.MarvinIP DEBUG: 29.3 MarvinManagerAction:on_device_connection_changed(iPad3,3 running iOS 7.0.4) DEBUG: 29.3 has_password: False DEBUG: 29.3 MarvinManagerAction:rebuild_menus() DEBUG: 29.3 Marvin connected Job: 1 Get device information started DeviceJob: 1 Get device information done, calling callback DeviceJob: 1Job: Get device information1 callback returnedGet device information finished No details available. Job: 2 Get list of books on device started Job: 1 Get device information finished No details available. DeviceJob: 2 Get list of books on device done, calling callback DeviceJob: 2 Get list of books on device callback returned Job: 2 Get list of books on device finished No details available. DEBUG: 71.9 MarvinManagerAction:main_menu_button_clicked() DEBUG: 71.9 MarvinManagerAction:show_installed_books() DEBUG: 71.9 MarvinManagerAction:launch_library_scanner(updating library index for virtual library u'') DEBUG: 71.9 MarvinManagerAction:start_library_indexing() DEBUG: 71.9 MarvinManagerAction:_busy_panel_setup() DEBUG: 73.2 MarvinManagerAction:library_index_complete() DEBUG: 73.2 MarvinManagerAction:_busy_panel_teardown() DEBUG: 73.7 BookStatusDialog:initialize() DEBUG: 73.7 BookStatusDialog:_generate_booklist() DEBUG: 73.9 BookStatusDialog:_scan_library_books(1907) DEBUG: 74.7 BookStatusDialog:_get_installed_books() DEBUG: 74.7 BookStatusDialog:_scan_marvin_books(1815 books) DEBUG: 74.7 BookStatusDialog:_localize_hash_cache() DEBUG: 74.8 remote hash cache: v1, 1815 books in cache DEBUG: 74.8 BookStatusDialog:_purge_cached_orphans() DEBUG: 75.2 BookStatusDialog:_update_remote_hash_cache() DEBUG: 86.1 BookStatusDialog:_purge_cover_hash_orphans() DEBUG: 86.1 BookStatusDialog:_busy_panel_setup(Removing obsolete cover hashes) DEBUG: 86.5 BookStatusDialog:_busy_panel_teardown() DEBUG: 86.5 BookStatusDialog:_generate_marvin_hash_map() DEBUG: 86.5 BookStatusDialog:_find_fuzzy_matches() DEBUG: 86.6 BookStatusDialog:_busy_panel_setup(Preparing Marvin library view…) DEBUG: 86.8 BookStatusDialog:_construct_table_data() DEBUG: 88.6 BookStatusDialog:_construct_table_view() DEBUG: 94.3 BookStatusDialog:_update_refresh_button() DEBUG: 94.3 BookStatusDialog:_busy_panel_teardown() DEBUG: 94.3 MarvinManagerAction:show_installed_books(1815 books) DEBUG: 98.4 BookStatusDialog:filter_table_rows(s) DEBUG: 98.7 BookStatusDialog:filter_table_rows(st) DEBUG: 98.9 BookStatusDialog:filter_table_rows(sta) DEBUG: 99.2 BookStatusDialog:filter_table_rows(stat) DEBUG: 99.4 BookStatusDialog:filter_table_rows(state) DEBUG: 99.7 BookStatusDialog:filter_table_rows(state) DEBUG: 100.2 BookStatusDialog:filter_table_rows(state o) DEBUG: 100.9 BookStatusDialog:filter_table_rows(state of) DEBUG: 105.1 BookStatusDialog:dispatch_context_menu_event(u'export_metadata' row: 1453) DEBUG: 105.1 BookStatusDialog:_update_metadata(export_metadata) DEBUG: 105.1 BookStatusDialog:_busy_status_setup() DEBUG: 105.1 BookStatusDialog:_busy_status_msg(Updating metadata) DEBUG: 105.1 BookStatusDialog:_update_marvin_metadata(Unknown) DEBUG: 105.1 BookStatusDialog:_build_metadata_update() DEBUG: 105.1 'Unknown': cover is up to date DEBUG: 105.1 BookStatusDialog:_issue_command() DEBUG: 105.1 BookStatusDialog:_stage_command_file(update_metadata) DEBUG: 105.1 

<?xml version='1.0' encoding='utf-8'?>
<updatemetadata timestamp="1386701018.0" cleanupcollections="yes">
 <manifest>
  <book author="" authorsort="Unknown" filename="State Of Siege - Tom Clancy.epub" pubdate="2013-12-10" publisher="" series="" seriesindex="" title="Unknown" titlesort="Unknown" uuid="dummy">
   <collections>
   </collections>
   <subjects>
   </subjects>
  </book>
 </manifest>
</updatemetadata>

DEBUG: 105.1 BookStatusDialog:_wait_for_command_completion() DEBUG: 105.1 18:43:38.451000: waiting for '/Library/calibre/status.xml' DEBUG: 105.1 libiMobileDevice:_afc_get_file_info( ERROR: Object not found (8) path:u'/Library/calibre/status.xml') DEBUG: 105.4 libiMobileDevice:_afc_get_file_info( ERROR: Object not found (8) path:u'/Library/calibre/status.xml') DEBUG: 105.6 libiMobileDevice:_afc_get_file_info( ERROR: Object not found (8) path:u'/Library/calibre/status.xml') DEBUG: 105.9 libiMobileDevice:_afc_get_file_info( ERROR: Object not found (8) path:u'/Library/calibre/status.xml') DEBUG: 106.1 libiMobileDevice:_afc_get_file_info( ERROR: Object not found (8) path:u'/Library/calibre/status.xml') DEBUG: 106.4 libiMobileDevice:_afc_get_file_info( ERROR: Object not found (8) path:u'/Library/calibre/status.xml') DEBUG: 106.7 libiMobileDevice:_afc_get_file_info( ERROR: Object not found (8) path:u'/Library/calibre/status.xml') DEBUG: 106.9 18:43:40.247000: monitoring progress of update_metadata DEBUG: 106.9 18:43:40.261000: 1 100% DEBUG: 107.2 code: 1 status: completed with warnings[State Of Siege - Tom Clancy.epub] Cannot locate book to update metadata - skipping. DEBUG: 107.2 18:43:40.518000: 'update_metadata' complete with errors DEBUG: 107.2 libiMobileDevice:remove( ERROR: Object not found (8) path:u'/Library/calibre/status.xml') DEBUG: 107.2 18:43:40.521000: 'update_metadata' complete DEBUG: 107.2 BookStatusDialog:_busy_status_teardown() DEBUG: 107.2 collected errors: [{u'status': u'completed with warnings', u'code': 1, u'details': u'[State Of Siege - Tom Clancy.epub] Cannot locate book to update metadata - skipping.'}] DEBUG: 107.2 BookStatusDialog:_show_command_error({u'details': u'[State Of Siege - Tom Clancy.epub] Cannot locate book to update metadata - skipping.\n'}) DEBUG: 113.1 BookStatusDialog:dispatch_button_click() DEBUG: 113.1 BookStatusDialog:accept() DEBUG: 113.1 BookStatusDialog:_save_column_widths()

GRiker commented 10 years ago

The reason that MXD loads more quickly is that you deleted more than half of your books from Marvin when you decided to delete all of your books with computer-generated names.

For some reason there's a big gap in the debug trace exactly where I need to see it. Please email me this most recent debug log. (Needed to be reformatted)

GRiker commented 10 years ago

Please check the calibre metadata for 'State of Siege' in calibre's metadata editor:

WyndhamMisio commented 10 years ago

Some wrong data.

GRiker commented 10 years ago

How many copies of State of Siege are in your calibre library?

WyndhamMisio commented 10 years ago

Only one.

GRiker commented 10 years ago

Please try the following steps:

WyndhamMisio commented 10 years ago

These books are now showing no metadata differences.