View Single Post
Old 09-19-2014, 05:44 PM   #6
rpspringuel
Enthusiast
rpspringuel began at the beginning.
 
Posts: 40
Karma: 10
Join Date: Feb 2014
Device: Kindle 4
Well, the error's back and now it's worse.
If calibre is open when I connect the Kindle, or if I open calibre while the Kindle is connected, the Kindle gets removed without being properly ejected (making the OS complain) shortly after calibre detects it. This happens a handful of times and then things seem to settle down and I appear to have a proper connection. However, when I then try to send a book (any book) a cycle of 2 more removals and remountings happens (and the book is not sent).

I tried all the fixes mentioned above, to no avail. I tried updating to 2.3, still experiencing the problems. Restarted both the Kindle and the computer, still experiencing the problems.

Running in debug mode I did the following:
Spoiler:


Connect kindle to OS (without calibre running)
Start calibre-debug
Wait for device detection (no problems this time)
Send a book to the device (3 removal errors from OS and an error from calibre before going back to appearing normal)
Try again with a different book (same results)

Below is the debug output for this process
Quote:
rps-mac:~ RPS$ calibre-debug -g
calibre 2.3 isfrozen: True is64bit: True
Darwin-12.5.0-x86_64-i386-64bit Darwin ('64bit', '')
('Darwin', '12.5.0', 'Darwin Kernel Version 12.5.0: Sun Sep 29 13:33:47 PDT 2013; root:xnu-2050.48.12~1/RELEASE_X86_64')
Python 2.7.8
OSX: ('10.8.5', ('', '', ''), 'x86_64')
Successfully initialized third party plugins: DeDRM && Webscription && Find Duplicates && KindleUnpack - The Plugin && Barnes & Noble && Hyphenate This! && SmartEject && Goodreads
Starting up...
Failed to load recipe from: u'Vatican_1000.recipe'
Traceback (most recent call last):
File "site-packages/calibre/web/feeds/recipes/collection.py", line 110, in get_custom_recipe_collection
IOError: [Errno 2] No such file or directory: u'/Users/RPS/Library/Preferences/calibre/custom_recipes/Vatican_1000.recipe'
libpng warning: iCCP: Not recognizing known sRGB profile that has been edited
Started up in 3.02 seconds with 298 books
Job: 1 Get device information started
DeviceJob: 1 Get device information done, calling callback
DeviceJob: 1 Get device information callback returned
Job: 2 Set library information started
DeviceJob: 2 Set library information done, calling callback
2014-09-19 17:33:02.145 Calibre[2557:707] deliverNotificationWithTitle()
DeviceJob: 2 Set library information callback returned
Job: 3 Get list of books on device started
DEBUG: 0.0 USBMS: Fetching list of books from device. Device= KINDLE2 oncard= None
DEBUG: 0.0 USBMS: dirs are: /Volumes/Kindle 2/ documents
exception during JSON decode_from_file
Traceback (most recent call last):
File "site-packages/calibre/ebooks/metadata/book/json_codec.py", line 163, in decode_from_file
File "lib/python2.7/json/__init__.py", line 290, in load
File "lib/python2.7/json/__init__.py", line 351, in loads
File "lib/python2.7/json/decoder.py", line 366, in decode
File "lib/python2.7/json/decoder.py", line 384, in raw_decode
ValueError: No JSON object could be decoded
DEBUG: 0.0 USBMS: scan from root False /Volumes/Kindle 2/documents
* Notification delivered.
Job: 2 Set library information finished
No details available.
Job: 1 Get device information finished
No details available.
Failed to read MOBI cover
Failed to read MOBI cover
Failed to read MOBI cover
Failed to read MOBI cover
DEBUG: 2.4 USBMS: count found in cache: 0, count of files in metadata: 87, need_sync: False
DEBUG: 2.4 USBMS: Finished fetching list of books from device. oncard= None
DEBUG: 2.4 USBMS: Fetching list of books from device. Device= KINDLE2 oncard= carda
DEBUG: 2.4 USBMS: Fetching list of books from device. Device= KINDLE2 oncard= cardb
DeviceJob: 3 Get list of books on device done, calling callback
DeviceJob: metadata_downloaded: Starting set_books_in_library
DeviceJob: set_books_in_library: books to process= 87
Job: 3 Get list of books on device finished
No details available.
DeviceJob: set_books_in_library finished: time= 0.0167779922485
DeviceJob: metadata_downloaded: updating views
DeviceJob: metadata_downloaded: syncing
DeviceJob: metadata_downloaded: refreshing ondevice
DeviceJob: metadata_downloaded: sending metadata_available signal
DeviceJob: 3 Get list of books on device callback returned
Job: 3 Get list of books on device finished
No details available.
2014-09-19 17:35:15.381 Calibre[2616:707] deliverNotificationWithTitle()
* Notification delivered.
Job: 4 Upload 1 books to device:The Conferences of John Cassian started
Device using plugboard azw3 KINDLE2 None
Setting metadata in: The Conferences of John Cassian at: /var/folders/7r/jmxwxnms3jl6hptxkfymx3800000gq/T/calibre_2.3.0_tmp_Bz4j4W/oglIsvcaltmpfmt.azw3
DEBUG: 134.8 USBMS: uploading 1 books
Invalid method choice for this book (u'none'), ignoring.
APNX META: guid: 9a209fc5
APNX META: ASIN: ebdbf7ad-dcdb-4e11-b32f-4d069f97b9f6
APNX META: CDE: EBOK
APNX META: format: MOBI_8
APNX META: Name: The_Conferences_of_John_Cassian
APNX Content Header: {"contentGuid":"9a209fc5","asin":"ebdbf7ad-dcdb-4e11-b32f-4d069f97b9f6","cdeType":"EBOK","format":"MOBI_8"," fileRevisionId":"1","acr":"The_Conferences_of_John _Cassian"}
DEBUG: 134.8 USBMS: finished uploading 1 books
DeviceJob: 4 Upload 1 books to device:The Conferences of John Cassian done, calling callback
DEBUG: 134.8 USBMS: adding metadata for 1 books
DEBUG: 134.8 USBMS: finished adding metadata
DeviceJob: set_books_in_library: books to process= 88
DeviceJob: set_books_in_library finished: time= 0.00594615936279
DeviceJob: 4 Upload 1 books to device:The Conferences of John Cassian callback returned
Job: 5 Send metadata to device started
DEBUG: 135.2 USBMS: starting sync_booklists
DeviceJob: 5 Send metadata to device done, calling callback
[Errno 13] Permission denied: u'/Volumes/Kindle 2/metadata.calibre'

