search

OllisGit / OctoPrint-FilamentManager

OctoPrint plugin to manage your filament inventory
GNU Affero General Public License v3.0
53 stars 24 forks source link

Filament Manager can no longer access postgresql database on external Raspberry after Python 3 Upgrade #7

Closed mpd352 closed 4 years ago

mpd352 commented 4 years ago

Describe the bug A clear and concise description of what the bug is.

After the upgrade of my two Octoprint instances I can no longer use the shared postgresql database. Neither instance of Octoprint can connect to the external database. The log shows the statement "tornado.access - WARNING - 400 POST /plugin/filamentmanager/database/test (::ffff:192.168.0.195) 35.89ms"

My research indicates that this is some kind of confusion with the API key. I am not however accomplish enough user to figure out exactly how to get around this.

Steps to reproduce

  1. Go to '...'
  2. Click on '....'
  3. Scroll down to '....'
  4. See error

Expected behavior A clear and concise description of what you expected to happen.

Did the same happen when all other 3rd party plugins are disabled? If not which plugin is causing the issue?

Log file Drag and drop the octoprint.log here. 2020-09-30 02:54:31,448 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 02:54:31,453 - octoprint.server - INFO - --- Log roll over detected --------------------------------------------------- 2020-09-30 02:54:31,453 - octoprint.server - INFO - OctoPrint 1.4.2 2020-09-30 02:54:31,460 - octoprint.plugin.core - INFO - 33 plugin(s) registered with the system: | Action Command Notification Support (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/action_command_notification | Action Command Prompt Support (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/action_command_prompt | Announcement Plugin (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/announcements | Anonymous Usage Tracking (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/tracking | Application Keys Plugin (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/appkeys | Backup & Restore (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/backup | Consolidate Temp Control (0.1.7) = /home/pi/oprint/lib/python3.7/site-packages/octoprint_consolidate_temp_control | Core Wizard (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/corewizard | Creality Temperature (1.2.4) = /home/pi/oprint/lib/python3.7/site-packages/octoprint_CrealityTemperature | Cura Thumbnails (0.2.0) = /home/pi/oprint/lib/python3.7/site-packages/octoprint_ultimakerformatpackage | Discovery (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/discovery | DisplayLayerProgress Plugin (1.23.3) = /home/pi/oprint/lib/python3.7/site-packages/octoprint_DisplayLayerProgress | Error Tracking (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/errortracking | Filament Manager (1.6.0) = /home/pi/oprint/lib/python3.7/site-packages/octoprint_filamentmanager | File Check (2020.8.7) (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint_file_check | Firmware Check (2020.9.23) (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint_firmware_check | Fullscreen Plugin (0.0.5) = /home/pi/oprint/lib/python3.7/site-packages/octoprint_fullscreen | GCode Viewer (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/gcodeviewer | Logging (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/logging | Login UI (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/loginui | MultiCam (0.2.8) = /home/pi/oprint/lib/python3.7/site-packages/octoprint_multicam | OctoPrint-WideScreen (0.1.3) = /home/pi/oprint/lib/python3.7/site-packages/octoprint_widescreen | Pi Support Plugin (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/pi_support | Plugin Manager (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/pluginmanager | Preheat Button (0.5.1) = /home/pi/oprint/lib/python3.7/site-packages/octoprint_preheat | PrettyGCode (1.2.2) = /home/pi/oprint/lib/python3.7/site-packages/octoprint_prettygcode | PrintJobHistory (1.9.0) = /home/pi/oprint/lib/python3.7/site-packages/octoprint_PrintJobHistory | Procastinator (1.1.1) = /home/pi/oprint/lib/python3.7/site-packages/octoprint_procastinator | PrusaSlicer Thumbnails (0.1.2) = /home/pi/oprint/lib/python3.7/site-packages/octoprint_prusaslicerthumbnails | Smart Preheat (0.0.5) = /home/pi/oprint/lib/python3.7/site-packages/octoprint_smartpreheat | Software Update (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/softwareupdate | Virtual Printer (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/virtual_printer | Webcam Tab (0.1.2) = /home/pi/oprint/lib/python3.7/site-packages/octoprint_webcamtab Prefix legend: ! = disabled, # = blacklisted, = incompatible 2020-09-30 02:54:31,479 - octoprint.environment - INFO - Detected environment is Python 3.7.3 under Linux (linux). Details: | hardware: | cores: 4 | freq: 1200.0 | ram: 916992000 | os: | id: linux | platform: linux | plugins: | pi_support: | model: Raspberry Pi 3 Model B Rev 1.2 | octopi_version: 0.17.0 | python: | pip: 20.2.3 | version: 3.7.3 | virtualenv: /home/pi/oprint 2020-09-30 02:54:31,480 - octoprint.server - INFO - ------------------------------------------------------------------------------ 2020-09-30 02:54:37,990 - octoprint.plugins.tracking - INFO - Sent tracking event pong, payload: {'version': '1.4.2', 'os': 'linux', 'python': '3.7.3', 'pip': '20.2.3', 'cores': 4, 'freq': 1200.0, 'ram': 916992000, 'pi_model': 'Raspberry Pi 3 Model B Rev 1.2', 'octopi_version': '0.17.0', 'plugins': 'prusaslicerthumbnails:0.1.2,printjobhistory:1.9.0,preheat:0.5.1,widescreen:0.1.3,ultimakerformatpackage:0.2.0,smartpreheat:0.0.5,procastinator:1.1.1,prettygcode:1.2.2,multicam:0.2.8,fullscreen:0.0.5,filamentmanager:1.6.0,displaylayerprogress:1.23.3,consolidate_temp_control:0.1.7,crealitytemperature:1.2.4'} 2020-09-30 02:54:39,211 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 518409} 2020-09-30 03:09:31,449 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 03:09:39,307 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 519309} 2020-09-30 03:24:31,451 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 03:24:39,301 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 520209} 2020-09-30 03:24:42,150 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.0.114 2020-09-30 03:24:44,215 - octoprint.plugins.PrintJobHistory - INFO - Plugin-State: PreHeat=enabled DisplayLayerProgress=enabled filamentmanager=enabled ultimakerformat=enabled PrusaSlicerThumbnails=enabled 2020-09-30 03:24:50,082 - octoprint.access.users - INFO - Logged in user: mpd352 2020-09-30 03:24:50,086 - octoprint.server.api - INFO - Actively logging in user mpd352 from ::ffff:192.168.0.114 2020-09-30 03:24:50,180 - octoprint.server.util.flask - INFO - Passively logging in user mpd352 from ::ffff:192.168.0.114 2020-09-30 03:24:50,181 - octoprint.access.users - INFO - Logged in user: mpd352 2020-09-30 03:24:50,282 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:192.168.0.114 2020-09-30 03:24:50,495 - octoprint.server.util.flask - INFO - Passively logging in user mpd352 from ::ffff:192.168.0.114 2020-09-30 03:24:50,495 - octoprint.access.users - INFO - Logged in user: mpd352 2020-09-30 03:24:50,890 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.0.114 2020-09-30 03:24:50,917 - octoprint.server.util.flask - INFO - Passively logging in user mpd352 from ::ffff:192.168.0.114 2020-09-30 03:24:50,917 - octoprint.access.users - INFO - Logged in user: mpd352 2020-09-30 03:24:52,813 - octoprint.plugins.announcements - INFO - Loaded channel _important from https://octoprint.org/feeds/important.xml in 0.8s 2020-09-30 03:24:52,923 - octoprint.plugins.PrintJobHistory - INFO - Plugin-State: PreHeat=enabled DisplayLayerProgress=enabled filamentmanager=enabled ultimakerformat=enabled PrusaSlicerThumbnails=enabled 2020-09-30 03:24:53,880 - octoprint.plugins.announcements - INFO - Loaded channel _releases from https://octoprint.org/feeds/releases.xml in 0.85s 2020-09-30 03:24:54,766 - octoprint.plugins.announcements - INFO - Loaded channel _blog from https://octoprint.org/feeds/octoblog.xml in 0.84s 2020-09-30 03:24:55,492 - octoprint.plugins.announcements - INFO - Loaded channel _plugins from https://plugins.octoprint.org/feed.xml in 0.68s 2020-09-30 03:24:56,217 - octoprint.plugins.announcements - INFO - Loaded channel _octopi from https://octoprint.org/feeds/octopi.xml in 0.65s 2020-09-30 03:24:57,339 - octoprint.plugins.pluginmanager - INFO - Loaded plugin notices data from https://plugins.octoprint.org/notices.json 2020-09-30 03:24:58,102 - octoprint.server.util.sockjs - INFO - User mpd352 logged in on the socket from client ::ffff:192.168.0.114 2020-09-30 03:26:51,734 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:192.168.0.114 2020-09-30 03:39:31,452 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 03:39:39,164 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 521109} 2020-09-30 03:54:31,453 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 03:54:39,208 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 522009} 2020-09-30 04:09:31,454 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 04:09:39,176 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 522909} 2020-09-30 04:24:31,456 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 04:24:39,205 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 523809} 2020-09-30 04:27:42,018 - octoprint.util.comm - INFO - Finished in 9799.445 s. 2020-09-30 04:27:42,019 - octoprint.util.comm - INFO - Changing monitoring state from "Printing" to "Finishing" 2020-09-30 04:27:42,032 - octoprint.plugins.filamentmanager - INFO - Filament used: 10169.645100003605 mm (tool0) 2020-09-30 04:27:42,047 - octoprint.printer.standard.job - INFO - Print job done - origin: local, path: 1911_Magazine_Rack_V2.gcode, owner: mpd352 2020-09-30 04:27:42,096 - octoprint.util.comm - INFO - Changing monitoring state from "Finishing" to "Operational" 2020-09-30 04:27:42,151 - octoprint.plugins.DisplayLayerProgress - INFO - Printing stopped. Detailed progress stopped. 2020-09-30 04:27:42,202 - octoprint.plugins.PrintJobHistory - INFO - Print result:success, CaptureMode:successful 2020-09-30 04:27:42,202 - octoprint.plugins.PrintJobHistory - INFO - Start capturing print job 2020-09-30 04:27:42,207 - octoprint.plugins.PrintJobHistory.SlicerSettingsParser - INFO - Start parsing Slicer-Settings 2020-09-30 04:27:42,289 - octoprint.plugins.PrintJobHistory.SlicerSettingsParser - INFO - Finished parsing Slicer-Settings 2020-09-30 04:27:42,294 - octoprint.plugins.PrintJobHistory - WARNING - Thumbnail not found in print metadata 2020-09-30 04:27:42,296 - octoprint.plugins.PrintJobHistory.CameraManager - INFO - Try taking snapshot '/home/pi/.octoprint/data/PrintJobHistory/snapshots/20200930-014422.jpg' from 'http://127.0.0.1:8080/?action=snapshot' 2020-09-30 04:27:42,343 - octoprint.plugins.PrintJobHistory.CameraManager - INFO - Process snapshot image 2020-09-30 04:27:42,865 - octoprint.plugins.tracking - INFO - Sent tracking event print_done, payload: {'origin': 'local', 'file': '70c46514440bfac21f943edf518ef509abeca844', 'elapsed': 9799, 'throttled_now': False, 'throttled_past': True, 'throttled_mask': 458752} 2020-09-30 04:39:31,457 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 04:39:39,184 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 524709} 2020-09-30 04:47:03,687 - octoprint.printer.standard.job - INFO - Print job deselected - user: n/a 2020-09-30 04:47:03,797 - tornado.access - WARNING - 404 GET /downloads/files/local/null (::ffff:192.168.0.195) 8.91ms 2020-09-30 04:47:51,362 - octoprint.plugins.DisplayLayerProgress - INFO - FilePreProcessor. Checking LayerExpressions. 2020-09-30 04:47:51,362 - octoprint.plugins.DisplayLayerProgress - INFO - FilePreProcessor. LayerExpression valid. Start processing... 2020-09-30 04:47:56,439 - octoprint.filemanager.analysis - INFO - Starting analysis of local:Mag_Racks.gcode 2020-09-30 04:47:56,441 - octoprint.filemanager.analysis - INFO - Invoking analysis command: /home/pi/oprint/bin/python -m octoprint analysis gcode --speed-x=6000 --speed-y=6000 --max-t=10 --throttle=0.0 --throttle-lines=100 /home/pi/.octoprint/uploads/Mag_Racks.gcode 2020-09-30 04:48:00,334 - octoprint.printer.standard.job - INFO - Print job selected - origin: local, path: Mag_Racks.gcode, owner: mpd352, user: mpd352 2020-09-30 04:48:00,367 - octoprint.plugins.DisplayLayerProgress - INFO - File '/home/pi/.octoprint/uploads/Mag_Racks.gcode' selected. Determining number of layers. 2020-09-30 04:48:00,379 - octoprint.plugins.DisplayLayerProgress - INFO - Read total height from MetaFile 2020-09-30 04:48:00,393 - octoprint.plugins.DisplayLayerProgress - INFO - Total height not found in MetaFile 2020-09-30 04:48:02,909 - octoprint.plugins.DisplayLayerProgress - INFO - Store layer count in MetaFile 2020-09-30 04:48:02,932 - octoprint.plugins.DisplayLayerProgress - INFO - File select-event processing done!' 2020-09-30 04:48:05,296 - octoprint.plugins.preheat - INFO - Preheating bed to 110.0. 2020-09-30 04:48:05,317 - octoprint.plugins.preheat - INFO - Preheating tool0 to 225.0. 2020-09-30 04:48:10,314 - octoprint.util.comm - INFO - Changing monitoring state from "Operational" to "Starting" 2020-09-30 04:48:10,322 - octoprint.printer.standard.job - INFO - Print job started - origin: local, path: Mag_Racks.gcode, owner: mpd352, user: mpd352 2020-09-30 04:48:10,548 - octoprint.plugins.DisplayLayerProgress - INFO - Printing started. Detailed progress started.{'name': 'Mag_Racks.gcode', 'path': 'Mag_Racks.gcode', 'origin': 'local', 'size': 5423872, 'owner': 'mpd352', 'user': 'mpd352'} 2020-09-30 04:48:10,552 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0 2020-09-30 04:48:10,570 - octoprint.plugins.PrintJobHistory - INFO - Try reading Temperature from PreHeat-Plugin... 2020-09-30 04:48:10,575 - octoprint.util.comm - INFO - Changing monitoring state from "Starting" to "Printing" 2020-09-30 04:48:10,589 - octoprint.plugin - ERROR - Error while calling plugin PrintJobHistory Traceback (most recent call last): File "/home/pi/oprint/lib/python3.7/site-packages/octoprint/plugin/init.py", line 230, in call_plugin result = getattr(plugin, method)(*args, **kwargs) File "/home/pi/oprint/lib/python3.7/site-packages/octoprint_PrintJobHistory/init.py", line 612, in on_event self._createPrintJobModel(payload) File "/home/pi/oprint/lib/python3.7/site-packages/octoprint_PrintJobHistory/init.py", line 337, in _createPrintJobModel preHeatTemperature = self._preHeatPluginImplementation.read_temperatures_from_file(path_on_disk) File "/home/pi/oprint/lib/python3.7/site-packages/octoprint_preheat/init.py", line 86, in read_temperatures_from_file enable_bed = self._settings.get_boolean(["enable_bed"]) AttributeError: 'NoneType' object has no attribute 'get_boolean' 2020-09-30 04:48:11,147 - octoprint.plugins.tracking - INFO - Sent tracking event print_started, payload: {'origin': 'local', 'file': '7932a354cbcf2dadbb16772157820172a9ed96b0', 'throttled_now': False, 'throttled_past': True, 'throttled_mask': 458752} 2020-09-30 04:52:22,005 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.0.114 2020-09-30 04:52:22,054 - octoprint.server.util.flask - INFO - Passively logging in user mpd352 from ::ffff:192.168.0.114 2020-09-30 04:52:22,055 - octoprint.access.users - INFO - Logged in user: mpd352 2020-09-30 04:52:22,110 - octoprint.server.util.sockjs - INFO - User mpd352 logged in on the socket from client ::ffff:192.168.0.114 2020-09-30 04:52:24,057 - octoprint.plugins.PrintJobHistory - INFO - Plugin-State: PreHeat=enabled DisplayLayerProgress=enabled filamentmanager=enabled ultimakerformat=enabled PrusaSlicerThumbnails=enabled 2020-09-30 04:54:31,458 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 04:54:39,184 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 525609} 2020-09-30 05:09:31,459 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 05:09:39,223 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 526509} 2020-09-30 05:11:55,871 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:192.168.0.114 2020-09-30 05:24:31,461 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 05:24:39,271 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 527409} 2020-09-30 05:39:31,462 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 05:39:39,262 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 528309} 2020-09-30 05:54:31,464 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 05:54:39,217 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 529209} 2020-09-30 06:09:31,467 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 06:09:39,178 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 530109} 2020-09-30 06:14:09,269 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.0.114 2020-09-30 06:14:09,330 - octoprint.server.util.flask - INFO - Passively logging in user mpd352 from ::ffff:192.168.0.114 2020-09-30 06:14:09,331 - octoprint.access.users - INFO - Logged in user: mpd352 2020-09-30 06:14:09,387 - octoprint.server.util.sockjs - INFO - User mpd352 logged in on the socket from client ::ffff:192.168.0.114 2020-09-30 06:14:11,316 - octoprint.plugins.PrintJobHistory - INFO - Plugin-State: PreHeat=enabled DisplayLayerProgress=enabled filamentmanager=enabled ultimakerformat=enabled PrusaSlicerThumbnails=enabled 2020-09-30 06:14:13,230 - octoprint.server.util.flask - INFO - Passively logging in user mpd352 from ::ffff:192.168.0.114 2020-09-30 06:14:13,231 - octoprint.access.users - INFO - Logged in user: mpd352 2020-09-30 06:14:13,381 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:192.168.0.114 2020-09-30 06:14:13,447 - octoprint.server.util.flask - INFO - Passively logging in user mpd352 from ::ffff:192.168.0.114 2020-09-30 06:14:13,448 - octoprint.access.users - INFO - Logged in user: mpd352 2020-09-30 06:14:14,001 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.0.114 2020-09-30 06:14:14,036 - octoprint.server.util.flask - INFO - Passively logging in user mpd352 from ::ffff:192.168.0.114 2020-09-30 06:14:14,040 - octoprint.access.users - INFO - Logged in user: mpd352 2020-09-30 06:14:16,025 - octoprint.plugins.PrintJobHistory - INFO - Plugin-State: PreHeat=enabled DisplayLayerProgress=enabled filamentmanager=enabled ultimakerformat=enabled PrusaSlicerThumbnails=enabled 2020-09-30 06:14:16,552 - octoprint.server.util.sockjs - INFO - User mpd352 logged in on the socket from client ::ffff:192.168.0.114 2020-09-30 06:23:38,211 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:192.168.0.114 2020-09-30 06:24:31,468 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 06:24:39,222 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 531009} 2020-09-30 06:39:31,469 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 06:39:39,208 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 531909} 2020-09-30 06:48:10,136 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.0.114 2020-09-30 06:48:10,228 - octoprint.server.util.flask - INFO - Passively logging in user mpd352 from ::ffff:192.168.0.114 2020-09-30 06:48:10,229 - octoprint.access.users - INFO - Logged in user: mpd352 2020-09-30 06:48:10,464 - octoprint.server.util.sockjs - INFO - User mpd352 logged in on the socket from client ::ffff:192.168.0.114 2020-09-30 06:48:12,168 - octoprint.plugins.PrintJobHistory - INFO - Plugin-State: PreHeat=enabled DisplayLayerProgress=enabled filamentmanager=enabled ultimakerformat=enabled PrusaSlicerThumbnails=enabled 2020-09-30 06:52:56,408 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:192.168.0.195 2020-09-30 06:54:31,470 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 06:54:39,200 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 532809} 2020-09-30 06:56:24,628 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:192.168.0.114 2020-09-30 07:09:31,472 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 07:09:39,216 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 533709} 2020-09-30 07:11:23,161 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.0.114 2020-09-30 07:11:23,249 - octoprint.server.util.flask - INFO - Passively logging in user mpd352 from ::ffff:192.168.0.114 2020-09-30 07:11:23,250 - octoprint.access.users - INFO - Cleaning up user session 6443234D1D61457BA868097F00A171AC for user mpd352 2020-09-30 07:11:23,251 - octoprint.access.users - INFO - Logged out user: mpd352 2020-09-30 07:11:23,251 - octoprint.access.users - INFO - Logged in user: mpd352 2020-09-30 07:11:23,397 - octoprint.server.util.sockjs - INFO - User mpd352 logged in on the socket from client ::ffff:192.168.0.114 2020-09-30 07:11:25,194 - octoprint.plugins.PrintJobHistory - INFO - Plugin-State: PreHeat=enabled DisplayLayerProgress=enabled filamentmanager=enabled ultimakerformat=enabled PrusaSlicerThumbnails=enabled 2020-09-30 07:23:52,493 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:192.168.0.114 2020-09-30 07:24:31,473 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 07:24:39,210 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 534609} 2020-09-30 07:39:31,474 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 07:39:39,219 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 535509} 2020-09-30 07:54:31,475 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 07:54:39,208 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 536409} 2020-09-30 08:09:31,477 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 08:09:39,243 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 537309} 2020-09-30 08:24:31,478 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 08:24:39,215 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 538209} 2020-09-30 08:39:31,479 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 08:39:39,211 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 539109} 2020-09-30 08:54:31,481 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 08:54:39,202 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 540009} 2020-09-30 09:09:31,482 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 09:09:39,230 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 540909} 2020-09-30 09:24:31,483 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 09:24:39,234 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 541809} 2020-09-30 09:39:31,486 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 09:39:39,226 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 542709} 2020-09-30 09:54:31,487 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 09:54:39,210 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 543609} 2020-09-30 10:09:31,488 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 10:09:39,219 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 544509} 2020-09-30 10:24:31,490 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 10:24:39,232 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 545409} 2020-09-30 10:25:49,034 - octoprint.server.util.flask - INFO - Passively logging in user mpd352 from ::ffff:192.168.0.195 2020-09-30 10:25:49,037 - octoprint.access.users - INFO - Logged in user: mpd352 2020-09-30 10:25:49,418 - octoprint.server.util.flask - INFO - Passively logging in user mpd352 from ::ffff:192.168.0.195 2020-09-30 10:25:49,419 - octoprint.access.users - INFO - Logged in user: mpd352 2020-09-30 10:25:50,065 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.0.195 2020-09-30 10:25:50,122 - octoprint.server.util.flask - INFO - Passively logging in user mpd352 from ::ffff:192.168.0.195 2020-09-30 10:25:50,122 - octoprint.access.users - INFO - Logged in user: mpd352 2020-09-30 10:25:51,973 - octoprint.plugins.announcements - INFO - Loaded channel _important from https://octoprint.org/feeds/important.xml in 0.58s 2020-09-30 10:25:52,094 - octoprint.plugins.PrintJobHistory - INFO - Plugin-State: PreHeat=enabled DisplayLayerProgress=enabled filamentmanager=enabled ultimakerformat=enabled PrusaSlicerThumbnails=enabled 2020-09-30 10:25:52,843 - octoprint.plugins.announcements - INFO - Loaded channel _releases from https://octoprint.org/feeds/releases.xml in 0.59s 2020-09-30 10:25:53,503 - octoprint.plugins.announcements - INFO - Loaded channel _blog from https://octoprint.org/feeds/octoblog.xml in 0.62s 2020-09-30 10:25:54,315 - octoprint.plugins.announcements - INFO - Loaded channel _plugins from https://plugins.octoprint.org/feed.xml in 0.76s 2020-09-30 10:25:54,994 - octoprint.plugins.announcements - INFO - Loaded channel _octopi from https://octoprint.org/feeds/octopi.xml in 0.61s 2020-09-30 10:25:56,232 - octoprint.plugins.pluginmanager - INFO - Loaded plugin repository data from https://plugins.octoprint.org/plugins.json 2020-09-30 10:25:58,456 - octoprint.plugins.pluginmanager - INFO - Loaded plugin notices data from https://plugins.octoprint.org/notices.json 2020-09-30 10:26:00,569 - octoprint.plugins.softwareupdate - INFO - Saved version cache to disk 2020-09-30 10:26:01,394 - octoprint.server.util.sockjs - INFO - User mpd352 logged in on the socket from client ::ffff:192.168.0.195 2020-09-30 10:39:31,491 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 10:39:39,233 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 546309} 2020-09-30 10:54:31,492 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 10:54:39,228 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 547209} 2020-09-30 11:09:31,493 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 11:09:39,263 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 548109} 2020-09-30 11:24:31,495 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 11:24:39,220 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 549009} 2020-09-30 11:39:31,496 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 11:39:39,253 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 549909} 2020-09-30 11:54:31,497 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 11:54:39,272 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 550809} 2020-09-30 12:09:31,500 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 12:09:39,231 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 551709} 2020-09-30 12:21:33,583 - octoprint.util.comm - INFO - Finished in 27203.323 s. 2020-09-30 12:21:33,584 - octoprint.util.comm - INFO - Changing monitoring state from "Printing" to "Finishing" 2020-09-30 12:21:33,591 - octoprint.filemanager.analysis - INFO - Starting analysis of local:Mag_Racks.gcode 2020-09-30 12:21:33,596 - octoprint.filemanager.analysis - INFO - Invoking analysis command: /home/pi/oprint/bin/python -m octoprint analysis gcode --speed-x=6000 --speed-y=6000 --max-t=10 --throttle=0.0 --throttle-lines=100 /home/pi/.octoprint/uploads/Mag_Racks.gcode 2020-09-30 12:21:33,602 - octoprint.plugins.filamentmanager - INFO - Filament used: 29214.14309998906 mm (tool0) 2020-09-30 12:21:33,606 - octoprint.printer.standard.job - INFO - Print job done - origin: local, path: Mag_Racks.gcode, owner: mpd352 2020-09-30 12:21:33,766 - octoprint.util.comm - INFO - Changing monitoring state from "Finishing" to "Operational" 2020-09-30 12:21:33,927 - octoprint.plugins.DisplayLayerProgress - INFO - Printing stopped. Detailed progress stopped. 2020-09-30 12:21:33,963 - octoprint.plugins.PrintJobHistory - INFO - Print result:success, CaptureMode:successful 2020-09-30 12:21:33,963 - octoprint.plugins.PrintJobHistory - INFO - Start capturing print job 2020-09-30 12:21:33,997 - octoprint.plugins.PrintJobHistory.SlicerSettingsParser - INFO - Start parsing Slicer-Settings 2020-09-30 12:21:34,176 - octoprint.plugins.PrintJobHistory.SlicerSettingsParser - INFO - Finished parsing Slicer-Settings 2020-09-30 12:21:34,197 - octoprint.plugins.PrintJobHistory - WARNING - Thumbnail not found in print metadata 2020-09-30 12:21:34,199 - octoprint.plugins.PrintJobHistory.CameraManager - INFO - Try taking snapshot '/home/pi/.octoprint/data/PrintJobHistory/snapshots/20200930-044810.jpg' from 'http://127.0.0.1:8080/?action=snapshot' 2020-09-30 12:21:34,241 - octoprint.plugins.PrintJobHistory - ERROR - MetaFile of 'Mag_Racks.gcode' doesnt include 'analysis' 2020-09-30 12:21:34,241 - octoprint.plugins.PrintJobHistory - ERROR - Filamentlength not found for toolId 'tool0' 2020-09-30 12:21:34,401 - octoprint.plugins.PrintJobHistory.CameraManager - INFO - Process snapshot image 2020-09-30 12:21:34,608 - octoprint.plugins.tracking - INFO - Sent tracking event print_done, payload: {'origin': 'local', 'file': '7932a354cbcf2dadbb16772157820172a9ed96b0', 'elapsed': 27203, 'throttled_now': False, 'throttled_past': True, 'throttled_mask': 458752} 2020-09-30 12:22:07,307 - octoprint.filemanager.analysis - INFO - Analysis of entry local:Mag_Racks.gcode finished, needed 33.72s 2020-09-30 12:22:07,319 - octoprint.plugins.DisplayLayerProgress - INFO - Store layer count in MetaFile 2020-09-30 12:22:07,334 - octoprint.plugins.DisplayLayerProgress - INFO - Read total height from MetaFile 2020-09-30 12:24:31,501 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 12:24:39,254 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 552609} 2020-09-30 12:39:31,502 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 12:39:39,253 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 553509} 2020-09-30 12:54:31,503 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 12:54:39,269 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 554409} 2020-09-30 13:09:31,505 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 13:09:39,253 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 555309} 2020-09-30 13:24:31,506 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 13:24:39,272 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 556209} 2020-09-30 13:39:31,508 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 13:39:39,248 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 557109} 2020-09-30 13:54:31,509 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 13:54:39,281 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 558009} 2020-09-30 14:09:31,511 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 14:09:39,263 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 558909} 2020-09-30 14:24:31,512 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 14:24:39,276 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 559809} 2020-09-30 14:39:31,513 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 14:39:39,314 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 560709} 2020-09-30 14:54:31,515 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 14:54:39,262 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 561609} 2020-09-30 15:09:31,516 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 15:09:39,302 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 562509} 2020-09-30 15:24:31,518 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 15:24:39,279 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 563409} 2020-09-30 15:39:31,519 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 15:39:39,288 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 564309} 2020-09-30 15:54:31,521 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 15:54:39,267 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 565209} 2020-09-30 16:09:31,522 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 16:09:39,298 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 566109} 2020-09-30 16:24:31,523 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 16:24:39,299 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 567009} 2020-09-30 16:39:31,525 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 16:39:39,300 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 567909} 2020-09-30 16:54:31,526 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 16:54:39,272 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 568809} 2020-09-30 17:09:31,528 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 17:09:39,280 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 569709} 2020-09-30 17:24:31,529 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 17:24:39,398 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 570609} 2020-09-30 17:39:31,531 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 17:39:39,315 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 571509} 2020-09-30 17:48:49,260 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.0.114 2020-09-30 17:48:49,481 - octoprint.server.util.flask - INFO - Passively logging in user mpd352 from ::ffff:192.168.0.114 2020-09-30 17:48:49,481 - octoprint.access.users - INFO - Logged in user: mpd352 2020-09-30 17:48:49,802 - octoprint.server.util.sockjs - INFO - User mpd352 logged in on the socket from client ::ffff:192.168.0.114 2020-09-30 17:48:51,386 - octoprint.plugins.PrintJobHistory - INFO - Plugin-State: PreHeat=enabled DisplayLayerProgress=enabled filamentmanager=enabled ultimakerformat=enabled PrusaSlicerThumbnails=enabled 2020-09-30 17:54:31,532 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 17:54:39,317 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 572409} 2020-09-30 18:09:31,534 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 18:09:39,331 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 573309} 2020-09-30 18:24:31,535 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 18:24:39,298 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 574209} 2020-09-30 18:26:07,561 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:192.168.0.114 2020-09-30 18:39:31,537 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 18:39:39,387 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 575109} 2020-09-30 18:54:31,538 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 18:54:39,322 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 576009} 2020-09-30 19:09:31,539 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 19:09:39,322 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 576909} 2020-09-30 19:24:31,541 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 19:24:39,342 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 577809} 2020-09-30 19:39:31,542 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 19:39:39,320 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 578709} 2020-09-30 19:54:31,544 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 19:54:39,301 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 579609} 2020-09-30 20:09:31,545 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 20:09:39,331 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 580509} 2020-09-30 20:24:31,547 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 20:24:39,323 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 581409} 2020-09-30 20:39:31,548 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 20:39:39,339 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 582309} 2020-09-30 20:54:31,549 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 20:54:39,342 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 583209} 2020-09-30 21:09:31,551 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 21:09:39,370 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 584109} 2020-09-30 21:24:31,552 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 21:24:39,359 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 585009} 2020-09-30 21:25:27,029 - octoprint.printer.standard.job - INFO - Print job deselected - user: n/a 2020-09-30 21:38:43,084 - octoprint.plugins.DisplayLayerProgress - INFO - FilePreProcessor. Checking LayerExpressions. 2020-09-30 21:38:43,084 - octoprint.plugins.DisplayLayerProgress - INFO - FilePreProcessor. LayerExpression valid. Start processing... 2020-09-30 21:38:50,922 - octoprint.filemanager.analysis - INFO - Starting analysis of local:AR_Mag_Rack_PLA.gcode 2020-09-30 21:38:50,924 - octoprint.filemanager.analysis - INFO - Invoking analysis command: /home/pi/oprint/bin/python -m octoprint analysis gcode --speed-x=6000 --speed-y=6000 --max-t=10 --throttle=0.0 --throttle-lines=100 /home/pi/.octoprint/uploads/AR_Mag_Rack_PLA.gcode 2020-09-30 21:39:00,716 - octoprint.printer.standard.job - INFO - Print job selected - origin: local, path: AR_Mag_Rack_PLA.gcode, owner: mpd352, user: mpd352 2020-09-30 21:39:01,390 - octoprint.plugins.DisplayLayerProgress - INFO - File '/home/pi/.octoprint/uploads/AR_Mag_Rack_PLA.gcode' selected. Determining number of layers. 2020-09-30 21:39:01,417 - octoprint.plugins.DisplayLayerProgress - INFO - Read total height from MetaFile 2020-09-30 21:39:01,476 - octoprint.plugins.DisplayLayerProgress - INFO - Total height not found in MetaFile 2020-09-30 21:39:05,482 - octoprint.plugins.preheat - INFO - Preheating bed to 70.0. 2020-09-30 21:39:05,485 - octoprint.plugins.preheat - INFO - Preheating tool0 to 210.0. 2020-09-30 21:39:10,061 - octoprint.plugins.DisplayLayerProgress - INFO - Store layer count in MetaFile 2020-09-30 21:39:10,125 - octoprint.plugins.DisplayLayerProgress - INFO - File select-event processing done!' 2020-09-30 21:39:31,553 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 21:39:39,473 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 585909} 2020-09-30 21:39:39,847 - octoprint.filemanager.analysis - INFO - Analysis of entry local:AR_Mag_Rack_PLA.gcode finished, needed 48.93s 2020-09-30 21:39:39,863 - octoprint.plugins.DisplayLayerProgress - INFO - Store layer count in MetaFile 2020-09-30 21:39:39,881 - octoprint.plugins.DisplayLayerProgress - INFO - Read total height from MetaFile 2020-09-30 21:40:10,456 - octoprint.util.comm - INFO - Changing monitoring state from "Operational" to "Starting" 2020-09-30 21:40:10,470 - octoprint.printer.standard.job - INFO - Print job started - origin: local, path: AR_Mag_Rack_PLA.gcode, owner: mpd352, user: mpd352 2020-09-30 21:40:10,593 - octoprint.util.comm - INFO - Communication timeout while printing, trying to trigger response from printer. 2020-09-30 21:40:10,609 - octoprint.plugins.DisplayLayerProgress - INFO - Printing started. Detailed progress started.{'name': 'AR_Mag_Rack_PLA.gcode', 'path': 'AR_Mag_Rack_PLA.gcode', 'origin': 'local', 'size': 8106573, 'owner': 'mpd352', 'user': 'mpd352'} 2020-09-30 21:40:10,643 - octoprint.plugins.PrintJobHistory - INFO - Try reading Temperature from PreHeat-Plugin... 2020-09-30 21:40:10,652 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0 2020-09-30 21:40:10,674 - octoprint.plugin - ERROR - Error while calling plugin PrintJobHistory Traceback (most recent call last): File "/home/pi/oprint/lib/python3.7/site-packages/octoprint/plugin/init.py", line 230, in call_plugin result = getattr(plugin, method)(*args, **kwargs) File "/home/pi/oprint/lib/python3.7/site-packages/octoprint_PrintJobHistory/init.py", line 612, in on_event self._createPrintJobModel(payload) File "/home/pi/oprint/lib/python3.7/site-packages/octoprint_PrintJobHistory/init.py", line 337, in _createPrintJobModel preHeatTemperature = self._preHeatPluginImplementation.read_temperatures_from_file(path_on_disk) File "/home/pi/oprint/lib/python3.7/site-packages/octoprint_preheat/init.py", line 86, in read_temperatures_from_file enable_bed = self._settings.get_boolean(["enable_bed"]) AttributeError: 'NoneType' object has no attribute 'get_boolean' 2020-09-30 21:40:10,693 - octoprint.util.comm - INFO - Changing monitoring state from "Starting" to "Printing" 2020-09-30 21:40:11,161 - octoprint.plugins.tracking - INFO - Sent tracking event print_started, payload: {'origin': 'local', 'file': 'fa06993831db5d613d2f604968312e4f44930993', 'throttled_now': False, 'throttled_past': True, 'throttled_mask': 458752} 2020-09-30 21:46:43,366 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.0.114 2020-09-30 21:46:43,449 - octoprint.server.util.flask - INFO - Passively logging in user mpd352 from ::ffff:192.168.0.114 2020-09-30 21:46:43,450 - octoprint.access.users - INFO - Logged in user: mpd352 2020-09-30 21:46:43,663 - octoprint.server.util.sockjs - INFO - User mpd352 logged in on the socket from client ::ffff:192.168.0.114 2020-09-30 21:46:45,411 - octoprint.plugins.PrintJobHistory - INFO - Plugin-State: PreHeat=enabled DisplayLayerProgress=enabled filamentmanager=enabled ultimakerformat=enabled PrusaSlicerThumbnails=enabled 2020-09-30 21:54:31,554 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 21:54:39,361 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 586809} 2020-09-30 22:09:31,556 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 22:09:39,373 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 587709} 2020-09-30 22:09:43,573 - octoprint.server.util.flask - INFO - Passively logging in user mpd352 from ::ffff:192.168.0.114 2020-09-30 22:09:43,574 - octoprint.access.users - INFO - Logged in user: mpd352 2020-09-30 22:09:43,719 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:192.168.0.114 2020-09-30 22:09:43,856 - octoprint.server.util.flask - INFO - Passively logging in user mpd352 from ::ffff:192.168.0.114 2020-09-30 22:09:43,856 - octoprint.access.users - INFO - Logged in user: mpd352 2020-09-30 22:09:44,285 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.0.114 2020-09-30 22:09:44,314 - octoprint.server.util.flask - INFO - Passively logging in user mpd352 from ::ffff:192.168.0.114 2020-09-30 22:09:44,314 - octoprint.access.users - INFO - Logged in user: mpd352 2020-09-30 22:09:46,049 - octoprint.plugins.announcements - INFO - Loaded channel _important from https://octoprint.org/feeds/important.xml in 0.6s 2020-09-30 22:09:46,302 - octoprint.plugins.PrintJobHistory - INFO - Plugin-State: PreHeat=enabled DisplayLayerProgress=enabled filamentmanager=enabled ultimakerformat=enabled PrusaSlicerThumbnails=enabled 2020-09-30 22:09:46,842 - octoprint.plugins.announcements - INFO - Loaded channel _releases from https://octoprint.org/feeds/releases.xml in 0.6s 2020-09-30 22:09:47,547 - octoprint.plugins.announcements - INFO - Loaded channel _blog from https://octoprint.org/feeds/octoblog.xml in 0.62s 2020-09-30 22:09:48,389 - octoprint.plugins.announcements - INFO - Loaded channel _plugins from https://plugins.octoprint.org/feed.xml in 0.79s 2020-09-30 22:09:49,047 - octoprint.plugins.announcements - INFO - Loaded channel _octopi from https://octoprint.org/feeds/octopi.xml in 0.6s 2020-09-30 22:09:49,933 - octoprint.plugins.pluginmanager - INFO - Loaded plugin notices data from https://plugins.octoprint.org/notices.json 2020-09-30 22:09:50,668 - octoprint.server.util.sockjs - INFO - User mpd352 logged in on the socket from client ::ffff:192.168.0.114 2020-09-30 22:24:31,557 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 22:24:39,475 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 588609} 2020-09-30 22:30:43,425 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:192.168.0.114 2020-09-30 22:39:31,559 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-09-30 22:39:39,364 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 589509} 2020-09-30 22:45:25,474 - tornado.access - WARNING - 400 POST /plugin/filamentmanager/database/test (::ffff:192.168.0.195) 35.89ms 2020-09-30 22:45:29,220 - tornado.access - WARNING - 400 POST /plugin/filamentmanager/database/test (::ffff:192.168.0.195) 32.55ms

Screenshots If applicable, add screenshots to help explain your problem. image

Additional context Add any other context about the problem here.

mpd352 commented 4 years ago

Olli:

I spun up a clean instant of Octoprint and installed postgresql on it after updating everything on the instance except updating to Python3. It's running Python2.7. I'm running that as a standalone Octprint instance not attached to a printer, hosting the external filament database only.

I'm running two Octoprint instances, one attached to a Qidi X-One2 and a second attached to an Ender 3 Pro. The Qidi octoprint has not been updated to Python3. It immediately attached to the new external database.

The Ender 3 Pro octoprint was upgraded to Phyton3, and was the original external database server. It would not attached to the new external database server, and the non Python3 octoprint could not attach to it.

I spun up another new instant of octoprint for the Ender 3 Pro. I updated everything on it except updating to Phython3. It's running Python2.7. It immediately connected to the new external database server.

To summarize what I figured out is a client running Phython3 will not connect to an external database server period. If the database server is running Python3, no clients can connect to it regardless of what version of Python they are running.

For a connection to an external database server to work neither the client or server can be running Python3.

The issue may be in the configuration of Filament Manager under Python3, but if so, I'm not smart enough to figure out what the issue is.

Thanks,

Kevin

OllisGit commented 4 years ago

Hi @mpd352 ,

I could not reproduce the issue. I create a fresh database and a fresh OP with FilamentManager on Py3.6 and everything works as expected.

On which network port is your database running? Do you use the default port: 5432? If not, just add your port behind the IP-Adresss, like this: postgresql://192.168.0.73:6432

If this is not the issue, please increase the log-level for FilamentManager and restart OP. image

PS. next time, instead of pasting the full log-message to the textbox, just attach it. Thx, in advance

mpd352 commented 4 years ago

OllisGit:

My postgresql instances are running on port 5432, and I've tried it both with and without. No joy.

OctoPI01-10-07-2020.log is from the server running my Qidi X-One2. OctoPI02-10-07-2020.log is from the server running my Ender 3. OctoPI03-10-07-2020.log is from the stand alone database server, it is not attached to any printer.

OctoPI03-10-07-2020.log OctoPI02-10-07-2020.log OctoPI01-10-07-2020.log

Thanks for the help

OllisGit commented 4 years ago

Hi @mpd352 ,

I looked into your provided log files and found the main reasons why the plugin is not working:

OctoPI01-10-07-2020.log
=======================
2020-10-07 11:14:23,730 - octoprint.plugins.filamentmanager - ERROR - Failed to initialize database: (psycopg2.OperationalError) could not connect to server: Network is unreachable
    Is the server running on host "192.168.0.75" and accepting
    TCP/IP connections on port 5432?

OctoPI02-10-07-2020.log
=======================
2020-10-07 11:15:34,920 - octoprint.plugins.filamentmanager - ERROR - Failed to initialize database: No module named 'psycopg2'

OctoPI03-10-07-2020.log
=======================
2020-10-07 11:13:25,693 - octoprint.plugins.filamentmanager - ERROR - Failed to initialize database: No module named 'psycopg2'

Log01) network issue: connect to the raspi and try to connect to the database via telnet like this: telnet ip port

Log02, 03) database module is not installed in your python environment. In most cases the module psycopg2 is installed during plugin installation, but sometimes OP has some hickups and you need to install it manually. try this:

~/OctoPrint/venv/bin/pip install psycopg2

More details, see https://github.com/OllisGit/OctoPrint-FilamentManager/issues/4 or in the wiki https://github.com/OllisGit/OctoPrint-FilamentManager/wiki/Setup-PostgreSQL-on-Raspbian-(Stretch)

mpd352 commented 4 years ago

OllisGit

~/OctoPrint/venv/bin/pip install psycopg2 returned an error of command not found. A little bit of poking around Google led me to believe that there was no virtual environment installed.

I filched these lines from another article on the OctoPrint forum:

cd ~ sudo apt update sudo apt install python-pip python-dev python-setuptools python-virtualenv git libyaml-dev build-essential cd OctoPrint virtualenv venv source venv/bin/activate ~/OctoPrint/venv/bin/pip install psycopg2

And that seemed to install psycopg2

Still doesn't work. I've attached new logs.

Thank you, Kevin Conde Peoria, AZ

From: OllisGit notifications@github.com Sent: Wednesday, October 7, 2020 2:38 PM To: OllisGit/OctoPrint-FilamentManager OctoPrint-FilamentManager@noreply.github.com Cc: Kevin Conde kevin@kcconde.net; Mention mention@noreply.github.com Subject: Re: [OllisGit/OctoPrint-FilamentManager] Filament Manager can no longer access postgresql database on external Raspberry after Python 3 Upgrade (#7)

Hi @mpd352https://github.com/mpd352 ,

I looked into your provided log files and found the main reasons why the plugin is not working:

OctoPI01-10-07-2020.log

=======================

2020-10-07 11:14:23,730 - octoprint.plugins.filamentmanager - ERROR - Failed to initialize database: (psycopg2.OperationalError) could not connect to server: Network is unreachable

    Is the server running on host "192.168.0.75" and accepting

    TCP/IP connections on port 5432?

OctoPI02-10-07-2020.log

=======================

2020-10-07 11:15:34,920 - octoprint.plugins.filamentmanager - ERROR - Failed to initialize database: No module named 'psycopg2'

OctoPI03-10-07-2020.log

=======================

2020-10-07 11:13:25,693 - octoprint.plugins.filamentmanager - ERROR - Failed to initialize database: No module named 'psycopg2'

  1. network issue: connect to the raspi and try to connect to the database via telnet like this: telnet ip port 02, 03) database module is not installed in your python environment. In most cases the module psycopg2 is installed during plugin installation, but sometimes OP has some hickups and you need to install it manually. try this:

~/OctoPrint/venv/bin/pip install psycopg2

More details, see #4https://github.com/OllisGit/OctoPrint-FilamentManager/issues/4 or in the wiki https://github.com/OllisGit/OctoPrint-FilamentManager/wiki/Setup-PostgreSQL-on-Raspbian-(Stretch)

— You are receiving this because you were mentioned. Reply to this email directly, view it on GitHubhttps://github.com/OllisGit/OctoPrint-FilamentManager/issues/7#issuecomment-705207904, or unsubscribehttps://github.com/notifications/unsubscribe-auth/AJ2OVLKRBQZTX2W6DSEWF6DSJTNUFANCNFSM4R7UKDNQ.

OllisGit commented 4 years ago

Hi @mpd352, you answered with the mail-client instead of using the GitHub-UI -> there is no attachment.

mpd352 commented 4 years ago

Hi OllisGit:

Sorry, here they are. Thanks for you help.

OctoPI02-10-07-2020.log OctoPI03-10-07-2020.log

OllisGit commented 4 years ago

Same issue (No module named 'psycopg2'), because OP use a different python environment.

In the logs you can find this entry virtualenv: /home/pi/oprint So, you need to install the package in this environment.

    cd /home/pi/oprint/bin
    source activate

Show all modules
    pip list

Search for psycopg2
    pip list | grep  psycopg2

Install
    pip install psycopg2-binary

See also the forum entry: https://community.octoprint.org/t/filament-manager-cant-connect-to-postgresql-db-after-python-3-update/24773/10

mpd352 commented 4 years ago

OllisGit:

That worked! I did have to make one minor change to the last command from:

pip install psycopg2-binary

to:

sudo pip install psycopg2-binary

to get around a failed permission error.

Thank you,

Kevin.

scaytrase commented 4 years ago

Actually for similar reason I cannot upgrade the plugin to the latest release

2020-10-09 02:06:38,024   /home/pi/oprint/bin/python2 -m pip --disable-pip-version-check install https://github.com/OllisGit/OctoPrint-FilamentManager/releases/latest/download/master.zip --no-cache-dir
2020-10-09 02:06:40,061 > Looking in indexes: https://pypi.org/simple, https://www.piwheels.org/simple
2020-10-09 02:06:40,063 > Collecting https://github.com/OllisGit/OctoPrint-FilamentManager/releases/latest/download/master.zip
2020-10-09 02:06:41,065 > Downloading https://github.com/OllisGit/OctoPrint-FilamentManager/releases/latest/download/master.zip (245kB)
2020-10-09 02:06:42,447 > Requirement already satisfied: OctoPrint in /home/pi/oprint/lib/python2.7/site-packages (from OctoPrint-FilamentManager==1.6.2) (1.4.2)
2020-10-09 02:06:42,448 > Requirement already satisfied: backports.csv<1.1,>=1.0.5 in /home/pi/oprint/lib/python2.7/site-packages (from OctoPrint-FilamentManager==1.6.2) (1.0.7)
2020-10-09 02:06:42,449 > Requirement already satisfied: uritools<2.2,>=2.1 in /home/pi/oprint/lib/python2.7/site-packages (from OctoPrint-FilamentManager==1.6.2) (2.1.1)
2020-10-09 02:06:42,449 > Requirement already satisfied: SQLAlchemy<1.2,>=1.1.15 in /home/pi/oprint/lib/python2.7/site-packages (from OctoPrint-FilamentManager==1.6.2) (1.1.18)
2020-10-09 02:06:44,179 > Collecting psycopg2-binary
2020-10-09 02:06:44,180 > Downloading https://files.pythonhosted.org/packages/fc/51/0f2c6aec5c59e5640f507b59567f63b9d73a9317898810b4db311da32dfc/psycopg2-binary-2.8.6.tar.gz (384kB)
2020-10-09 02:06:45,184 ! ERROR: Command errored out with exit status 1:
2020-10-09 02:06:45,185 ! command: /home/pi/oprint/bin/python2 -c 'import sys, setuptools, tokenize; sys.argv[0] = '"'"'/tmp/pip-install-5CmQNJ/psycopg2-binary/setup.py'"'"'; __file__='"'"'/tmp/pip-install-5CmQNJ/psycopg2-binary/setup.py'"'"';f=getattr(tokenize, '"'"'open'"'"', open)(__file__);code=f.read().replace('"'"'\r\n'"'"', '"'"'\n'"'"');f.close();exec(compile(code, __file__, '"'"'exec'"'"'))' egg_info --egg-base /tmp/pip-install-5CmQNJ/psycopg2-binary/pip-egg-info
2020-10-09 02:06:45,186 ! cwd: /tmp/pip-install-5CmQNJ/psycopg2-binary/
2020-10-09 02:06:45,186 ! Complete output (23 lines):
2020-10-09 02:06:45,186 ! running egg_info
2020-10-09 02:06:45,187 ! creating /tmp/pip-install-5CmQNJ/psycopg2-binary/pip-egg-info/psycopg2_binary.egg-info
2020-10-09 02:06:45,187 ! writing /tmp/pip-install-5CmQNJ/psycopg2-binary/pip-egg-info/psycopg2_binary.egg-info/PKG-INFO
2020-10-09 02:06:45,188 ! writing top-level names to /tmp/pip-install-5CmQNJ/psycopg2-binary/pip-egg-info/psycopg2_binary.egg-info/top_level.txt
2020-10-09 02:06:45,188 ! writing dependency_links to /tmp/pip-install-5CmQNJ/psycopg2-binary/pip-egg-info/psycopg2_binary.egg-info/dependency_links.txt
2020-10-09 02:06:45,188 ! writing manifest file '/tmp/pip-install-5CmQNJ/psycopg2-binary/pip-egg-info/psycopg2_binary.egg-info/SOURCES.txt'
2020-10-09 02:06:45,189 ! 
2020-10-09 02:06:45,189 ! Error: pg_config executable not found.
2020-10-09 02:06:45,189 ! 
2020-10-09 02:06:45,190 ! pg_config is required to build psycopg2 from source.  Please add the directory
2020-10-09 02:06:45,190 ! containing pg_config to the $PATH or specify the full executable path with the
2020-10-09 02:06:45,190 ! option:
2020-10-09 02:06:45,191 ! 
2020-10-09 02:06:45,191 ! python setup.py build_ext --pg-config /path/to/pg_config build ...
2020-10-09 02:06:45,192 ! 
2020-10-09 02:06:45,192 ! or with the pg_config option in 'setup.cfg'.
2020-10-09 02:06:45,192 ! 
2020-10-09 02:06:45,193 ! If you prefer to avoid building psycopg2 from source, please install the PyPI
2020-10-09 02:06:45,193 ! 'psycopg2-binary' package instead.
2020-10-09 02:06:45,193 ! 
2020-10-09 02:06:45,194 ! For further information please check the 'doc/src/install.rst' file (also at
2020-10-09 02:06:45,194 ! <https://www.psycopg.org/docs/install.html>).
2020-10-09 02:06:45,194 ! 
2020-10-09 02:06:45,195 ! ----------------------------------------
2020-10-09 02:06:45,195 ! ERROR: Command errored out with exit status 1: python setup.py egg_info Check the logs for full command output.

it suggest me install psycopg2

scaytrase commented 4 years ago

Solved by 

apt install libpq-dev
mpd352 commented 4 years ago

OllisGit:

Me again. The 1.6.2 update hit today. 2 of the 3 instances I'm running upgraded fine. One did not.

When it failed install from the plugin manager I tried installing it from the command line. That failed, so I uninstalled the plugin and tried again.

That also failed from the manager and command line.

SOrry to bother you, but I'm just not that familiar with linuix to figure out the small details. Log is attached. octoprint.log

Kevin

mpd352 commented 4 years ago

I resolved the issue through the back door. I am running three instances of Octoprint. Two are connected to printers, the third is just a database server for filament manager. The filament manager instance and one of the printer instances are new, having been built up over the last 10 days in my fight to get filament manager to run under Python3.

Both of those instances successfully upgraded to 1.6.2. I just cloned the working printer instance, and reconfigured it, and now all three instances of Octoprint are connecting to the filament manager database.

Thanks,

Kevin

OllisGit commented 4 years ago

Glad to hear that you figured out the problem!

excalbian commented 4 years ago

Just came to read this thread after the upgrade to 1.6.2 broke for me. Looks like most people have resolved this by installing pyscopg manually, so that's what I'm going to try next. I don't use the Postgres option for filament manager, so it seems a bit off that I have to install (at least part of?) Postgres to be able to use this plugin.

++++++++++++++++++++++++++++++++++++++
Now updating Filament Manager to 1.6.2
++++++++++++++++++++++++++++++++++++++
/home/pi/oprint/bin/python2 -m pip --disable-pip-version-check install https://github.com/OllisGit/OctoPrint-FilamentManager/releases/latest/download/master.zip --no-cache-dir
Looking in indexes: https://pypi.org/simple, https://www.piwheels.org/simple
Collecting https://github.com/OllisGit/OctoPrint-FilamentManager/releases/latest/download/master.zip
Downloading https://github.com/OllisGit/OctoPrint-FilamentManager/releases/latest/download/master.zip (245kB)
Requirement already satisfied: OctoPrint in /home/pi/oprint/lib/python2.7/site-packages (from OctoPrint-FilamentManager==1.6.2) (1.4.2)
Requirement already satisfied: backports.csv<1.1,>=1.0.5 in /home/pi/oprint/lib/python2.7/site-packages (from OctoPrint-FilamentManager==1.6.2) (1.0.7)
Requirement already satisfied: uritools<2.2,>=2.1 in /home/pi/oprint/lib/python2.7/site-packages (from OctoPrint-FilamentManager==1.6.2) (2.1.1)
Requirement already satisfied: SQLAlchemy<1.2,>=1.1.15 in /home/pi/oprint/lib/python2.7/site-packages (from OctoPrint-FilamentManager==1.6.2) (1.1.18)
Collecting psycopg2-binary
Downloading https://files.pythonhosted.org/packages/fc/51/0f2c6aec5c59e5640f507b59567f63b9d73a9317898810b4db311da32dfc/psycopg2-binary-2.8.6.tar.gz (384kB)
ERROR: Command errored out with exit status 1:
command: /home/pi/oprint/bin/python2 -c 'import sys, setuptools, tokenize; sys.argv[0] = '"'"'/tmp/pip-install-3iEUIc/psycopg2-binary/setup.py'"'"'; __file__='"'"'/tmp/pip-install-3iEUIc/psycopg2-binary/setup.py'"'"';f=getattr(tokenize, '"'"'open'"'"', open)(__file__);code=f.read().replace('"'"'\r\n'"'"', '"'"'\n'"'"');f.close();exec(compile(code, __file__, '"'"'exec'"'"'))' egg_info --egg-base /tmp/pip-install-3iEUIc/psycopg2-binary/pip-egg-info
cwd: /tmp/pip-install-3iEUIc/psycopg2-binary/
Complete output (23 lines):
running egg_info
creating /tmp/pip-install-3iEUIc/psycopg2-binary/pip-egg-info/psycopg2_binary.egg-info
writing /tmp/pip-install-3iEUIc/psycopg2-binary/pip-egg-info/psycopg2_binary.egg-info/PKG-INFO
writing top-level names to /tmp/pip-install-3iEUIc/psycopg2-binary/pip-egg-info/psycopg2_binary.egg-info/top_level.txt
writing dependency_links to /tmp/pip-install-3iEUIc/psycopg2-binary/pip-egg-info/psycopg2_binary.egg-info/dependency_links.txt
writing manifest file '/tmp/pip-install-3iEUIc/psycopg2-binary/pip-egg-info/psycopg2_binary.egg-info/SOURCES.txt'
Error: pg_config executable not found.
pg_config is required to build psycopg2 from source.  Please add the directory
containing pg_config to the $PATH or specify the full executable path with the
option:
python setup.py build_ext --pg-config /path/to/pg_config build ...
or with the pg_config option in 'setup.cfg'.
If you prefer to avoid building psycopg2 from source, please install the PyPI
'psycopg2-binary' package instead.
For further information please check the 'doc/src/install.rst' file (also at
<https://www.psycopg.org/docs/install.html>).
----------------------------------------
ERROR: Command errored out with exit status 1: python setup.py egg_info Check the logs for full command output.