Bot freezing mid-print

[Nick Foley]

Lately we've been having issues where our bots stop printing mid-print. They stay turned on, and at temperature, but they stop moving. If the job is canceled and re-run, they start the job over and print as normal, possibly freezing again during the print, or possibly succeeding. Sometimes, this issue will happen once in a week, other times, it will happen several times over the course of an hour. It seems to be very random at this point. 

I will post an info.log when it happens again. Mostly I am trying to debug the issue right now, and wondering if anyone else has seen this behavior. We are printing from Raspberry Pi's. It happens on two of our three bots. It has never happened when we are printing from the SD card slot directly on one of the affected machines, so I suspect it has something to do with the host computer (Pi in this case).

[graphmastur]

Does the pi lose Ethernet during this event? Are any of your pi having trouble slicing?

On Aug 28, 2013 12:22 AM, "Nick Foley" <ni...@socialbicycles.com> wrote:

Lately we've been having issues where our bots stop printing mid-print. They stay turned on, and at temperature, but they stop moving. If the job is canceled and re-run, they start the job over and print as normal, possibly freezing again during the print, or possibly succeeding. Sometimes, this issue will happen once in a week, other times, it will happen several times over the course of an hour. It seems to be very random at this point. 

I will post an info.log when it happens again. Mostly I am trying to debug the issue right now, and wondering if anyone else has seen this behavior. We are printing from Raspberry Pi's. It happens on two of our three bots. It has never happened when we are printing from the SD card slot directly on one of the affected machines, so I suspect it has something to do with the host computer (Pi in this case).

--
You received this message because you are subscribed to the Google Groups "BotQueue" group.
To unsubscribe from this group and stop receiving emails from it, send an email to botqueue+u...@googlegroups.com.
For more options, visit https://groups.google.com/groups/opt_out.

[Nick Foley]

Sorry, should have added that detail - The Pis are on wifi - and I don't think they lose internet, because I've connected to them remotely while they were stalled, but it is certainly possible there is a temporary drop in their connection. We are not sending STL files, we are sending GCODE files to the Pi's, so slicing shouldn't be an issue. I will try to dig one of these events out of my (huge) info.log file today and see if there is anything noteworthy when this happens.

[Nick Foley]

Ok, so here's the log from a print that failed about 83% into the print, at which point I canceled the print from the web. You can see that the internet connection does go down several times during the print, but it doesn't seem to be down when the print progress hangs at 83.46%:

[2013-08-28 17:14:59,545] INFO: Rambutan: print: 72.26%

[2013-08-28 17:14:59,554] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:15:05,564] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:15:10,472] ERROR: updatejobprogress call failed: internet connection is down: HTTPSConnectionPool(host='www.botqueue.com', port=443): Max retries exceeded with url: /api/v1/endpoint?api_call=updatejobprogress&_local_ip=192.168.1.149&oauth_nonce=3500613&oauth_timestamp=1377724499&_client_version=0.4&_client_name=Bumblebee&oauth_consumer_key=4b99f7bb861ad3fab5b3d4a189c81c0b893c043f&oauth_signature_method=HMAC-SHA1&api_output=json&oauth_version=1.0&_uid=315454d5b070814700600238ff452065387b7364&oauth_token=1d84f68168a488f7c388ef013bbfffb319bb1e83&temperatures=%7B%22bed_target%22%3A+%220.0%22%2C+%22extruder_target%22%3A+%22280.0%22%2C+%22bed%22%3A+%220.0%22%2C+%22extruder%22%3A+%22280.1%22%7D&progress=72.26361&oauth_signature=%2BP5%2FHt%2FIE2yoSdE9d0lIV68av9g%3D&job_id=5294 (Caused by <class 'socket.error'>: [Errno 111] Connection refused)

[2013-08-28 17:15:18,149] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:15:20,983] INFO: Rambutan: print: 72.55%

[2013-08-28 17:15:20,986] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:15:34,456] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:15:36,258] INFO: Rambutan: print: 72.73%

[2013-08-28 17:15:36,271] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:15:47,150] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:15:51,671] INFO: Rambutan: print: 72.94%

[2013-08-28 17:15:51,674] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:15:58,877] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:16:06,760] INFO: Rambutan: print: 73.14%

[2013-08-28 17:16:06,764] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:16:10,220] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:16:21,823] INFO: Rambutan: print: 73.33%

