Register Guidelines E-Books Search Today's Posts Mark Forums Read

Go Back   MobileRead Forums > E-Book Software > Calibre > Related Tools

Notices

Reply
 
Thread Tools Search this Thread
Old 04-11-2018, 10:05 AM   #1
jehrler
Enthusiast
jehrler began at the beginning.
 
Posts: 38
Karma: 10
Join Date: Apr 2018
Device: iphone
"kicking" calibre to wake it up required?

Posted this to the Calibre Companion section but no bites. So thought I would try it here.

We have Calibre set up on a headless Mac mini in our house and have the content server and wireless device connections active. We access it from our iOS devices via calibre companion remotely as a wireless device with no problem but...

If the Mac mini has not been used for a while and we try to access the following happens:

Calibre companion searches for calibre, says it is synching but waiting on calibre...and just sits there waiting...and waiting.

It will not finish the synching until I access the Mac mini remotely to even just look at the lock screen. Then it proceeds and finishes up fine.

Any ideas why calibre companion isn't able to give it the final kick or why the wireless device connection requires some additional user action? The Mac mini is set not to sleep and the process does seem to find the server, it just can't get all the way through.
jehrler is offline   Reply With Quote
Old 04-11-2018, 10:12 AM   #2
kovidgoyal
creator of calibre
kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.
 
kovidgoyal's Avatar
 
Posts: 43,826
Karma: 22666666
Join Date: Oct 2006
Location: Mumbai, India
Device: Various
Are you able to access the server using a browser? What do the server logs say?
kovidgoyal is offline   Reply With Quote
Advert
Old 04-11-2018, 10:31 AM   #3
jehrler
Enthusiast
jehrler began at the beginning.
 
Posts: 38
Karma: 10
Join Date: Apr 2018
Device: iphone
Good question! When I tried to access from browser it said:

Loading, please wait…
You can also use a simple, non-javascript version of this site: here

and then gave me:

Failed to communicate with "/interface-data/init?1523456722000", timed out after: 60 seconds

Note...I run Mac OSX server on the mini to use its vpn capability and that connects and initiates no problem.

Where would be the best place to find the relevant log?
jehrler is offline   Reply With Quote
Old 04-11-2018, 10:33 AM   #4
kovidgoyal
creator of calibre
kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.
 
kovidgoyal's Avatar
 
Posts: 43,826
Karma: 22666666
Join Date: Oct 2006
Location: Mumbai, India
Device: Various
If the browser is also failing to connect then the mini is putting the server process to sleep. I'm not a mac user so I cant help you with figuring out how to prevent it from doing that, probably need to add an exception for it to the power saving rules or somesuch. The server logs are avalable from Preferences->Sharing over the net but if the process is being put to sleep there wont be anything in the logs either.
kovidgoyal is offline   Reply With Quote
Old 04-11-2018, 10:46 AM   #5
jehrler
Enthusiast
jehrler began at the beginning.
 
Posts: 38
Karma: 10
Join Date: Apr 2018
Device: iphone
Quote:
Originally Posted by kovidgoyal View Post
If the browser is also failing to connect then the mini is putting the server process to sleep. I'm not a mac user so I cant help you with figuring out how to prevent it from doing that, probably need to add an exception for it to the power saving rules or somesuch. The server logs are avalable from Preferences->Sharing over the net but if the process is being put to sleep there wont be anything in the logs either.
My error, I can connect to the content server from the browser.

Let me give it some time to retry when I get back later today.
jehrler is offline   Reply With Quote
Advert
Old 04-11-2018, 04:15 PM   #6
jehrler
Enthusiast
jehrler began at the beginning.
 
Posts: 38
Karma: 10
Join Date: Apr 2018
Device: iphone
Welp. It cleared itself up. Reboot maybe?

Thanks for the reply.
jehrler is offline   Reply With Quote
Old 04-11-2018, 08:08 PM   #7
jehrler
Enthusiast
jehrler began at the beginning.
 
Posts: 38
Karma: 10
Join Date: Apr 2018
Device: iphone
OK. It has started again. I *can* access the content server via a web browser.

However, the wireless device connection is not making full connection.

