Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

dark screen #160

Open
georgeoftown opened this issue Jun 24, 2020 · 2 comments
Open

dark screen #160

georgeoftown opened this issue Jun 24, 2020 · 2 comments

Comments

@georgeoftown
Copy link 

commit 1fc3d7cdc81c6486aebc5c56eb7d3346b0ed54fc
Author: Henric Andersson 
Date:   Thu Apr 30 11:22:17 2020 -0700

    Hot fix: Used object which can be null

On branch master
Your branch is up to date with 'origin/master'.

nothing to commit, working tree clean

Jun 24 19:48:28 localhost photoframe[978]: 2020-06-24 19:48:28,042 - INFO - Loading service GooglePhotos from svc_googlephotos.py
Jun 24 19:48:28 localhost photoframe[978]: 2020-06-24 19:48:28,076 - INFO - GPIO shutdown can be triggered by GPIO 3
Jun 24 19:48:28 localhost photoframe[978]: 2020-06-24 19:48:28,081 - INFO - Cache is set to True
Jun 24 19:48:28 localhost photoframe[978]: 2020-06-24 19:48:28,087 - INFO - No http-auth.json found, disabling http authentication
Jun 24 19:48:28 localhost photoframe[978]: Exception in thread Thread-1:
Jun 24 19:48:28 localhost photoframe[978]: Traceback (most recent call last):
Jun 24 19:48:28 localhost photoframe[978]:   File "/usr/lib/python2.7/threading.py", line 801, in __bootstrap_inner
Jun 24 19:48:28 localhost photoframe[978]:     self.run()
Jun 24 19:48:28 localhost photoframe[978]:   File "/root/photoframe/modules/colormatch.py", line 149, in run
Jun 24 19:48:28 localhost photoframe[978]:     ver = bus.read_byte(0x29)
Jun 24 19:48:28 localhost photoframe[978]: IOError: [Errno 5] Input/output error
Jun 24 19:48:28 localhost photoframe[978]: 2020-06-24 19:48:28,108 - INFO - Registering URL /debug to RouteDebug (simple)
Jun 24 19:48:28 localhost photoframe[978]: 2020-06-24 19:48:28,115 - INFO - Registering URL /options/ to RouteOptions (simple)
Jun 24 19:48:28 localhost photoframe[978]: 2020-06-24 19:48:28,118 - INFO - Registering URL /options// to RouteOptions (simple)
Jun 24 19:48:28 localhost photoframe[978]: 2020-06-24 19:48:28,131 - INFO - Registering URL / to RoutePages (simple)
Jun 24 19:48:28 localhost photoframe[978]: 2020-06-24 19:48:28,133 - INFO - Registering URL / to RoutePages (simple)
Jun 24 19:48:28 localhost photoframe[978]: 2020-06-24 19:48:28,155 - INFO - Registering URL /setting to RouteSettings
Jun 24 19:48:28 localhost photoframe[978]: 2020-06-24 19:48:28,157 - INFO - Registering URL /setting/ to RouteSettings
Jun 24 19:48:28 localhost photoframe[978]: 2020-06-24 19:48:28,159 - INFO - Registering URL /setting// to RouteSettings
Jun 24 19:48:28 localhost photoframe[978]: 2020-06-24 19:48:28,162 - INFO - Registering URL /keywords//help to RouteKeywords
Jun 24 19:48:28 localhost photoframe[978]: 2020-06-24 19:48:28,164 - INFO - Registering URL /keywords/ to RouteKeywords
Jun 24 19:48:28 localhost photoframe[978]: 2020-06-24 19:48:28,166 - INFO - Registering URL /keywords//add to RouteKeywords
Jun 24 19:48:28 localhost photoframe[978]: 2020-06-24 19:48:28,168 - INFO - Registering URL /keywords//delete to RouteKeywords
Jun 24 19:48:28 localhost photoframe[978]: 2020-06-24 19:48:28,170 - INFO - Registering URL /keywords//source/ to RouteKeywords
Jun 24 19:48:28 localhost photoframe[978]: 2020-06-24 19:48:28,173 - INFO - Registering URL /keywords//details/ to RouteKeywords
Jun 24 19:48:28 localhost photoframe[978]: 2020-06-24 19:48:28,176 - INFO - Registering URL /rotation to RouteOrientation
Jun 24 19:48:28 localhost photoframe[978]: 2020-06-24 19:48:28,178 - INFO - Registering URL /rotation/ to RouteOrientation
Jun 24 19:48:28 localhost photoframe[978]: 2020-06-24 19:48:28,180 - INFO - Registering URL /overscan to RouteOverscan
Jun 24 19:48:28 localhost photoframe[978]: 2020-06-24 19:48:28,181 - INFO - Registering URL /overscan/ to RouteOverscan
Jun 24 19:48:28 localhost photoframe[978]: 2020-06-24 19:48:28,184 - INFO - Registering URL /maintenance/ to RouteMaintenance
Jun 24 19:48:28 localhost photoframe[978]: 2020-06-24 19:48:28,186 - INFO - Registering URL /details/ to RouteDetails
Jun 24 19:48:28 localhost photoframe[978]: 2020-06-24 19:48:28,189 - INFO - Registering URL /upload/ to RouteUpload
Jun 24 19:48:28 localhost photoframe[978]: 2020-06-24 19:48:28,191 - INFO - Registering URL /callback to RouteOAuthLink
Jun 24 19:48:28 localhost photoframe[978]: 2020-06-24 19:48:28,192 - INFO - Registering URL /service//link to RouteOAuthLink
Jun 24 19:48:28 localhost photoframe[978]: 2020-06-24 19:48:28,195 - INFO - Registering URL /service//oauth to RouteOAuthLink
Jun 24 19:48:28 localhost photoframe[978]: 2020-06-24 19:48:28,197 - INFO - Registering URL /service/ to RouteService
Jun 24 19:48:28 localhost photoframe[978]: 2020-06-24 19:48:28,200 - INFO - Registering URL /control/ to RouteControl
Jun 24 19:48:28 localhost photoframe[978]: 2020-06-24 19:48:28,202 - INFO - Registering URL /events to RouteEvents
Jun 24 19:48:28 localhost photoframe[978]: 2020-06-24 19:48:28,203 - INFO - Registering URL /events/ to RouteEvents
Jun 24 19:48:28 localhost photoframe[978]: 2020-06-24 19:48:28,206 - INFO - Registering URL /events/remove/ to RouteEvents
Jun 24 19:48:28 localhost photoframe[978]:  * Serving Flask app "modules.server" (lazy loading)
Jun 24 19:48:28 localhost photoframe[978]:  * Environment: production
Jun 24 19:48:28 localhost photoframe[978]:    WARNING: Do not use the development server in a production environment.
Jun 24 19:48:28 localhost photoframe[978]:    Use a production WSGI server instead.
Jun 24 19:48:28 localhost photoframe[978]:  * Debug mode: off
Jun 24 19:48:28 localhost photoframe[978]: 2020-06-24 19:48:28,223 - ERROR - Failed to get IP via old method
Jun 24 19:48:28 localhost photoframe[978]: Traceback (most recent call last):
Jun 24 19:48:28 localhost photoframe[978]:   File "/root/photoframe/modules/helper.py", line 96, in _checkNetwork
Jun 24 19:48:28 localhost photoframe[978]:     s.connect(("photoframe.sensenet.nu", 80))
Jun 24 19:48:28 localhost photoframe[978]:   File "/usr/lib/python2.7/socket.py", line 228, in meth
Jun 24 19:48:28 localhost photoframe[978]:     return getattr(self._sock,name)(*args)
Jun 24 19:48:28 localhost photoframe[978]: gaierror: [Errno -2] Name or service not known
Jun 24 19:48:28 localhost photoframe[978]: 2020-06-24 19:48:28,253 - ERROR - Failed to get IP via old method
Jun 24 19:48:28 localhost photoframe[978]: Traceback (most recent call last):
Jun 24 19:48:28 localhost photoframe[978]:   File "/root/photoframe/modules/helper.py", line 96, in _checkNetwork
Jun 24 19:48:28 localhost photoframe[978]:     s.connect(("photoframe.sensenet.nu", 80))
Jun 24 19:48:28 localhost photoframe[978]:   File "/usr/lib/python2.7/socket.py", line 228, in meth
Jun 24 19:48:28 localhost photoframe[978]:     return getattr(self._sock,name)(*args)
Jun 24 19:48:28 localhost photoframe[978]: gaierror: [Errno -2] Name or service not known
Jun 24 19:48:28 localhost photoframe[978]: 2020-06-24 19:48:28,254 - ERROR - You must have functional internet connection to use this app
Jun 24 20:07:31 localhost photoframe[978]: 2020-06-24 20:07:31,767 - INFO - Network connection reestablished
Jun 24 20:07:38 localhost photoframe[978]: 2020-06-24 20:07:38,389 - ERROR - /opt/vc/bin/tvservice is missing! No HDMI resolutions will be available
Jun 24 20:07:38 localhost photoframe[978]: 2020-06-24 20:07:38,536 - ERROR - Unable to execute /opt/vc/bin/vcgencmd
Jun 24 20:07:38 localhost photoframe[978]: Traceback (most recent call last):
Jun 24 20:07:38 localhost photoframe[978]:   File "/root/photoframe/routes/details.py", line 74, in handle
Jun 24 20:07:38 localhost photoframe[978]:     output = subprocess.check_output(['/opt/vc/bin/vcgencmd', 'get_throttled'], stderr=self.void)
Jun 24 20:07:38 localhost photoframe[978]:   File "/usr/lib/python2.7/subprocess.py", line 216, in check_output
Jun 24 20:07:38 localhost photoframe[978]:     process = Popen(stdout=PIPE, *popenargs, **kwargs)
Jun 24 20:07:38 localhost photoframe[978]:   File "/usr/lib/python2.7/subprocess.py", line 394, in __init__
Jun 24 20:07:38 localhost photoframe[978]:     errread, errwrite)
Jun 24 20:07:38 localhost photoframe[978]:   File "/usr/lib/python2.7/subprocess.py", line 1047, in _execute_child
Jun 24 20:07:38 localhost photoframe[978]:     raise child_exception
Jun 24 20:07:38 localhost photoframe[978]: OSError: [Errno 2] No such file or directory
Jun 24 20:07:38 localhost photoframe[978]: 2020-06-24 20:07:38,547 - ERROR - Output from vcgencmd get_throttled has changed
Jun 24 20:07:41 localhost photoframe[978]: 2020-06-24 20:07:41,841 - INFO - Starting presentation
Jun 24 20:07:43 localhost photoframe[978]: Exception in thread Thread-5:
Jun 24 20:07:43 localhost photoframe[978]: Traceback (most recent call last):
Jun 24 20:07:43 localhost photoframe[978]:   File "/usr/lib/python2.7/threading.py", line 801, in __bootstrap_inner
Jun 24 20:07:43 localhost photoframe[978]:     self.run()
Jun 24 20:07:43 localhost photoframe[978]:   File "/usr/lib/python2.7/threading.py", line 754, in run
Jun 24 20:07:43 localhost photoframe[978]:     self.__target(*self.__args, **self.__kwargs)
Jun 24 20:07:43 localhost photoframe[978]:   File "/root/photoframe/modules/slideshow.py", line 293, in presentation
Jun 24 20:07:43 localhost photoframe[978]:     result = self.services.servicePrepareNextItem(self.settings.get('tempfolder'), self.supportedFormats, displaySize, randomize)
Jun 24 20:07:43 localhost photoframe[978]:   File "/root/photoframe/modules/servicemanager.py", line 442, in servicePrepareNextItem
Jun 24 20:07:43 localhost photoframe[978]:     result = svc.prepareNextItem(destinationDir, supportedMimeTypes, displaySize, randomize)
Jun 24 20:07:43 localhost photoframe[978]:   File "/root/photoframe/services/base.py", line 438, in prepareNextItem
Jun 24 20:07:43 localhost photoframe[978]:     result = self.selectRandomImageFromAlbum(destinationFile, supportedMimeTypes, displaySize)
Jun 24 20:07:43 localhost photoframe[978]:   File "/root/photoframe/services/base.py", line 537, in selectRandomImageFromAlbum
Jun 24 20:07:43 localhost photoframe[978]:     return self.fetchImage(image, destinationDir, supportedMimeTypes, displaySize)
Jun 24 20:07:43 localhost photoframe[978]:   File "/root/photoframe/services/base.py", line 579, in fetchImage
Jun 24 20:07:43 localhost photoframe[978]:     image.setMimetype(helper.getMimetype(image.filename))
Jun 24 20:07:43 localhost photoframe[978]:   File "/root/photoframe/modules/helper.py", line 127, in getMimetype
Jun 24 20:07:43 localhost photoframe[978]:     output = subprocess.check_output(cmd, stderr=void).strip("\n")
Jun 24 20:07:43 localhost photoframe[978]:   File "/usr/lib/python2.7/subprocess.py", line 216, in check_output
Jun 24 20:07:43 localhost photoframe[978]:     process = Popen(stdout=PIPE, *popenargs, **kwargs)
Jun 24 20:07:43 localhost photoframe[978]:   File "/usr/lib/python2.7/subprocess.py", line 394, in __init__
Jun 24 20:07:43 localhost photoframe[978]:     errread, errwrite)
Jun 24 20:07:43 localhost photoframe[978]:   File "/usr/lib/python2.7/subprocess.py", line 1047, in _execute_child
Jun 24 20:07:43 localhost photoframe[978]:     raise child_exception
Jun 24 20:07:43 localhost photoframe[978]: OSError: [Errno 2] No such file or directory

 Jun 24 19:48:30 localhost NetworkManager[629]:   [1593020910.1801] dhcp4 (wlxe84e067c2cc7):   domain name 'serrallo'