[2013-08-28 17:16:21,827] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:16:27,226] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:16:37,210] INFO: Rambutan: print: 73.55%

[2013-08-28 17:16:37,216] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:16:41,491] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:16:52,465] INFO: Rambutan: print: 73.74%

[2013-08-28 17:16:52,469] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:16:53,510] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:17:05,506] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:17:07,526] INFO: Rambutan: print: 73.92%

[2013-08-28 17:17:07,533] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:17:17,940] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:17:22,871] INFO: Rambutan: print: 74.17%

[2013-08-28 17:17:22,877] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:17:29,958] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:17:38,251] INFO: Rambutan: print: 74.37%

[2013-08-28 17:17:38,258] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:17:41,712] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:17:53,020] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:17:53,691] INFO: Rambutan: print: 74.60%

[2013-08-28 17:17:53,717] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:18:05,157] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:18:09,050] INFO: Rambutan: print: 74.77%

[2013-08-28 17:18:09,053] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:18:24,239] INFO: Rambutan: print: 74.96%

[2013-08-28 17:18:24,244] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:18:27,366] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:18:38,848] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:18:39,543] INFO: Rambutan: print: 75.17%

[2013-08-28 17:18:39,546] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:18:50,763] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:18:54,968] INFO: Rambutan: print: 75.39%

[2013-08-28 17:18:54,971] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:19:02,236] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:19:10,074] INFO: Rambutan: print: 75.59%

[2013-08-28 17:19:10,087] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:19:25,549] INFO: Rambutan: print: 75.80%

[2013-08-28 17:19:25,552] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:19:29,377] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:19:40,987] INFO: Rambutan: print: 76.02%

[2013-08-28 17:19:40,998] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:19:41,779] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:19:53,561] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:19:56,318] INFO: Rambutan: print: 76.23%

[2013-08-28 17:19:56,321] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:20:05,334] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:20:11,417] INFO: Rambutan: print: 76.44%

[2013-08-28 17:20:11,421] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:20:16,576] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:20:26,670] INFO: Rambutan: print: 76.64%

[2013-08-28 17:20:26,692] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:20:38,097] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:20:41,904] INFO: Rambutan: print: 76.84%

[2013-08-28 17:20:41,908] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:20:49,710] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:20:56,996] INFO: Rambutan: print: 77.06%

[2013-08-28 17:20:57,008] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:21:01,502] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:21:12,031] INFO: Rambutan: print: 77.27%

[2013-08-28 17:21:12,034] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:21:13,432] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:21:24,724] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:21:27,035] INFO: Rambutan: print: 77.47%

[2013-08-28 17:21:27,043] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:21:36,502] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:21:42,039] INFO: Rambutan: print: 77.69%

[2013-08-28 17:21:42,042] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:21:47,888] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:21:52,470] ERROR: updatejobprogress call failed: internet connection is down: HTTPSConnectionPool(host='www.botqueue.com', port=443): Max retries exceeded with url: /api/v1/endpoint?api_call=updatejobprogress&_local_ip=192.168.1.149&oauth_nonce=2653962&oauth_timestamp=1377724902&_client_version=0.4&_client_name=Bumblebee&oauth_consumer_key=4b99f7bb861ad3fab5b3d4a189c81c0b893c043f&oauth_signature_method=HMAC-SHA1&api_output=json&oauth_version=1.0&_uid=315454d5b070814700600238ff452065387b7364&oauth_token=1d84f68168a488f7c388ef013bbfffb319bb1e83&temperatures=%7B%22bed_target%22%3A+%220.0%22%2C+%22extruder_target%22%3A+%22280.0%22%2C+%22bed%22%3A+%220.0%22%2C+%22extruder%22%3A+%22281.0%22%7D&progress=77.69181&oauth_signature=9mCByph8PeAWcN%2Bvr95TKkZ4K7k%3D&job_id=5294 (Caused by <class 'socket.error'>: [Errno 111] Connection refused)

[2013-08-28 17:22:02,978] INFO: Rambutan: print: 78.00%

[2013-08-28 17:22:02,993] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:22:04,369] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:22:15,925] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:22:18,052] INFO: Rambutan: print: 78.20%

[2013-08-28 17:22:18,055] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:22:27,631] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:22:33,214] INFO: Rambutan: print: 78.42%

[2013-08-28 17:22:33,217] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:22:39,561] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:22:48,334] INFO: Rambutan: print: 78.63%

