AFFiNE icon indicating copy to clipboard operation
AFFiNE copied to clipboard
verified publisher icon toeverything

[Bug]: graphql 400

Open cethink opened this issue 9 months ago • 1 comments

What happened?

When I open a document, it 400

Image

Image

Distribution version

Linux

App Version

0.20.5

What browsers are you seeing the problem on if you're using web version?

No response

Are you self-hosting?

  • [x] Yes

Self-hosting Version

0.20.5

Relevant log output

Anything else?

No response

cethink avatar Mar 21 '25 09:03 cethink

Issue Status: 🆕 *Untriaged

*🆕 Untriaged

The team has not yet reviewed the issue. We usually do it within one business day. Docs: https://github.com/toeverything/AFFiNE/blob/canary/docs/issue-triaging.md

This is an automatic reply by the bot.

affine-issue-bot[bot] avatar Mar 21 '25 09:03 affine-issue-bot[bot]

logs required

forehalo avatar Mar 27 '25 05:03 forehalo

logs required

how

cethink avatar Mar 27 '25 06:03 cethink

you can print them through docker logs

forehalo avatar Apr 07 '25 04:04 forehalo

docker logs affine_server

[Nest] 1  - 04/07/2025, 4:42:39 AM    WARN [MailerModule] MailerModule is not enabled because of the required configuration is not satisfied.
[Nest] 1  - 04/07/2025, 4:42:39 AM    WARN [MailerModule] Unsatisfied configuration:
[Nest] 1  - 04/07/2025, 4:42:39 AM    WARN [MailerModule]   AFFiNE.mailer.host
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [NestFactory] Starting Nest application...
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] ClsModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] ClsCommonModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] ConfigModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] ConfigModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] GqlModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM   DEBUG [ClsModule] ClsInterceptor will be automatically mounted
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] DiscoveryModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] ClsRootModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] RedisModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] PrismaModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] MetricsModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] MailModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] StorageProviderModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] HelpersModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] LoggerModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] ErrorModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] WebSocketModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] ScheduleModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] ScannerModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] VersionModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] BullModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] BullModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] AppModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] ThrottlerModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] CacheModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] MutexModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] EventModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] JobModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] RuntimeModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] ServerConfigModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] GraphQLSchemaBuilderModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] ClsPluginModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] RateLimiterModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] FeatureModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] StorageModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] PermissionModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] BullModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] ModelsModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] QuotaModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] LicenseModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] UserModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] SelfhostModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] GraphQLModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] DocStorageModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] DocRendererModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] AuthModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] DocServiceModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] SyncModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [InstanceLoader] WorkspaceModule dependencies initialized
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [WebSocketsController] SpaceSyncGateway subscribed to the "space:join" message
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [WebSocketsController] SpaceSyncGateway subscribed to the "space:leave" message
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [WebSocketsController] SpaceSyncGateway subscribed to the "space:load-doc" message
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [WebSocketsController] SpaceSyncGateway subscribed to the "space:delete-doc" message
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [WebSocketsController] SpaceSyncGateway subscribed to the "space:push-doc-updates" message
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [WebSocketsController] SpaceSyncGateway subscribed to the "space:push-doc-update" message
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [WebSocketsController] SpaceSyncGateway subscribed to the "space:load-doc-timestamps" message
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [WebSocketsController] SpaceSyncGateway subscribed to the "space:join-awareness" message
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [WebSocketsController] SpaceSyncGateway subscribed to the "space:leave-awareness" message
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [WebSocketsController] SpaceSyncGateway subscribed to the "space:load-awarenesses" message
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [WebSocketsController] SpaceSyncGateway subscribed to the "space:update-awareness" message
[Nest] 1  - 04/07/2025, 4:42:39 AM   DEBUG [ClsModule] Mounting ClsMiddleware to *
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [RoutesResolver] AppController {/info}:
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [RouterExplorer] Mapped {/info, GET} route
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [RoutesResolver] UserAvatarController {/api/avatars}:
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [RouterExplorer] Mapped {/api/avatars/:id, GET} route
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [RoutesResolver] AuthController {/api/auth}:
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [RouterExplorer] Mapped {/api/auth/preflight, POST} route
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [RouterExplorer] Mapped {/api/auth/sign-in, POST} route
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [RouterExplorer] Mapped {/api/auth/sign-out, GET} route
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [RouterExplorer] Mapped {/api/auth/magic-link, POST} route
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [RouterExplorer] Mapped {/api/auth/session, GET} route
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [RouterExplorer] Mapped {/api/auth/sessions, GET} route
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [RoutesResolver] WorkspacesController {/api/workspaces}:
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [RouterExplorer] Mapped {/api/workspaces/:id/blobs/:name, GET} route
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [RouterExplorer] Mapped {/api/workspaces/:id/docs/:guid, GET} route
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [RouterExplorer] Mapped {/api/workspaces/:id/docs/:guid/histories/:timestamp, GET} route
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [RoutesResolver] DocRendererController {/workspace}:
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [RouterExplorer] Mapped {/workspace/*, GET} route
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [RoutesResolver] DocRpcController {/rpc}:
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [RouterExplorer] Mapped {/rpc/workspaces/:workspaceId/docs/:docId, GET} route
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [RouterExplorer] Mapped {/rpc/workspaces/:workspaceId/docs/:docId/diff, POST} route
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [RouterExplorer] Mapped {/rpc/workspaces/:workspaceId/docs/:docId/content, GET} route
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [RouterExplorer] Mapped {/rpc/workspaces/:workspaceId/content, GET} route
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [RoutesResolver] CustomSetupController {/api/setup}:
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [RouterExplorer] Mapped {/api/setup/create-admin-user, POST} route
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [GraphQLModule] Mapped {/graphql, POST} route
[Nest] 1  - 04/07/2025, 4:42:39 AM VERBOSE [EventBus] Event handler registered [user.updated] (UserEventsListener.onUserUpdated)
[Nest] 1  - 04/07/2025, 4:42:39 AM VERBOSE [EventBus] Event handler registered [user.deleted] (UserEventsListener.onUserDeleted)
[Nest] 1  - 04/07/2025, 4:42:39 AM VERBOSE [EventBus] Event handler registered [workspace.blob.sync] (WorkspaceBlobStorage.syncBlobMeta)
[Nest] 1  - 04/07/2025, 4:42:39 AM VERBOSE [EventBus] Event handler registered [workspace.deleted] (WorkspaceBlobStorage.onWorkspaceDeleted)
[Nest] 1  - 04/07/2025, 4:42:39 AM VERBOSE [EventBus] Event handler registered [workspace.blob.delete] (WorkspaceBlobStorage.onDeleteWorkspaceBlob)
[Nest] 1  - 04/07/2025, 4:42:39 AM VERBOSE [EventBus] Event handler registered [user.deleted] (AvatarStorage.onUserDeleted)
[Nest] 1  - 04/07/2025, 4:42:39 AM VERBOSE [EventBus] Event handler registered [doc.created] (PermissionService.setDefaultPageOwner)
[Nest] 1  - 04/07/2025, 4:42:39 AM VERBOSE [EventBus] Event handler registered [user.postCreated] (QuotaService.onUserCreated)
[Nest] 1  - 04/07/2025, 4:42:39 AM VERBOSE [EventBus] Event handler registered [user.deleted] (DocStorageCronJob.clearUserWorkspaces)
[Nest] 1  - 04/07/2025, 4:42:39 AM VERBOSE [EventBus] Event handler registered [doc.snapshot.updated] (DocEventsListener.markDocContentCacheStale)
[Nest] 1  - 04/07/2025, 4:42:39 AM VERBOSE [EventBus] Event handler registered [workspace.members.leave] (WorkspaceService.onMemberLeave)
[Nest] 1  - 04/07/2025, 4:42:39 AM VERBOSE [EventBus] Event handler registered [workspace.members.removed] (WorkspaceService.onMemberRemoved)
[Nest] 1  - 04/07/2025, 4:42:39 AM VERBOSE [EventBus] Event handler registered [workspace.members.reviewRequested] (WorkspaceEvents.onReviewRequested)
[Nest] 1  - 04/07/2025, 4:42:39 AM VERBOSE [EventBus] Event handler registered [workspace.members.requestApproved] (WorkspaceEvents.onApproveRequest)
[Nest] 1  - 04/07/2025, 4:42:39 AM VERBOSE [EventBus] Event handler registered [workspace.members.requestDeclined] (WorkspaceEvents.onDeclineRequest)
[Nest] 1  - 04/07/2025, 4:42:39 AM VERBOSE [EventBus] Event handler registered [workspace.members.roleChanged] (WorkspaceEvents.onRoleChanged)
[Nest] 1  - 04/07/2025, 4:42:39 AM VERBOSE [EventBus] Event handler registered [workspace.members.ownershipTransferred] (WorkspaceEvents.onOwnerTransferred)
[Nest] 1  - 04/07/2025, 4:42:39 AM VERBOSE [EventBus] Event handler registered [workspace.members.updated] (LicenseService.updateTeamSeats)
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [GuardProvider] Guard provider [version] registered
[Nest] 1  - 04/07/2025, 4:42:39 AM VERBOSE [EventBus] Event handler registered [workspace.subscription.activated] ()
[Nest] 1  - 04/07/2025, 4:42:39 AM VERBOSE [EventBus] Event handler registered [workspace.subscription.canceled] ()
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [job] Queue Worker [nightly] started; concurrency=1;
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [job] Queue Worker [notification] started; concurrency=10;
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [job] Queue Worker [doc] started; concurrency=1;
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [NestApplication] Nest application successfully started
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [App] AFFiNE Server is running in [selfhosted] mode
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [App] Listening on http://0.0.0.0:3010
[Nest] 1  - 04/07/2025, 4:42:39 AM     LOG [App] And the public server should be recognized as https://affine.example.com
[Nest] 1  - 04/07/2025, 4:43:36 AM VERBOSE [Locker] <allinone:http:48e10594-36ca-4fee-b16f-812f66c11707> Client cluster:CLHKHGztJdc3oK2jh6rkH:bUmmr62jNTMUDtxFeGUuX is trying to lock resource doc:update:1c639b0a-40f9-4349-865e-4c2c6c7ce485:1c639b0a-40f9-4349-865e-4c2c6c7ce485
[Nest] 1  - 04/07/2025, 4:43:40 AM     LOG [SpaceSyncGateway] New connection, total: 1
[Nest] 1  - 04/07/2025, 4:43:40 AM VERBOSE [Locker] <allinone:ws:2d2dd5cb-69cb-48e9-8c0a-0b0644cb4e8f> Client cluster:CLHKHGztJdc3oK2jh6rkH:ioY0nunT2svNV-pFkc3UG is trying to lock resource doc:update:1c639b0a-40f9-4349-865e-4c2c6c7ce485:1c639b0a-40f9-4349-865e-4c2c6c7ce485
[Nest] 1  - 04/07/2025, 4:43:40 AM VERBOSE [FsStorageProvider:avatars] <allinone:http:0d35d06d-6423-4dbe-b339-145feaea0704> Read object `e174a24f-7920-4cf4-bd17-14d50b38167f-avatar-1742549317076`
[Nest] 1  - 04/07/2025, 4:43:40 AM VERBOSE [Locker] <allinone:http:d1aa9dbb-9638-45e7-8bd1-981e090c0651> Client cluster:CLHKHGztJdc3oK2jh6rkH:RVUqT7wprBDCSbMlLzFpx is trying to lock resource doc:update:1c639b0a-40f9-4349-865e-4c2c6c7ce485:1c639b0a-40f9-4349-865e-4c2c6c7ce485
[Nest] 1  - 04/07/2025, 4:46:03 AM     LOG [SpaceSyncGateway] Connection disconnected, total: 0
[Nest] 1  - 04/07/2025, 4:46:03 AM VERBOSE [Locker] <allinone:http:fea24a02-daa3-4b28-a843-9f219ce1a48b> Client cluster:CLHKHGztJdc3oK2jh6rkH:Sk_tcNJhebRbYNyEBX8x2 is trying to lock resource doc:update:1c639b0a-40f9-4349-865e-4c2c6c7ce485:G8_hh2Yck4
[Nest] 1  - 04/07/2025, 4:46:05 AM VERBOSE [Locker] <allinone:http:487004af-31cf-47b1-8f97-662f45de2eda> Client cluster:CLHKHGztJdc3oK2jh6rkH:rkWyRB8OeZzDEOV6GEX2F is trying to lock resource doc:update:1c639b0a-40f9-4349-865e-4c2c6c7ce485:1c639b0a-40f9-4349-865e-4c2c6c7ce485
[Nest] 1  - 04/07/2025, 4:46:07 AM     LOG [SpaceSyncGateway] New connection, total: 1
[Nest] 1  - 04/07/2025, 4:46:07 AM VERBOSE [Locker] <allinone:http:0b0b1368-e72b-42a5-bbb8-3c3728ebddff> Client cluster:CLHKHGztJdc3oK2jh6rkH:YR0xIBmapOGCZ_uND1gNw is trying to lock resource doc:update:1c639b0a-40f9-4349-865e-4c2c6c7ce485:1c639b0a-40f9-4349-865e-4c2c6c7ce485
[Nest] 1  - 04/07/2025, 4:46:07 AM VERBOSE [Locker] <allinone:ws:8e4982fb-461e-4dc6-8ae2-9f2c14846fce> Client cluster:CLHKHGztJdc3oK2jh6rkH:dUFPK9RANi85BM6IOWpv7 is trying to lock resource doc:update:1c639b0a-40f9-4349-865e-4c2c6c7ce485:1c639b0a-40f9-4349-865e-4c2c6c7ce485

cethink avatar Apr 07 '25 04:04 cethink

there must be "internal server error" related logs if you met the 400 error again

forehalo avatar Apr 08 '25 02:04 forehalo

Forget it, it doesn't affect the use at the moment, thanks

cethink avatar Apr 08 '25 02:04 cethink