As soon as I login to see what is going on via VPN, the connection continues. I did notice that the icon for Calibre was bouncing in the dock (a Mac alert to look at an app...could that be causing the wireless connection server to pause waiting for that alert to be acted on?--added, no, turned off bouncing via terminal and see log below.)

I see the log for the content server but not for the wireless device service.

Last edited by jehrler; 04-11-2018 at 08:27 PM.
jehrler is offline   Reply With Quote
Old 04-11-2018, 08:25 PM   #8
jehrler
Enthusiast
jehrler began at the beginning.
 
Posts: 38
Karma: 10
Join Date: Apr 2018
Device: iphone
I used the calibre companion debug to get some log info from that side. At 19:17 began trying to connect using calibre companion via wireless device. At 19:20, while cc was still "waiting for calibre" I accessed the content server via web browser.

At 19:21 I used vpn to connect to the Mac mini but didn't log in. CC immediately connected as wireless device.

Here is the debug log from calibre companion:

Spoiler:
2018-04-11 19:17:07.167 [Default|INFO] Log event: A_ConnectWireless ["MobileDataEnabled": false, "WirelessPortPreset": false, "WirelessIpPreset": false, "WirelessAttemptFailure": false]
2018-04-11 19:17:07.174 [Default|INFO] WirelessDevice: begin scanning
2018-04-11 19:17:07.181 [Default|INFO] ServerScan: Broadcasting on port 54982
2018-04-11 19:17:07.185 [Default|INFO] ServerScan: Broadcasting on port 48123
2018-04-11 19:17:07.187 [Default|INFO] ServerScan: Broadcasting on port 39001
2018-04-11 19:17:07.190 [Default|INFO] ServerScan: Broadcasting on port 44044
2018-04-11 19:17:07.193 [Default|INFO] ServerScan: Broadcasting on port 59678
2018-04-11 19:17:07.197 [Default|INFO] Searching Bonjour
2018-04-11 19:17:07.232 [Default|INFO] Received response from 192.168.0.52 via Bonjour
2018-04-11 19:17:11.204 [Default|INFO] WirelessDevice: Found 1 servers
2018-04-11 19:17:11.211 [Default|INFO] WirelessDevice: Connecting to http://192.168.0.52:9090
2018-04-11 19:17:11.231 [Default|INFO] Communicator connected to host 192.168.0.52 on port 9090
2018-04-11 19:17:21.241 [Default|WARNING] Communicator timed out after 10.0s, extending by another 10 seconds. Has dataHandler: false, combinedDataLength nil
2018-04-11 19:17:26.581 [Default|INFO] Received OpCode: getInitialisationInfo
2018-04-11 19:17:26.586 [Default|INFO] GetInitializationInfo: calibre version: 3, 21, 0
2018-04-11 19:17:26.595 [Default|INFO] GetInitializationInfo: sent sync column info to calibre
2018-04-11 19:17:26.673 [Default|INFO] Received OpCode: getDeviceInformation
2018-04-11 19:17:26.685 [Default|INFO] Received OpCode: setCalibreDeviceInfo
2018-04-11 19:17:27.057 [Default|INFO] Received OpCode: freeSpace
2018-04-11 19:17:27.093 [Default|INFO] Received OpCode: setLibraryInfo
2018-04-11 19:18:17.101 [Default|WARNING] Communicator timed out after 50.0s, extending by another 10 seconds. Has dataHandler: false, combinedDataLength nil
2018-04-11 19:18:27.106 [Default|WARNING] Communicator timed out after 60.0s, extending by another 10 seconds. Has dataHandler: false, combinedDataLength nil
2018-04-11 19:18:37.112 [Default|WARNING] Communicator timed out after 70.0s, extending by another 10 seconds. Has dataHandler: false, combinedDataLength nil
2018-04-11 19:18:47.117 [Default|WARNING] Communicator timed out after 80.0s, extending by another 10 seconds. Has dataHandler: false, combinedDataLength nil
2018-04-11 19:18:57.123 [Default|WARNING] Communicator timed out after 90.0s, extending by another 10 seconds. Has dataHandler: false, combinedDataLength nil
2018-04-11 19:19:07.137 [Default|WARNING] Communicator timed out after 100.0s, extending by another 10 seconds. Has dataHandler: false, combinedDataLength nil
2018-04-11 19:19:17.144 [Default|WARNING] Communicator timed out after 110.0s, extending by another 10 seconds. Has dataHandler: false, combinedDataLength nil
2018-04-11 19:19:27.149 [Default|WARNING] Communicator timed out after 120.0s, extending by another 10 seconds. Has dataHandler: false, combinedDataLength nil
2018-04-11 19:19:37.168 [Default|WARNING] Communicator timed out after 130.0s, extending by another 10 seconds. Has dataHandler: false, combinedDataLength nil
2018-04-11 19:19:47.174 [Default|WARNING] Communicator timed out after 140.0s, extending by another 10 seconds. Has dataHandler: false, combinedDataLength nil
2018-04-11 19:19:57.181 [Default|WARNING] Communicator timed out after 150.0s, extending by another 10 seconds. Has dataHandler: false, combinedDataLength nil
2018-04-11 19:20:07.188 [Default|WARNING] Communicator timed out after 160.0s, extending by another 10 seconds. Has dataHandler: false, combinedDataLength nil
2018-04-11 19:20:17.194 [Default|WARNING] Communicator timed out after 170.0s, extending by another 10 seconds. Has dataHandler: false, combinedDataLength nil
2018-04-11 19:20:27.199 [Default|WARNING] Communicator timed out after 180.0s, extending by another 10 seconds. Has dataHandler: false, combinedDataLength nil
2018-04-11 19:20:37.211 [Default|WARNING] Communicator timed out after 190.0s, extending by another 10 seconds. Has dataHandler: false, combinedDataLength nil
2018-04-11 19:20:47.217 [Default|WARNING] Communicator timed out after 200.0s, extending by another 10 seconds. Has dataHandler: false, combinedDataLength nil
2018-04-11 19:20:57.226 [Default|WARNING] Communicator timed out after 210.0s, extending by another 10 seconds. Has dataHandler: false, combinedDataLength nil
2018-04-11 19:21:07.232 [Default|WARNING] Communicator timed out after 220.0s, extending by another 10 seconds. Has dataHandler: false, combinedDataLength nil
2018-04-11 19:21:17.239 [Default|WARNING] Communicator timed out after 230.0s, extending by another 10 seconds. Has dataHandler: false, combinedDataLength nil
2018-04-11 19:21:27.244 [Default|WARNING] Communicator timed out after 240.0s, extending by another 10 seconds. Has dataHandler: false, combinedDataLength nil
2018-04-11 19:21:37.252 [Default|WARNING] Communicator timed out after 250.0s, extending by another 10 seconds. Has dataHandler: false, combinedDataLength nil
2018-04-11 19:21:43.856 [Default|INFO] Received OpCode: getBookCount
2018-04-11 19:21:43.866 [Default|INFO] Realm instance loaded, schema: 52
2018-04-11 19:21:43.966 [Default|INFO] GetBooks: Total books=334, of which are new=0
2018-04-11 19:21:43.967 [Default|INFO] GetBooks: Asking calibre what metadata it needs
2018-04-11 19:21:43.970 [Default|INFO] GetBooks: Syncing newer books
2018-04-11 19:21:43.972 [Default|INFO] GetBooks: Sending metadata 1. PriKey 1, Dragonfly in Amber - Diana Gabaldon (320).epub
2018-04-11 19:21:44.011 [Default|INFO] GetBooks: Sending metadata 31. PriKey 31, Oathbringer - Brandon Sanderson (506).epub
2018-04-11 19:21:44.050 [Default|INFO] GetBooks: Sending metadata 61. PriKey 61, Promise of Blood_ The Powder Mage Trilogy_ Book 1 - Brian McClellan (452).epub
2018-04-11 19:21:44.088 [Default|INFO] GetBooks: Sending metadata 91. PriKey 91, Ep.#3 - _Resurrection_ (The Frontiers Saga - Part 2_ Rogue Castes) - Ryk Brown (416).epub
2018-04-11 19:21:44.127 [Default|INFO] GetBooks: Sending metadata 121. PriKey 121, Close Your Eyes - Michael Robotham (375).epub
2018-04-11 19:21:44.170 [Default|INFO] GetBooks: Sending metadata 151. PriKey 151, Vital Abyss, The - James S. A. Corey (340).epub
2018-04-11 19:21:44.211 [Default|INFO] GetBooks: Sending metadata 181. PriKey 181, Ivanhoe - Sir Walter Scott (303).epub
2018-04-11 19:21:44.250 [Default|INFO] GetBooks: Sending metadata 211. PriKey 211, In Fire Forged_ Worlds of Honor V - David Weber (203).epub
2018-04-11 19:21:44.288 [Default|INFO] GetBooks: Sending metadata 241. PriKey 241, Shadow of Freedom - David Weber (171).epub
2018-04-11 19:21:44.327 [Default|INFO] GetBooks: Sending metadata 271. PriKey 271, At All Costs - David Weber (126).epub
2018-04-11 19:21:44.365 [Default|INFO] GetBooks: Sending metadata 301. PriKey 301, Antares Victory - Michael McCollum (280).epub
2018-04-11 19:21:44.404 [Default|INFO] GetBooks: Sending metadata 331. PriKey 331, Matter - Iain M. Banks (539).epub
2018-04-11 19:21:44.407 [Default|INFO] GetBooks: Sending metadata 333. PriKey 333, Colony in a Nation, A - Chris Hayes (542).epub
2018-04-11 19:21:44.410 [Default|INFO] GetBooks: Have sent all known book metadata. Books.size=334, including discovered=334
2018-04-11 19:21:44.411 [Default|INFO] GetBooks: Have finished sending placeholders for all discovered books.
2018-04-11 19:21:46.118 [Default|INFO] GetBooks: Number of books needed by Calibre: 0
2018-04-11 19:21:46.124 [Default|INFO] Completed Sending books and paths
2018-04-11 19:21:47.230 [Default|INFO] Received OpCode: sendBooklists
2018-04-11 19:21:47.253 [Default|INFO] SendBookLists: 0 books with updated metadata about to be sent
2018-04-11 19:21:47.776 [Default|INFO] Received OpCode: freeSpace
2018-04-11 19:21:47.802 [Default|INFO] Received OpCode: noOp
2018-04-11 19:21:59.851 [Default|INFO] Received OpCode: noOp
2018-04-11 19:22:12.214 [Default|INFO] Received OpCode: noOp
2018-04-11 19:22:24.300 [Default|INFO] Received OpCode: noOp
2018-04-11 19:22:28.554 [Default|INFO] Received OpCode: noOp
2018-04-11 19:22:30.000 [Default|INFO] Communicator disconnected
2018-04-11 19:22:31.942 [Default|INFO] Log event: A_OpenedExtraMenu [:]
2018-04-11 19:22:34.398 [Default|INFO] Log event: V_Settings [:]
2018-04-11 19:22:39.682 [Default|INFO] Log event: A_EmailLog [:]