[2013-08-28 17:22:48,337] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:22:51,565] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:23:03,113] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:23:03,416] INFO: Rambutan: print: 78.86%

[2013-08-28 17:23:03,420] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:23:15,102] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:23:18,801] INFO: Rambutan: print: 79.09%

[2013-08-28 17:23:18,804] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:23:27,242] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:23:33,945] INFO: Rambutan: print: 79.30%

[2013-08-28 17:23:33,948] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:23:39,222] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:23:49,195] INFO: Rambutan: print: 79.51%

[2013-08-28 17:23:49,198] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:23:51,256] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:24:02,671] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:24:04,291] INFO: Rambutan: print: 79.70%

[2013-08-28 17:24:04,294] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:24:14,013] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:24:19,441] INFO: Rambutan: print: 79.91%

[2013-08-28 17:24:19,445] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:24:25,654] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:24:34,678] INFO: Rambutan: print: 80.12%

[2013-08-28 17:24:34,681] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:24:37,667] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:24:48,950] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:24:49,964] INFO: Rambutan: print: 80.34%

[2013-08-28 17:24:49,968] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:25:00,455] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:25:05,090] INFO: Rambutan: print: 80.58%

[2013-08-28 17:25:05,093] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:25:12,025] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:25:20,147] INFO: Rambutan: print: 80.76%

[2013-08-28 17:25:20,157] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:25:23,711] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:25:35,091] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:25:35,204] INFO: Rambutan: print: 80.96%

[2013-08-28 17:25:35,208] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:25:46,617] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:25:50,462] INFO: Rambutan: print: 81.20%

[2013-08-28 17:25:50,465] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:25:57,969] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:26:05,582] INFO: Rambutan: print: 81.42%

[2013-08-28 17:26:05,600] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:26:19,227] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:26:20,897] INFO: Rambutan: print: 81.63%

[2013-08-28 17:26:20,903] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:26:31,258] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:26:36,157] INFO: Rambutan: print: 81.86%

[2013-08-28 17:26:36,164] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:26:43,136] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:26:51,371] INFO: Rambutan: print: 82.06%

[2013-08-28 17:26:51,374] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:26:54,557] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:27:06,103] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:27:06,664] INFO: Rambutan: print: 82.27%

[2013-08-28 17:27:06,667] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:27:18,211] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:27:22,067] INFO: Rambutan: print: 82.49%

[2013-08-28 17:27:22,071] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:27:30,003] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:27:37,333] INFO: Rambutan: print: 82.71%

[2013-08-28 17:27:37,337] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:27:42,005] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:27:52,633] INFO: Rambutan: print: 82.93%

[2013-08-28 17:27:52,636] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:27:53,927] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:28:07,867] INFO: Rambutan: print: 83.16%

[2013-08-28 17:28:07,877] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:28:15,538] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:28:23,035] INFO: Rambutan: print: 83.39%

[2013-08-28 17:28:23,042] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:28:27,501] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:28:38,303] INFO: Rambutan: print: 83.46%

[2013-08-28 17:28:38,306] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:28:39,396] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:28:50,570] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:28:53,715] INFO: Rambutan: print: 83.46%

[2013-08-28 17:28:53,718] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:29:01,941] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:29:08,779] INFO: Rambutan: print: 83.46%

[2013-08-28 17:29:08,791] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:29:13,546] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:29:23,800] INFO: Rambutan: print: 83.46%

[2013-08-28 17:29:23,804] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:29:34,875] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:29:38,844] INFO: Rambutan: print: 83.46%

[2013-08-28 17:29:38,847] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:29:47,607] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:29:53,858] INFO: Rambutan: print: 83.46%

[2013-08-28 17:29:53,861] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:29:59,517] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:30:08,922] INFO: Rambutan: print: 83.46%

[2013-08-28 17:30:08,926] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:30:11,494] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:30:22,661] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:30:24,118] INFO: Rambutan: print: 83.46%

[2013-08-28 17:30:24,128] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:30:34,002] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:30:39,369] INFO: Rambutan: print: 83.46%

[2013-08-28 17:30:39,372] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:30:47,338] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:30:54,478] INFO: Rambutan: print: 83.46%

[2013-08-28 17:30:54,481] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:30:59,205] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:31:09,751] INFO: Rambutan: print: 83.46%