Jun 24 19:48:30 localhost NetworkManager[629]:   [1593020910.1802] dhcp4 (wlxe84e067c2cc7): state changed unknown -> bound
Jun 24 19:48:30 localhost NetworkManager[629]:   [1593020910.1887] device (wlxe84e067c2cc7): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed')
Jun 24 19:48:30 localhost NetworkManager[629]:   [1593020910.1965] device (wlxe84e067c2cc7): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed')
Jun 24 19:48:30 localhost NetworkManager[629]:   [1593020910.1983] device (wlxe84e067c2cc7): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed')
Jun 24 19:48:30 localhost NetworkManager[629]:   [1593020910.2014] manager: NetworkManager state is now CONNECTED_LOCAL
Jun 24 19:48:30 localhost dhclient[1135]: bound to 192.168.0.27 -- renewal in 39224 seconds.
Jun 24 19:48:30 localhost NetworkManager[629]:   [1593020910.2316] manager: NetworkManager state is now CONNECTED_SITE
Jun 24 19:48:30 localhost NetworkManager[629]:   [1593020910.2325] policy: set 'Cacahuete' (wlxe84e067c2cc7) as default for IPv4 routing and DNS
Jun 24 19:48:30 localhost NetworkManager[629]:   [1593020910.2461] device (wlxe84e067c2cc7): Activation: successful, device activated.
Jun 24 19:48:30 localhost NetworkManager[629]:   [1593020910.2505] manager: NetworkManager state is now CONNECTED_GLOBAL
Jun 24 19:48:30 localhost nm-dispatcher: req:3 'up' [wlxe84e067c2cc7]: new request (2 scripts)
Jun 24 19:48:30 localhost nm-dispatcher: req:3 'up' [wlxe84e067c2cc7]: start running ordered scripts...
Jun 24 19:48:30 localhost nm-dispatcher: req:4 'connectivity-change': new request (2 scripts)
Jun 24 19:48:30 localhost nm-dispatcher: req:4 'connectivity-change': start running ordered scripts...
Jun 24 19:48:31 localhost NetworkManager[629]:   [1593020911.5817] manager: startup complete
Jun 24 19:48:31 localhost systemd[1]: Started Network Manager Wait Online.
Jun 24 19:48:31 localhost systemd[1]: Reached target Network is Online.
Jun 24 19:48:31 localhost systemd[1]: Starting LSB: Advanced IEEE 802.11 management daemon...
Jun 24 19:48:31 localhost systemd[1]: Starting /etc/rc.local Compatibility...
Jun 24 19:48:31 localhost systemd[1]: Condition check resulted in Armbian first run optional user configuration being skipped.
Jun 24 19:48:31 localhost systemd[1]: Started LSB: Advanced IEEE 802.11 management daemon.
Jun 24 19:48:31 localhost systemd[1]: Started /etc/rc.local Compatibility.
Jun 24 19:48:31 localhost systemd[1]: Started Serial Getty on ttyS0.
Jun 24 19:48:31 localhost systemd[1]: Reached target Login Prompts.
Jun 24 19:48:31 localhost systemd[1]: Reached target Multi-User System.
Jun 24 19:48:31 localhost systemd[1]: Reached target Graphical Interface.
Jun 24 19:48:31 localhost systemd[1]: Starting Update UTMP about System Runlevel Changes...
Jun 24 19:48:31 localhost systemd[1]: systemd-update-utmp-runlevel.service: Succeeded.
Jun 24 19:48:31 localhost systemd[1]: Started Update UTMP about System Runlevel Changes.
Jun 24 19:48:31 localhost systemd[1]: Startup finished in 5.870s (kernel) + 20.422s (userspace) = 26.292s.
Jun 24 20:07:28 localhost chronyd[987]: Selected source 162.159.200.1
Jun 24 20:07:28 localhost chronyd[987]: System clock wrong by 1133.341122 seconds, adjustment started
Jun 24 20:07:28 localhost chronyd[987]: System clock was stepped by 1133.341122 seconds
Jun 24 20:07:28 localhost systemd[1]: Created slice User Slice of UID 0.
Jun 24 20:07:28 localhost systemd[1]: Starting User Runtime Directory /run/user/0...
Jun 24 20:07:28 localhost systemd[1]: Started User Runtime Directory /run/user/0.
Jun 24 20:07:29 localhost systemd[1]: Starting User Manager for UID 0...
Jun 24 20:07:29 localhost systemd[1221]: Reached target Timers.
Jun 24 20:07:29 localhost systemd[1221]: Listening on GnuPG cryptographic agent and passphrase cache.
Jun 24 20:07:29 localhost systemd[1221]: Listening on GnuPG cryptographic agent and passphrase cache (restricted).
Jun 24 20:07:29 localhost systemd[1221]: Reached target Paths.
Jun 24 20:07:29 localhost systemd[1221]: Listening on GnuPG cryptographic agent (ssh-agent emulation).
Jun 24 20:07:29 localhost systemd[1221]: Listening on GnuPG network certificate management daemon.
Jun 24 20:07:29 localhost systemd[1221]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers).
Jun 24 20:07:29 localhost systemd[1221]: Reached target Sockets.
Jun 24 20:07:29 localhost systemd[1221]: Reached target Basic System.
Jun 24 20:07:29 localhost systemd[1221]: Reached target Default.
Jun 24 20:07:29 localhost systemd[1221]: Startup finished in 269ms.
Jun 24 20:07:29 localhost systemd[1]: Started User Manager for UID 0.
Jun 24 20:07:29 localhost systemd[1]: Started Session 2 of user root.
Jun 24 20:07:31 localhost photoframe[978]: 2020-06-24 20:07:31,767 - INFO - Network connection reestablished
Jun 24 20:07:32 localhost kernel: [   33.780570] usb0-vbus: disabling
Jun 24 20:07:32 localhost kernel: [   33.780577] vcc3v0: disabling
Jun 24 20:07:32 localhost kernel: [   33.780582] vcc5v0: disabling
Jun 24 20:07:33 localhost systemd[1]: NetworkManager-dispatcher.service: Succeeded.
Jun 24 20:07:38 localhost photoframe[978]: 2020-06-24 20:07:38,389 - ERROR - /opt/vc/bin/tvservice is missing! No HDMI resolutions will be available
Jun 24 20:07:38 localhost dbus-daemon[621]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.13' (uid=0 pid=1329 comm="/usr/bin/timedatectl list-timezones " label="kernel")
Jun 24 20:07:38 localhost systemd[1]: Starting Time & Date Service...
Jun 24 20:07:38 localhost photoframe[978]: 2020-06-24 20:07:38,536 - ERROR - Unable to execute /opt/vc/bin/vcgencmd
Jun 24 20:07:38 localhost photoframe[978]: Traceback (most recent call last):
Jun 24 20:07:38 localhost photoframe[978]:   File "/root/photoframe/routes/details.py", line 74, in handle
Jun 24 20:07:38 localhost photoframe[978]:     output = subprocess.check_output(['/opt/vc/bin/vcgencmd', 'get_throttled'], stderr=self.void)
Jun 24 20:07:38 localhost photoframe[978]:   File "/usr/lib/python2.7/subprocess.py", line 216, in check_output
Jun 24 20:07:38 localhost photoframe[978]:     process = Popen(stdout=PIPE, *popenargs, **kwargs)
Jun 24 20:07:38 localhost photoframe[978]:   File "/usr/lib/python2.7/subprocess.py", line 394, in __init__
Jun 24 20:07:38 localhost photoframe[978]:     errread, errwrite)
Jun 24 20:07:38 localhost photoframe[978]:   File "/usr/lib/python2.7/subprocess.py", line 1047, in _execute_child
Jun 24 20:07:38 localhost photoframe[978]:     raise child_exception
Jun 24 20:07:38 localhost photoframe[978]: OSError: [Errno 2] No such file or directory
Jun 24 20:07:38 localhost photoframe[978]: 2020-06-24 20:07:38,547 - ERROR - Output from vcgencmd get_throttled has changed
Jun 24 20:07:38 localhost dbus-daemon[621]: [system] Successfully activated service 'org.freedesktop.timedate1'
Jun 24 20:07:38 localhost systemd[1]: Started Time & Date Service.
Jun 24 20:07:41 localhost photoframe[978]: 2020-06-24 20:07:41,841 - INFO - Starting presentation
Jun 24 20:07:43 localhost photoframe[978]: Exception in thread Thread-5:
Jun 24 20:07:43 localhost photoframe[978]: Traceback (most recent call last):
Jun 24 20:07:43 localhost photoframe[978]:   File "/usr/lib/python2.7/threading.py", line 801, in __bootstrap_inner
Jun 24 20:07:43 localhost photoframe[978]:     self.run()
Jun 24 20:07:43 localhost photoframe[978]:   File "/usr/lib/python2.7/threading.py", line 754, in run
Jun 24 20:07:43 localhost photoframe[978]:     self.__target(*self.__args, **self.__kwargs)
Jun 24 20:07:43 localhost photoframe[978]:   File "/root/photoframe/modules/slideshow.py", line 293, in presentation
Jun 24 20:07:43 localhost photoframe[978]:     result = self.services.servicePrepareNextItem(self.settings.get('tempfolder'), self.supportedFormats, displaySize, randomize)
Jun 24 20:07:43 localhost photoframe[978]:   File "/root/photoframe/modules/servicemanager.py", line 442, in servicePrepareNextItem
Jun 24 20:07:43 localhost photoframe[978]:     result = svc.prepareNextItem(destinationDir, supportedMimeTypes, displaySize, randomize)
Jun 24 20:07:43 localhost photoframe[978]:   File "/root/photoframe/services/base.py", line 438, in prepareNextItem
Jun 24 20:07:43 localhost photoframe[978]:     result = self.selectRandomImageFromAlbum(destinationFile, supportedMimeTypes, displaySize)
Jun 24 20:07:43 localhost photoframe[978]:   File "/root/photoframe/services/base.py", line 537, in selectRandomImageFromAlbum
Jun 24 20:07:43 localhost photoframe[978]:     return self.fetchImage(image, destinationDir, supportedMimeTypes, displaySize)
Jun 24 20:07:43 localhost photoframe[978]:   File "/root/photoframe/services/base.py", line 579, in fetchImage
Jun 24 20:07:43 localhost photoframe[978]:     image.setMimetype(helper.getMimetype(image.filename))
Jun 24 20:07:43 localhost photoframe[978]:   File "/root/photoframe/modules/helper.py", line 127, in getMimetype
Jun 24 20:07:43 localhost photoframe[978]:     output = subprocess.check_output(cmd, stderr=void).strip("\n")
Jun 24 20:07:43 localhost photoframe[978]:   File "/usr/lib/python2.7/subprocess.py", line 216, in check_output
Jun 24 20:07:43 localhost photoframe[978]:     process = Popen(stdout=PIPE, *popenargs, **kwargs)
Jun 24 20:07:43 localhost photoframe[978]:   File "/usr/lib/python2.7/subprocess.py", line 394, in __init__
Jun 24 20:07:43 localhost photoframe[978]:     errread, errwrite)
Jun 24 20:07:43 localhost photoframe[978]:   File "/usr/lib/python2.7/subprocess.py", line 1047, in _execute_child
Jun 24 20:07:43 localhost photoframe[978]:     raise child_exception
Jun 24 20:07:43 localhost photoframe[978]: OSError: [Errno 2] No such file or directory
Jun 24 20:07:48 localhost systemd[1]: systemd-hostnamed.service: Succeeded.

 # ThreadID: -1325407136