Last edited by BetterRed; 04-11-2018 at 09:27 PM. Reason: inset spoiler tags
jehrler is offline   Reply With Quote
Old 04-11-2018, 08:37 PM   #9
jehrler
Enthusiast
jehrler began at the beginning.
 
Posts: 38
Karma: 10
Join Date: Apr 2018
Device: iphone
Here are the server logs from calibre:

Error log:
calibre server listening on 0.0.0.0:26279
OPDS feeds advertised via BonJour at: 192.168.0.52 port: 26279

Access log:
Spoiler:
24.118.242.56 port-56813 - 11/Apr/2018:19:20:04 -0500 "GET /ajax-setup?1523492404828 HTTP/1.1" 200 369
24.118.242.56 port-56814 - 11/Apr/2018:19:20:04 -0500 "GET /interface-data/update?1523492404837 HTTP/1.1" 401 292
24.118.242.56 port-56815 - 11/Apr/2018:19:20:04 -0500 "GET /interface-data/books-init?library_id=EBooks&sort=timestamp.desc&1523492 404863 HTTP/1.1" 401 292
24.118.242.56 port-56812 - 11/Apr/2018:19:20:04 -0500 "GET /calibre.appcache HTTP/1.1" 304 135
24.118.242.56 port-56813 jehrler 11/Apr/2018:19:20:05 -0500 "GET /interface-data/update?1523492404837 HTTP/1.1" 200 -
24.118.242.56 port-56814 jehrler 11/Apr/2018:19:20:05 -0500 "GET /interface-data/books-init?library_id=EBooks&sort=timestamp.desc&1523492 404863 HTTP/1.1" 200 -
24.118.242.56 port-56813 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/544/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56814 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/545/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56815 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/542/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56816 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/543/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56813 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/541/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56814 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/539/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56815 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/538/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56816 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/536/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56813 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/530/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56814 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/531/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56815 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/529/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56813 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/527/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56816 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/524/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56814 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/525/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56813 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/545/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56813 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/519/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56813 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/518/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56813 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/516/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56815 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/544/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56813 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/515/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56815 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/517/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56813 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/513/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56815 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/514/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56813 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/510/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56815 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/512/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56815 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/511/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56815 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/508/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56815 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/505/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56815 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/504/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56815 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/503/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56815 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/502/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56815 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/501/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56815 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/500/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56815 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/499/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56816 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/542/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56814 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/543/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56813 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/541/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56815 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/498/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56814 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/497/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56816 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/496/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56813 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/506/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56814 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/495/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56813 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/490/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56815 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/491/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56816 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/492/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56813 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/489/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56814 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/488/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56815 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/487/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56816 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/486/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56813 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/484/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56814 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/482/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56816 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/480/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56815 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/483/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56813 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/479/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56814 - 11/Apr/2018:19:20:05 -0500 "GET /get/thumb/481/EBooks?sz=900x1200 HTTP/1.1" 401 292
24.118.242.56 port-56815 - 11/Apr/2018:19:20:06 -0500 "GET /get/thumb/538/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56815 - 11/Apr/2018:19:20:06 -0500 "POST /interface-data/set-session-data HTTP/1.1" 401 292
24.118.242.56 port-56816 - 11/Apr/2018:19:20:06 -0500 "GET /get/thumb/539/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56816 jehrler 11/Apr/2018:19:20:06 -0500 "POST /interface-data/set-session-data HTTP/1.1" 200 197
24.118.242.56 port-56813 - 11/Apr/2018:19:20:06 -0500 "GET /get/thumb/536/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56814 - 11/Apr/2018:19:20:06 -0500 "GET /get/thumb/530/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56815 - 11/Apr/2018:19:20:06 -0500 "GET /get/thumb/531/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56816 - 11/Apr/2018:19:20:06 -0500 "GET /get/thumb/529/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56813 - 11/Apr/2018:19:20:06 -0500 "GET /get/thumb/527/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56814 - 11/Apr/2018:19:20:06 -0500 "GET /get/thumb/524/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56815 - 11/Apr/2018:19:20:06 -0500 "GET /get/thumb/525/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56816 - 11/Apr/2018:19:20:06 -0500 "GET /get/thumb/519/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56813 - 11/Apr/2018:19:20:07 -0500 "GET /get/thumb/518/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56814 - 11/Apr/2018:19:20:07 -0500 "GET /get/thumb/516/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56815 - 11/Apr/2018:19:20:07 -0500 "GET /get/thumb/515/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56816 - 11/Apr/2018:19:20:07 -0500 "GET /get/thumb/517/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56813 - 11/Apr/2018:19:20:07 -0500 "GET /get/thumb/513/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56814 - 11/Apr/2018:19:20:07 -0500 "GET /get/thumb/514/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56815 - 11/Apr/2018:19:20:08 -0500 "GET /get/thumb/510/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56816 - 11/Apr/2018:19:20:08 -0500 "GET /get/thumb/512/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56813 - 11/Apr/2018:19:20:08 -0500 "GET /get/thumb/511/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56814 - 11/Apr/2018:19:20:08 -0500 "GET /get/thumb/508/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56815 - 11/Apr/2018:19:20:08 -0500 "GET /get/thumb/505/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56816 - 11/Apr/2018:19:20:08 -0500 "GET /get/thumb/504/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56813 - 11/Apr/2018:19:20:08 -0500 "GET /get/thumb/503/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56814 - 11/Apr/2018:19:20:08 -0500 "GET /get/thumb/502/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56815 - 11/Apr/2018:19:20:08 -0500 "GET /get/thumb/501/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56816 - 11/Apr/2018:19:20:08 -0500 "GET /get/thumb/500/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56813 - 11/Apr/2018:19:20:09 -0500 "GET /get/thumb/499/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56814 - 11/Apr/2018:19:20:09 -0500 "GET /get/thumb/497/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56816 - 11/Apr/2018:19:20:09 -0500 "GET /get/thumb/496/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56815 - 11/Apr/2018:19:20:09 -0500 "GET /get/thumb/498/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56813 - 11/Apr/2018:19:20:09 -0500 "GET /get/thumb/506/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56814 - 11/Apr/2018:19:20:09 -0500 "GET /get/thumb/490/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56816 - 11/Apr/2018:19:20:09 -0500 "GET /get/thumb/495/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56815 - 11/Apr/2018:19:20:09 -0500 "GET /get/thumb/491/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56813 - 11/Apr/2018:19:20:09 -0500 "GET /get/thumb/492/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56814 - 11/Apr/2018:19:20:10 -0500 "GET /get/thumb/489/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56816 - 11/Apr/2018:19:20:10 -0500 "GET /get/thumb/488/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56815 - 11/Apr/2018:19:20:11 -0500 "GET /get/thumb/487/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56813 - 11/Apr/2018:19:20:11 -0500 "GET /get/thumb/486/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56814 - 11/Apr/2018:19:20:11 -0500 "GET /get/thumb/484/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56816 - 11/Apr/2018:19:20:11 -0500 "GET /get/thumb/482/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56815 - 11/Apr/2018:19:20:11 -0500 "GET /get/thumb/480/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56813 - 11/Apr/2018:19:20:11 -0500 "GET /get/thumb/483/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56814 - 11/Apr/2018:19:20:12 -0500 "GET /get/thumb/479/EBooks?sz=900x1200 HTTP/1.1" 304 135
24.118.242.56 port-56816 - 11/Apr/2018:19:20:12 -0500 "GET /get/thumb/481/EBooks?sz=900x1200 HTTP/1.1" 304 135