[2013-08-28 17:31:09,754] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:31:10,496] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:31:21,878] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:31:24,902] INFO: Rambutan: print: 83.46%

[2013-08-28 17:31:24,907] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:31:33,562] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:31:39,927] INFO: Rambutan: print: 83.46%

[2013-08-28 17:31:39,930] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:31:45,134] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:31:54,991] INFO: Rambutan: print: 83.46%

[2013-08-28 17:31:54,995] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:31:56,243] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:32:07,648] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:32:10,165] INFO: Rambutan: print: 83.46%

[2013-08-28 17:32:10,168] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:32:18,821] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:32:25,434] INFO: Rambutan: print: 83.46%

[2013-08-28 17:32:25,437] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:32:30,090] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:32:40,570] INFO: Rambutan: print: 83.46%

[2013-08-28 17:32:40,574] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:32:41,335] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:32:52,483] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:32:55,901] INFO: Rambutan: print: 83.46%

[2013-08-28 17:32:55,905] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:33:03,848] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:33:11,183] INFO: Rambutan: print: 83.46%

[2013-08-28 17:33:11,186] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:33:15,477] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:33:26,646] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:33:26,677] INFO: Rambutan: print: 83.46%

[2013-08-28 17:33:26,719] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:33:37,899] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:33:42,066] INFO: Rambutan: print: 83.46%

[2013-08-28 17:33:42,070] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:33:49,650] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:33:57,077] INFO: Rambutan: print: 83.46%

[2013-08-28 17:33:57,090] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:34:06,041] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:34:12,553] INFO: Rambutan: print: 83.46%

[2013-08-28 17:34:12,557] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:34:17,630] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:34:27,729] INFO: Rambutan: print: 83.46%

[2013-08-28 17:34:27,732] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:34:29,542] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:34:40,727] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:34:42,847] INFO: Rambutan: print: 83.46%

[2013-08-28 17:34:42,854] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:34:52,134] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:34:57,921] INFO: Rambutan: print: 83.46%

[2013-08-28 17:34:57,924] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:35:03,873] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:35:12,960] INFO: Rambutan: print: 83.46%

[2013-08-28 17:35:12,963] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:35:25,840] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:35:28,009] INFO: Rambutan: print: 83.46%

[2013-08-28 17:35:28,013] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:35:43,038] INFO: Rambutan: print: 83.46%

[2013-08-28 17:35:43,042] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:35:46,178] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:35:58,533] INFO: Rambutan: print: 83.46%

[2013-08-28 17:35:58,537] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:36:01,347] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:36:12,881] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:36:13,746] INFO: Rambutan: print: 83.46%

[2013-08-28 17:36:13,750] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:36:28,815] INFO: Rambutan: print: 83.46%

[2013-08-28 17:36:28,818] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:36:37,723] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:36:43,905] INFO: Rambutan: print: 83.46%

[2013-08-28 17:36:43,919] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:36:50,085] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:36:59,250] INFO: Rambutan: print: 83.46%

[2013-08-28 17:36:59,269] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:37:01,986] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:37:13,157] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:37:14,329] INFO: Rambutan: print: 83.46%

[2013-08-28 17:37:14,333] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:37:27,721] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:37:29,435] INFO: Rambutan: print: 83.46%

[2013-08-28 17:37:29,445] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:37:39,543] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:37:44,437] INFO: Rambutan: print: 83.46%

[2013-08-28 17:37:44,440] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:37:51,588] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:37:59,571] INFO: Rambutan: print: 83.46%

[2013-08-28 17:37:59,632] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:38:04,021] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:38:14,866] INFO: Rambutan: print: 83.46%

[2013-08-28 17:38:14,871] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:38:15,404] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:38:30,107] INFO: Rambutan: print: 83.46%

[2013-08-28 17:38:30,115] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:38:45,407] INFO: Rambutan: print: 83.46%