File: "/usr/lib/python2.7/threading.py", line 774, in __bootstrap
  self.__bootstrap_inner()
File: "/usr/lib/python2.7/threading.py", line 801, in __bootstrap_inner
  self.run()
File: "/usr/lib/python2.7/threading.py", line 754, in run
  self.__target(*self.__args, **self.__kwargs)
File: "/usr/lib/python2.7/SocketServer.py", line 599, in process_request_thread
  self.finish_request(request, client_address)
File: "/usr/lib/python2.7/SocketServer.py", line 334, in finish_request
  self.RequestHandlerClass(request, client_address, self)
File: "/usr/lib/python2.7/SocketServer.py", line 655, in __init__
  self.handle()
File: "/usr/lib/python2.7/dist-packages/werkzeug/serving.py", line 293, in handle
  rv = BaseHTTPRequestHandler.handle(self)
File: "/usr/lib/python2.7/BaseHTTPServer.py", line 340, in handle
  self.handle_one_request()
File: "/usr/lib/python2.7/dist-packages/werkzeug/serving.py", line 324, in handle_one_request
  self.raw_requestline = self.rfile.readline()
File: "/usr/lib/python2.7/socket.py", line 451, in readline
  data = self._sock.recv(self._rbufsize)

# ThreadID: -1243917216
File: "/usr/lib/python2.7/threading.py", line 774, in __bootstrap
  self.__bootstrap_inner()
