I see the green "Solution" checkmark. Does this mean it's working ?
Cuz I'm curious about the missing USB Device #4. I was wondering if it's a BT keyboard adapter or something
The device number increments everytime a device is reconnected.
Here the output of my pi before and after I reconnected my printer
pi@octopi:~ $ lsusb
Bus 003 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 002 Device 001: ID 1d6b:0003 Linux Foundation 3.0 root hub
Bus 001 Device 005: ID 1a86:7523 QinHeng Electronics HL-340 USB-Serial adapter
Bus 001 Device 004: ID 0c45:6340 Microdia Camera
Bus 001 Device 003: ID 046d:c52e Logitech, Inc. MK260 Wireless Combo Receiver
Bus 001 Device 002: ID 2109:3431 VIA Labs, Inc. Hub
Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
pi@octopi:~ $ lsusb
Bus 003 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 002 Device 001: ID 1d6b:0003 Linux Foundation 3.0 root hub
Bus 001 Device 006: ID 1a86:7523 QinHeng Electronics HL-340 USB-Serial adapter
Bus 001 Device 004: ID 0c45:6340 Microdia Camera
Bus 001 Device 003: ID 046d:c52e Logitech, Inc. MK260 Wireless Combo Receiver
Bus 001 Device 002: ID 2109:3431 VIA Labs, Inc. Hub
Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Nope , there is nothing connected to the PI other than the printer. No bt connected.
No it is still not connecting with the printer. Could it be something in Octoprint itself, or the installation. I assume if that was the case, it wouldn't even connect wirelessly.
Okay. Admittedly, I confuse easily, so it shouldn't be much of a surprise when I say that I'm confused, cuz it says...
ch341-uart converter now attached to ttyUSB0
Which means that the Pi is seeing it. well, that's the last thing it said. Does lsusb still say that it's connected ?
Cuz we've also got this...
So, to simplify (to myself because you guys are obviously far beyond me, and because I think best when I think out loud)... The handshake process has been successfully negotiated. The 2 devices, both printer controller board, and Pi, have agreed upon a set of communication skills, which we see listed, yet, when trying to actually communicate using the agreed upon skillset, it appears to be the printer controller board that is refusing to answer the "hello" sent by the Pi, at which point the Pi sends out another "hello", which the controller board dutifully refuses to respond to, causing the Pi to give up and actually quit trying, and terminate the connection
Which leads me to wonder again, what does lsusb say now that the log claims that the Pi has given up ?
Why would the printer board stay online long enough to negotiate the handshake only to not answer the "hello" ?
Can you change the "timeout" ? Increase it to maybe 100 ? Or 1000 ?
That is what I can't figure out. This time I tried with the web cam, so tihs is the lsusb command after trying to connect and again timing out. I did raise the time out value to 100 after the following print out, to see what happens, but just waited longer for it to time out again.
If the printer gets detected but talking to it doesn't work, it sounds like something is up with the printer's firmware or the baudrate is wrong.
I am running Marlin 1.8.9, and when I try to connect in Auto, once it times out, the setting in Octoprint change themselves to dev/ttyUSB0 & 115200.
It connects with USB cable from computer to printer alright. Could it be Octoprint or a setting in Marlin?
With which settings?
edit And what does happen on the printer display when attempting to connect via OctoPrint vs when connecting from your computer?
When connected via usb cable from computer it shows connected Com7 115200.
The printer display shows the same thing which ever way I am connected " 3D Printer Ready"
It's time to share some logs. Full. Not truncated.
Would you like the log for today? This is the Serial log.
2019-11-10 04:26:09,066 - Connecting to: /dev/ttyUSB0
2019-11-10 04:26:09,082 - Changing monitoring state from "Offline" to "Opening serial port"
2019-11-10 04:26:09,089 - Connected to: Serial<id=0x71a4eb90, open=True>(port='/dev/ttyUSB0', baudrate=115200, bytesize=8, parity='N', stopbits=1, timeout=10.0, xonxoff=False, rtscts=False, dsrdtr=False), starting monitor
2019-11-10 04:26:09,090 - Changing monitoring state from "Opening serial port" to "Connecting"
2019-11-10 04:26:09,106 - Send: N0 M110 N0*125
2019-11-10 04:26:09,112 - Send: N0 M110 N0*125
2019-11-10 04:26:10,717 - Recv: start
2019-11-10 04:26:10,721 - Send: N0 M110 N0*125
2019-11-10 04:26:10,723 - Recv: echo: External Reset
2019-11-10 04:26:10,727 - Recv: Marlin bugfix-1.1.x
2019-11-10 04:26:10,729 - Recv:
2019-11-10 04:26:10,743 - Recv: echo: Last Updated: 2018-07-31 | Author: (Bob Kuhn, Anet config)
2019-11-10 04:26:10,745 - Recv: echo:Compiled: Aug 8 2019
2019-11-10 04:26:10,747 - Recv: echo: Free Memory: 11604 PlannerBufferBytes: 1232
2019-11-10 04:26:10,752 - Recv: echo:V55 stored settings retrieved (655 bytes; crc 3186)
2019-11-10 04:26:13,745 - Recv: ok
2019-11-10 04:26:13,749 - Changing monitoring state from "Connecting" to "Operational"
2019-11-10 04:26:13,759 - Send: N0 M110 N0*125
2019-11-10 04:26:13,773 - Recv: ok
2019-11-10 04:26:13,775 - Send: N1 M115*39
2019-11-10 04:26:13,802 - Recv: FIRMWARE_NAME:Marlin bugfix-1.1.x (Github) SOURCE_CODE_URL:https://github.com/MarlinFirmware/Marlin PROTOCOL_VERSION:1.0 MACHINE_TYPE:3D Printer EXTRUDER_COUNT:1 UUID:cede2a2f-41a2-4748-9b12-c55c62f367ff
2019-11-10 04:26:13,808 - Recv: Cap:SERIAL_XON_XOFF:0
2019-11-10 04:26:13,810 - Recv: Cap:EEPROM:1
2019-11-10 04:26:13,812 - Recv: Cap:VOLUMETRIC:1
2019-11-10 04:26:13,814 - Recv: Cap:AUTOREPORT_TEMP:1
2019-11-10 04:26:13,817 - Recv: Cap:PROGRESS:0
2019-11-10 04:26:13,819 - Recv: Cap:PRINT_JOB:1
2019-11-10 04:26:13,828 - Recv: Cap:AUTOLEVEL:0
2019-11-10 04:26:13,829 - Recv: Cap:Z_PROBE:0
2019-11-10 04:26:13,831 - Recv: Cap:LEVELING_DATA:1
2019-11-10 04:26:13,832 - Recv: Cap:BUILD_PERCENT:0
2019-11-10 04:26:13,837 - Recv: Cap:SOFTWARE_POWER:0
2019-11-10 04:26:13,838 - Recv: Cap:TOGGLE_LIGHTS:0
2019-11-10 04:26:13,840 - Recv: Cap:CASE_LIGHT_BRIGHTNESS:0
2019-11-10 04:26:13,842 - Recv: Cap:EMERGENCY_PARSER:0
2019-11-10 04:26:13,843 - Recv: Cap:AUTOREPORT_SD_STATUS:0
2019-11-10 04:26:13,844 - Recv: Cap:THERMAL_PROTECTION:1
2019-11-10 04:26:13,846 - Recv: ok
2019-11-10 04:26:13,848 - Send: M21
2019-11-10 04:26:14,016 - Recv: echo:SD card ok
2019-11-10 04:26:14,018 - Recv: ok
2019-11-10 04:26:14,021 - Send: M155 S2
2019-11-10 04:26:14,026 - Recv: ok
2019-11-10 04:26:14,028 - Send: M20
2019-11-10 04:26:14,034 - Recv: Begin file list
2019-11-10 04:26:18,719 - Recv: 1_LG_R~1.GCO 6448342
2019-11-10 04:26:18,723 - Recv: 1_SNAK~1.GCO 8003946
2019-11-10 04:26:18,724 - Recv: 1_LCD_~4.GCO 2179211
2019-11-10 04:26:18,727 - Recv: JE1F78~1.GCO 2981362
2019-11-10 04:26:18,729 - Recv: LCD_BR~1.GCO 3314037
2019-11-10 04:26:18,731 - Recv: JE0173~1.GCO 5571409
2019-11-10 04:26:18,732 - Recv: 1_ARTI~1.GCO 1898092
2019-11-10 04:26:18,737 - Recv: 1_OCTO~1.GCO 26943573
2019-11-10 04:26:18,738 - Recv: NUVI_2~1.GCO 494017
2019-11-10 04:26:18,741 - Recv: AFDEKK~1.GCO 3821159
2019-11-10 04:26:18,743 - Recv: 1_LIZA~1.GCO 8403821
2019-11-12 04:25:55,414 - Communication timeout while idle, trying to trigger response from printer. Configure long running commands or increase communication timeout if that happens regularly on specific commands or long moves.
2019-11-12 04:26:55,773 - No response from printer after 3 consecutive communication timeouts, considering it dead. Configure long running commands or increase communication timeout if that happens regularly on specific commands or long moves.
2019-11-12 04:26:55,801 - Changing monitoring state from "Operational" to "Offline (Error: Too many consecutive timeouts, printer still connected and alive?)"
2019-11-12 04:26:55,816 - Connection closed, closing down monitor
2019-11-12 04:43:25,178 - Connecting to: /dev/ttyUSB0
2019-11-12 04:43:25,195 - Changing monitoring state from "Offline" to "Opening serial port"
2019-11-12 04:43:25,198 - Connected to: Serial<id=0x685fdd30, open=True>(port='/dev/ttyUSB0', baudrate=115200, bytesize=8, parity='N', stopbits=1, timeout=10.0, xonxoff=False, rtscts=False, dsrdtr=False), starting monitor
2019-11-12 04:43:25,198 - Changing monitoring state from "Opening serial port" to "Connecting"
2019-11-12 04:43:25,210 - Send: N0 M110 N0*125
2019-11-12 04:43:25,219 - Send: N0 M110 N0*125
2019-11-12 04:43:55,240 - No answer from the printer within the connection timeout, trying another hello
2019-11-12 04:43:55,253 - Send: N0 M110 N0*125
2019-11-12 04:44:05,254 - There was a timeout while trying to connect to the printer
2019-11-12 04:44:05,271 - Changing monitoring state from "Connecting" to "Offline"
2019-11-12 04:44:05,284 - Connection closed, closing down monitor
2019-11-12 05:23:33,800 - Connecting to: /dev/ttyUSB0
2019-11-12 05:23:33,819 - Changing monitoring state from "Offline" to "Opening serial port"
2019-11-12 05:23:33,823 - Connected to: Serial<id=0x68691890, open=True>(port='/dev/ttyUSB0', baudrate=115200, bytesize=8, parity='N', stopbits=1, timeout=10.0, xonxoff=False, rtscts=False, dsrdtr=False), starting monitor
2019-11-12 05:23:33,825 - Changing monitoring state from "Opening serial port" to "Connecting"
2019-11-12 05:23:33,835 - Send: N0 M110 N0*125
2019-11-12 05:23:33,838 - Send: N0 M110 N0*125
2019-11-12 05:24:03,870 - No answer from the printer within the connection timeout, trying another hello
2019-11-12 05:24:03,885 - Send: N0 M110 N0*125
2019-11-12 05:24:13,891 - There was a timeout while trying to connect to the printer
2019-11-12 05:24:13,907 - Changing monitoring state from "Connecting" to "Offline"
2019-11-12 05:24:13,919 - Connection closed, closing down monitor
2019-11-12 05:25:29,682 - Connecting to: /dev/ttyUSB0
2019-11-12 05:25:29,701 - Changing monitoring state from "Offline" to "Opening serial port"
2019-11-12 05:25:29,707 - Connected to: Serial<id=0x698b7bf0, open=True>(port='/dev/ttyUSB0', baudrate=115200, bytesize=8, parity='N', stopbits=1, timeout=10.0, xonxoff=False, rtscts=False, dsrdtr=False), starting monitor
2019-11-12 05:25:29,708 - Changing monitoring state from "Opening serial port" to "Connecting"
2019-11-12 05:25:29,718 - Send: N0 M110 N0*125
2019-11-12 05:25:29,731 - Send: N0 M110 N0*125
2019-11-12 05:25:59,744 - No answer from the printer within the connection timeout, trying another hello
2019-11-12 05:25:59,757 - Send: N0 M110 N0*125
2019-11-12 05:26:09,762 - There was a timeout while trying to connect to the printer
2019-11-12 05:26:09,779 - Changing monitoring state from "Connecting" to "Offline"
2019-11-12 05:26:09,789 - Connection closed, closing down monitor
2019-11-12 05:27:11,537 - Connecting to: /dev/ttyUSB0
2019-11-12 05:27:11,556 - Changing monitoring state from "Offline" to "Opening serial port"
2019-11-12 05:27:11,560 - Connected to: Serial<id=0x69888490, open=True>(port='/dev/ttyUSB0', baudrate=115200, bytesize=8, parity='N', stopbits=1, timeout=10.0, xonxoff=False, rtscts=False, dsrdtr=False), starting monitor
2019-11-12 05:27:11,562 - Changing monitoring state from "Opening serial port" to "Connecting"
2019-11-12 05:27:11,568 - Send: N0 M110 N0*125
2019-11-12 05:27:11,572 - Send: N0 M110 N0*125
2019-11-12 05:27:41,606 - No answer from the printer within the connection timeout, trying another hello
2019-11-12 05:27:41,619 - Send: N0 M110 N0*125
2019-11-12 05:27:51,633 - There was a timeout while trying to connect to the printer
2019-11-12 05:27:51,648 - Changing monitoring state from "Connecting" to "Offline"
2019-11-12 05:27:51,657 - Connection closed, closing down monitor
2019-11-12 05:29:20,724 - Connecting to: /dev/ttyUSB0
2019-11-12 05:29:20,742 - Changing monitoring state from "Offline" to "Opening serial port"
2019-11-12 05:29:20,746 - Connected to: Serial<id=0x698c8490, open=True>(port='/dev/ttyUSB0', baudrate=115200, bytesize=8, parity='N', stopbits=1, timeout=10.0, xonxoff=False, rtscts=False, dsrdtr=False), starting monitor
2019-11-12 05:29:20,748 - Changing monitoring state from "Opening serial port" to "Connecting"
2019-11-12 05:29:50,777 - There was a timeout while trying to connect to the printer
2019-11-12 05:29:50,791 - Changing monitoring state from "Connecting" to "Offline"
2019-11-12 05:29:50,799 - Connection closed, closing down monitor
2019-11-12 05:30:17,190 - Changing monitoring state from "Offline" to "Detecting serial port"
2019-11-12 05:30:17,215 - Serial port list: ['/dev/ttyUSB0']
2019-11-12 05:30:17,216 - Connecting to: /dev/ttyUSB0
2019-11-12 05:30:17,235 - Changing monitoring state from "Detecting serial port" to "Opening serial port"
2019-11-12 05:30:17,239 - Connected to: Serial<id=0x69f01230, open=True>(port='/dev/ttyUSB0', baudrate=115200, bytesize=8, parity='N', stopbits=1, timeout=10.0, xonxoff=False, rtscts=False, dsrdtr=False), starting monitor
2019-11-12 05:30:17,239 - Starting baud rate detection...
2019-11-12 05:30:17,242 - Changing monitoring state from "Opening serial port" to "Detecting baudrate"
2019-11-12 05:30:18,249 - Trying baudrate: 115200
2019-11-12 05:30:18,262 - Send: N0 M110 N0*125
2019-11-12 05:30:28,272 - Baudrate test retry #1
2019-11-12 05:30:28,284 - Send: N0 M110 N0*125
2019-11-12 05:30:29,285 - Baudrate test retry #2
2019-11-12 05:30:29,297 - Send: N0 M110 N0*125
2019-11-12 05:30:30,297 - Baudrate test retry #3
2019-11-12 05:30:30,302 - Send: N0 M110 N0*125
2019-11-12 05:30:31,303 - Baudrate test retry #4
2019-11-12 05:30:31,316 - Send: N0 M110 N0*125
2019-11-12 05:30:32,325 - Trying baudrate: 250000
2019-11-12 05:30:32,331 - Send: N0 M110 N0*125
2019-11-12 05:30:33,332 - Baudrate test retry #1
2019-11-12 05:30:33,338 - Send: N0 M110 N0*125
2019-11-12 05:30:34,339 - Baudrate test retry #2
2019-11-12 05:30:34,352 - Send: N0 M110 N0*125
2019-11-12 05:30:35,348 - Baudrate test retry #3
2019-11-12 05:30:35,354 - Send: N0 M110 N0*125
2019-11-12 05:30:36,354 - Baudrate test retry #4
2019-11-12 05:30:36,365 - Send: N0 M110 N0*125
2019-11-12 05:30:37,366 - Trying baudrate: 230400
2019-11-12 05:30:37,380 - Send: N0 M110 N0*125
2019-11-12 05:30:38,381 - Baudrate test retry #1
2019-11-12 05:30:38,394 - Send: N0 M110 N0*125
2019-11-12 05:30:39,395 - Baudrate test retry #2
2019-11-12 05:30:39,405 - Send: N0 M110 N0*125
2019-11-12 05:30:40,409 - Baudrate test retry #3
2019-11-12 05:30:40,420 - Send: N0 M110 N0*125
2019-11-12 05:30:41,418 - Baudrate test retry #4
2019-11-12 05:30:41,428 - Send: N0 M110 N0*125
2019-11-12 05:30:42,435 - Trying baudrate: 57600
2019-11-12 05:30:42,444 - Send: N0 M110 N0*125
2019-11-12 05:30:43,443 - Baudrate test retry #1
2019-11-12 05:30:43,449 - Send: N0 M110 N0*125
2019-11-12 05:30:44,449 - Baudrate test retry #2
2019-11-12 05:30:44,460 - Send: N0 M110 N0*125
2019-11-12 05:30:45,459 - Baudrate test retry #3
2019-11-12 05:30:45,466 - Send: N0 M110 N0*125
2019-11-12 05:30:46,467 - Baudrate test retry #4
2019-11-12 05:30:46,478 - Send: N0 M110 N0*125
2019-11-12 05:30:47,480 - Trying baudrate: 38400
2019-11-12 05:30:47,491 - Send: N0 M110 N0*125
2019-11-12 05:30:48,489 - Baudrate test retry #1
2019-11-12 05:30:48,495 - Send: N0 M110 N0*125
2019-11-12 05:30:49,496 - Baudrate test retry #2
2019-11-12 05:30:49,504 - Send: N0 M110 N0*125
2019-11-12 05:30:50,505 - Baudrate test retry #3
2019-11-12 05:30:50,516 - Send: N0 M110 N0*125
2019-11-12 05:30:51,512 - Baudrate test retry #4
2019-11-12 05:30:51,525 - Send: N0 M110 N0*125
2019-11-12 05:30:52,523 - Trying baudrate: 19200
2019-11-12 05:30:52,528 - Send: N0 M110 N0*125
2019-11-12 05:30:53,529 - Baudrate test retry #1
2019-11-12 05:30:53,535 - Send: N0 M110 N0*125
2019-11-12 05:30:54,536 - Baudrate test retry #2
2019-11-12 05:30:54,549 - Send: N0 M110 N0*125
2019-11-12 05:30:55,550 - Baudrate test retry #3
2019-11-12 05:30:55,561 - Send: N0 M110 N0*125
2019-11-12 05:30:56,560 - Baudrate test retry #4
2019-11-12 05:30:56,574 - Send: N0 M110 N0*125
2019-11-12 05:30:57,573 - Trying baudrate: 9600
2019-11-12 05:30:57,580 - Send: N0 M110 N0*125
2019-11-12 05:30:58,582 - Baudrate test retry #1
2019-11-12 05:30:58,595 - Send: N0 M110 N0*125
2019-11-12 05:30:59,594 - Baudrate test retry #2
2019-11-12 05:30:59,605 - Send: N0 M110 N0*125
2019-11-12 05:31:00,601 - Baudrate test retry #3
2019-11-12 05:31:00,612 - Send: N0 M110 N0*125
2019-11-12 05:31:01,613 - Baudrate test retry #4
2019-11-12 05:31:01,620 - Send: N0 M110 N0*125
2019-11-12 05:31:02,619 - Changing monitoring state from "Detecting baudrate" to "Error: No more baudrates to test, and no suitable baudrate found."
2019-11-12 05:31:02,646 - Changing monitoring state from "Error: No more baudrates to test, and no suitable baudrate found." to "Offline (Error: No more baudrates to test, and no suitable baudrate found.)"
2019-11-12 05:31:02,673 - Connection closed, closing down monitor
2019-11-12 05:31:15,606 - Connecting to: /dev/ttyUSB0
2019-11-12 05:31:15,625 - Changing monitoring state from "Offline" to "Opening serial port"
2019-11-12 05:31:15,629 - Connected to: Serial<id=0x69a05710, open=True>(port='/dev/ttyUSB0', baudrate=115200, bytesize=8, parity='N', stopbits=1, timeout=10.0, xonxoff=False, rtscts=False, dsrdtr=False), starting monitor
2019-11-12 05:31:15,629 - Changing monitoring state from "Opening serial port" to "Connecting"
2019-11-12 05:31:45,671 - There was a timeout while trying to connect to the printer
2019-11-12 05:31:45,686 - Changing monitoring state from "Connecting" to "Offline"
2019-11-12 05:31:45,702 - Connection closed, closing down monitor
2019-11-12 06:53:37,844 - Connecting to: /dev/ttyUSB0
2019-11-12 06:53:37,863 - Changing monitoring state from "Offline" to "Opening serial port"
2019-11-12 06:53:37,868 - Connected to: Serial<id=0x685fd690, open=True>(port='/dev/ttyUSB0', baudrate=115200, bytesize=8, parity='N', stopbits=1, timeout=10.0, xonxoff=False, rtscts=False, dsrdtr=False), starting monitor
2019-11-12 06:53:37,869 - Changing monitoring state from "Opening serial port" to "Connecting"
2019-11-12 06:53:37,890 - Send: N0 M110 N0*125
2019-11-12 06:53:37,893 - Send: N0 M110 N0*125
2019-11-12 06:54:07,914 - No answer from the printer within the connection timeout, trying another hello
2019-11-12 06:54:07,926 - Send: N0 M110 N0*125
2019-11-12 06:54:17,933 - There was a timeout while trying to connect to the printer
2019-11-12 06:54:17,950 - Changing monitoring state from "Connecting" to "Offline"
2019-11-12 06:54:17,960 - Connection closed, closing down monitor
The Octoprint log is 3600 characters long, and this will only allow 3200. You want me to chop in half for two different posts:?
First half of Octoprint log:
19-11-12 04:25:31,206 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-11-12 04:25:31,261 - octoprint.server - INFO - --- Log roll over detected ---------------------------------------------------
2019-11-12 04:25:31,262 - octoprint.server - INFO - OctoPrint 1.3.12
2019-11-12 04:25:31,284 - octoprint.plugin.core - INFO - 18 plugin(s) registered with the system:
| Action Command Prompt Support (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/action_command_prompt
| Announcement Plugin (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/announcements
| Anonymous Usage Tracking (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/tracking
| Application Keys Plugin (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/appkeys
| Backup & Restore (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/backup
| Core Wizard (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/corewizard
| Discovery (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/discovery
| Error Tracking (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/errortracking
| Force Login (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/forcelogin
| Logging (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/logging
| Navbar Temperature Plugin (0.11) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_navbartemp
| !Octolapse (0.3.4) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_octolapse
| Pi Support Plugin (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/pi_support
| Plugin Manager (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/pluginmanager
| Printer Safety Check (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/printer_safety_check
| Software Update (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/softwareupdate
| Themeify (1.2.0) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_themeify
| Virtual Printer (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/virtual_printer
2019-11-12 04:25:31,300 - octoprint.environment - INFO - Detected environment is Python 2.7.13 under Linux (linux2). Details:
| hardware:
| cores: 4
| freq: 1400.0
| ram: 918188032
| os:
| id: linux
| platform: linux2
| plugins:
| pi_support:
| model: Raspberry Pi 3 Model B Plus Rev 1.3
| octopi_version: 0.16.0
| python:
| pip: 19.0.1
| version: 2.7.13
| virtualenv: /home/pi/oprint
2019-11-12 04:25:31,300 - octoprint.server - INFO - ------------------------------------------------------------------------------
2019-11-12 04:25:32,002 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 23}
2019-11-12 04:25:32,828 - octoprint.plugins.tracking - INFO - Sent tracking event pong, payload: {'plugins': u'themeify:1.2.0,navbartemp:0.11'}
2019-11-12 04:25:39,642 - octoprint.server.preemptive_cache - INFO - ... done in 13.72s
2019-11-12 04:25:39,643 - octoprint.server.preemptive_cache - INFO - Preemptively caching / (ui _default) for {'query_string': 'l10n=en', 'path': '/', 'base_url': 'http://192.168.0.23/'}
2019-11-12 04:25:45,672 - octoprint.server.preemptive_cache - INFO - ... done in 6.03s
2019-11-12 04:25:45,673 - octoprint.server.preemptive_cache - INFO - Preemptively caching / (ui _default) for {'query_string': 'l10n=en', 'path': '/', 'base_url': 'http://192.168.0.25/'}
2019-11-12 04:25:51,724 - octoprint.server.preemptive_cache - INFO - ... done in 6.05s
2019-11-12 04:25:51,724 - octoprint.server.preemptive_cache - INFO - Preemptively caching / (ui _default) for {'query_string': 'l10n=en', 'path': '/', 'base_url': 'http://192.168.0.20/'}
2019-11-12 04:25:55,364 - octoprint.util.comm - INFO - Communication timeout while idle, trying to trigger response from printer.
2019-11-12 04:25:57,767 - octoprint.server.preemptive_cache - INFO - ... done in 6.04s
2019-11-12 04:26:55,770 - octoprint.util.comm - INFO - No response from printer after 3 consecutive communication timeouts, considering it dead.
2019-11-12 04:26:55,803 - octoprint.util.comm - INFO - Changing monitoring state from "Operational" to "Offline (Error: Too many consecutive timeouts, printer still connected and alive?)"
2019-11-12 04:26:56,434 - octoprint.plugins.tracking - INFO - Sent tracking event commerror_timeout, payload: {'commerror_text': 'Too many consecutive timeouts, printer still connected and alive?'}
2019-11-12 04:40:31,215 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-11-12 04:40:31,827 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 923}
2019-11-12 04:42:07,059 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.0.2
2019-11-12 04:42:30,829 - octoprint.server.api - INFO - Actively logging in user PiperPilot from ::ffff:192.168.0.2
2019-11-12 04:42:30,906 - octoprint.server.util.flask - INFO - Passively logging in user PiperPilot from ::ffff:192.168.0.2
2019-11-12 04:42:31,051 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:192.168.0.2
2019-11-12 04:42:31,998 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.0.2
2019-11-12 04:42:32,090 - octoprint.server.util.flask - INFO - Passively logging in user PiperPilot from ::ffff:192.168.0.2
2019-11-12 04:42:33,606 - octoprint.plugins.announcements - INFO - Loaded channel _important from https://octoprint.org/feeds/important.xml in 0.66s
2019-11-12 04:42:34,600 - octoprint.plugins.announcements - INFO - Loaded channel _releases from https://octoprint.org/feeds/releases.xml in 0.67s
2019-11-12 04:42:35,987 - octoprint.plugins.announcements - INFO - Loaded channel _blog from https://octoprint.org/feeds/octoblog.xml in 0.68s
2019-11-12 04:42:36,772 - octoprint.plugins.announcements - INFO - Loaded channel _plugins from https://plugins.octoprint.org/feed.xml in 0.71s
2019-11-12 04:42:37,446 - octoprint.plugins.announcements - INFO - Loaded channel _octopi from https://octoprint.org/feeds/octopi.xml in 0.57s
2019-11-12 04:42:38,418 - octoprint.plugins.pluginmanager - INFO - Loaded plugin repository data from https://plugins.octoprint.org/plugins.json
2019-11-12 04:42:39,455 - octoprint.plugins.pluginmanager - INFO - Loaded plugin notices data from https://plugins.octoprint.org/notices.json
2019-11-12 04:42:40,147 - octoprint.plugins.softwareupdate - INFO - Saved version cache to disk
2019-11-12 04:42:40,205 - octoprint.server.util.sockjs - INFO - User PiperPilot logged in on the socket from client ::ffff:192.168.0.2
2019-11-12 04:43:25,195 - octoprint.util.comm - INFO - Changing monitoring state from "Offline" to "Opening serial port"
2019-11-12 04:43:25,199 - octoprint.util.comm - INFO - Changing monitoring state from "Opening serial port" to "Connecting"
2019-11-12 04:43:25,206 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 04:43:25,212 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 04:43:55,250 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 04:44:05,271 - octoprint.util.comm - INFO - Changing monitoring state from "Connecting" to "Offline"
2019-11-12 04:44:13,766 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:192.168.0.2
2019-11-12 04:55:31,217 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-11-12 04:55:31,821 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 1823}
2019-11-12 05:10:31,219 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-11-12 05:10:31,826 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 2723}
2019-11-12 05:22:39,608 - octoprint.server.util.flask - INFO - Passively logging in user PiperPilot from ::ffff:192.168.0.2
2019-11-12 05:22:41,294 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.0.2
2019-11-12 05:22:41,374 - octoprint.server.util.flask - INFO - Passively logging in user PiperPilot from ::ffff:192.168.0.2
2019-11-12 05:22:42,900 - octoprint.server.util.sockjs - INFO - User PiperPilot logged in on the socket from client ::ffff:192.168.0.2
2019-11-12 05:23:33,819 - octoprint.util.comm - INFO - Changing monitoring state from "Offline" to "Opening serial port"
2019-11-12 05:23:33,826 - octoprint.util.comm - INFO - Changing monitoring state from "Opening serial port" to "Connecting"
2019-11-12 05:23:33,833 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 05:23:33,837 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 05:24:03,881 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 05:24:13,908 - octoprint.util.comm - INFO - Changing monitoring state from "Connecting" to "Offline"
2019-11-12 05:25:29,702 - octoprint.util.comm - INFO - Changing monitoring state from "Offline" to "Opening serial port"
2019-11-12 05:25:29,708 - octoprint.util.comm - INFO - Changing monitoring state from "Opening serial port" to "Connecting"
2019-11-12 05:25:29,715 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 05:25:29,720 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 05:25:31,221 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-11-12 05:25:31,842 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 3623}
2019-11-12 05:25:59,753 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 05:26:09,780 - octoprint.util.comm - INFO - Changing monitoring state from "Connecting" to "Offline"
2019-11-12 05:27:11,557 - octoprint.util.comm - INFO - Changing monitoring state from "Offline" to "Opening serial port"
2019-11-12 05:27:11,563 - octoprint.util.comm - INFO - Changing monitoring state from "Opening serial port" to "Connecting"
2019-11-12 05:27:11,567 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 05:27:11,571 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 05:27:41,616 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 05:27:51,649 - octoprint.util.comm - INFO - Changing monitoring state from "Connecting" to "Offline"
2019-11-12 05:29:20,743 - octoprint.util.comm - INFO - Changing monitoring state from "Offline" to "Opening serial port"
2019-11-12 05:29:20,749 - octoprint.util.comm - INFO - Changing monitoring state from "Opening serial port" to "Connecting"
2019-11-12 05:29:50,792 - octoprint.util.comm - INFO - Changing monitoring state from "Connecting" to "Offline"
2019-11-12 05:30:17,191 - octoprint.util.comm - INFO - Changing monitoring state from "Offline" to "Detecting serial port"
2019-11-12 05:30:17,236 - octoprint.util.comm - INFO - Changing monitoring state from "Detecting serial port" to "Opening serial port"
2019-11-12 05:30:17,243 - octoprint.util.comm - INFO - Changing monitoring state from "Opening serial port" to "Detecting baudrate"
2019-11-12 05:30:18,260 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 05:30:28,282 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 05:30:29,294 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 05:30:30,301 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 05:30:31,311 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 05:30:32,330 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 05:30:33,336 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 05:30:34,346 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 05:30:35,352 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 05:30:36,362 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 05:30:37,379 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 05:30:38,392 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 05:30:39,402 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 05:30:40,417 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 05:30:41,425 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 05:30:42,442 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 05:30:43,447 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 05:30:44,459 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 05:30:45,465 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 05:30:46,477 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 05:30:47,489 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 05:30:48,493 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 05:30:49,503 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 05:30:50,515 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 05:30:51,521 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 05:30:52,527 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 05:30:53,533 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 05:30:54,547 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 05:30:55,557 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 05:30:56,570 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 05:30:57,577 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 05:30:58,592 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 05:30:59,602 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 05:31:00,609 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 05:31:01,616 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 05:31:02,620 - octoprint.util.comm - INFO - Changing monitoring state from "Detecting baudrate" to "Error: No more baudrates to test, and no suitable baudrate found."
2019-11-12 05:31:02,647 - octoprint.util.comm - INFO - Changing monitoring state from "Error: No more baudrates to test, and no suitable baudrate found." to "Offline (Error: No more baudrates to test, and no suitable baudrate found.)"
Second half of Octoprint log:
2019-11-12 05:31:03,284 - octoprint.plugins.tracking - INFO - Sent tracking event commerror_autodetect_baudrate, payload: {'commerror_text': 'No more baudrates to test, and no suitable baudrate found.'}
2019-11-12 05:31:15,626 - octoprint.util.comm - INFO - Changing monitoring state from "Offline" to "Opening serial port"
2019-11-12 05:31:15,630 - octoprint.util.comm - INFO - Changing monitoring state from "Opening serial port" to "Connecting"
2019-11-12 05:31:45,687 - octoprint.util.comm - INFO - Changing monitoring state from "Connecting" to "Offline"
2019-11-12 05:36:13,891 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:192.168.0.2
2019-11-12 05:40:31,225 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-11-12 05:40:31,866 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 4523}
2019-11-12 05:55:31,228 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-11-12 05:55:31,868 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 5423}
2019-11-12 06:10:31,230 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-11-12 06:10:31,882 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 6323}
2019-11-12 06:25:31,233 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-11-12 06:25:31,835 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 7223}
2019-11-12 06:40:31,234 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-11-12 06:40:31,898 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 8123}
2019-11-12 06:53:29,048 - octoprint.server.util.flask - INFO - Passively logging in user PiperPilot from ::ffff:192.168.0.2
2019-11-12 06:53:30,446 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.0.2
2019-11-12 06:53:30,593 - octoprint.server.util.flask - INFO - Passively logging in user PiperPilot from ::ffff:192.168.0.2
2019-11-12 06:53:31,801 - octoprint.server.util.sockjs - INFO - User PiperPilot logged in on the socket from client ::ffff:192.168.0.2
2019-11-12 06:53:37,863 - octoprint.util.comm - INFO - Changing monitoring state from "Offline" to "Opening serial port"
2019-11-12 06:53:37,869 - octoprint.util.comm - INFO - Changing monitoring state from "Opening serial port" to "Connecting"
2019-11-12 06:53:37,885 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 06:53:37,892 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 06:54:07,924 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 06:54:17,954 - octoprint.util.comm - INFO - Changing monitoring state from "Connecting" to "Offline"
2019-11-12 06:54:52,492 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:192.168.0.2
2019-11-12 06:55:31,238 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-11-12 06:55:31,914 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 9023}
2019-11-12 06:41:38,697 - octoprint.startup - INFO - ******************************************************************************
2019-11-12 06:41:38,699 - octoprint.startup - INFO - Starting OctoPrint 1.3.12
2019-11-12 06:41:38,699 - octoprint.startup - INFO - ******************************************************************************
2019-11-12 06:41:38,766 - octoprint.startup - INFO - We don't appear to be online, not fetching plugin blacklist
2019-11-12 06:41:38,839 - octoprint.plugin.core - INFO - Loading plugins from /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins, /home/pi/.octoprint/plugins and installed plugin packages...
2019-11-12 06:41:41,361 - octoprint.plugin.core - INFO - Plugin Octolapse (0.3.4) is disabled.
2019-11-12 06:41:41,480 - octoprint.plugin.core - INFO - Found 18 plugin(s) providing 17 mixin implementations, 25 hook handlers
2019-11-12 06:41:41,760 - octoprint.server.heartbeat - INFO - Starting server heartbeat, 900.0s interval
2019-11-12 06:41:41,807 - octoprint.server - INFO - Intermediary server started
2019-11-12 06:41:41,808 - octoprint.plugin.core - INFO - Loading plugins from /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins, /home/pi/.octoprint/plugins and installed plugin packages...
2019-11-12 06:41:42,452 - octoprint.plugin.core - INFO - Found 18 plugin(s) providing 17 mixin implementations, 25 hook handlers
2019-11-12 06:41:42,503 - octoprint.filemanager.storage - INFO - Initializing the file metadata for /home/pi/.octoprint/uploads...
2019-11-12 06:41:43,054 - octoprint.filemanager.storage - INFO - ... file metadata for /home/pi/.octoprint/uploads initialized successfully.
2019-11-12 06:41:43,165 - octoprint.plugins.softwareupdate - INFO - Loaded version cache from disk
2019-11-12 06:41:45,056 - octoprint.util.pip - INFO - Using "/home/pi/oprint/bin/python2 -m pip" as command to invoke pip
2019-11-12 06:41:46,515 - octoprint.util.pip - INFO - Version of pip is 19.0.1
2019-11-12 06:41:46,517 - octoprint.util.pip - INFO - pip installs to /home/pi/oprint/lib/python2.7/site-packages (writable -> yes), --user flag needed -> no, virtual env -> yes
2019-11-12 06:41:46,518 - octoprint.util.pip - INFO - ==> pip ok -> yes
2019-11-12 06:41:46,524 - octoprint.plugin.core - INFO - Initialized 17 plugin implementation(s)
2019-11-12 06:41:46,537 - octoprint.plugin.core - INFO - 18 plugin(s) registered with the system:
| Action Command Prompt Support (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/action_command_prompt
| Announcement Plugin (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/announcements
| Anonymous Usage Tracking (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/tracking
| Application Keys Plugin (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/appkeys
| Backup & Restore (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/backup
| Core Wizard (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/corewizard
| Discovery (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/discovery
| Error Tracking (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/errortracking
| Force Login (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/forcelogin
| Logging (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/logging
| Navbar Temperature Plugin (0.11) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_navbartemp
| !Octolapse (0.3.4) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_octolapse
| Pi Support Plugin (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/pi_support
| Plugin Manager (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/pluginmanager
| Printer Safety Check (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/printer_safety_check
| Software Update (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/softwareupdate
| Themeify (1.2.0) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_themeify
| Virtual Printer (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/virtual_printer
2019-11-12 06:41:46,558 - octoprint.environment - INFO - Detected environment is Python 2.7.13 under Linux (linux2). Details:
| hardware:
| cores: 4
| freq: 1400.0
| ram: 918188032
| os:
| id: linux
| platform: linux2
| plugins:
| pi_support:
| model: Raspberry Pi 3 Model B Plus Rev 1.3
| octopi_version: 0.16.0
| python:
| pip: 19.0.1
| version: 2.7.13
| virtualenv: /home/pi/oprint
2019-11-12 06:41:46,568 - octoprint.server - INFO - Reset webasset folder /home/pi/.octoprint/generated/webassets...
2019-11-12 06:41:46,583 - octoprint.server - INFO - Reset webasset folder /home/pi/.octoprint/generated/.webassets-cache...
2019-11-12 06:41:47,585 - octoprint.server - INFO - Shutting down intermediary server...
2019-11-12 06:41:47,861 - octoprint.server - INFO - Intermediary server shut down
2019-11-12 06:41:47,864 - octoprint.events - INFO - Processing startup event, this is our first event
2019-11-12 06:41:47,865 - octoprint.events - INFO - Adding 0 events to queue that were held back before startup event
2019-11-12 06:41:47,868 - octoprint.filemanager - INFO - Adding backlog items from all storage types to analysis queue...
2019-11-12 06:41:47,907 - octoprint.filemanager - INFO - Added 0 items from storage type "local" to analysis queue
2019-11-12 06:41:47,978 - octoprint.server.util.watchdog - INFO - Running initial scan on watched folder...
2019-11-12 06:41:47,981 - octoprint.server.util.watchdog - INFO - ... initial scan done.
2019-11-12 06:41:47,989 - octoprint.util.comm - INFO - Changing monitoring state from "Offline" to "Opening serial port"
2019-11-12 06:41:47,996 - octoprint.util.comm - INFO - Changing monitoring state from "Opening serial port" to "Connecting"
2019-11-12 06:41:47,998 - octoprint.plugins.discovery - INFO - Registered 'OctoPrint instance on octopi' for _http._tcp
2019-11-12 06:41:48,005 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 06:41:48,013 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 06:41:48,018 - octoprint.plugins.discovery - INFO - Registered 'OctoPrint instance on octopi' for _octoprint._tcp
2019-11-12 06:41:48,030 - octoprint.plugins.discovery - INFO - Registered OctoPrint instance on octopi for SSDP
2019-11-12 06:41:48,045 - octoprint.server - INFO - Listening on http://127.0.0.1:5000
2019-11-12 06:41:48,076 - octoprint.plugins.pluginmanager - INFO - Loaded plugin repository data from disk, was still valid
2019-11-12 06:41:48,575 - octoprint.plugins.pluginmanager - INFO - Loaded notice data from disk, was still valid
2019-11-12 06:41:49,657 - octoprint.server.util.flask - INFO - Passively logging in user PiperPilot from ::ffff:192.168.0.2
2019-11-12 06:41:49,664 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 06:41:49,796 - octoprint.util.pip - INFO - Using "/home/pi/oprint/bin/python2 -m pip" as command to invoke pip
2019-11-12 06:41:49,797 - octoprint.util.pip - INFO - pip installs to /home/pi/oprint/lib/python2.7/site-packages (writable -> yes), --user flag needed -> no, virtual env -> yes
2019-11-12 06:41:49,798 - octoprint.util.pip - INFO - ==> pip ok -> yes
2019-11-12 06:41:49,829 - octoprint.util.connectivity_checker - INFO - Connectivity changed from offline to online
2019-11-12 06:41:49,853 - octoprint.plugins.pluginmanager - INFO - Loaded plugin repository data from disk, was still valid
2019-11-12 06:41:50,247 - octoprint.plugins.navbartemp - INFO - Broadcom detected
2019-11-12 06:41:50,251 - octoprint.plugins.themeify - INFO - Themeify initialized.
2019-11-12 06:41:50,554 - octoprint.plugins.pluginmanager - INFO - Loaded notice data from disk, was still valid
2019-11-12 06:41:50,865 - octoprint.server.preemptive_cache - INFO - Preemptively caching / (ui _default) for {'query_string': 'l10n=en', 'path': '/', 'base_url': 'http://octopi.local/'}
2019-11-12 06:41:51,255 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 9}
2019-11-12 06:41:51,826 - tornado.access - WARNING - 404 GET /static/webassets/packed_libs.css?ec88168c (::ffff:192.168.0.2) 130.65ms
2019-11-12 06:41:51,837 - tornado.access - WARNING - 404 GET /static/webassets/packed_core.css?0635c0de (::ffff:192.168.0.2) 5.93ms
2019-11-12 06:41:51,845 - tornado.access - WARNING - 404 GET /static/webassets/packed_plugins.css?1762dcaa (::ffff:192.168.0.2) 2.84ms
2019-11-12 06:41:51,907 - octoprint.plugins.tracking - INFO - Sent tracking event startup, payload: {'python': '2.7.13', 'ram': 918188032, 'octopi_version': '0.16.0', 'version': u'1.3.12', 'pip': '19.0.1', 'cores': 4, 'freq': 1400.0, 'pi_model': 'Raspberry Pi 3 Model B Plus Rev 1.3', 'os': 'linux'}
2019-11-12 06:41:52,214 - tornado.access - WARNING - 404 GET /static/webassets/packed_libs.js?ef1e2987 (::ffff:192.168.0.2) 63.90ms
2019-11-12 06:41:52,331 - tornado.access - WARNING - 404 GET /static/webassets/packed_client.js?68a8d0b0 (::ffff:192.168.0.2) 82.03ms
2019-11-12 06:41:52,388 - tornado.access - WARNING - 404 GET /static/webassets/packed_plugins.js?3ff7757e (::ffff:192.168.0.2) 38.30ms
2019-11-12 06:41:52,425 - tornado.access - WARNING - 404 GET /static/webassets/packed_core.js?b00f909b (::ffff:192.168.0.2) 8.01ms
2019-11-12 06:41:52,546 - octoprint.plugins.tracking - INFO - Sent tracking event pong, payload: {'plugins': u'themeify:1.2.0,navbartemp:0.11'}
2019-11-12 06:41:52,783 - octoprint.util.comm - INFO - Changing monitoring state from "Connecting" to "Operational"
2019-11-12 06:41:52,851 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 06:41:53,000 - octoprint.util.comm - INFO - Printer reports firmware name "Marlin bugfix-1.1.x (Github)"
2019-11-12 06:41:53,024 - octoprint.util.comm - INFO - Firmware states that it supports temperature autoreporting
2019-11-12 06:41:53,660 - octoprint.plugins.tracking - INFO - Sent tracking event printer_connected, payload: {u'printer_baudrate': 115200, u'printer_port': '/dev/ttyUSB0', 'firmware_name': 'Marlin bugfix-1.1.x (Github)'}
2019-11-12 16:07:17,622 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-11-12 16:07:18,121 - octoprint.server.preemptive_cache - INFO - ... done in 13.45s
2019-11-12 16:07:18,122 - octoprint.server.preemptive_cache - INFO - Preemptively caching / (ui _default) for {'query_string': 'l10n=en', 'path': '/', 'base_url': 'http://192.168.0.23/'}
2019-11-12 16:07:18,523 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 23}
2019-11-12 16:07:24,188 - octoprint.server.preemptive_cache - INFO - ... done in 6.07s
2019-11-12 16:07:24,190 - octoprint.server.preemptive_cache - INFO - Preemptively caching / (ui _default) for {'query_string': 'l10n=en', 'path': '/', 'base_url': 'http://192.168.0.25/'}
2019-11-12 16:07:30,257 - octoprint.server.preemptive_cache - INFO - ... done in 6.07s
2019-11-12 16:07:30,258 - octoprint.server.preemptive_cache - INFO - Preemptively caching / (ui _default) for {'query_string': 'l10n=en', 'path': '/', 'base_url': 'http://192.168.0.20/'}
2019-11-12 16:07:36,308 - octoprint.server.preemptive_cache - INFO - ... done in 6.05s
2019-11-12 16:07:37,330 - octoprint.util.comm - INFO - Communication timeout while idle, trying to trigger response from printer.
2019-11-12 16:08:32,485 - octoprint.server.util.flask - INFO - Passively logging in user PiperPilot from ::ffff:192.168.0.2
2019-11-12 16:08:34,113 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.0.2
2019-11-12 16:08:34,247 - octoprint.server.util.flask - INFO - Passively logging in user PiperPilot from ::ffff:192.168.0.2
2019-11-12 16:08:35,661 - octoprint.plugins.pluginmanager - INFO - Loaded plugin notices data from https://plugins.octoprint.org/notices.json
2019-11-12 16:08:36,588 - octoprint.plugins.announcements - INFO - Loaded channel _important from https://octoprint.org/feeds/important.xml in 0.7s
2019-11-12 16:08:37,397 - octoprint.util.comm - INFO - No response from printer after 3 consecutive communication timeouts, considering it dead.
2019-11-12 16:08:37,428 - octoprint.util.comm - INFO - Changing monitoring state from "Operational" to "Offline (Error: Too many consecutive timeouts, printer still connected and alive?)"
2019-11-12 16:08:37,507 - octoprint.plugins.announcements - INFO - Loaded channel _releases from https://octoprint.org/feeds/releases.xml in 0.62s
2019-11-12 16:08:38,242 - octoprint.plugins.tracking - INFO - Sent tracking event commerror_timeout, payload: {'commerror_text': 'Too many consecutive timeouts, printer still connected and alive?'}
2019-11-12 16:08:38,853 - octoprint.plugins.announcements - INFO - Loaded channel _blog from https://octoprint.org/feeds/octoblog.xml in 0.67s
2019-11-12 16:08:39,648 - octoprint.plugins.announcements - INFO - Loaded channel _plugins from https://plugins.octoprint.org/feed.xml in 0.73s
2019-11-12 16:08:40,318 - octoprint.plugins.announcements - INFO - Loaded channel _octopi from https://octoprint.org/feeds/octopi.xml in 0.57s
2019-11-12 16:08:40,598 - octoprint.server.util.sockjs - INFO - User PiperPilot logged in on the socket from client ::ffff:192.168.0.2
2019-11-12 16:09:01,533 - octoprint.util.comm - INFO - Changing monitoring state from "Offline" to "Opening serial port"
2019-11-12 16:09:01,538 - octoprint.util.comm - INFO - Changing monitoring state from "Opening serial port" to "Connecting"
2019-11-12 16:09:01,547 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 16:09:01,550 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 16:09:31,591 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 16:09:41,616 - octoprint.util.comm - INFO - Changing monitoring state from "Connecting" to "Offline"
2019-11-12 16:22:17,648 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-11-12 16:22:18,290 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 923}
2019-11-12 16:33:52,414 - octoprint.util.comm - INFO - Changing monitoring state from "Offline" to "Opening serial port"
2019-11-12 16:33:52,419 - octoprint.util.comm - INFO - Changing monitoring state from "Opening serial port" to "Connecting"
2019-11-12 16:33:52,427 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 16:33:52,441 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 16:35:32,565 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 16:35:42,586 - octoprint.util.comm - INFO - Changing monitoring state from "Connecting" to "Offline"
2019-11-12 16:37:17,658 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-11-12 16:37:18,264 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 1823}
2019-11-12 16:37:23,389 - octoprint.util.comm - INFO - Changing monitoring state from "Offline" to "Opening serial port"
2019-11-12 16:37:23,395 - octoprint.util.comm - INFO - Changing monitoring state from "Opening serial port" to "Connecting"
2019-11-12 16:37:23,404 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 16:37:23,408 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 16:37:53,450 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 16:38:03,479 - octoprint.util.comm - INFO - Changing monitoring state from "Connecting" to "Offline"
2019-11-12 16:38:45,283 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:192.168.0.2
2019-11-12 16:52:17,667 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-11-12 16:52:18,275 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 2723}
2019-11-12 17:07:17,672 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-11-12 17:07:18,683 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 3623}
2019-11-12 17:22:17,675 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-11-12 17:22:18,275 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 4523}
2019-11-12 17:24:21,473 - octoprint.server.util.flask - INFO - Passively logging in user PiperPilot from ::ffff:192.168.0.2
2019-11-12 17:24:23,694 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.0.2
2019-11-12 17:24:23,774 - octoprint.server.util.flask - INFO - Passively logging in user PiperPilot from ::ffff:192.168.0.2
2019-11-12 17:24:25,736 - octoprint.server.util.sockjs - INFO - User PiperPilot logged in on the socket from client ::ffff:192.168.0.2
2019-11-12 17:24:32,106 - octoprint.util.comm - INFO - Changing monitoring state from "Offline" to "Opening serial port"
2019-11-12 17:24:32,109 - octoprint.util.comm - INFO - Changing monitoring state from "Opening serial port" to "Connecting"
2019-11-12 17:24:32,119 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 17:24:32,122 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 17:25:02,162 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 17:25:12,187 - octoprint.util.comm - INFO - Changing monitoring state from "Connecting" to "Offline"
2019-11-12 17:37:17,677 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-11-12 17:37:18,281 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 5423}
I can't put my finger on it, but, something about the UUID listed in your logs is bugging me
The whole thing is bugging me. I disconnected usb from PI, and connected usb cable (printer to computer) and not getting connection now, but it has taken plugging and unplugging cable to get it to work, but once connected , no problem.
Just ran Octoprint logs, and see at 11/12/19 at 21:14 it shows "Operational" and I didn't see that unless it was for a second and didn't show in Octoprint. Another thing, it shows 11/13/19 and it isn't 11/13 yet for another 2hrs and 20minutes, so what's up with that?
2019-11-12 21:14:06,423 - octoprint.startup - INFO - ******************************************************************************
2019-11-12 21:14:06,425 - octoprint.startup - INFO - Starting OctoPrint 1.3.12
2019-11-12 21:14:06,425 - octoprint.startup - INFO - ******************************************************************************
2019-11-12 21:14:06,491 - octoprint.startup - INFO - We don't appear to be online, not fetching plugin blacklist
2019-11-12 21:14:06,563 - octoprint.plugin.core - INFO - Loading plugins from /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins, /home/pi/.octoprint/plugins and installed plugin packages...
2019-11-12 21:14:09,074 - octoprint.plugin.core - INFO - Plugin Octolapse (0.3.4) is disabled.
2019-11-12 21:14:09,190 - octoprint.plugin.core - INFO - Found 18 plugin(s) providing 17 mixin implementations, 25 hook handlers
2019-11-12 21:14:09,470 - octoprint.server.heartbeat - INFO - Starting server heartbeat, 900.0s interval
2019-11-12 21:14:09,516 - octoprint.server - INFO - Intermediary server started
2019-11-12 21:14:09,517 - octoprint.plugin.core - INFO - Loading plugins from /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins, /home/pi/.octoprint/plugins and installed plugin packages...
2019-11-12 21:14:10,120 - octoprint.plugin.core - INFO - Found 18 plugin(s) providing 17 mixin implementations, 25 hook handlers
2019-11-12 21:14:10,161 - octoprint.filemanager.storage - INFO - Initializing the file metadata for /home/pi/.octoprint/uploads...
2019-11-12 21:14:10,699 - octoprint.filemanager.storage - INFO - ... file metadata for /home/pi/.octoprint/uploads initialized successfully.
2019-11-12 21:14:10,805 - octoprint.plugins.softwareupdate - INFO - Loaded version cache from disk
2019-11-12 21:14:12,634 - octoprint.util.pip - INFO - Using "/home/pi/oprint/bin/python2 -m pip" as command to invoke pip
2019-11-12 21:14:14,080 - octoprint.util.pip - INFO - Version of pip is 19.0.1
2019-11-12 21:14:14,081 - octoprint.util.pip - INFO - pip installs to /home/pi/oprint/lib/python2.7/site-packages (writable -> yes), --user flag needed -> no, virtual env -> yes
2019-11-12 21:14:14,082 - octoprint.util.pip - INFO - ==> pip ok -> yes
2019-11-12 21:14:14,088 - octoprint.plugin.core - INFO - Initialized 17 plugin implementation(s)
2019-11-12 21:14:14,100 - octoprint.plugin.core - INFO - 18 plugin(s) registered with the system:
| Action Command Prompt Support (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/action_command_prompt
| Announcement Plugin (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/announcements
| Anonymous Usage Tracking (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/tracking
| Application Keys Plugin (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/appkeys
| Backup & Restore (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/backup
| Core Wizard (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/corewizard
| Discovery (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/discovery
| Error Tracking (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/errortracking
| Force Login (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/forcelogin
| Logging (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/logging
| Navbar Temperature Plugin (0.11) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_navbartemp
| !Octolapse (0.3.4) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_octolapse
| Pi Support Plugin (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/pi_support
| Plugin Manager (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/pluginmanager
| Printer Safety Check (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/printer_safety_check
| Software Update (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/softwareupdate
| Themeify (1.2.0) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_themeify
| Virtual Printer (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/virtual_printer
2019-11-12 21:14:14,121 - octoprint.environment - INFO - Detected environment is Python 2.7.13 under Linux (linux2). Details:
| hardware:
| cores: 4
| freq: 1400.0
| ram: 918188032
| os:
| id: linux
| platform: linux2
| plugins:
| pi_support:
| model: Raspberry Pi 3 Model B Plus Rev 1.3
| octopi_version: 0.16.0
| python:
| pip: 19.0.1
| version: 2.7.13
| virtualenv: /home/pi/oprint
2019-11-12 21:14:14,130 - octoprint.server - INFO - Reset webasset folder /home/pi/.octoprint/generated/webassets...
2019-11-12 21:14:14,145 - octoprint.server - INFO - Reset webasset folder /home/pi/.octoprint/generated/.webassets-cache...
2019-11-12 21:14:15,158 - octoprint.server - INFO - Shutting down intermediary server...
2019-11-12 21:14:15,628 - octoprint.server - INFO - Intermediary server shut down
2019-11-12 21:14:15,630 - octoprint.events - INFO - Processing startup event, this is our first event
2019-11-12 21:14:15,631 - octoprint.events - INFO - Adding 0 events to queue that were held back before startup event
2019-11-12 21:14:15,635 - octoprint.filemanager - INFO - Adding backlog items from all storage types to analysis queue...
2019-11-12 21:14:15,638 - octoprint.filemanager - INFO - Added 0 items from storage type "local" to analysis queue
2019-11-12 21:14:15,739 - octoprint.server.util.watchdog - INFO - Running initial scan on watched folder...
2019-11-12 21:14:15,743 - octoprint.server.util.watchdog - INFO - ... initial scan done.
2019-11-12 21:14:15,752 - octoprint.util.comm - INFO - Changing monitoring state from "Offline" to "Opening serial port"
2019-11-12 21:14:15,759 - octoprint.util.comm - INFO - Changing monitoring state from "Opening serial port" to "Connecting"
2019-11-12 21:14:15,760 - octoprint.plugins.discovery - INFO - Registered 'OctoPrint instance on octopi' for _http._tcp
2019-11-12 21:14:15,767 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 21:14:15,773 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 21:14:15,781 - octoprint.plugins.discovery - INFO - Registered 'OctoPrint instance on octopi' for _octoprint._tcp
2019-11-12 21:14:15,792 - octoprint.plugins.discovery - INFO - Registered OctoPrint instance on octopi for SSDP
2019-11-12 21:14:15,806 - octoprint.server - INFO - Listening on http://127.0.0.1:5000
2019-11-12 21:14:15,880 - octoprint.plugins.pluginmanager - INFO - Loaded plugin repository data from disk, was still valid
2019-11-12 21:14:16,435 - octoprint.plugins.pluginmanager - INFO - Loaded notice data from disk, was still valid
2019-11-12 21:14:17,386 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 21:14:17,512 - octoprint.util.pip - INFO - Using "/home/pi/oprint/bin/python2 -m pip" as command to invoke pip
2019-11-12 21:14:17,514 - octoprint.util.pip - INFO - pip installs to /home/pi/oprint/lib/python2.7/site-packages (writable -> yes), --user flag needed -> no, virtual env -> yes
2019-11-12 21:14:17,515 - octoprint.util.pip - INFO - ==> pip ok -> yes
2019-11-12 21:14:17,549 - octoprint.util.connectivity_checker - INFO - Connectivity changed from offline to online
2019-11-12 21:14:17,595 - octoprint.plugins.pluginmanager - INFO - Loaded plugin repository data from disk, was still valid
2019-11-12 21:14:18,071 - octoprint.plugins.navbartemp - INFO - Broadcom detected
2019-11-12 21:14:18,111 - octoprint.plugins.themeify - INFO - Themeify initialized.
2019-11-12 21:14:18,249 - octoprint.plugins.pluginmanager - INFO - Loaded notice data from disk, was still valid
2019-11-12 21:14:18,514 - octoprint.server.preemptive_cache - INFO - Preemptively caching / (ui _default) for {'query_string': 'l10n=en', 'path': '/', 'base_url': 'http://octopi.local/'}
2019-11-12 21:14:18,794 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 9}
2019-11-12 21:14:19,728 - octoprint.plugins.tracking - INFO - Sent tracking event startup, payload: {'python': '2.7.13', 'ram': 918188032, 'octopi_version': '0.16.0', 'version': u'1.3.12', 'pip': '19.0.1', 'cores': 4, 'freq': 1400.0, 'pi_model': 'Raspberry Pi 3 Model B Plus Rev 1.3', 'os': 'linux'}
2019-11-12 21:14:20,417 - octoprint.util.comm - INFO - Changing monitoring state from "Connecting" to "Operational"
2019-11-12 21:14:20,423 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-12 21:14:20,591 - octoprint.plugins.tracking - INFO - Sent tracking event pong, payload: {'plugins': u'themeify:1.2.0,navbartemp:0.11'}
2019-11-12 21:14:20,873 - octoprint.util.comm - INFO - Printer reports firmware name "Marlin bugfix-1.1.x (Github)"
2019-11-12 21:14:20,890 - octoprint.util.comm - INFO - Firmware states that it supports temperature autoreporting
2019-11-12 21:14:21,689 - octoprint.plugins.tracking - INFO - Sent tracking event printer_connected, payload: {u'printer_baudrate': 115200, u'printer_port': '/dev/ttyUSB0', 'firmware_name': 'Marlin bugfix-1.1.x (Github)'}
2019-11-13 02:20:04,023 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-11-13 02:20:04,141 - octoprint.server.preemptive_cache - INFO - ... done in 13.36s
2019-11-13 02:20:04,142 - octoprint.server.preemptive_cache - INFO - Preemptively caching / (ui _default) for {'query_string': 'l10n=en', 'path': '/', 'base_url': 'http://192.168.0.23/'}
2019-11-13 02:20:04,719 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 23}
2019-11-13 02:20:10,273 - octoprint.server.preemptive_cache - INFO - ... done in 6.13s
2019-11-13 02:20:10,274 - octoprint.server.preemptive_cache - INFO - Preemptively caching / (ui _default) for {'query_string': 'l10n=en', 'path': '/', 'base_url': 'http://192.168.0.25/'}
2019-11-13 02:20:16,398 - octoprint.server.preemptive_cache - INFO - ... done in 6.12s
2019-11-13 02:20:16,399 - octoprint.server.preemptive_cache - INFO - Preemptively caching / (ui _default) for {'query_string': 'l10n=en', 'path': '/', 'base_url': 'http://192.168.0.20/'}
2019-11-13 02:20:22,493 - octoprint.server.preemptive_cache - INFO - ... done in 6.09s
2019-11-13 02:20:23,667 - octoprint.util.comm - INFO - Communication timeout while idle, trying to trigger response from printer.
2019-11-13 02:21:23,710 - octoprint.util.comm - INFO - No response from printer after 3 consecutive communication timeouts, considering it dead.
2019-11-13 02:21:23,742 - octoprint.util.comm - INFO - Changing monitoring state from "Operational" to "Offline (Error: Too many consecutive timeouts, printer still connected and alive?)"
2019-11-13 02:21:24,396 - octoprint.plugins.tracking - INFO - Sent tracking event commerror_timeout, payload: {'commerror_text': 'Too many consecutive timeouts, printer still connected and alive?'}
2019-11-13 02:24:17,896 - octoprint.server.util.flask - INFO - Passively logging in user PiperPilot from ::ffff:192.168.0.2
2019-11-13 02:24:25,386 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.0.2
2019-11-13 02:24:25,464 - octoprint.server.util.flask - INFO - Passively logging in user PiperPilot from ::ffff:192.168.0.2
2019-11-13 02:24:27,075 - octoprint.plugins.announcements - INFO - Loaded channel _important from https://octoprint.org/feeds/important.xml in 0.69s
2019-11-13 02:24:27,968 - octoprint.plugins.announcements - INFO - Loaded channel _releases from https://octoprint.org/feeds/releases.xml in 0.59s
2019-11-13 02:24:29,231 - octoprint.plugins.announcements - INFO - Loaded channel _blog from https://octoprint.org/feeds/octoblog.xml in 0.58s
2019-11-13 02:24:29,942 - octoprint.plugins.announcements - INFO - Loaded channel _plugins from https://plugins.octoprint.org/feed.xml in 0.64s
2019-11-13 02:24:30,634 - octoprint.plugins.announcements - INFO - Loaded channel _octopi from https://octoprint.org/feeds/octopi.xml in 0.57s
2019-11-13 02:24:31,544 - octoprint.plugins.pluginmanager - INFO - Loaded plugin notices data from https://plugins.octoprint.org/notices.json
2019-11-13 02:24:31,791 - octoprint.server.util.sockjs - INFO - User PiperPilot logged in on the socket from client ::ffff:192.168.0.2
2019-11-13 02:24:39,298 - octoprint.util.comm - INFO - Changing monitoring state from "Offline" to "Opening serial port"
2019-11-13 02:24:39,304 - octoprint.util.comm - INFO - Changing monitoring state from "Opening serial port" to "Connecting"
2019-11-13 02:24:39,314 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-13 02:24:39,321 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-13 02:25:09,355 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-11-13 02:25:19,376 - octoprint.util.comm - INFO - Changing monitoring state from "Connecting" to "Offline"
I guess the timezone is wrong. Run sudo raspi-config and select the correct one.
Got it thanks. Well it connected for the longest time yet, about a minute. Kept trying to lower Z axis, but would not do anything. Then timed out.