[2013-08-28 17:38:45,412] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:38:45,727] ERROR: updatejobprogress call failed: internet connection is down: HTTPSConnectionPool(host='www.botqueue.com', port=443): Max retries exceeded with url: /api/v1/endpoint?api_call=updatejobprogress&_local_ip=192.168.1.149&oauth_nonce=77611325&oauth_timestamp=1377725925&_client_version=0.4&_client_name=Bumblebee&oauth_consumer_key=4b99f7bb861ad3fab5b3d4a189c81c0b893c043f&oauth_signature_method=HMAC-SHA1&api_output=json&oauth_version=1.0&_uid=315454d5b070814700600238ff452065387b7364&oauth_token=1d84f68168a488f7c388ef013bbfffb319bb1e83&temperatures=%7B%22bed_target%22%3A+%220.0%22%2C+%22extruder_target%22%3A+%22280.0%22%2C+%22bed%22%3A+%220.0%22%2C+%22extruder%22%3A+%22280.8%22%7D&progress=83.46133&oauth_signature=x%2FGrbPQXpZkz%2Bfq4JDeZX4x8UO8%3D&job_id=5294 (Caused by <class 'httplib.BadStatusLine'>: '')

[2013-08-28 17:38:51,069] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:39:00,748] INFO: Rambutan: print: 83.46%

[2013-08-28 17:39:00,751] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:39:02,195] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:39:15,903] INFO: Rambutan: print: 83.46%

[2013-08-28 17:39:15,906] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:39:23,916] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:39:30,999] INFO: Rambutan: print: 83.46%

[2013-08-28 17:39:31,002] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:39:37,433] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:39:46,100] INFO: Rambutan: print: 83.46%

[2013-08-28 17:39:46,103] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:39:51,202] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:40:01,539] INFO: Rambutan: print: 83.46%

[2013-08-28 17:40:01,544] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - updatejobprogress (1 tries remaining)

[2013-08-28 17:40:02,505] ERROR: API: Bot does not exist.

[2013-08-28 17:40:03,115] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - getmybots (1 tries remaining)

[2013-08-28 17:40:05,560] INFO: Bot Rambutan looking for new job.

[2013-08-28 17:40:05,567] DEBUG: Calling https://www.botqueue.com/api/v1/endpoint - findnewjob (999999 tries remaining)

[2013-08-28 17:40:06,027] INFO: Rambutan: Changing status from working to idle

[2013-08-28 17:40:06,030] INFO: Rambutan: Stopping job.

[2013-08-28 17:40:06,032] INFO: Rambutan: stopping driver.

[2013-08-28 17:40:08,030] DEBUG: Bumbledrive: finishing print.

[2013-08-28 17:40:08,040] DEBUG: Rambutan: Bot finished @ state idle

-- 
Nick

www.nhfoley.com

 

[Zach 'Hoeken' Smith]

This is definitely something in the serial driver - possibly a pi problem or an arduino serial problem. :(

--

----

Zach Hoeken Smith

Work: www.haxlr8r.com

Blog: www.hoektronics.com

Twitter: @hoeken  Skype: chilldude22

QQ: 1489598623  China: +86-186-8209-7069

[Nick Foley]

Do you have any suggestions for debugging this? The only non-standard thing I've done with the pi is installing the pyserial trick to allow it to communicate with the printer at 250000 baud. Maybe that step isn't actually necessary anymore though? 

[Nick Foley]

If nobody else is having this problem with the Pi, re-flashing my Ultimaker firmware to communicate at 112500 baud seems like a starting point.

[graphmastur]

I had the issue at 115200. I only fan into the issue once. What firmware are you using?

[Nick Foley]

I'm using the latest Marlin compiled from ErikZalm's github.

[graphmastur]

Okay, so same firmware. The best I can think of right now is that it fails to send and bumblebee doesn't like what it gets sent back.

[Nick Foley]

Ok. One more interesting note that seems to be related: 

Sometimes when this problem happens, the print can be resumed by using the LCD control panel of my printer to send a "Disable Stepper Motors" command. The print then resumes as normal. It doesn't always work, and may be a completely separate problem... but maybe not...

[graph...@gmail.com]

Did you see any errors on the LCD panel? It may be that M84 clears some error bit.

[Nick Foley]

No, unfortunately the panel doesn't say anything when this happens. I haven't yet been able to replicate the bug when printing from Cura over USB from my laptop, which would hopefully give me an error message if the print stopped for any reason. The debugging continues...

[Zach 'Hoeken' Smith]

Actually a good step would be to to check the "thread.html" or some such file that shows what each thread is doing. The printcore driver is probably hungup somewhere. 

--

You received this message because you are subscribed to the Google Groups "BotQueue" group.
To unsubscribe from this group and stop receiving emails from it, send an email to botqueue+u...@googlegroups.com.
For more options, visit https://groups.google.com/groups/opt_out.