Last edited by BetterRed; 04-11-2018 at 09:29 PM. Reason: inset spoiler tags
jehrler is offline   Reply With Quote
Old 04-11-2018, 10:33 PM   #10
kovidgoyal
creator of calibre
kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.
 
kovidgoyal's Avatar
 
Posts: 43,826
Karma: 22666666
Join Date: Oct 2006
Location: Mumbai, India
Device: Various
I'm afraid I can't help you with CC issues as I know nothing about it, sorry. You'll have to ask in the CC forum.
kovidgoyal is offline   Reply With Quote
Old 04-12-2018, 04:32 AM   #11
jehrler
Enthusiast
jehrler began at the beginning.
 
Posts: 38
Karma: 10
Join Date: Apr 2018
Device: iphone
Posted the above log to the Calibre companion area and here is their reply:

don't own a Mac so any opinion I have is suspect, but it does seem clear that something in the Mini is "sleeping".

What I see from that log:
Calibre is awake enough to respond to CC's connection request.
Calibre starts the sequence of "jobs" to actually connect.
Calibre sends several "commands" to CC, and CC successfully responds.
Calibre successfully sends CC a command with information about the calibre library.
Calibre does not receive CC's reply to the library info command until the Mini is "tickled".
From CC's point of view, calibre went away in the middle of processing the library information command, then came back. I don't see any obvious reason why calibre would pause after sending that command. The networking for that step is exactly the same as what happened successfully in previous steps.