Traceback (most recent call last):
File "site-packages/calibre/gui2/device.py", line 87, in run
File "site-packages/calibre/gui2/device.py", line 532, in _sync_booklists
File "site-packages/calibre/devices/usbms/driver.py", line 395, in sync_booklists
File "site-packages/calibre/devices/usbms/driver.py", line 392, in write_prefix
IOError: [Errno 13] Permission denied: u'/Volumes/Kindle 2/metadata.calibre'

DeviceJob: 5 Send metadata to device callback returned
Job: 4 Upload 1 books to device:The Conferences of John Cassian finished
No details available.
Job: 5 Send metadata to device finished
[Errno 13] Permission denied: u'/Volumes/Kindle 2/metadata.calibre'

Traceback (most recent call last):
File "site-packages/calibre/gui2/device.py", line 87, in run
File "site-packages/calibre/gui2/device.py", line 532, in _sync_booklists
File "site-packages/calibre/devices/usbms/driver.py", line 395, in sync_booklists
File "site-packages/calibre/devices/usbms/driver.py", line 392, in write_prefix
IOError: [Errno 13] Permission denied: u'/Volumes/Kindle 2/metadata.calibre'

Job: 6 Get device information started
DeviceJob: 6 Get device information done, calling callback
DeviceJob: 6 Get device information callback returned
Job: 7 Set library information started
DeviceJob: 7 Set library information done, calling callback
2014-09-19 17:35:40.844 Calibre[2660:707] deliverNotificationWithTitle()
DeviceJob: 7 Set library information callback returned
Job: 8 Get list of books on device started
DEBUG: 158.7 USBMS: Fetching list of books from device. Device= KINDLE2 oncard= None
DEBUG: 158.7 USBMS: dirs are: /Volumes/Kindle 3/ documents
exception during JSON decode_from_file
Traceback (most recent call last):
File "site-packages/calibre/ebooks/metadata/book/json_codec.py", line 163, in decode_from_file
File "lib/python2.7/json/__init__.py", line 290, in load
File "lib/python2.7/json/__init__.py", line 351, in loads
File "lib/python2.7/json/decoder.py", line 366, in decode
File "lib/python2.7/json/decoder.py", line 384, in raw_decode
ValueError: No JSON object could be decoded
DEBUG: 158.7 USBMS: scan from root False /Volumes/Kindle 3/documents
* Notification delivered.
Job: 6 Get device information finished
No details available.
Job: 7 Set library information finished
No details available.
Failed to read MOBI cover
Failed to read MOBI cover
Failed to read MOBI cover
Failed to read MOBI cover
DEBUG: 162.0 USBMS: count found in cache: 0, count of files in metadata: 87, need_sync: False
DEBUG: 162.0 USBMS: Finished fetching list of books from device. oncard= None
DEBUG: 162.0 USBMS: Fetching list of books from device. Device= KINDLE2 oncard= carda
DEBUG: 162.0 USBMS: Fetching list of books from device. Device= KINDLE2 oncard= cardb
DeviceJob: 8 Get list of books on device done, calling callback
DeviceJob: metadata_downloaded: Starting set_books_in_library
DeviceJob: set_books_in_library: books to process= 87
DeviceJob: set_books_in_library finished: time= 0.00887393951416
DeviceJob: metadata_downloaded: updating views
DeviceJob: metadata_downloaded: syncing
DeviceJob: metadata_downloaded: refreshing ondevice
DeviceJob: metadata_downloaded: sending metadata_available signal
DeviceJob: 8 Get list of books on device callback returned
Job: 8 Get list of books on device finished
No details available.
2014-09-19 17:36:37.658 Calibre[2683:707] deliverNotificationWithTitle()
* Notification delivered.
Job: 9 Upload 1 books to device:The Sign of the Four started
Device using plugboard azw3 KINDLE2 None
Setting metadata in: The Sign of the Four at: /var/folders/7r/jmxwxnms3jl6hptxkfymx3800000gq/T/calibre_2.3.0_tmp_Bz4j4W/Tz7coYcaltmpfmt.azw3
DEBUG: 216.4 USBMS: uploading 1 books
Invalid method choice for this book (u'none'), ignoring.
Failed to generate APNX
Traceback (most recent call last):
File "site-packages/calibre/devices/kindle/driver.py", line 491, in upload_apnx
File "site-packages/calibre/devices/kindle/apnx.py", line 37, in write_apnx
File "site-packages/calibre/ebooks/pdb/header.py", line 18, in __init__
File "site-packages/calibre/ebooks/pdb/header.py", line 24, in identity
IOError: [Errno 6] Device not configured
DEBUG: 216.4 USBMS: finished uploading 1 books
DeviceJob: 9 Upload 1 books to device:The Sign of the Four done, calling callback
DEBUG: 216.4 USBMS: adding metadata for 1 books
DEBUG: 216.4 USBMS: finished adding metadata
DeviceJob: set_books_in_library: books to process= 88
Job: 9 Upload 1 books to device:The Sign of the Four finished
No details available.
DeviceJob: set_books_in_library finished: time= 0.0195710659027
DeviceJob: 9 Upload 1 books to device:The Sign of the Four callback returned
Job: 10 Send metadata to device started
DEBUG: 216.9 USBMS: starting sync_booklists
DeviceJob: 10 Send metadata to device done, calling callback
[Errno 13] Permission denied: u'/Volumes/Kindle 3/metadata.calibre'