File: "/usr/lib/python2.7/threading.py", line 801, in __bootstrap_inner
  self.run()
File: "/usr/lib/python2.7/threading.py", line 754, in run
  self.__target(*self.__args, **self.__kwargs)
File: "/usr/lib/python2.7/SocketServer.py", line 599, in process_request_thread
  self.finish_request(request, client_address)
File: "/usr/lib/python2.7/SocketServer.py", line 334, in finish_request
  self.RequestHandlerClass(request, client_address, self)
File: "/usr/lib/python2.7/SocketServer.py", line 655, in __init__
  self.handle()
File: "/usr/lib/python2.7/dist-packages/werkzeug/serving.py", line 293, in handle
  rv = BaseHTTPRequestHandler.handle(self)
File: "/usr/lib/python2.7/BaseHTTPServer.py", line 340, in handle
  self.handle_one_request()
File: "/usr/lib/python2.7/dist-packages/werkzeug/serving.py", line 328, in handle_one_request
  return self.run_wsgi()
File: "/usr/lib/python2.7/dist-packages/werkzeug/serving.py", line 270, in run_wsgi
  execute(self.server.app)
File: "/usr/lib/python2.7/dist-packages/werkzeug/serving.py", line 258, in execute
  application_iter = app(environ, start_response)
File: "/usr/lib/python2.7/dist-packages/flask/app.py", line 2309, in __call__
  return self.wsgi_app(environ, start_response)
