pi@C3PO:~ $ sudo SYSTEMD_LESS=RXMK /usr/bin/journalctl -f -- Logs begin at Thu 2019-02-14 05:11:59 EST. -- Sep 09 12:16:08 C3PO DuetWebServer[653]: DuetWebServer.Controllers.WebSocketController[0] WebSocket connected from ::ffff:192.168.2.191:58866 Sep 09 12:16:08 C3PO DuetWebServer[653]: Microsoft.AspNetCore.Hosting.Diagnostics[1] Request starting HTTP/1.1 GET http://c3po.local/machine/file/0:%2Fsys%2Fdwc-settings.json - - Sep 09 12:16:08 C3PO DuetWebServer[653]: Microsoft.AspNetCore.Routing.EndpointMiddleware[0] Executing endpoint 'DuetWebServer.Controllers.MachineController.DownloadFile (DuetWebServer)' Sep 09 12:16:08 C3PO DuetWebServer[653]: Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker[3] Route matched with {action = "DownloadFile", controller = "Machine"}. Executing controller action with signature System.Threading.Tasks.Task`1[Microsoft.AspNetCore.Mvc.IActionResult] DownloadFile(System.String) on controller DuetWebServer.Controllers.MachineController (DuetWebServer). Sep 09 12:16:08 C3PO DuetWebServer[653]: Microsoft.AspNetCore.Mvc.Infrastructure.FileStreamResultExecutor[2] Executing FileStreamResult, sending file with download name '' ... Sep 09 12:16:08 C3PO DuetWebServer[653]: Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker[2] Executed action DuetWebServer.Controllers.MachineController.DownloadFile (DuetWebServer) in 44.5244ms Sep 09 12:16:08 C3PO DuetWebServer[653]: Microsoft.AspNetCore.Routing.EndpointMiddleware[1] Executed endpoint 'DuetWebServer.Controllers.MachineController.DownloadFile (DuetWebServer)' Sep 09 12:16:08 C3PO DuetWebServer[653]: Microsoft.AspNetCore.Hosting.Diagnostics[2] Request finished HTTP/1.1 GET http://c3po.local/machine/file/0:%2Fsys%2Fdwc-settings.json - - - 200 1450 application/octet-stream 55.6733ms Sep 09 12:16:12 C3PO sudo[2866]: pi : TTY=pts/1 ; PWD=/home/pi ; USER=root ; ENV=SYSTEMD_LESS=RXMK ; COMMAND=/usr/bin/journalctl -f Sep 09 12:16:12 C3PO sudo[2866]: pam_unix(sudo:session): session opened for user root by pi(uid=0) Sep 09 12:16:22 C3PO DuetWebServer[653]: Microsoft.AspNetCore.Hosting.Diagnostics[1] Request starting HTTP/1.1 POST http://c3po.local/machine/startPlugin text/plain;charset=UTF-8 10 Sep 09 12:16:22 C3PO DuetWebServer[653]: Microsoft.AspNetCore.Cors.Infrastructure.CorsService[5] CORS policy execution failed. Sep 09 12:16:22 C3PO DuetWebServer[653]: Microsoft.AspNetCore.Cors.Infrastructure.CorsService[6] Request origin http://c3po.local does not have permission to access the resource. Sep 09 12:16:22 C3PO DuetWebServer[653]: Microsoft.AspNetCore.Routing.EndpointMiddleware[0] Executing endpoint 'DuetWebServer.Controllers.MachineController.StartPlugin (DuetWebServer)' Sep 09 12:16:22 C3PO DuetWebServer[653]: Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker[3] Route matched with {action = "StartPlugin", controller = "Machine"}. Executing controller action with signature System.Threading.Tasks.Task`1[Microsoft.AspNetCore.Mvc.IActionResult] StartPlugin() on controller DuetWebServer.Controllers.MachineController (DuetWebServer). Sep 09 12:16:22 C3PO DuetPluginService[930]: [info] Plugin DuetLapse3: Process has been started (pid 2874) Sep 09 12:16:22 C3PO DuetWebServer[653]: Microsoft.AspNetCore.Mvc.StatusCodeResult[1] Executing StatusCodeResult, setting HTTP status code 204 Sep 09 12:16:22 C3PO DuetWebServer[653]: Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker[2] Executed action DuetWebServer.Controllers.MachineController.StartPlugin (DuetWebServer) in 68.007ms Sep 09 12:16:22 C3PO DuetWebServer[653]: Microsoft.AspNetCore.Routing.EndpointMiddleware[1] Executed endpoint 'DuetWebServer.Controllers.MachineController.StartPlugin (DuetWebServer)' Sep 09 12:16:22 C3PO DuetWebServer[653]: Microsoft.AspNetCore.Hosting.Diagnostics[2] Request finished HTTP/1.1 POST http://c3po.local/machine/startPlugin text/plain;charset=UTF-8 10 - 204 - - 71.3342ms Sep 09 12:16:22 C3PO DuetWebServer[653]: Microsoft.AspNetCore.Hosting.Diagnostics[1] Request starting HTTP/1.1 GET http://c3po.local/js/DuetLapse3.36004283.js - - Sep 09 12:16:22 C3PO DuetWebServer[653]: Microsoft.AspNetCore.Hosting.Diagnostics[1] Request starting HTTP/1.1 GET http://c3po.local/css/DuetLapse3.5bfff5a6.css - - Sep 09 12:16:22 C3PO DuetWebServer[653]: Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware[6] The file /css/DuetLapse3.5bfff5a6.css was not modified Sep 09 12:16:22 C3PO DuetWebServer[653]: Microsoft.AspNetCore.Hosting.Diagnostics[2] Request finished HTTP/1.1 GET http://c3po.local/css/DuetLapse3.5bfff5a6.css - - - 304 - text/css 29.1607ms Sep 09 12:16:22 C3PO DuetWebServer[653]: Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware[6] The file /js/DuetLapse3.36004283.js was not modified Sep 09 12:16:22 C3PO DuetWebServer[653]: Microsoft.AspNetCore.Hosting.Diagnostics[2] Request finished HTTP/1.1 GET http://c3po.local/js/DuetLapse3.36004283.js - - - 304 - application/javascript 38.3006ms Sep 09 12:16:22 C3PO DuetWebServer[653]: Microsoft.AspNetCore.Hosting.Diagnostics[1] Request starting HTTP/1.1 PUT http://c3po.local/machine/file/0:%2Fsys%2Fdwc-settings.json?timeModified=2023-09-09T14%3A18%3A12 - 1463 Sep 09 12:16:22 C3PO DuetWebServer[653]: Microsoft.AspNetCore.Cors.Infrastructure.CorsService[5] CORS policy execution failed. Sep 09 12:16:22 C3PO DuetWebServer[653]: Microsoft.AspNetCore.Cors.Infrastructure.CorsService[6] Request origin http://c3po.local does not have permission to access the resource. Sep 09 12:16:22 C3PO DuetWebServer[653]: Microsoft.AspNetCore.Routing.EndpointMiddleware[0] Executing endpoint 'DuetWebServer.Controllers.MachineController.UploadFile (DuetWebServer)' Sep 09 12:16:22 C3PO DuetWebServer[653]: Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker[3] Route matched with {action = "UploadFile", controller = "Machine"}. Executing controller action with signature System.Threading.Tasks.Task`1[Microsoft.AspNetCore.Mvc.IActionResult] UploadFile(System.String, System.Nullable`1[System.DateTime], DuetWebServer.Singletons.ISessionStorage) on controller DuetWebServer.Controllers.MachineController (DuetWebServer). Sep 09 12:16:22 C3PO DuetWebServer[653]: DuetWebServer.Singletons.SessionStorage[0] Session 5bfba53cafa149a1916452e387788a69 started a long-running request Sep 09 12:16:22 C3PO DuetWebServer[653]: DuetWebServer.Singletons.SessionStorage[0] Session 5bfba53cafa149a1916452e387788a69 finished a long-running request Sep 09 12:16:22 C3PO DuetWebServer[653]: Microsoft.AspNetCore.Mvc.Infrastructure.ObjectResultExecutor[1] Executing CreatedResult, writing value of type 'null'. Sep 09 12:16:22 C3PO DuetWebServer[653]: Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker[2] Executed action DuetWebServer.Controllers.MachineController.UploadFile (DuetWebServer) in 20.9666ms Sep 09 12:16:22 C3PO DuetWebServer[653]: Microsoft.AspNetCore.Routing.EndpointMiddleware[1] Executed endpoint 'DuetWebServer.Controllers.MachineController.UploadFile (DuetWebServer)' Sep 09 12:16:22 C3PO DuetWebServer[653]: Microsoft.AspNetCore.Hosting.Diagnostics[2] Request finished HTTP/1.1 PUT http://c3po.local/machine/file/0:%2Fsys%2Fdwc-settings.json?timeModified=2023-09-09T14%3A18%3A12 - 1463 - 201 0 - 22.1000ms Sep 09 12:16:23 C3PO audit[2874]: AVC apparmor="DENIED" operation="ptrace" profile="/opt/dsf/plugins/DuetLapse3/**" pid=2874 comm="DuetLapse3.py" requested_mask="read" denied_mask="read" peer="unconfined" Sep 09 12:16:23 C3PO audit[2874]: AVC apparmor="DENIED" operation="ptrace" profile="/opt/dsf/plugins/DuetLapse3/**" pid=2874 comm="DuetLapse3.py" requested_mask="read" denied_mask="read" peer="unconfined" Sep 09 12:16:23 C3PO audit[2874]: AVC apparmor="DENIED" operation="ptrace" profile="/opt/dsf/plugins/DuetLapse3/**" pid=2874 comm="DuetLapse3.py" requested_mask="read" denied_mask="read" peer="unconfined" Sep 09 12:16:23 C3PO kernel: audit: type=1400 audit(1694276183.499:77): apparmor="DENIED" operation="ptrace" profile="/opt/dsf/plugins/DuetLapse3/**" pid=2874 comm="DuetLapse3.py" requested_mask="read" denied_mask="read" peer="unconfined" Sep 09 12:16:23 C3PO kernel: audit: type=1400 audit(1694276183.499:78): apparmor="DENIED" operation="ptrace" profile="/opt/dsf/plugins/DuetLapse3/**" pid=2874 comm="DuetLapse3.py" requested_mask="read" denied_mask="read" peer="unconfined" Sep 09 12:16:23 C3PO kernel: audit: type=1400 audit(1694276183.499:79): apparmor="DENIED" operation="ptrace" profile="/opt/dsf/plugins/DuetLapse3/**" pid=2874 comm="DuetLapse3.py" requested_mask="read" denied_mask="read" peer="unconfined" Sep 09 12:16:23 C3PO audit[2874]: AVC apparmor="DENIED" operation="ptrace" profile="/opt/dsf/plugins/DuetLapse3/**" pid=2874 comm="DuetLapse3.py" requested_mask="read" denied_mask="read" peer="/opt/dsf/plugins/MotionWebcamServer/**" Sep 09 12:16:23 C3PO audit[2874]: AVC apparmor="DENIED" operation="ptrace" profile="/opt/dsf/plugins/DuetLapse3/**" pid=2874 comm="DuetLapse3.py" requested_mask="read" denied_mask="read" peer="/opt/dsf/plugins/MotionWebcamServer/**" Sep 09 12:16:23 C3PO audit[2874]: AVC apparmor="DENIED" operation="ptrace" profile="/opt/dsf/plugins/DuetLapse3/**" pid=2874 comm="DuetLapse3.py" requested_mask="read" denied_mask="read" peer="/opt/dsf/plugins/MotionWebcamServer/**" Sep 09 12:16:23 C3PO kernel: audit: type=1400 audit(1694276183.509:80): apparmor="DENIED" operation="ptrace" profile="/opt/dsf/plugins/DuetLapse3/**" pid=2874 comm="DuetLapse3.py" requested_mask="read" denied_mask="read" peer="/opt/dsf/plugins/MotionWebcamServer/**" Sep 09 12:16:23 C3PO kernel: audit: type=1400 audit(1694276183.509:81): apparmor="DENIED" operation="ptrace" profile="/opt/dsf/plugins/DuetLapse3/**" pid=2874 comm="DuetLapse3.py" requested_mask="read" denied_mask="read" peer="/opt/dsf/plugins/MotionWebcamServer/**" Sep 09 12:16:23 C3PO kernel: audit: type=1400 audit(1694276183.509:82): apparmor="DENIED" operation="ptrace" profile="/opt/dsf/plugins/DuetLapse3/**" pid=2874 comm="DuetLapse3.py" requested_mask="read" denied_mask="read" peer="/opt/dsf/plugins/MotionWebcamServer/**" Sep 09 12:16:23 C3PO DuetPluginService[930]: [error] Plugin DuetLapse3: c3po.local MainThread - The top level dir is /opt/dsf/sd/DuetLapse3/C3PO/c3po-local Sep 09 12:16:23 C3PO DuetPluginService[930]: [error] Plugin DuetLapse3: c3po.local MainThread - ***** Cleaning up files for phase: startup ***** Sep 09 12:16:23 C3PO audit[2874]: AVC apparmor="DENIED" operation="ptrace" profile="/opt/dsf/plugins/DuetLapse3/**" pid=2874 comm="DuetLapse3.py" requested_mask="read" denied_mask="read" peer="unconfined" Sep 09 12:16:23 C3PO audit[2874]: AVC apparmor="DENIED" operation="ptrace" profile="/opt/dsf/plugins/DuetLapse3/**" pid=2874 comm="DuetLapse3.py" requested_mask="read" denied_mask="read" peer="unconfined" Sep 09 12:16:23 C3PO kernel: audit: type=1400 audit(1694276183.579:83): apparmor="DENIED" operation="ptrace" profile="/opt/dsf/plugins/DuetLapse3/**" pid=2874 comm="DuetLapse3.py" requested_mask="read" denied_mask="read" peer="unconfined" Sep 09 12:16:23 C3PO kernel: audit: type=1400 audit(1694276183.579:84): apparmor="DENIED" operation="ptrace" profile="/opt/dsf/plugins/DuetLapse3/**" pid=2874 comm="DuetLapse3.py" requested_mask="read" denied_mask="read" peer="unconfined" Sep 09 12:16:23 C3PO kernel: audit: type=1400 audit(1694276183.589:85): apparmor="DENIED" operation="ptrace" profile="/opt/dsf/plugins/DuetLapse3/**" pid=2874 comm="DuetLapse3.py" requested_mask="read" denied_mask="read" peer="/opt/dsf/plugins/MotionWebcamServer/**" Sep 09 12:16:23 C3PO kernel: audit: type=1400 audit(1694276183.589:86): apparmor="DENIED" operation="ptrace" profile="/opt/dsf/plugins/DuetLapse3/**" pid=2874 comm="DuetLapse3.py" requested_mask="read" denied_mask="read" peer="/opt/dsf/plugins/MotionWebcamServer/**" Sep 09 12:16:23 C3PO audit[2874]: AVC apparmor="DENIED" operation="ptrace" profile="/opt/dsf/plugins/DuetLapse3/**" pid=2874 comm="DuetLapse3.py" requested_mask="read" denied_mask="read" peer="/opt/dsf/plugins/MotionWebcamServer/**" Sep 09 12:16:23 C3PO audit[2874]: AVC apparmor="DENIED" operation="ptrace" profile="/opt/dsf/plugins/DuetLapse3/**" pid=2874 comm="DuetLapse3.py" requested_mask="read" denied_mask="read" peer="/opt/dsf/plugins/MotionWebcamServer/**" Sep 09 12:16:23 C3PO DuetPluginService[930]: [error] Plugin DuetLapse3: Traceback (most recent call last): Sep 09 12:16:23 C3PO DuetPluginService[930]: [error] Plugin DuetLapse3: File "/opt/dsf/plugins/DuetLapse3/dsf/DuetLapse3.py", line 3402, in Sep 09 12:16:23 C3PO DuetPluginService[930]: [error] Plugin DuetLapse3: main() Sep 09 12:16:23 C3PO DuetPluginService[930]: [error] Plugin DuetLapse3: File "/opt/dsf/plugins/DuetLapse3/dsf/DuetLapse3.py", line 3389, in main Sep 09 12:16:23 C3PO DuetPluginService[930]: [error] Plugin DuetLapse3: init() Sep 09 12:16:23 C3PO DuetPluginService[930]: [error] Plugin DuetLapse3: File "/opt/dsf/plugins/DuetLapse3/dsf/DuetLapse3.py", line 503, in init Sep 09 12:16:23 C3PO DuetPluginService[930]: [error] Plugin DuetLapse3: setuplogfile() Sep 09 12:16:23 C3PO DuetPluginService[930]: [error] Plugin DuetLapse3: File "/opt/dsf/plugins/DuetLapse3/dsf/DuetLapse3.py", line 667, in setuplogfile Sep 09 12:16:23 C3PO DuetPluginService[930]: [error] Plugin DuetLapse3: f_handler = logging.FileHandler(logfilename, mode='w', encoding='utf-8') Sep 09 12:16:23 C3PO DuetPluginService[930]: [error] Plugin DuetLapse3: File "/usr/lib/python3.7/logging/__init__.py", line 1092, in __init__ Sep 09 12:16:23 C3PO DuetPluginService[930]: [error] Plugin DuetLapse3: StreamHandler.__init__(self, self._open()) Sep 09 12:16:23 C3PO DuetPluginService[930]: [error] Plugin DuetLapse3: File "/usr/lib/python3.7/logging/__init__.py", line 1121, in _open Sep 09 12:16:23 C3PO DuetPluginService[930]: [error] Plugin DuetLapse3: return open(self.baseFilename, self.mode, encoding=self.encoding) Sep 09 12:16:23 C3PO DuetPluginService[930]: [error] Plugin DuetLapse3: PermissionError: [Errno 13] Permission denied: '/opt/dsf/sd/DuetLapse3/C3PO/c3po-local/startup.log' Sep 09 12:16:23 C3PO DuetPluginService[930]: [info] Plugin DuetLapse3: Process has been stopped with exit code 1