One possibility is that the calibre GUI is being blocked. If this happened then the wireless device networking thread would also be blocked. Various GUI things do happen during connection and CC does continue once the GUI is awakened, so there might be something here.

Getting a calibre debug log might help because it will show what calibre thinks is happening. This FAQ answer explains how to get that log.
__________________
Charles Haley
Use Calibre Companion to connect your An
jehrler is offline   Reply With Quote
Old 04-12-2018, 05:11 AM   #12
jehrler
Enthusiast
jehrler began at the beginning.
 
Posts: 38
Karma: 10
Join Date: Apr 2018
Device: iphone
attached is the calibre debug file from a connection where calibre stalled
Attached Files
File Type: rtf Untitled.rtf (115.8 KB, 288 views)

Last edited by jehrler; 04-12-2018 at 05:56 AM.
jehrler is offline   Reply With Quote
Old 04-12-2018, 07:05 AM   #13
kovidgoyal
creator of calibre
kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.
 
kovidgoyal's Avatar
 
Posts: 43,826
Karma: 22666666
Join Date: Oct 2006
Location: Mumbai, India
Device: Various
Post the debug log in the CC forum, the developer of CC is also the developer of the smart device driver in calibre, so he is the best person to make sense of it.
kovidgoyal is offline   Reply With Quote
Old 04-12-2018, 07:22 AM   #14
chaley
Grand Sorcerer
chaley ought to be getting tired of karma fortunes by now.chaley ought to be getting tired of karma fortunes by now.chaley ought to be getting tired of karma fortunes by now.chaley ought to be getting tired of karma fortunes by now.chaley ought to be getting tired of karma fortunes by now.chaley ought to be getting tired of karma fortunes by now.chaley ought to be getting tired of karma fortunes by now.chaley ought to be getting tired of karma fortunes by now.chaley ought to be getting tired of karma fortunes by now.chaley ought to be getting tired of karma fortunes by now.chaley ought to be getting tired of karma fortunes by now.
 