File: "/usr/lib/python2.7/dist-packages/flask/app.py", line 2292, in wsgi_app
  response = self.full_dispatch_request()
File: "/usr/lib/python2.7/dist-packages/flask/app.py", line 1813, in full_dispatch_request
  rv = self.dispatch_request()
File: "/usr/lib/python2.7/dist-packages/flask/app.py", line 1799, in dispatch_request
  return self.view_functions[rule.endpoint](**req.view_args)
File: "/root/photoframe/routes/baseroute.py", line 62, in __call__
  return self.handle(self.app, **kwargs)
File: "/root/photoframe/routes/debug.py", line 34, in handle
  report.append(debug.stacktrace())
File: "/root/photoframe/modules/debug.py", line 48, in stacktrace
  for filename, lineno, name, line in traceback.extract_stack(stack):

# ThreadID: -1264585632
File: "/usr/lib/python2.7/threading.py", line 774, in __bootstrap
  self.__bootstrap_inner()
File: "/usr/lib/python2.7/threading.py", line 801, in __bootstrap_inner
  self.run()
File: "/root/photoframe/modules/shutdown.py", line 59, in run
  i = poller.poll(None)

# ThreadID: -1225637872
File: "./frame.py", line 185, in 
  frame.start()
File: "./frame.py", line 182, in start
  self.webServer.start()
