[@speckle/server]: [server] INFO: /api/file/autodetect/67c336d2c4/Wooden%20chair.obj request received {"req":{"id":"bdaf392c-6bc8-4e69-bd52-3408c79ab2c4","method":"OPTIONS","path":"/api/file/autodetect/67c336d2c4/Wooden%20chair.obj","headers":{"host":"127.0.0.1:3000","connection":"keep-alive","accept":"*/*","access-control-request-method":"POST","access-control-request-headers":"authorization","origin":"http://127.0.0.1:8081","user-agent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36","sec-fetch-mode":"cors","sec-fetch-site":"same-site","sec-fetch-dest":"empty","referer":"http://127.0.0.1:8081/","accept-encoding":"gzip, deflate, br, zstd","accept-language":"ko-KR,ko;q=0.9","x-request-id":"bdaf392c-6bc8-4e69-bd52-3408c79ab2c4"}},"requestPath":"/api/file/autodetect/67c336d2c4/Wooden%20chair.obj"} [@speckle/server]: [server] INFO: /api/file/autodetect/67c336d2c4/Wooden%20chair.obj request completed in 0 ms {"req":{"id":"bdaf392c-6bc8-4e69-bd52-3408c79ab2c4","method":"OPTIONS","path":"/api/file/autodetect/67c336d2c4/Wooden%20chair.obj","headers":{"host":"127.0.0.1:3000","connection":"keep-alive","accept":"*/*","access-control-request-method":"POST","access-control-request-headers":"authorization","origin":"http://127.0.0.1:8081","user-agent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36","sec-fetch-mode":"cors","sec-fetch-site":"same-site","sec-fetch-dest":"empty","referer":"http://127.0.0.1:8081/","accept-encoding":"gzip, deflate, br, zstd","accept-language":"ko-KR,ko;q=0.9","x-request-id":"bdaf392c-6bc8-4e69-bd52-3408c79ab2c4"}},"res":{"statusCode":204,"headers":{"x-request-id":"bdaf392c-6bc8-4e69-bd52-3408c79ab2c4","access-control-allow-origin":"*","access-control-allow-methods":"GET,HEAD,PUT,PATCH,POST,DELETE","vary":"Access-Control-Request-Headers","access-control-allow-headers":"authorization","content-length":"0"}},"responseTime":0,"requestStatus":"completed","requestPath":"/api/file/autodetect/67c336d2c4/Wooden%20chair.obj"} [@speckle/server]: [server] INFO: /api/file/autodetect/67c336d2c4/Wooden%20chair.obj request received {"req":{"id":"254a2d1b-8023-4022-b2f8-7cabd6f48d1c","method":"POST","path":"/api/file/autodetect/67c336d2c4/Wooden%20chair.obj","headers":{"host":"127.0.0.1:3000","connection":"keep-alive","content-length":"20405035","sec-ch-ua-platform":"\"Windows\"","user-agent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36","sec-ch-ua":"\"Google Chrome\";v=\"131\", \"Chromium\";v=\"131\", \"Not_A Brand\";v=\"24\"","content-type":"multipart/form-data; boundary=----WebKitFormBoundaryp30PxM6PieLSlIgb","sec-ch-ua-mobile":"?0","accept":"*/*","origin":"http://127.0.0.1:8081","sec-fetch-site":"same-site","sec-fetch-mode":"cors","sec-fetch-dest":"empty","referer":"http://127.0.0.1:8081/","accept-encoding":"gzip, deflate, br, zstd","accept-language":"ko-KR,ko;q=0.9","x-request-id":"254a2d1b-8023-4022-b2f8-7cabd6f48d1c"}},"requestPath":"/api/file/autodetect/67c336d2c4/Wooden%20chair.obj"} [@speckle/server]: [server] INFO: /api/stream/67c336d2c4/blob request received {"req":{"id":"254a2d1b-8023-4022-b2f8-7cabd6f48d1c","method":"POST","path":"/api/stream/67c336d2c4/blob","headers":{"host":"127.0.0.1:3000","connection":"keep-alive","content-length":"20405035","sec-ch-ua-platform":"\"Windows\"","user-agent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36","sec-ch-ua":"\"Google Chrome\";v=\"131\", \"Chromium\";v=\"131\", \"Not_A Brand\";v=\"24\"","content-type":"multipart/form-data; boundary=----WebKitFormBoundaryp30PxM6PieLSlIgb","sec-ch-ua-mobile":"?0","accept":"*/*","origin":"http://127.0.0.1:8081","sec-fetch-site":"same-site","sec-fetch-mode":"cors","sec-fetch-dest":"empty","referer":"http://127.0.0.1:8081/","accept-encoding":"gzip, deflate, br, zstd","accept-language":"ko-KR,ko;q=0.9","x-request-id":"254a2d1b-8023-4022-b2f8-7cabd6f48d1c"}},"requestPath":"/api/stream/67c336d2c4/blob"} [@speckle/server]: [server] INFO: /api/stream/67c336d2c4/blob request completed in 207 ms {"req":{"id":"254a2d1b-8023-4022-b2f8-7cabd6f48d1c","method":"POST","path":"/api/stream/67c336d2c4/blob","headers":{"host":"127.0.0.1:3000","connection":"keep-alive","content-length":"20405035","sec-ch-ua-platform":"\"Windows\"","user-agent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36","sec-ch-ua":"\"Google Chrome\";v=\"131\", \"Chromium\";v=\"131\", \"Not_A Brand\";v=\"24\"","content-type":"multipart/form-data; boundary=----WebKitFormBoundaryp30PxM6PieLSlIgb","sec-ch-ua-mobile":"?0","accept":"*/*","origin":"http://127.0.0.1:8081","sec-fetch-site":"same-site","sec-fetch-mode":"cors","sec-fetch-dest":"empty","referer":"http://127.0.0.1:8081/","accept-encoding":"gzip, deflate, br, zstd","accept-language":"ko-KR,ko;q=0.9","x-request-id":"254a2d1b-8023-4022-b2f8-7cabd6f48d1c"}},"res":{"statusCode":201,"headers":{"x-request-id":"254a2d1b-8023-4022-b2f8-7cabd6f48d1c","access-control-allow-origin":"*","x-ratelimit-remaining":498,"content-security-policy":"frame-ancestors 'none'","content-type":"application/json; charset=utf-8","content-length":"127","etag":"W/\"7f-J6yGFL4tm8L1g7ol3lbpRmM08N4\""},"userId":"231cee48bb"},"responseTime":207,"requestStatus":"completed","requestPath":"/api/stream/67c336d2c4/blob"} [@speckle/server]: [server] INFO: /api/file/autodetect/67c336d2c4/Wooden%20chair.obj request completed in 276 ms {"req":{"id":"254a2d1b-8023-4022-b2f8-7cabd6f48d1c","method":"POST","path":"/api/file/autodetect/67c336d2c4/Wooden%20chair.obj","headers":{"host":"127.0.0.1:3000","connection":"keep-alive","content-length":"20405035","sec-ch-ua-platform":"\"Windows\"","user-agent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36","sec-ch-ua":"\"Google Chrome\";v=\"131\", \"Chromium\";v=\"131\", \"Not_A Brand\";v=\"24\"","content-type":"multipart/form-data; boundary=----WebKitFormBoundaryp30PxM6PieLSlIgb","sec-ch-ua-mobile":"?0","accept":"*/*","origin":"http://127.0.0.1:8081","sec-fetch-site":"same-site","sec-fetch-mode":"cors","sec-fetch-dest":"empty","referer":"http://127.0.0.1:8081/","accept-encoding":"gzip, deflate, br, zstd","accept-language":"ko-KR,ko;q=0.9","x-request-id":"254a2d1b-8023-4022-b2f8-7cabd6f48d1c"}},"res":{"statusCode":201,"headers":{"x-request-id":"254a2d1b-8023-4022-b2f8-7cabd6f48d1c","access-control-allow-origin":"*","x-ratelimit-remaining":499,"content-security-policy":"frame-ancestors 'none'","content-type":"text/html; charset=utf-8","content-length":"127","etag":"W/\"7f-J6yGFL4tm8L1g7ol3lbpRmM08N4\""},"userId":"231cee48bb"},"responseTime":276,"requestStatus":"completed","requestPath":"/api/file/autodetect/67c336d2c4/Wooden%20chair.obj"} [@speckle/server]: [server] INFO: GQL subscription event OnProjectPendingModelsUpdated emitted {"component":"graphql","graphql_query":"subscription OnProjectPendingModelsUpdated($id: String!) {\n projectPendingModelsUpdated(id: $id) {\n id\n type\n model {\n ...PendingFileUpload\n model {\n ...ProjectPageLatestItemsModelItem\n __typename\n }\n __typename\n }\n __typename\n }\n}\n\nfragment PendingFileUpload on FileUpload {\n id\n projectId\n modelName\n convertedStatus\n convertedMessage\n uploadDate\n convertedLastUpdate\n fileType\n fileName\n __typename\n}\n\nfragment ProjectPageModelsCardRenameDialog on Model {\n id\n name\n description\n __typename\n}\n\nfragment ProjectPageModelsCardDeleteDialog on Model {\n id\n name\n __typename\n}\n\nfragment ProjectPageModelsActions on Model {\n id\n name\n __typename\n}\n\nfragment FunctionRunStatusForSummary on AutomateFunctionRun {\n id\n status\n __typename\n}\n\nfragment TriggeredAutomationsStatusSummary on TriggeredAutomationsStatus {\n id\n automationRuns {\n id\n functionRuns {\n id\n ...FunctionRunStatusForSummary\n __typename\n }\n __typename\n }\n __typename\n}\n\nfragment AutomateRunsTriggerStatusDialogFunctionRun_AutomateFunctionRun on AutomateFunctionRun {\n id\n results\n status\n statusMessage\n contextView\n function {\n id\n logo\n name\n __typename\n }\n createdAt\n updatedAt\n __typename\n}\n\nfragment AutomationsStatusOrderedRuns_AutomationRun on AutomateRun {\n id\n automation {\n id\n name\n __typename\n }\n functionRuns {\n id\n updatedAt\n __typename\n }\n __typename\n}\n\nfragment AutomateRunsTriggerStatusDialogRunsRows_AutomateRun on AutomateRun {\n id\n functionRuns {\n id\n ...AutomateRunsTriggerStatusDialogFunctionRun_AutomateFunctionRun\n __typename\n }\n ...AutomationsStatusOrderedRuns_AutomationRun\n __typename\n}\n\nfragment AutomateRunsTriggerStatusDialog_TriggeredAutomationsStatus on TriggeredAutomationsStatus {\n id\n automationRuns {\n id\n ...AutomateRunsTriggerStatusDialogRunsRows_AutomateRun\n __typename\n }\n __typename\n}\n\nfragment AutomateRunsTriggerStatus_TriggeredAutomationsStatus on TriggeredAutomationsStatus {\n id\n ...TriggeredAutomationsStatusSummary\n ...AutomateRunsTriggerStatusDialog_TriggeredAutomationsStatus\n __typename\n}\n\nfragment ProjectPageLatestItemsModelItem on Model {\n id\n name\n displayName\n versionCount: versions(limit: 0) {\n totalCount\n __typename\n }\n commentThreadCount: commentThreads(limit: 0) {\n totalCount\n __typename\n }\n pendingImportedVersions(limit: 1) {\n ...PendingFileUpload\n __typename\n }\n previewUrl\n createdAt\n updatedAt\n ...ProjectPageModelsCardRenameDialog\n ...ProjectPageModelsCardDeleteDialog\n ...ProjectPageModelsActions\n automationsStatus {\n ...AutomateRunsTriggerStatus_TriggeredAutomationsStatus\n __typename\n }\n __typename\n}\n","graphql_variables":{"id":"67c336d2c4"},"graphql_operation_name":"OnProjectPendingModelsUpdated","graphql_operation_type":"subscription","userId":"231cee48bb"} [@speckle/fileimport-service]: INFO: Doing task '1fd12ab77f'. {"component":"fileimport-service","taskId":"1fd12ab77f"} [@speckle/fileimport-service]: INFO: Obj file depends on {"component":"fileimport-service"} [@speckle/fileimport-service]: WARN: Invalid filename reference in OBJ dependencies: {"component":"fileimport-service"} [@speckle/fileimport-service]: INFO: Starting process. {"component":"fileimport-service","taskId":"1fd12ab77f","fileId":"1fd12ab77f","fileType":"obj","fileName":"Wooden chair.obj","fileSize":20404831,"userId":"231cee48bb","streamId":"67c336d2c4","branchName":"Wooden chair.obj","cmd":"python3","args":["-u","./obj/import_file.py","/tmp/file_to_import/file","231cee48bb","67c336d2c4","Wooden chair.obj","File upload: Wooden chair.obj"]} [@speckle/server]: [server] INFO (notification): Message #324d93024f4e1189a5d2c492ad473537 of channel file_import_started starting processing... {"component":"db-notifications","length":39,"processId":413,"channel":"file_import_started","payload":"1fd12ab77f","listenerRegistered":true,"messageId":"324d93024f4e1189a5d2c492ad473537"} [@speckle/server]: [server] INFO: /api/stream/67c336d2c4/blob/1fd12ab77f request received {"req":{"id":"ea8d4021-9659-4801-87ac-d6edff94910d","method":"GET","path":"/api/stream/67c336d2c4/blob/1fd12ab77f","headers":{"host":"127.0.0.1:3000","connection":"keep-alive","x-request-id":"ea8d4021-9659-4801-87ac-d6edff94910d"}},"requestPath":"/api/stream/67c336d2c4/blob/1fd12ab77f"} [@speckle/server]: [server] INFO: /api/stream/67c336d2c4/blob/1fd12ab77f request completed in 118 ms {"req":{"id":"ea8d4021-9659-4801-87ac-d6edff94910d","method":"GET","path":"/api/stream/67c336d2c4/blob/1fd12ab77f","headers":{"host":"127.0.0.1:3000","connection":"keep-alive","x-request-id":"ea8d4021-9659-4801-87ac-d6edff94910d"}},"res":{"statusCode":200,"headers":{"x-request-id":"ea8d4021-9659-4801-87ac-d6edff94910d","access-control-allow-origin":"*","x-ratelimit-remaining":499,"content-security-policy":"frame-ancestors 'none'","content-type":"application/octet-stream","content-disposition":"attachment; filename=\"Wooden chair.obj\""},"userId":"231cee48bb"},"responseTime":118,"requestStatus":"completed","requestPath":"/api/stream/67c336d2c4/blob/1fd12ab77f"} [@speckle/server]: [server] INFO: /api/stream/67c336d2c4/blobs request received {"req":{"id":"57d40b63-9432-4636-9d12-e0871e142710","method":"GET","path":"/api/stream/67c336d2c4/blobs","headers":{"host":"127.0.0.1:3000","connection":"keep-alive","accept":"*/*","accept-language":"*","sec-fetch-mode":"cors","user-agent":"undici","accept-encoding":"gzip, deflate","x-request-id":"57d40b63-9432-4636-9d12-e0871e142710"}},"requestPath":"/api/stream/67c336d2c4/blobs"} [@speckle/server]: [server] INFO: /api/stream/67c336d2c4/blobs request completed in 57 ms {"req":{"id":"57d40b63-9432-4636-9d12-e0871e142710","method":"GET","path":"/api/stream/67c336d2c4/blobs","headers":{"host":"127.0.0.1:3000","connection":"keep-alive","accept":"*/*","accept-language":"*","sec-fetch-mode":"cors","user-agent":"undici","accept-encoding":"gzip, deflate","x-request-id":"57d40b63-9432-4636-9d12-e0871e142710"}},"res":{"statusCode":200,"headers":{"x-request-id":"57d40b63-9432-4636-9d12-e0871e142710","access-control-allow-origin":"*","x-ratelimit-remaining":498,"content-security-policy":"frame-ancestors 'none'","content-type":"application/json; charset=utf-8","content-length":"354","etag":"W/\"162-qPBkf6sldYbMgd2az2Ns9mNgWgg\""},"userId":"231cee48bb"},"responseTime":57,"requestStatus":"completed","requestPath":"/api/stream/67c336d2c4/blobs"} [@speckle/fileimport-service]: {"event": "ImportOBJ argv[1:]:['/tmp/file_to_import/file', '231cee48bb', '67c336d2c4', 'Wooden chair.obj', 'File upload: Wooden chair.obj']", "level": "info", "timestamp": "2024-11-27T06:33:48.597554Z", "filename": "import_file.py", "lineno": 61, "func_name": "import_obj"} [@speckle/fileimport-service]: {"event": "Missing MTL file:/tmp/file_to_import/", "level": "error", "timestamp": "2024-11-27T06:33:48.606957Z", "filename": "mtl_file_collection.py", "lineno": 27, "func_name": "mtllib"} [@speckle/fileimport-service]: {"event": "Parsed obj with 499690 faces (749319 vertices)", "level": "info", "timestamp": "2024-11-27T06:33:50.028272Z", "filename": "import_file.py", "lineno": 65, "func_name": "import_obj"} [@speckle/fileimport-service]: {"object_name": "", "event": "Converting object", "level": "info", "timestamp": "2024-11-27T06:33:50.028520Z", "filename": "import_file.py", "lineno": 74, "func_name": "import_obj"} [@speckle/fileimport-service]: {"event": "GraphQLException()", "level": "error", "exception": "Traceback (most recent call last):\n File \"/root/speckle-server/packages/fileimport-service/./obj/import_file.py\", line 159, in \n commit_id = import_obj()\n ^^^^^^^^^^^^\n File \"/root/speckle-server/packages/fileimport-service/./obj/import_file.py\", line 130, in import_obj\n client.authenticate_with_token(os.environ[\"USER_TOKEN\"])\n File \"/root/speckle-server/lib/python3.11/site-packages/specklepy/api/client.py\", line 146, in authenticate_with_token\n return super().authenticate_with_token(token)\n ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n File \"/root/speckle-server/lib/python3.11/site-packages/specklepy/core/api/client.py\", line 139, in authenticate_with_token\n self._set_up_client()\n File \"/root/speckle-server/lib/python3.11/site-packages/specklepy/core/api/client.py\", line 178, in _set_up_client\n raise user_or_error\nspecklepy.logging.exceptions.GraphQLException: GraphQLException: Failed to execute the GraphQL active_user request. Errors: [{'message': 'Your auth token does not have the required scope: profile:read.', 'locations': [{'line': 2, 'column': 3}], 'path': ['activeUser'], 'extensions': {'scope': 'profile:read', 'code': 'FORBIDDEN', 'exception': {'stacktrace': ['ForbiddenError: Your auth token does not have the required scope: profile:read.', ' at validateScopes (/root/speckle-server/packages/server/dist/modules/shared/index.js:23:15)', ' at Object.activeUser (/root/speckle-server/packages/server/dist/modules/core/graph/resolvers/users.js:29:19)', ' at process.processTicksAndRejections (node:internal/process/task_queues:95:5)']}}}]", "timestamp": "2024-11-27T06:33:50.212355Z", "filename": "import_file.py", "lineno": 164, "func_name": ""} [@speckle/fileimport-service]: INFO (38686): ParserLog: >>> {"query": "query Server {\n serverInfo {\n version\n }\n}"} {"component":"fileimport-service","taskId":"1fd12ab77f","fileId":"1fd12ab77f","fileType":"obj","fileName":"Wooden chair.obj","fileSize":20404831,"userId":"231cee48bb","streamId":"67c336d2c4","branchName":"Wooden chair.obj","cmd":"python3","args":["-u","./obj/import_file.py","/tmp/file_to_import/file","231cee48bb","67c336d2c4","Wooden chair.obj","File upload: Wooden chair.obj"],"parserLogLine":">>> {\"query\": \"query Server {\\n serverInfo {\\n version\\n }\\n}\"}"} [@speckle/fileimport-service]: INFO (38686): ParserLog: <<< {"data":{"serverInfo":{"version":"dev"}}} {"component":"fileimport-service","taskId":"1fd12ab77f","fileId":"1fd12ab77f","fileType":"obj","fileName":"Wooden chair.obj","fileSize":20404831,"userId":"231cee48bb","streamId":"67c336d2c4","branchName":"Wooden chair.obj","cmd":"python3","args":["-u","./obj/import_file.py","/tmp/file_to_import/file","231cee48bb","67c336d2c4","Wooden chair.obj","File upload: Wooden chair.obj"],"parserLogLine":"<<< {\"data\":{\"serverInfo\":{\"version\":\"dev\"}}}"} [@speckle/fileimport-service]: INFO (38686): ParserLog: >>> {"query": "query Server {\n serverInfo {\n version\n }\n}"} {"component":"fileimport-service","taskId":"1fd12ab77f","fileId":"1fd12ab77f","fileType":"obj","fileName":"Wooden chair.obj","fileSize":20404831,"userId":"231cee48bb","streamId":"67c336d2c4","branchName":"Wooden chair.obj","cmd":"python3","args":["-u","./obj/import_file.py","/tmp/file_to_import/file","231cee48bb","67c336d2c4","Wooden chair.obj","File upload: Wooden chair.obj"],"parserLogLine":">>> {\"query\": \"query Server {\\n serverInfo {\\n version\\n }\\n}\"}"} [@speckle/fileimport-service]: INFO (38686): ParserLog: <<< {"data":{"serverInfo":{"version":"dev"}}} {"component":"fileimport-service","taskId":"1fd12ab77f","fileId":"1fd12ab77f","fileType":"obj","fileName":"Wooden chair.obj","fileSize":20404831,"userId":"231cee48bb","streamId":"67c336d2c4","branchName":"Wooden chair.obj","cmd":"python3","args":["-u","./obj/import_file.py","/tmp/file_to_import/file","231cee48bb","67c336d2c4","Wooden chair.obj","File upload: Wooden chair.obj"],"parserLogLine":"<<< {\"data\":{\"serverInfo\":{\"version\":\"dev\"}}}"} [@speckle/fileimport-service]: INFO (38686): ParserLog: >>> {"query": "query User {\n activeUser {\n id\n email\n name\n bio\n company\n avatar\n verified\n profiles\n role\n }\n}"} {"component":"fileimport-service","taskId":"1fd12ab77f","fileId":"1fd12ab77f","fileType":"obj","fileName":"Wooden chair.obj","fileSize":20404831,"userId":"231cee48bb","streamId":"67c336d2c4","branchName":"Wooden chair.obj","cmd":"python3","args":["-u","./obj/import_file.py","/tmp/file_to_import/file","231cee48bb","67c336d2c4","Wooden chair.obj","File upload: Wooden chair.obj"],"parserLogLine":">>> {\"query\": \"query User {\\n activeUser {\\n id\\n email\\n name\\n bio\\n company\\n avatar\\n verified\\n profiles\\n role\\n }\\n}\"}"} [@speckle/fileimport-service]: INFO (38686): ParserLog: <<< {"errors":[{"message":"Your auth token does not have the required scope: profile:read.","locations":[{"line":2,"column":3}],"path":["activeUser"],"extensions":{"scope":"profile:read","code":"FORBIDDEN","exception":{"stacktrace":["ForbiddenError: Your auth token does not have the required scope: profile:read."," at validateScopes (/root/speckle-server/packages/server/dist/modules/shared/index.js:23:15)"," at Object.activeUser (/root/speckle-server/packages/server/dist/modules/core/graph/resolvers/users.js:29:19)"," at process.processTicksAndRejections (node:internal/process/task_queues:95:5)"]}}}],"data":{"activeUser":null}} {"component":"fileimport-service","taskId":"1fd12ab77f","fileId":"1fd12ab77f","fileType":"obj","fileName":"Wooden chair.obj","fileSize":20404831,"userId":"231cee48bb","streamId":"67c336d2c4","branchName":"Wooden chair.obj","cmd":"python3","args":["-u","./obj/import_file.py","/tmp/file_to_import/file","231cee48bb","67c336d2c4","Wooden chair.obj","File upload: Wooden chair.obj"],"parserLogLine":"<<< {\"errors\":[{\"message\":\"Your auth token does not have the required scope: profile:read.\",\"locations\":[{\"line\":2,\"column\":3}],\"path\":[\"activeUser\"],\"extensions\":{\"scope\":\"profile:read\",\"code\":\"FORBIDDEN\",\"exception\":{\"stacktrace\":[\"ForbiddenError: Your auth token does not have the required scope: profile:read.\",\" at validateScopes (/root/speckle-server/packages/server/dist/modules/shared/index.js:23:15)\",\" at Object.activeUser (/root/speckle-server/packages/server/dist/modules/core/graph/resolvers/users.js:29:19)\",\" at process.processTicksAndRejections (node:internal/process/task_queues:95:5)\"]}}}],\"data\":{\"activeUser\":null}}"} [@speckle/fileimport-service]: INFO (38686): Process exited with code '0' {"component":"fileimport-service","taskId":"1fd12ab77f","fileId":"1fd12ab77f","fileType":"obj","fileName":"Wooden chair.obj","fileSize":20404831,"userId":"231cee48bb","streamId":"67c336d2c4","branchName":"Wooden chair.obj","cmd":"python3","args":["-u","./obj/import_file.py","/tmp/file_to_import/file","231cee48bb","67c336d2c4","Wooden chair.obj","File upload: Wooden chair.obj"],"exitCode":0} [@speckle/fileimport-service]: ERROR: GraphQLException: Failed to execute the GraphQL active_user request. Errors: [{'message': 'Your auth token does not have the required scope: profile:read.', 'locations': [{'line': 2, 'column': 3}], 'path': ['activeUser'], 'extensions': {'scope': 'profile:read', 'code': 'FORBIDDEN', 'exception': {'stacktrace': ['ForbiddenError: Your auth token does not have the required scope: profile:read.', ' at validateScopes (/root/speckle-server/packages/server/dist/modules/shared/index.js:23:15)', ' at Object.activeUser (/root/speckle-server/packages/server/dist/modules/core/graph/resolvers/users.js:29:19)', ' at process.processTicksAndRejections (node:internal/process/task_queues:95:5)']}}}] {"component":"fileimport-service","taskId":"1fd12ab77f","fileId":"1fd12ab77f","fileType":"obj","fileName":"Wooden chair.obj","fileSize":20404831,"userId":"231cee48bb","streamId":"67c336d2c4","branchName":"Wooden chair.obj"} [@speckle/fileimport-service]: err: { [@speckle/fileimport-service]: "type": "Error", [@speckle/fileimport-service]: "message": "GraphQLException: Failed to execute the GraphQL active_user request. Errors: [{'message': 'Your auth token does not have the required scope: profile:read.', 'locations': [{'line': 2, 'column': 3}], 'path': ['activeUser'], 'extensions': {'scope': 'profile:read', 'code': 'FORBIDDEN', 'exception': {'stacktrace': ['ForbiddenError: Your auth token does not have the required scope: profile:read.', ' at validateScopes (/root/speckle-server/packages/server/dist/modules/shared/index.js:23:15)', ' at Object.activeUser (/root/speckle-server/packages/server/dist/modules/core/graph/resolvers/users.js:29:19)', ' at process.processTicksAndRejections (node:internal/process/task_queues:95:5)']}}}]", [@speckle/fileimport-service]: "stack": [@speckle/fileimport-service]: Error: GraphQLException: Failed to execute the GraphQL active_user request. Errors: [{'message': 'Your auth token does not have the required scope: profile:read.', 'locations': [{'line': 2, 'column': 3}], 'path': ['activeUser'], 'extensions': {'scope': 'profile:read', 'code': 'FORBIDDEN', 'exception': {'stacktrace': ['ForbiddenError: Your auth token does not have the required scope: profile:read.', ' at validateScopes (/root/speckle-server/packages/server/dist/modules/shared/index.js:23:15)', ' at Object.activeUser (/root/speckle-server/packages/server/dist/modules/core/graph/resolvers/users.js:29:19)', ' at process.processTicksAndRejections (node:internal/process/task_queues:95:5)']}}}] [@speckle/fileimport-service]: at doTask (/root/speckle-server/packages/fileimport-service/src/daemon.js:184:32) [@speckle/fileimport-service]: at async Timeout.tick [as _onTimeout] (/root/speckle-server/packages/fileimport-service/src/daemon.js:323:5) [@speckle/fileimport-service]: } [@speckle/fileimport-service]: ERROR: Error executing task {"component":"fileimport-service"} [@speckle/fileimport-service]: err: { [@speckle/fileimport-service]: "type": "DatabaseError", [@speckle/fileimport-service]: "message": "\n UPDATE file_uploads\n SET\n \"convertedStatus\" = 3,\n \"convertedLastUpdate\" = NOW(),\n \"convertedMessage\" = $1\n WHERE \"id\" = $2\n - value too long for type character varying(255)", [@speckle/fileimport-service]: "stack": [@speckle/fileimport-service]: error: [@speckle/fileimport-service]: UPDATE file_uploads [@speckle/fileimport-service]: SET [@speckle/fileimport-service]: "convertedStatus" = 3, [@speckle/fileimport-service]: "convertedLastUpdate" = NOW(), [@speckle/fileimport-service]: "convertedMessage" = $1 [@speckle/fileimport-service]: WHERE "id" = $2 [@speckle/fileimport-service]: - value too long for type character varying(255) [@speckle/fileimport-service]: at Parser.parseErrorMessage (/root/speckle-server/node_modules/pg-protocol/dist/parser.js:287:98) [@speckle/fileimport-service]: at Parser.handlePacket (/root/speckle-server/node_modules/pg-protocol/dist/parser.js:126:29) [@speckle/fileimport-service]: at Parser.parse (/root/speckle-server/node_modules/pg-protocol/dist/parser.js:39:38) [@speckle/fileimport-service]: at Socket. (/root/speckle-server/node_modules/pg-protocol/dist/index.js:11:42) [@speckle/fileimport-service]: at Socket.emit (node:events:517:28) [@speckle/fileimport-service]: at addChunk (node:internal/streams/readable:368:12) [@speckle/fileimport-service]: at readableAddChunk (node:internal/streams/readable:341:9) [@speckle/fileimport-service]: at Readable.push (node:internal/streams/readable:278:10) [@speckle/fileimport-service]: at TCP.onStreamRead (node:internal/stream_base_commons:190:23) [@speckle/fileimport-service]: "length": 99, [@speckle/fileimport-service]: "name": "error", [@speckle/fileimport-service]: "severity": "ERROR", [@speckle/fileimport-service]: "code": "22001", [@speckle/fileimport-service]: "file": "varchar.c", [@speckle/fileimport-service]: "line": "632", [@speckle/fileimport-service]: "routine": "varchar" [@speckle/fileimport-service]: } [@speckle/server]: [server] INFO: /graphql request received {"req":{"id":"88a23efc-3a35-478e-8f7a-c3da2c38411a","method":"POST","path":"/graphql","headers":{"host":"127.0.0.1:3000","user-agent":"python-requests/2.32.3","accept-encoding":"gzip, deflate","accept":"*/*","connection":"keep-alive","content-length":"64","content-type":"application/json","x-request-id":"88a23efc-3a35-478e-8f7a-c3da2c38411a"}},"requestPath":"/graphql"} [@speckle/server]: [server] INFO: GQL query serverInfo finished after 1 ms {"req":{"id":"88a23efc-3a35-478e-8f7a-c3da2c38411a","method":"POST","path":null,"headers":{"host":"127.0.0.1:3000","user-agent":"python-requests/2.32.3","accept-encoding":"gzip, deflate","accept":"*/*","connection":"keep-alive","content-length":"64","content-type":"application/json","x-request-id":"88a23efc-3a35-478e-8f7a-c3da2c38411a"}},"authContext":{"auth":false},"component":"graphql","graphql_operation_kind":"query","graphql_query":"query Server {\n serverInfo {\n version\n }\n}","graphql_operation_value":"GQL query serverInfo","graphql_operation_name":"GQL serverInfo","actionName":"query serverInfo","apollo_query_duration_ms":1} [@speckle/server]: [server] INFO: /graphql request completed in 4 ms {"req":{"id":"88a23efc-3a35-478e-8f7a-c3da2c38411a","method":"POST","path":"/graphql","headers":{"host":"127.0.0.1:3000","user-agent":"python-requests/2.32.3","accept-encoding":"gzip, deflate","accept":"*/*","connection":"keep-alive","content-length":"64","content-type":"application/json","x-request-id":"88a23efc-3a35-478e-8f7a-c3da2c38411a"}},"res":{"statusCode":200,"headers":{"x-request-id":"88a23efc-3a35-478e-8f7a-c3da2c38411a","access-control-allow-origin":"*","x-ratelimit-remaining":49,"content-security-policy":"frame-ancestors 'none'","content-type":"application/json; charset=utf-8","content-length":"42","etag":"W/\"2a-dFCf0hVPKzmFRKhjgI4qFfvnpzA\""}},"responseTime":4,"requestStatus":"completed","requestPath":"/graphql"} [@speckle/server]: [server] INFO: /graphql request received {"req":{"id":"e29f9ea1-7cd5-4e2a-97d9-e17010c8196b","method":"POST","path":"/graphql","headers":{"host":"127.0.0.1:3000","user-agent":"python-requests/2.32.3","accept-encoding":"gzip, deflate","accept":"*/*","connection":"keep-alive","content-type":"application/json","apollographql-client-name":"python","apollographql-client-version":"python 3.11","content-length":"64","x-request-id":"e29f9ea1-7cd5-4e2a-97d9-e17010c8196b"}},"requestPath":"/graphql"} [@speckle/server]: [server] INFO: GQL query serverInfo finished after 2 ms {"req":{"id":"e29f9ea1-7cd5-4e2a-97d9-e17010c8196b","method":"POST","path":null,"headers":{"host":"127.0.0.1:3000","user-agent":"python-requests/2.32.3","accept-encoding":"gzip, deflate","accept":"*/*","connection":"keep-alive","content-type":"application/json","apollographql-client-name":"python","apollographql-client-version":"python 3.11","content-length":"64","x-request-id":"e29f9ea1-7cd5-4e2a-97d9-e17010c8196b"}},"authContext":{"auth":true,"userId":"231cee48bb","role":"server:admin","scopes":["streams:write","streams:read"],"appId":null,"resourceAccessRules":null},"component":"graphql","graphql_operation_kind":"query","graphql_query":"query Server {\n serverInfo {\n version\n }\n}","graphql_operation_value":"GQL query serverInfo","graphql_operation_name":"GQL serverInfo","userId":"231cee48bb","actionName":"query serverInfo","apollo_query_duration_ms":2} [@speckle/server]: [server] INFO: /graphql request completed in 57 ms {"req":{"id":"e29f9ea1-7cd5-4e2a-97d9-e17010c8196b","method":"POST","path":"/graphql","headers":{"host":"127.0.0.1:3000","user-agent":"python-requests/2.32.3","accept-encoding":"gzip, deflate","accept":"*/*","connection":"keep-alive","content-type":"application/json","apollographql-client-name":"python","apollographql-client-version":"python 3.11","content-length":"64","x-request-id":"e29f9ea1-7cd5-4e2a-97d9-e17010c8196b"}},"res":{"statusCode":200,"headers":{"x-request-id":"e29f9ea1-7cd5-4e2a-97d9-e17010c8196b","access-control-allow-origin":"*","x-ratelimit-remaining":49,"content-security-policy":"frame-ancestors 'none'","content-type":"application/json; charset=utf-8","content-length":"42","etag":"W/\"2a-dFCf0hVPKzmFRKhjgI4qFfvnpzA\""},"userId":"231cee48bb"},"responseTime":57,"requestStatus":"completed","requestPath":"/graphql"} [@speckle/server]: [server] INFO: /graphql request received {"req":{"id":"eab66e1a-a099-4e8b-9efd-dee45ab38204","method":"POST","path":"/graphql","headers":{"host":"127.0.0.1:3000","user-agent":"python-requests/2.32.3","accept-encoding":"gzip, deflate","accept":"*/*","connection":"keep-alive","content-type":"application/json","apollographql-client-name":"python","apollographql-client-version":"python 3.11","content-length":"150","x-request-id":"eab66e1a-a099-4e8b-9efd-dee45ab38204"}},"requestPath":"/graphql"} [@speckle/server]: [server] INFO: GQL query activeUser failed after 1 ms {"req":{"id":"eab66e1a-a099-4e8b-9efd-dee45ab38204","method":"POST","path":null,"headers":{"host":"127.0.0.1:3000","user-agent":"python-requests/2.32.3","accept-encoding":"gzip, deflate","accept":"*/*","connection":"keep-alive","content-type":"application/json","apollographql-client-name":"python","apollographql-client-version":"python 3.11","content-length":"150","x-request-id":"eab66e1a-a099-4e8b-9efd-dee45ab38204"}},"authContext":{"auth":true,"userId":"231cee48bb","role":"server:admin","scopes":["streams:write","streams:read"],"appId":null,"resourceAccessRules":null},"component":"graphql","graphql_operation_kind":"query","graphql_query":"query User {\n activeUser {\n id\n email\n name\n bio\n company\n avatar\n verified\n profiles\n role\n }\n}","graphql_operation_value":"GQL query activeUser","graphql_operation_name":null,"userId":"231cee48bb","actionName":"query activeUser","apollo_query_duration_ms":1,"query-path":"activeUser"} [@speckle/server]: [server] err: { [@speckle/server]: [server] "type": "GraphQLError", [@speckle/server]: [server] "message": "Your auth token does not have the required scope: profile:read.", [@speckle/server]: [server] "stack": [@speckle/server]: [server] ForbiddenError: Your auth token does not have the required scope: profile:read. [@speckle/server]: [server] at validateScopes (/root/speckle-server/packages/server/dist/modules/shared/index.js:23:15) [@speckle/server]: [server] at Object.activeUser (/root/speckle-server/packages/server/dist/modules/core/graph/resolvers/users.js:29:19) [@speckle/server]: [server] at process.processTicksAndRejections (node:internal/process/task_queues:95:5) [@speckle/server]: [server] "locations": [ [@speckle/server]: [server] { [@speckle/server]: [server] "line": 2, [@speckle/server]: [server] "column": 3 [@speckle/server]: [server] } [@speckle/server]: [server] ], [@speckle/server]: [server] "path": [ [@speckle/server]: [server] "activeUser" [@speckle/server]: [server] ], [@speckle/server]: [server] "extensions": { [@speckle/server]: [server] "scope": "profile:read", [@speckle/server]: [server] "code": "FORBIDDEN" [@speckle/server]: [server] } [@speckle/server]: [server] } [@speckle/server]: [server] INFO: GQL query activeUser finished after 1 ms {"req":{"id":"eab66e1a-a099-4e8b-9efd-dee45ab38204","method":"POST","path":null,"headers":{"host":"127.0.0.1:3000","user-agent":"python-requests/2.32.3","accept-encoding":"gzip, deflate","accept":"*/*","connection":"keep-alive","content-type":"application/json","apollographql-client-name":"python","apollographql-client-version":"python 3.11","content-length":"150","x-request-id":"eab66e1a-a099-4e8b-9efd-dee45ab38204"}},"authContext":{"auth":true,"userId":"231cee48bb","role":"server:admin","scopes":["streams:write","streams:read"],"appId":null,"resourceAccessRules":null},"component":"graphql","graphql_operation_kind":"query","graphql_query":"query User {\n activeUser {\n id\n email\n name\n bio\n company\n avatar\n verified\n profiles\n role\n }\n}","graphql_operation_value":"GQL query activeUser","graphql_operation_name":"GQL activeUser","userId":"231cee48bb","actionName":"query activeUser","apollo_query_duration_ms":1} [@speckle/server]: [server] INFO: /graphql request completed in 53 ms {"req":{"id":"eab66e1a-a099-4e8b-9efd-dee45ab38204","method":"POST","path":"/graphql","headers":{"host":"127.0.0.1:3000","user-agent":"python-requests/2.32.3","accept-encoding":"gzip, deflate","accept":"*/*","connection":"keep-alive","content-type":"application/json","apollographql-client-name":"python","apollographql-client-version":"python 3.11","content-length":"150","x-request-id":"eab66e1a-a099-4e8b-9efd-dee45ab38204"}},"res":{"statusCode":200,"headers":{"x-request-id":"eab66e1a-a099-4e8b-9efd-dee45ab38204","access-control-allow-origin":"*","x-ratelimit-remaining":48,"content-security-policy":"frame-ancestors 'none'","content-type":"application/json; charset=utf-8","content-length":"641","etag":"W/\"281-bUmkyHxVsoEZNxJxRCzboiJzcvs\""},"userId":"231cee48bb"},"responseTime":53,"requestStatus":"completed","requestPath":"/graphql"} [@speckle/server]: [server] INFO (notification): Message #e482c9225648693cbabbdbd816abca9e of channel file_import_update starting processing... {"component":"db-notifications","length":74,"processId":413,"channel":"file_import_update","payload":"1fd12ab77f:::67c336d2c4:::Wooden chair.obj:::1","listenerRegistered":true,"messageId":"e482c9225648693cbabbdbd816abca9e"} [@speckle/server]: [server] INFO: GQL subscription event OnProjectPendingModelsUpdated emitted {"component":"graphql","graphql_query":"subscription OnProjectPendingModelsUpdated($id: String!) {\n projectPendingModelsUpdated(id: $id) {\n id\n type\n model {\n ...PendingFileUpload\n model {\n ...ProjectPageLatestItemsModelItem\n __typename\n }\n __typename\n }\n __typename\n }\n}\n\nfragment PendingFileUpload on FileUpload {\n id\n projectId\n modelName\n convertedStatus\n convertedMessage\n uploadDate\n convertedLastUpdate\n fileType\n fileName\n __typename\n}\n\nfragment ProjectPageModelsCardRenameDialog on Model {\n id\n name\n description\n __typename\n}\n\nfragment ProjectPageModelsCardDeleteDialog on Model {\n id\n name\n __typename\n}\n\nfragment ProjectPageModelsActions on Model {\n id\n name\n __typename\n}\n\nfragment FunctionRunStatusForSummary on AutomateFunctionRun {\n id\n status\n __typename\n}\n\nfragment TriggeredAutomationsStatusSummary on TriggeredAutomationsStatus {\n id\n automationRuns {\n id\n functionRuns {\n id\n ...FunctionRunStatusForSummary\n __typename\n }\n __typename\n }\n __typename\n}\n\nfragment AutomateRunsTriggerStatusDialogFunctionRun_AutomateFunctionRun on AutomateFunctionRun {\n id\n results\n status\n statusMessage\n contextView\n function {\n id\n logo\n name\n __typename\n }\n createdAt\n updatedAt\n __typename\n}\n\nfragment AutomationsStatusOrderedRuns_AutomationRun on AutomateRun {\n id\n automation {\n id\n name\n __typename\n }\n functionRuns {\n id\n updatedAt\n __typename\n }\n __typename\n}\n\nfragment AutomateRunsTriggerStatusDialogRunsRows_AutomateRun on AutomateRun {\n id\n functionRuns {\n id\n ...AutomateRunsTriggerStatusDialogFunctionRun_AutomateFunctionRun\n __typename\n }\n ...AutomationsStatusOrderedRuns_AutomationRun\n __typename\n}\n\nfragment AutomateRunsTriggerStatusDialog_TriggeredAutomationsStatus on TriggeredAutomationsStatus {\n id\n automationRuns {\n id\n ...AutomateRunsTriggerStatusDialogRunsRows_AutomateRun\n __typename\n }\n __typename\n}\n\nfragment AutomateRunsTriggerStatus_TriggeredAutomationsStatus on TriggeredAutomationsStatus {\n id\n ...TriggeredAutomationsStatusSummary\n ...AutomateRunsTriggerStatusDialog_TriggeredAutomationsStatus\n __typename\n}\n\nfragment ProjectPageLatestItemsModelItem on Model {\n id\n name\n displayName\n versionCount: versions(limit: 0) {\n totalCount\n __typename\n }\n commentThreadCount: commentThreads(limit: 0) {\n totalCount\n __typename\n }\n pendingImportedVersions(limit: 1) {\n ...PendingFileUpload\n __typename\n }\n previewUrl\n createdAt\n updatedAt\n ...ProjectPageModelsCardRenameDialog\n ...ProjectPageModelsCardDeleteDialog\n ...ProjectPageModelsActions\n automationsStatus {\n ...AutomateRunsTriggerStatus_TriggeredAutomationsStatus\n __typename\n }\n __typename\n}\n","graphql_variables":{"id":"67c336d2c4"},"graphql_operation_name":"OnProjectPendingModelsUpdated","graphql_operation_type":"subscription","userId":"231cee48bb"}