Traceback (most recent call last):
File "site-packages/calibre/gui2/device.py", line 87, in run
File "site-packages/calibre/gui2/device.py", line 532, in _sync_booklists
File "site-packages/calibre/devices/usbms/driver.py", line 395, in sync_booklists
File "site-packages/calibre/devices/usbms/driver.py", line 392, in write_prefix
IOError: [Errno 13] Permission denied: u'/Volumes/Kindle 3/metadata.calibre'

DeviceJob: 10 Send metadata to device callback returned
Job: 9 Upload 1 books to device:The Sign of the Four finished
No details available.
Job: 10 Send metadata to device finished
[Errno 13] Permission denied: u'/Volumes/Kindle 3/metadata.calibre'

Traceback (most recent call last):
File "site-packages/calibre/gui2/device.py", line 87, in run
File "site-packages/calibre/gui2/device.py", line 532, in _sync_booklists
File "site-packages/calibre/devices/usbms/driver.py", line 395, in sync_booklists
File "site-packages/calibre/devices/usbms/driver.py", line 392, in write_prefix
IOError: [Errno 13] Permission denied: u'/Volumes/Kindle 3/metadata.calibre'

Job: 11 Get device information started
DeviceJob: 11 Get device information done, calling callback
2014-09-19 17:37:08.703 Calibre[2734:707] deliverNotificationWithTitle()
DeviceJob: 11 Get device information callback returned
Job: 12 Set library information started
DeviceJob: 12 Set library information done, calling callback
DeviceJob: 12 Set library information callback returned
Job: 13 Get list of books on device started
DEBUG: 246.6 USBMS: Fetching list of books from device. Device= KINDLE2 oncard= None
DEBUG: 246.6 USBMS: dirs are: /Volumes/Kindle 4/ documents
exception during JSON decode_from_file
Traceback (most recent call last):
File "site-packages/calibre/ebooks/metadata/book/json_codec.py", line 163, in decode_from_file
File "lib/python2.7/json/__init__.py", line 290, in load
File "lib/python2.7/json/__init__.py", line 351, in loads
File "lib/python2.7/json/decoder.py", line 366, in decode
File "lib/python2.7/json/decoder.py", line 384, in raw_decode
ValueError: No JSON object could be decoded
DEBUG: 246.6 USBMS: scan from root False /Volumes/Kindle 4/documents
* Notification delivered.
Job: 11 Get device information finished
No details available.
Job: 12 Set library information finished
No details available.
Failed to read MOBI cover
Failed to read MOBI cover
Failed to read MOBI cover
Failed to read MOBI cover
DEBUG: 249.8 USBMS: count found in cache: 0, count of files in metadata: 87, need_sync: False
DEBUG: 249.8 USBMS: Finished fetching list of books from device. oncard= None
DEBUG: 249.8 USBMS: Fetching list of books from device. Device= KINDLE2 oncard= carda
DEBUG: 249.8 USBMS: Fetching list of books from device. Device= KINDLE2 oncard= cardb
DeviceJob: 13 Get list of books on device done, calling callback
DeviceJob: metadata_downloaded: Starting set_books_in_library
DeviceJob: set_books_in_library: books to process= 87
DeviceJob: set_books_in_library finished: time= 0.0106639862061
DeviceJob: metadata_downloaded: updating views
DeviceJob: metadata_downloaded: syncing
DeviceJob: metadata_downloaded: refreshing ondevice
DeviceJob: metadata_downloaded: sending metadata_available signal
DeviceJob: 13 Get list of books on device callback returned
Job: 13 Get list of books on device finished
No details available.


Any further ideas, suggestions?

Last edited by theducks; 09-19-2014 at 05:57 PM. Reason: Wrapped long paste in Spoiler
rpspringuel is offline   Reply With Quote