File: "/root/photoframe/modules/server.py", line 84, in start
  self.run()
File: "/root/photoframe/modules/server.py", line 101, in run
  self.app.run(debug=False, port=self.port, host=self.listen )
File: "/usr/lib/python2.7/dist-packages/flask/app.py", line 943, in run
  run_simple(host, port, self, **options)
File: "/usr/lib/python2.7/dist-packages/werkzeug/serving.py", line 814, in run_simple
  inner()
File: "/usr/lib/python2.7/dist-packages/werkzeug/serving.py", line 777, in inner
  srv.serve_forever()
File: "/usr/lib/python2.7/dist-packages/werkzeug/serving.py", line 612, in serve_forever
  HTTPServer.serve_forever(self)
File: "/usr/lib/python2.7/SocketServer.py", line 231, in serve_forever
  poll_interval)
File: "/usr/lib/python2.7/SocketServer.py", line 150, in _eintr_retry
  return func(*args)

# ThreadID: -1254099872
File: "/usr/lib/python2.7/threading.py", line 774, in __bootstrap
  self.__bootstrap_inner()
File: "/usr/lib/python2.7/threading.py", line 801, in __bootstrap_inner
  self.run()
File: "/root/photoframe/modules/timekeeper.py", line 118, in run
  time.sleep(60) # every minute
@mrworf
Copy link
Owner

mrworf commented Jun 24, 2020

What are you running this on? Seeing as I see that the tvservice is missing. Please try to provide more details since all the logs do is show me why the app potentially died. Knowing what you did at the time and what you're running this on helps a lot 😄

@lp27
Copy link

lp27 commented Jun 24, 2020 •
edited

I am seeing the same issue