Posts: 11,728
Karma: 6690881
Join Date: Jan 2010
Location: Notts, England
Device: Kobo Libra 2
---- (Duplicating what I said in the CC forum)

There was a 2.5 minute pause at the point just after the device was detected (CC connected and exchanged network traffic with calibre) and before calibre asks CC if it has appropriate memory. When the pause happens calibre is informing its GUI and any interested plugins that a device has connected. CC is waiting for calibre at that point and cannot be involved in the pause.

This sequence demonstrates that the problem is in the Mini. It argues that something on the Mini is preventing the calibre GUI (or something connected to the GUI such as some kind of tray notifier) from running. I have no idea what that "something" might be, nor do I have any idea whether there is some setting that affects it.

FWIW: it seems that this "pause" is at a different place than the one indicated by the CC debug log. If that is indeed the case then whatever is happening on the Mini doesn't consistently happen at the same time.
---- (End of duplicated text)

Kovid: One reason that the content server might not "pause" when the wireless device does is that the CS doesn't run on/interact with the GUI thread.
chaley is offline   Reply With Quote
Old 04-12-2018, 08:19 AM   #15
kovidgoyal
creator of calibre
kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.kovidgoyal ought to be getting tired of karma fortunes by now.
 
kovidgoyal's Avatar
 
Posts: 43,826
Karma: 22666666
Join Date: Oct 2006
Location: Mumbai, India
Device: Various
Notifications on macOS happen via separate process asynchronously, so they cant cause pauses. I cant think of anything else that could cause a pause that would affect only the GUI thread and not any other threads. But I agree the issue is likely in the mini, as I suggested before the place to look is the power settings.
kovidgoyal is offline   Reply With Quote
Reply

Thread Tools Search this Thread
Search this Thread:

Advanced Search

Forum Jump

Similar Threads
Thread Thread Starter Forum Replies Last Post
"kicking" calibre to wake it up required? jehrler Calibre Companion 7 04-12-2018 01:37 PM
Generic "Sending to Device" template not kicking in on Folder Device At_Libitum Library Management 2 11-01-2013 07:12 AM
Checking for required libs. Exporting Binaries "easily" twobob Kindle Developer's Corner 28 10-13-2013 10:57 PM
"White Spaces Are Required"...PLEASE HELP! UntreedReads ePub 12 07-22-2010 03:47 PM
PDF "Kicking" iliad Havard iRex 1 10-24-2009 10:34 AM


All times are GMT -4. The time now is 04:28 PM.


MobileRead.com is a privately owned, operated and funded community.