calibre 2.4 isfrozen: True is64bit: True
Linux-3.13.0-24-generic-x86_64-with-debian-jessie-sid Linux ('64bit', 'ELF')
('Linux', '3.13.0-24-generic', '#47-Ubuntu SMP Fri May 2 23:30:00 UTC 2014')
Python 2.7.8
Linux: ('debian', 'jessie/sid', '')
Successfully initialized third party plugins: DeDRM && Goodreads && Generate Cover && KindleUnpack - The Plugin && Barnes & Noble && Baen && EpubSplit && Modify ePub
Starting up...
libpng warning: iCCP: Not recognizing known sRGB profile that has been edited
libpng warning: iCCP: Not recognizing known sRGB profile that has been edited
Started up in 3.16 seconds with 2767 books
SMART_DEV ( 0.00: 0.001) _attach_to_port try ip address "" on port 9090
SMART_DEV ( 0.95: 0.805) startup_on_demand listening on port 9090
SMART_DEV ( 1.01: 0.001) _attach_to_port try ip address "" on port 54982
SMART_DEV ( 1.06: 0.000) startup_on_demand broadcast socket listening on port 54982
SMART_DEV ( 2.12: 1.007) run All IP addresses {'lo': [{'peer': '127.0.0.1', 'netmask': '255.0.0.0', 'addr': '127.0.0.1'}], 'eth0': [{'broadcast': '192.168.1.255', 'netmask': '255.255.255.0', 'addr': '192.168.1.9'}]}
SMART_DEV ( 4.13: 2.003) run received broadcast ('localhost', ('192.168.1.119', 54982)) calibre wireless device client (on myusername-SDA);8090,9090
SMART_DEV ( 7.15: 3.003) run attempt to open device socket
SMART_DEV ( 7.65: 0.480) reset
SMART_DEV ( 7.68: 0.000) set_progress_reporter
SMART_DEV ( 7.71: 0.000) open
SMART_DEV ( 7.73: 0.001) _call_client GET_INITIALIZATION_INFO wfr True
SMART_DEV ( 7.89: 0.120) open Device can stream books True
SMART_DEV ( 7.92: 0.000) open Device can stream metadata True
SMART_DEV ( 7.94: 0.000) open Device can receive book binary True
SMART_DEV ( 7.97: 0.000) open Device can delete multiple books True
SMART_DEV ( 8.00: 0.000) open Device can use cached metadata True
SMART_DEV ( 8.03: 0.000) open Cache uses lpaths True
SMART_DEV ( 8.06: 0.000) open Can send OK to sendbook True
SMART_DEV ( 8.08: 0.000) open Can accept library info True
SMART_DEV ( 8.11: 0.000) open Will ask for update books False
SMART_DEV ( 8.14: 0.000) open Will set temp mark when syncing read False
SMART_DEV ( 8.17: 0.001) open Client device kind M470BSA
SMART_DEV ( 8.20: 0.000) open Client device name M470BSA
SMART_DEV ( 8.23: 0.000) open Client app name CalibreCompanion
SMART_DEV ( 8.26: 0.000) open App version #: 106
SMART_DEV ( 8.29: 0.000) open max_book_packet_len 4096
SMART_DEV ( 8.32: 0.000) open Device wants UUID file names False
SMART_DEV ( 8.35: 0.001) open selected formats [u'epub', u'pdf']
SMART_DEV ( 8.38: 0.000) open extension path lengths {u'pdf': 32, u'epub': 32}
SMART_DEV ( 8.41: 0.000) open cover height 480
SMART_DEV ( 8.43: 0.000) open Device is_read sync col None
SMART_DEV ( 8.46: 0.000) open Device is_read_date sync col None
SMART_DEV ( 8.49: 0.000) card_prefix
SMART_DEV ( 8.56: 0.029) set_progress_reporter
Job: 1 Get device information started
SMART_DEV ( 8.58: 0.001) get_device_information
SMART_DEV ( 8.62: 0.001) _call_client GET_DEVICE_INFORMATION wfr True
SMART_DEV ( 8.89: 0.236) _call_client SET_CALIBRE_DEVICE_INFO wfr True
SMART_DEV ( 9.04: 0.112) _read_metadata_cache device uuid b31cc4ef-bccb-4032-8745-d7e90db2400f
SMART_DEV ( 12.36: 3.291) _read_metadata_cache loaded 1287 cache items
SMART_DEV ( 12.39: 0.000) card_prefix
SMART_DEV ( 12.42: 0.000) free_space
SMART_DEV ( 12.45: 0.001) _call_client FREE_SPACE wfr True
DeviceJob: 1 Get device information done, calling callback
DeviceJob: 1 Get device information callback returned
SMART_DEV ( 12.57: 0.086) set_progress_reporter
Job: 2 Set library information started
SMART_DEV ( 12.60: 0.000) set_library_info Calibre Library cb6f22fc-dca1-4bd5-99c9-5a441c91b638
SMART_DEV ( 12.64: 0.001) _call_client SET_LIBRARY_INFO wfr True
DeviceJob: 2 Set library information done, calling callback
DeviceJob: 2 Set library information callback returned
SMART_DEV ( 12.82: 0.134) set_progress_reporter
Job: 3 Get list of books on device started
SMART_DEV ( 12.84: 0.000) books None
SMART_DEV ( 12.87: 0.001) _call_client GET_BOOK_COUNT wfr True
SMART_DEV ( 12.94: 0.030) books caching. count= 1287
Job: 1 Get device information finished
No details available.
Job: 2 Set library information finished
No details available.
SMART_DEV ( 17.60: 4.633) books received all books. count= 1287
SMART_DEV ( 19.39: 1.763) books processed cache. count= 1287
SMART_DEV ( 19.42: 0.001) books removed 0 books from caches
SMART_DEV ( 19.45: 0.000) books caching. Need count from device 0
SMART_DEV ( 19.53: 0.042) books finished getting book metadata
SMART_DEV ( 19.56: 0.002) books carda
SMART_DEV ( 19.58: 0.000) books 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= 1287
SMART_DEV ( 135.25:115.638) set_plugboards
DeviceJob: set_books_in_library finished: time= 115.213045835
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
SMART_DEV ( 135.68: 0.388) set_progress_reporter
Job: 4 Send metadata to device started
SMART_DEV ( 135.74: 0.007) sync_booklists collection attributes [u'']
DEBUG: 0.0 Starting get_collections: on_connect
DEBUG: 0.0 Renaming rules: {}
DEBUG: 0.0 Formatting template: {value}{category:| (|)}
DEBUG: 0.0 Sorting rules: []
SMART_DEV ( 135.99: 0.207) _call_client SEND_BOOKLISTS wfr False
SMART_DEV ( 136.05: 0.000) card_prefix
SMART_DEV ( 136.08: 0.000) free_space
SMART_DEV ( 136.12: 0.001) _call_client FREE_SPACE wfr True
Job: 3 Get list of books on device finished
No details available.
DeviceJob: 4 Send metadata to device done, calling callback
DeviceJob: 4 Send metadata to device callback returned
Job: 4 Send metadata to device finished
SMART_DEV ( 136.34: 0.179) _call_clientNo details available.
NOOP wfr True
SMART_DEV ( 138.40: 2.015) _call_client NOOP wfr True
SMART_DEV ( 140.51: 2.073) _call_client NOOP wfr True
SMART_DEV ( 142.79: 2.240) _call_client NOOP wfr True
SMART_DEV ( 145.04: 2.215) _call_client NOOP wfr True
SMART_DEV ( 147.49: 2.411) _call_client NOOP wfr True
SMART_DEV ( 151.75: 4.231) _call_client NOOP wfr True
SMART_DEV ( 151.80: 0.001) _receive_from_client protocol error -- empty json string
SMART_DEV ( 151.83: 0.000) _call_client other exception
Traceback (most recent call last):
File "site-packages/calibre/devices/smart_device_app/driver.py", line 610, in _call_client
File "site-packages/calibre/devices/smart_device_app/driver.py", line 652, in _receive_from_client
ControlError: Device responded with incorrect information
SMART_DEV ( 151.87: 0.000) _write_metadata_cache
SMART_DEV ( 153.58: 1.671) _write_metadata_cache wrote 1287 entries, purged 0 entries
SMART_DEV ( 153.63: 0.012) post_yank_cleanup