Jun 24 14:10:14 raspberrypilcd35 photoframe[439]: self.flush()
Jun 24 14:10:14 raspberrypilcd35 photoframe[439]: File "/usr/lib/python2.7/socket.py", line 307, in flush
Jun 24 14:10:14 raspberrypilcd35 photoframe[439]: self._sock.sendall(view[write_offset:write_offset+buffer_size])
Jun 24 14:10:14 raspberrypilcd35 photoframe[439]: error: [Errno 32] Broken pipe
Jun 24 14:10:18 raspberrypilcd35 photoframe[439]: 2020-06-24 14:10:18,377 - INFO - Internal display detected
Jun 24 14:10:18 raspberrypilcd35 photoframe[439]: 2020-06-24 14:10:18,387 - ERROR - Failed to decode JSON file, maybe it was corrupted? Size is 933888
Jun 24 14:10:18 raspberrypilcd35 photoframe[439]: Traceback (most recent call last):
Jun 24 14:10:18 raspberrypilcd35 photoframe[439]: File "/root/photoframe/services/svc_googlephotos.py", line 291, in getImagesFor
Jun 24 14:10:18 raspberrypilcd35 photoframe[439]: images = json.load(f)
Jun 24 14:10:18 raspberrypilcd35 photoframe[439]: File "/usr/lib/python2.7/json/init.py", line 291, in load
Jun 24 14:10:18 raspberrypilcd35 photoframe[439]: **kw)
Jun 24 14:10:18 raspberrypilcd35 photoframe[439]: File "/usr/lib/python2.7/json/init.py", line 339, in loads
Jun 24 14:10:18 raspberrypilcd35 photoframe[439]: return _default_decoder.decode(s)
Jun 24 14:10:18 raspberrypilcd35 photoframe[439]: File "/usr/lib/python2.7/json/decoder.py", line 364, in decode
Jun 24 14:10:18 raspberrypilcd35 photoframe[439]: obj, end = self.raw_decode(s, idx=_w(s, 0).end())
Jun 24 14:10:18 raspberrypilcd35 photoframe[439]: File "/usr/lib/python2.7/json/decoder.py", line 380, in raw_decode
Jun 24 14:10:18 raspberrypilcd35 photoframe[439]: obj, end = self.scan_once(s, idx)
Jun 24 14:10:18 raspberrypilcd35 photoframe[439]: ValueError: Unterminated string starting at: line 1 column 928930 (char 928929)
Jun 24 14:10:18 raspberrypilcd35 photoframe[439]: 2020-06-24 14:10:18,532 - ERROR - Since file is corrupt, we try to save a copy for later analysis (/root/photoframe_config/services/1a5adc2f91ad7fdc71f94b805a7fa816a5b569d7/private/b220ee2eb110e04999d6aaa434a9d965cd98a2e3.json.corrupt)
Jun 24 14:10:21 raspberrypilcd35 photoframe[439]: 2020-06-24 14:10:21,926 - INFO - Internal display detected
Jun 24 14:10:38 raspberrypilcd35 photoframe[439]: Traceback (most recent call last):
Jun 24 14:10:38 raspberrypilcd35 photoframe[439]: File "/usr/lib/python2.7/SocketServer.py", line 599, in process_request_thread
Jun 24 14:10:38 raspberrypilcd35 photoframe[439]: self.finish_request(request, client_address)
Jun 24 14:10:38 raspberrypilcd35 photoframe[439]: File "/usr/lib/python2.7/SocketServer.py", line 334, in finish_request
Jun 24 14:10:38 raspberrypilcd35 photoframe[439]: self.RequestHandlerClass(request, client_address, self)
Jun 24 14:10:38 raspberrypilcd35 photoframe[439]: File "/usr/lib/python2.7/SocketServer.py", line 657, in init
Jun 24 14:10:38 raspberrypilcd35 photoframe[439]: self.finish()
Jun 24 14:10:38 raspberrypilcd35 photoframe[439]: File "/usr/lib/python2.7/SocketServer.py", line 716, in finish
Jun 24 14:10:38 raspberrypilcd35 photoframe[439]: self.wfile.close()
Jun 24 14:10:38 raspberrypilcd35 photoframe[439]: File "/usr/lib/python2.7/socket.py", line 283, in close
Jun 24 14:10:39 raspberrypilcd35 photoframe[439]: self.flush()
Jun 24 14:10:39 raspberrypilcd35 photoframe[439]: File "/usr/lib/python2.7/socket.py", line 307, in flush
Jun 24 14:10:39 raspberrypilcd35 photoframe[439]: self._sock.sendall(view[write_offset:write_offset+buffer_size])
Jun 24 14:10:39 raspberrypilcd35 photoframe[439]: error: [Errno 32] Broken pipe
Jun 24 14:10:39 raspberrypilcd35 photoframe[439]: 2020-06-24 14:10:39,862 - INFO - Internal display detected
Jun 24 14:10:43 raspberrypilcd35 photoframe[439]: 2020-06-24 14:10:43,850 - INFO - Internal display detected
Jun 24 14:10:53 raspberrypilcd35 photoframe[439]: 2020-06-24 14:10:53,244 - ERROR - Failed to decode JSON file, maybe it was corrupted? Size is 1496254
Jun 24 14:10:53 raspberrypilcd35 photoframe[439]: Traceback (most recent call last):
Jun 24 14:10:53 raspberrypilcd35 photoframe[439]: File "/root/photoframe/services/svc_googlephotos.py", line 291, in getImagesFor
Jun 24 14:10:53 raspberrypilcd35 photoframe[439]: images = json.load(f)
Jun 24 14:10:53 raspberrypilcd35 photoframe[439]: File "/usr/lib/python2.7/json/init.py", line 291, in load
Jun 24 14:10:53 raspberrypilcd35 photoframe[439]: **kw)
Jun 24 14:10:53 raspberrypilcd35 photoframe[439]: File "/usr/lib/python2.7/json/init.py", line 339, in loads
Jun 24 14:10:53 raspberrypilcd35 photoframe[439]: return _default_decoder.decode(s)
Jun 24 14:10:53 raspberrypilcd35 photoframe[439]: File "/usr/lib/python2.7/json/decoder.py", line 364, in decode
Jun 24 14:10:53 raspberrypilcd35 photoframe[439]: obj, end = self.raw_decode(s, idx=_w(s, 0).end())
Jun 24 14:10:53 raspberrypilcd35 photoframe[439]: File "/usr/lib/python2.7/json/decoder.py", line 380, in raw_decode
Jun 24 14:10:53 raspberrypilcd35 photoframe[439]: obj, end = self.scan_once(s, idx)
Jun 24 14:10:53 raspberrypilcd35 photoframe[439]: ValueError: Invalid control character at: line 1 column 425985 (char 425984)
Jun 24 14:10:53 raspberrypilcd35 photoframe[439]: 2020-06-24 14:10:53,282 - ERROR - Since file is corrupt, we try to save a copy for later analysis (/root/photoframe_config/services/1a5adc2f91ad7fdc71f94b805a7fa816a5b569d7/private/b220ee2eb110e04999d6aaa434a9d965cd98a2e3.json.corrupt)
Jun 24 14:11:03 raspberrypilcd35 photoframe[439]: 2020-06-24 14:11:03,056 - INFO - Internal display detected
Jun 24 14:11:08 raspberrypilcd35 photoframe[439]: 2020-06-24 14:11:08,951 - INFO - Internal display detected
Jun 24 14:11:51 raspberrypilcd35 photoframe[439]: 2020-06-24 14:11:51,773 - INFO - Internal display detected
Jun 24 14:15:03 raspberrypilcd35 photoframe[439]: 2020-06-24 14:15:03,630 - INFO - Internal display detected
Jun 24 14:17:35 raspberrypilcd35 photoframe[440]: 2020-06-24 14:17:35,814 - INFO - No http-auth.json found, disabling http authentication
Jun 24 14:17:36 raspberrypilcd35 photoframe[440]: 2020-06-24 14:17:36,081 - INFO - Internal display detected
Jun 24 14:17:36 raspberrypilcd35 photoframe[440]: 2020-06-24 14:17:36,499 - INFO - ColorSensor not available
Jun 24 14:17:36 raspberrypilcd35 photoframe[440]: Importing svc_picasaweb.py
Jun 24 14:17:36 raspberrypilcd35 photoframe[440]: Importing svc_googlephotos.py
Jun 24 14:17:36 raspberrypilcd35 photoframe[440]: Importing svc_usb.py
Jun 24 14:17:36 raspberrypilcd35 photoframe[440]: Importing svc_simpleurl.py
Jun 24 14:17:36 raspberrypilcd35 photoframe[440]: * Serving Flask app "frame" (lazy loading)
Jun 24 14:17:36 raspberrypilcd35 photoframe[440]: * Environment: production
Jun 24 14:17:36 raspberrypilcd35 photoframe[440]: WARNING: Do not use the development server in a production environment.
Jun 24 14:17:36 raspberrypilcd35 photoframe[440]: Use a production WSGI server instead.
Jun 24 14:17:36 raspberrypilcd35 photoframe[440]: * Debug mode: off
Jun 24 14:17:37 raspberrypilcd35 photoframe[440]: 2020-06-24 14:17:37,958 - INFO - Garbage Collector was able to free up 0.5MB of disk space!
Jun 24 14:20:08 raspberrypilcd35 photoframe[440]: 2020-06-24 14:20:08,975 - INFO - Internal display detected
Jun 24 14:20:14 raspberrypilcd35 photoframe[440]: 2020-06-24 14:20:14,026 - INFO - Starting presentation
Jun 24 14:20:14 raspberrypilcd35 photoframe[440]: Exception in thread Thread-4:
Jun 24 14:20:14 raspberrypilcd35 photoframe[440]: Traceback (most recent call last):
Jun 24 14:20:14 raspberrypilcd35 photoframe[440]: File "/usr/lib/python2.7/threading.py", line 801, in __bootstrap_inner
Jun 24 14:20:14 raspberrypilcd35 photoframe[440]: self.run()
Jun 24 14:20:14 raspberrypilcd35 photoframe[440]: File "/usr/lib/python2.7/threading.py", line 754, in run
Jun 24 14:20:14 raspberrypilcd35 photoframe[440]: self.__target(*self.__args, **self.__kwargs)
Jun 24 14:20:14 raspberrypilcd35 photoframe[440]: File "/root/photoframe/modules/slideshow.py", line 273, in presentation
Jun 24 14:20:14 raspberrypilcd35 photoframe[440]: result = self.services.servicePrepareNextItem(cacheFolder, self.supportedFormats, displaySize, randomize)
Jun 24 14:20:14 raspberrypilcd35 photoframe[440]: File "/root/photoframe/modules/servicemanager.py", line 400, in servicePrepareNextItem
Jun 24 14:20:14 raspberrypilcd35 photoframe[440]: result = svc.prepareNextItem(destinationDir, supportedMimeTypes, displaySize, randomize)
Jun 24 14:20:14 raspberrypilcd35 photoframe[440]: File "/root/photoframe/services/base.py", line 397, in prepareNextItem
Jun 24 14:20:14 raspberrypilcd35 photoframe[440]: result = self.selectImageFromAlbum(destinationFile, supportedMimeTypes, displaySize, randomize)
Jun 24 14:20:14 raspberrypilcd35 photoframe[440]: File "/root/photoframe/services/svc_googlephotos.py", line 240, in selectImageFromAlbum
Jun 24 14:20:14 raspberrypilcd35 photoframe[440]: result = BaseService.selectImageFromAlbum(self, destinationDir, supportedMimeTypes, displaySize, randomize)
Jun 24 14:20:14 raspberrypilcd35 photoframe[440]: File "/root/photoframe/services/base.py", line 471, in selectImageFromAlbum
Jun 24 14:20:14 raspberrypilcd35 photoframe[440]: image = self.selectImage(images, supportedMimeTypes, displaySize, randomize)
Jun 24 14:20:14 raspberrypilcd35 photoframe[440]: File "/root/photoframe/services/base.py", line 519, in selectImage
Jun 24 14:20:14 raspberrypilcd35 photoframe[440]: if randomize and self.memorySeen(image['id']):
Jun 24 14:20:14 raspberrypilcd35 photoframe[440]: File "/root/photoframe/services/base.py", line 663, in memorySeen
Jun 24 14:20:14 raspberrypilcd35 photoframe[440]: self._fetchMemory(keywords)
Jun 24 14:20:14 raspberrypilcd35 photoframe[440]: File "/root/photoframe/services/base.py", line 621, in _fetchMemory
Jun 24 14:20:14 raspberrypilcd35 photoframe[440]: self._MEMORY = json.load(f)
Jun 24 14:20:14 raspberrypilcd35 photoframe[440]: File "/usr/lib/python2.7/json/init.py", line 291, in load
Jun 24 14:20:14 raspberrypilcd35 photoframe[440]: **kw)
Jun 24 14:20:14 raspberrypilcd35 photoframe[440]: File "/usr/lib/python2.7/json/init.py", line 339, in loads
Jun 24 14:20:14 raspberrypilcd35 photoframe[440]: return _default_decoder.decode(s)
Jun 24 14:20:14 raspberrypilcd35 photoframe[440]: File "/usr/lib/python2.7/json/decoder.py", line 364, in decode
Jun 24 14:20:14 raspberrypilcd35 photoframe[440]: obj, end = self.raw_decode(s, idx=_w(s, 0).end())
Jun 24 14:20:14 raspberrypilcd35 photoframe[440]: File "/usr/lib/python2.7/json/decoder.py", line 382, in raw_decode
Jun 24 14:20:14 raspberrypilcd35 photoframe[440]: raise ValueError("No JSON object could be decoded")
Jun 24 14:20:14 raspberrypilcd35 photoframe[440]: ValueError: No JSON object could be decoded

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
None yet
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
3 participants
@mrworf @georgeoftown @lp27