Skip to content
API

MM-60541 Don't save an unmodified draft when changing channels (#28620) #5

Sign in to view logs

MM-60541 Don't save an unmodified draft when changing channels (#28620)

MM-60541 Don't save an unmodified draft when changing channels (#28620) #5

GitHub Actions / MySQL (Results) failed Oct 11, 2024 in 0s

12983 tests run, 12913 passed, 60 skipped, 10 failed, 3 retried.

Annotations

Check failure on line 1 in com/mattermost/mattermost/server/v8/channels/api4

See this annotation in the file changed.

@github-actions github-actions / MySQL (Results)

com/mattermost/mattermost/server/v8/channels/api4.TestSyncLdap/SystemAdminClient#01 

Failed
Raw output 
=== RUN   TestSyncLdap/SystemAdminClient#01
{"timestamp":"2024-10-11 15:41:07.331 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/ldap/sync","request_id":"a6naocft7f8fznrspig5ogeqey","user_id":"qewfpoegjjfcdkf158jr6wa7sy","status_code":"200"}
==================
WARNING: DATA RACE
Read at 0x00c004bc1110 by goroutine 141332:
  reflect.maplen()
      /usr/local/go/src/runtime/map.go:1406 +0x0
  reflect.Value.lenNonSlice()
      /usr/local/go/src/reflect/value.go:1785 +0x1e9
  reflect.Value.Len()
      /usr/local/go/src/reflect/value.go:1774 +0x137
  internal/fmtsort.Sort()
      /usr/local/go/src/internal/fmtsort/sort.go:58 +0x121
  fmt.(*pp).printValue()
      /usr/local/go/src/fmt/print.go:816 +0x1144
  fmt.(*pp).printValue()
      /usr/local/go/src/fmt/print.go:853 +0x1d3e
  fmt.(*pp).printValue()
      /usr/local/go/src/fmt/print.go:853 +0x1d3e
  fmt.(*pp).printValue()
      /usr/local/go/src/fmt/print.go:921 +0x132a
  fmt.(*pp).printArg()
      /usr/local/go/src/fmt/print.go:759 +0xb84
  fmt.(*pp).doPrintf()
      /usr/local/go/src/fmt/print.go:1174 +0x10ce
  fmt.Sprintf()
      /usr/local/go/src/fmt/print.go:239 +0x5c
  github.com/stretchr/testify/mock.Arguments.Diff()
      /go/pkg/mod/github.com/stretchr/[email protected]/mock/mock.go:939 +0x1b2
  github.com/stretchr/testify/mock.(*Mock).findExpectedCall()
      /go/pkg/mod/github.com/stretchr/[email protected]/mock/mock.go:368 +0x147
  github.com/stretchr/testify/mock.(*Mock).MethodCalled()
      /go/pkg/mod/github.com/stretchr/[email protected]/mock/mock.go:476 +0xac
  github.com/stretchr/testify/mock.(*Mock).Called()
      /go/pkg/mod/github.com/stretchr/[email protected]/mock/mock.go:466 +0x195
  github.com/mattermost/mattermost/server/v8/einterfaces/mocks.(*LdapInterface).StartSynchronizeJob()
      /mattermost/server/einterfaces/mocks/LdapInterface.go:406 +0x1b0
  github.com/mattermost/mattermost/server/v8/channels/app.(*App).SyncLdap.func1()
      /mattermost/server/channels/app/ldap.go:33 +0x1d8
  github.com/mattermost/mattermost/server/v8/channels/app/platform.(*PlatformService).Go.func1()
      /mattermost/server/channels/app/platform/goroutines.go:14 +0x37

Previous write at 0x00c004bc1110 by goroutine 141299:
  runtime.mapclear()
      /usr/local/go/src/runtime/map.go:989 +0x0
  github.com/mattermost/mattermost/server/v8/channels/app/platform.(*PlatformService).ReturnSessionToPool()
      /mattermost/server/channels/app/platform/session.go:25 +0xcf
  github.com/mattermost/mattermost/server/v8/channels/app.(*App).ReturnSessionToPool()
      /mattermost/server/channels/app/session.go:227 +0x26
  github.com/mattermost/mattermost/server/v8/channels/web.Handler.ServeHTTP.deferwrap1()
      /mattermost/server/channels/web/handlers.go:295 +0x55
  runtime.deferreturn()
      /usr/local/go/src/runtime/panic.go:602 +0x5d
  github.com/mattermost/mattermost/server/v8/channels/web.(*Handler).ServeHTTP()
      <autogenerated>:1 +0xe7
  github.com/klauspost/compress/gzhttp.NewWrapper.func1.1()
      /go/pkg/mod/github.com/klauspost/[email protected]/gzhttp/compress.go:495 +0x7c6
  net/http.HandlerFunc.ServeHTTP()
      /usr/local/go/src/net/http/server.go:2171 +0x47
  github.com/gorilla/mux.(*Router).ServeHTTP()
      /go/pkg/mod/github.com/gorilla/[email protected]/mux.go:212 +0x371
  net/http.serverHandler.ServeHTTP()
      /usr/local/go/src/net/http/server.go:3142 +0x2a1
  net/http.(*conn).serve()
      /usr/local/go/src/net/http/server.go:2044 +0x13c4
  net/http.(*Server).Serve.gowrap3()
      /usr/local/go/src/net/http/server.go:3290 +0x4f

Goroutine 141332 (running) created at:
  github.com/mattermost/mattermost/server/v8/channels/app/platform.(*PlatformService).Go()
      /mattermost/server/channels/app/platform/goroutines.go:13 +0xec
  github.com/mattermost/mattermost/server/v8/channels/app.(*Server).Go()
      /mattermost/server/channels/app/server.go:806 +0x148
  github.com/mattermost/mattermost/server/v8/channels/app.(*App).SyncLdap()
      /mattermost/server/channels/app/ldap.go:21 +0x5d
  github.com/mattermost/mattermost/server/v8/channels/api4.syncLdap()
      /mattermost/server/channels/api4/ldap.go:69 +0x9de
  github.com/mattermost/mattermost/server/v8/channels/web.Handler.ServeHTTP()
      /mattermost/server/channels/web/handlers.go:387 +0x6645
  github.com/mattermost/mattermost/server/v8/channels/web.(*Handler).ServeHTTP()
      <autogenerated>:1 +0xe7
  github.com/klauspost/compress/gzhttp.NewWrapper.func1.1()
      /go/pkg/mod/github.com/klauspost/[email protected]/gzhttp/compress.go:495 +0x7c6
  net/http.HandlerFunc.ServeHTTP()
      /usr/local/go/src/net/http/server.go:2171 +0x47
  github.com/gorilla/mux.(*Router).ServeHTTP()
      /go/pkg/mod/github.com/gorilla/[email protected]/mux.go:212 +0x371
  net/http.serverHandler.ServeHTTP()
      /usr/local/go/src/net/http/server.go:3142 +0x2a1
  net/http.(*conn).serve()
      /usr/local/go/src/net/http/server.go:2044 +0x13c4
  net/http.(*Server).Serve.gowrap3()
      /usr/local/go/src/net/http/server.go:3290 +0x4f

Goroutine 141299 (running) created at:
  net/http.(*Server).Serve()
      /usr/local/go/src/net/http/server.go:3290 +0x8ec
  github.com/mattermost/mattermost/server/v8/channels/app.(*Server).Start.func2()
      /mattermost/server/channels/app/server.go:1070 +0x224
==================
{"timestamp":"2024-10-11 15:41:07.334 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/ldap/sync","request_id":"5ckpcsi6gp8ppjqcs9oqwreppo","user_id":"qewfpoegjjfcdkf158jr6wa7sy","status_code":"200"}
    testing.go:1398: race detected during execution of test
--- FAIL: TestSyncLdap/SystemAdminClient#01 (0.01s)

Check failure on line 1 in com/mattermost/mattermost/server/v8/channels/api4

See this annotation in the file changed.

@github-actions github-actions / MySQL (Results)

com/mattermost/mattermost/server/v8/channels/api4.TestSyncLdap 

Failed
Raw output 
=== RUN   TestSyncLdap
{"timestamp":"2024-10-11 15:41:05.700 Z","level":"debug","msg":"Advanced logging config not provided for notification logging","caller":"platform/config.go:165"}
{"timestamp":"2024-10-11 15:41:05.700 Z","level":"info","msg":"Successfully connected to cache backend","caller":"platform/service.go:191","backend":"lru","result":"OK"}
{"timestamp":"2024-10-11 15:41:05.700 Z","level":"info","msg":"Server is initializing...","caller":"platform/service.go:194","go_version":"go1.22.6"}
{"timestamp":"2024-10-11 15:41:05.705 Z","level":"info","msg":"Starting websocket hubs","caller":"platform/web_hub.go:106","number_of_hubs":4}
{"timestamp":"2024-10-11 15:41:05.705 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":1}
{"timestamp":"2024-10-11 15:41:05.705 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":0}
{"timestamp":"2024-10-11 15:41:05.705 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":2}
{"timestamp":"2024-10-11 15:41:05.706 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":3}
{"timestamp":"2024-10-11 15:41:05.706 Z","level":"info","msg":"Loaded system translations","caller":"i18n/i18n.go:175","for locale":"en","from locale":"/mattermost/server/i18n/en.json"}
{"timestamp":"2024-10-11 15:41:05.714 Z","level":"info","msg":"Ensuring the telemetry ID..","caller":"telemetry/telemetry.go:167"}
{"timestamp":"2024-10-11 15:41:05.715 Z","level":"info","msg":"telemetry ID is set","caller":"telemetry/telemetry.go:177","id":"sr876bj8s7rkmm3xim7donzcpo"}
{"timestamp":"2024-10-11 15:41:05.736 Z","level":"error","msg":"SiteURL must be set. Some features will operate incorrectly if the SiteURL is not set. See documentation for details: https://mattermost.com/pl/configure-site-url","caller":"app/server.go:390"}
{"timestamp":"2024-10-11 15:41:05.736 Z","level":"info","msg":"Current version is 10.2.0 (///)","caller":"app/server.go:404","current_version":"10.2.0","build_number":"","build_date":"","build_hash":"","build_hash_enterprise":"","service_environment":"dev"}
{"timestamp":"2024-10-11 15:41:05.736 Z","level":"info","msg":"Team Edition Build","caller":"app/server.go:420","enterprise_build":false}
{"timestamp":"2024-10-11 15:41:05.736 Z","level":"info","msg":"Printing current working","caller":"app/server.go:424","directory":"/tmp/testlib3283879473"}
{"timestamp":"2024-10-11 15:41:05.736 Z","level":"info","msg":"Loaded config","caller":"app/server.go:425","source":"memory://"}
{"timestamp":"2024-10-11 15:41:06.002 Z","level":"debug","msg":"initElasticsearchChannelIndexCheck: calling elasticsearchChannelIndexCheckWithRetry before setting up config and license change listeners...","caller":"app/elasticsearch.go:21"}
{"timestamp":"2024-10-11 15:41:06.004 Z","level":"debug","msg":"elasticsearchChannelIndexCheckWithRetry: attempting to check channel index state...","caller":"app/elasticsearch.go:64"}
{"timestamp":"2024-10-11 15:41:06.005 Z","level":"debug","msg":"elasticsearchChannelIndexCheckWithRetry: skipping because elasticsearch indexing is disabled","caller":"app/elasticsearch.go:67"}
{"timestamp":"2024-10-11 15:41:06.058 Z","level":"debug","msg":"Fetching user count for first user account check","caller":"platform/config.go:356"}
{"timestamp":"2024-10-11 15:41:06.072 Z","level":"debug","msg":"Advanced logging config not provided for notification logging","caller":"platform/config.go:165"}
{"timestamp":"2024-10-11 15:41:06.072 Z","level":"info","msg":"Starting up plugins","caller":"app/plugin.go:191"}
{"timestamp":"2024-10-11 15:41:06.072 Z","level":"debug","msg":"Enabling plugin health check job","caller":"plugin/environment.go:661","interval_s":"30s"}
{"timestamp":"2024-10-11 15:41:06.072 Z","level":"info","msg":"Syncing plugins from the file store","caller":"app/plugin.go:268"}
{"timestamp":"2024-10-11 15:41:06.072 Z","level":"info","msg":"No plugins to sync from the file store","caller":"app/plugin.go:311"}
{"timestamp":"2024-10-11 15:41:06.073 Z","level":"debug","msg":"Plugin health check job starting.","caller":"plugin/health_check.go:31"}
{"timestamp":"2024-10-11 15:41:06.073 Z","level":"debug","msg":"No prepackaged plugins directory found","caller":"app/plugin.go:929"}
{"timestamp":"2024-10-11 15:41:06.073 Z","level":"debug","msg":"Not persisting transitionally prepackaged plugins: none found","caller":"app/plugin.go:1201"}
{"timestamp":"2024-10-11 15:41:06.082 Z","level":"debug","msg":"Able to write files to local storage.","caller":"filestore/localstore.go:82"}
{"timestamp":"2024-10-11 15:41:06.118 Z","level":"info","msg":"Starting Server...","caller":"app/server.go:887"}
{"timestamp":"2024-10-11 15:41:06.119 Z","level":"info","msg":"Server is listening on 127.0.0.1:33657","caller":"app/server.go:963","address":"127.0.0.1:33657"}
{"timestamp":"2024-10-11 15:41:06.119 Z","level":"debug","msg":"No license provided; Remote Cluster services disabled","caller":"app/server.go:580"}
{"timestamp":"2024-10-11 15:41:06.209 Z","level":"debug","msg":"Initializing web routes","caller":"web/web.go:26"}
{"timestamp":"2024-10-11 15:41:06.210 Z","level":"debug","msg":"Skipping update to assets subpath since CI build","caller":"utils/subpath.go:187"}
{"timestamp":"2024-10-11 15:41:06.210 Z","level":"debug","msg":"Using client directory","caller":"web/static.go:34","clientDir":"/tmp/testlib3283879473/client"}
{"timestamp":"2024-10-11 15:41:06.211 Z","level":"debug","msg":"Configured Client4 bool string values","caller":"api4/apitestlib.go:212","true":"T","false":"0"}
{"timestamp":"2024-10-11 15:41:07.266 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users/login","request_id":"3hp4gwm9u3d5ue7ppp44k97rqo","user_id":"ak7rztuwr3fcipqn8kqjsbbcbo","status_code":"200"}
{"timestamp":"2024-10-11 15:41:07.281 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users/login","request_id":"j6odug8i3fb43q8xmr9t64mm8c","user_id":"qewfpoegjjfcdkf158jr6wa7sy","status_code":"200"}
{"timestamp":"2024-10-11 15:41:07.285 Z","level":"info","msg":"Set license","caller":"platform/license.go:384","id":"","issued_at":"1970-01-01 00:00:00.000 Z","starts_at":"1970-01-01 00:00:00.000 Z","expires_at":"2025-01-09 15:41:07.285 Z","sku_name":"","sku_short_name":"","is_trial":false,"is_gov_sku":false,"customer_id":"some ID","features.users":0,"features":{"advanced_logging":true,"cloud":false,"cluster":true,"compliance":true,"custom_permissions_schemes":true,"data_retention":true,"elastic_search":true,"email_notification_contents":true,"enterprise_plugins":true,"future":true,"google":true,"guest_accounts":true,"guest_accounts_permissions":true,"id_loaded":true,"ldap":true,"ldap_groups":true,"lock_teammate_name_display":true,"message_export":true,"metrics":true,"mfa":true,"mhpns":true,"office365":true,"openid":true,"outgoing_oauth_connections":true,"remote_cluster_service":true,"saml":true,"shared_channels":true}}
{"timestamp":"2024-10-11 15:41:07.320 Z","level":"debug","msg":"Fetching user count for first user account check","caller":"platform/config.go:356"}
{"timestamp":"2024-10-11 15:41:07.327 Z","level":"debug","msg":"Advanced logging config not provided for notification logging","caller":"platform/config.go:165"}
{"timestamp":"2024-10-11 15:41:07.339 Z","level":"debug","msg":"You do not have the appropriate permissions.","caller":"web/context.go:120","path":"/api/v4/ldap/sync","request_id":"enzc9ysmb3df3c8jbw15b444aa","ip_addr":"127.0.0.1","user_id":"ak7rztuwr3fcipqn8kqjsbbcbo","method":"POST","err_where":"Permissions","http_code":403,"error":"Permissions: You do not have the appropriate permissions., userId=ak7rztuwr3fcipqn8kqjsbbcbo, permission=create_ldap_sync_job"}
{"timestamp":"2024-10-11 15:41:07.339 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/ldap/sync","request_id":"enzc9ysmb3df3c8jbw15b444aa","user_id":"ak7rztuwr3fcipqn8kqjsbbcbo","status_code":"403"}
{"timestamp":"2024-10-11 15:41:07.340 Z","level":"info","msg":"Purging all caches","caller":"platform/cluster_handlers.go:114"}
{"timestamp":"2024-10-11 15:41:07.341 Z","level":"info","msg":"License key has not been uploaded. Loading license key from disk.","caller":"utils/license.go:107","filename":"/tmp/testlib3283879473/config/mattermost.mattermost-license"}
{"timestamp":"2024-10-11 15:41:07.341 Z","level":"warn","msg":"Failed to get license from disk","caller":"platform/license.go:93","error":"We could not find the license key on disk at /tmp/testlib3283879473/config/mattermost.mattermost-license: stat /tmp/testlib3283879473/config/mattermost.mattermost-license: no such file or directory"}
{"timestamp":"2024-10-11 15:41:07.342 Z","level":"warn","msg":"License key from https://mattermost.com required to unlock enterprise features.","caller":"platform/license.go:105","error":"resource \"License\" not found, id: "}
{"timestamp":"2024-10-11 15:41:07.342 Z","level":"info","msg":"Cleared license","caller":"platform/license.go:384","id":"","issued_at":"1970-01-01 00:00:00.000 Z","starts_at":"1970-01-01 00:00:00.000 Z","expires_at":"2025-01-09 15:41:07.285 Z","sku_name":"","sku_short_name":"","is_trial":false,"is_gov_sku":false,"customer_id":"some ID","features.users":0,"features":{"advanced_logging":true,"cloud":false,"cluster":true,"compliance":true,"custom_permissions_schemes":true,"data_retention":true,"elastic_search":true,"email_notification_contents":true,"enterprise_plugins":true,"future":true,"google":true,"guest_accounts":true,"guest_accounts_permissions":true,"id_loaded":true,"ldap":true,"ldap_groups":true,"lock_teammate_name_display":true,"message_export":true,"metrics":true,"mfa":true,"mhpns":true,"office365":true,"openid":true,"outgoing_oauth_connections":true,"remote_cluster_service":true,"saml":true,"shared_channels":true}}
{"timestamp":"2024-10-11 15:41:07.346 Z","level":"info","msg":"Stopping Server...","caller":"app/server.go:669"}
{"timestamp":"2024-10-11 15:41:07.348 Z","level":"info","msg":"Shutting down Email batching service...","caller":"email/service.go:85"}
{"timestamp":"2024-10-11 15:41:07.348 Z","level":"info","msg":"Shutting down plugins","caller":"app/plugin.go:362"}
{"timestamp":"2024-10-11 15:41:07.348 Z","level":"debug","msg":"Disabling plugin health check job","caller":"plugin/environment.go:670"}
{"timestamp":"2024-10-11 15:41:07.348 Z","level":"info","msg":"stopping websocket hub connections","caller":"platform/web_hub.go:127"}
{"timestamp":"2024-10-11 15:41:07.348 Z","level":"info","msg":"Server stopped","caller":"app/server.go:753"}
--- FAIL: TestSyncLdap (2.12s)

Check failure on line 1 in com/mattermost/mattermost/server/v8/channels/api4

See this annotation in the file changed.

@github-actions github-actions / MySQL (Results)

com/mattermost/mattermost/server/v8/channels/api4.TestWebSocketReconnectRace 

Failed
Raw output 
=== RUN   TestWebSocketReconnectRace
{"timestamp":"2024-10-11 16:13:17.014 Z","level":"debug","msg":"Advanced logging config not provided for notification logging","caller":"platform/config.go:165"}
{"timestamp":"2024-10-11 16:13:17.014 Z","level":"info","msg":"Successfully connected to cache backend","caller":"platform/service.go:191","backend":"lru","result":"OK"}
{"timestamp":"2024-10-11 16:13:17.014 Z","level":"info","msg":"Server is initializing...","caller":"platform/service.go:194","go_version":"go1.22.6"}
{"timestamp":"2024-10-11 16:13:17.020 Z","level":"info","msg":"Starting websocket hubs","caller":"platform/web_hub.go:106","number_of_hubs":4}
{"timestamp":"2024-10-11 16:13:17.021 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":1}
{"timestamp":"2024-10-11 16:13:17.021 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":0}
{"timestamp":"2024-10-11 16:13:17.021 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":2}
{"timestamp":"2024-10-11 16:13:17.021 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":3}
{"timestamp":"2024-10-11 16:13:17.022 Z","level":"info","msg":"Loaded system translations","caller":"i18n/i18n.go:175","for locale":"en","from locale":"/mattermost/server/i18n/en.json"}
{"timestamp":"2024-10-11 16:13:17.031 Z","level":"info","msg":"Ensuring the telemetry ID..","caller":"telemetry/telemetry.go:167"}
{"timestamp":"2024-10-11 16:13:17.033 Z","level":"info","msg":"telemetry ID is set","caller":"telemetry/telemetry.go:177","id":"3b8pyirzhjyb9k8ag7zsccgyqo"}
{"timestamp":"2024-10-11 16:13:17.054 Z","level":"error","msg":"SiteURL must be set. Some features will operate incorrectly if the SiteURL is not set. See documentation for details: https://mattermost.com/pl/configure-site-url","caller":"app/server.go:390"}
{"timestamp":"2024-10-11 16:13:17.054 Z","level":"info","msg":"Current version is 10.2.0 (///)","caller":"app/server.go:404","current_version":"10.2.0","build_number":"","build_date":"","build_hash":"","build_hash_enterprise":"","service_environment":"dev"}
{"timestamp":"2024-10-11 16:13:17.054 Z","level":"info","msg":"Team Edition Build","caller":"app/server.go:420","enterprise_build":false}
{"timestamp":"2024-10-11 16:13:17.054 Z","level":"info","msg":"Printing current working","caller":"app/server.go:424","directory":"/tmp/testlib3283879473"}
{"timestamp":"2024-10-11 16:13:17.054 Z","level":"info","msg":"Loaded config","caller":"app/server.go:425","source":"memory://"}
{"timestamp":"2024-10-11 16:13:17.270 Z","level":"debug","msg":"initElasticsearchChannelIndexCheck: calling elasticsearchChannelIndexCheckWithRetry before setting up config and license change listeners...","caller":"app/elasticsearch.go:21"}
{"timestamp":"2024-10-11 16:13:17.270 Z","level":"debug","msg":"elasticsearchChannelIndexCheckWithRetry: attempting to check channel index state...","caller":"app/elasticsearch.go:64"}
{"timestamp":"2024-10-11 16:13:17.270 Z","level":"debug","msg":"elasticsearchChannelIndexCheckWithRetry: skipping because elasticsearch indexing is disabled","caller":"app/elasticsearch.go:67"}
{"timestamp":"2024-10-11 16:13:17.303 Z","level":"debug","msg":"Fetching user count for first user account check","caller":"platform/config.go:356"}
{"timestamp":"2024-10-11 16:13:17.310 Z","level":"debug","msg":"Advanced logging config not provided for notification logging","caller":"platform/config.go:165"}
{"timestamp":"2024-10-11 16:13:17.310 Z","level":"info","msg":"Starting up plugins","caller":"app/plugin.go:191"}
{"timestamp":"2024-10-11 16:13:17.310 Z","level":"debug","msg":"Enabling plugin health check job","caller":"plugin/environment.go:661","interval_s":"30s"}
{"timestamp":"2024-10-11 16:13:17.310 Z","level":"info","msg":"Syncing plugins from the file store","caller":"app/plugin.go:268"}
{"timestamp":"2024-10-11 16:13:17.310 Z","level":"debug","msg":"Plugin health check job starting.","caller":"plugin/health_check.go:31"}
{"timestamp":"2024-10-11 16:13:17.310 Z","level":"info","msg":"No plugins to sync from the file store","caller":"app/plugin.go:311"}
{"timestamp":"2024-10-11 16:13:17.310 Z","level":"debug","msg":"No prepackaged plugins directory found","caller":"app/plugin.go:929"}
{"timestamp":"2024-10-11 16:13:17.310 Z","level":"debug","msg":"Not persisting transitionally prepackaged plugins: none found","caller":"app/plugin.go:1201"}
{"timestamp":"2024-10-11 16:13:17.316 Z","level":"debug","msg":"Able to write files to local storage.","caller":"filestore/localstore.go:82"}
{"timestamp":"2024-10-11 16:13:17.334 Z","level":"info","msg":"Starting Server...","caller":"app/server.go:887"}
{"timestamp":"2024-10-11 16:13:17.335 Z","level":"info","msg":"Server is listening on 127.0.0.1:44785","caller":"app/server.go:963","address":"127.0.0.1:44785"}
{"timestamp":"2024-10-11 16:13:17.335 Z","level":"debug","msg":"No license provided; Remote Cluster services disabled","caller":"app/server.go:580"}
{"timestamp":"2024-10-11 16:13:17.394 Z","level":"debug","msg":"Initializing web routes","caller":"web/web.go:26"}
{"timestamp":"2024-10-11 16:13:17.395 Z","level":"debug","msg":"Skipping update to assets subpath since CI build","caller":"utils/subpath.go:187"}
{"timestamp":"2024-10-11 16:13:17.396 Z","level":"debug","msg":"Using client directory","caller":"web/static.go:34","clientDir":"/tmp/testlib3283879473/client"}
{"timestamp":"2024-10-11 16:13:17.396 Z","level":"debug","msg":"Configured Client4 bool string values","caller":"api4/apitestlib.go:212","true":"T","false":"f"}
{"timestamp":"2024-10-11 16:13:18.266 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users/login","request_id":"fw3b4iagkfyf9cggdcae9mp8yh","user_id":"qewfpoegjjfcdkf158jr6wa7sy","status_code":"200"}
{"timestamp":"2024-10-11 16:13:18.284 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users/login","request_id":"ti18y6r6mf865fujo35yie4njo","user_id":"ak7rztuwr3fcipqn8kqjsbbcbo","status_code":"200"}
{"timestamp":"2024-10-11 16:13:18.333 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/teams","request_id":"q5w9jpdoh7n4dx3ginbu45yhwy","user_id":"ak7rztuwr3fcipqn8kqjsbbcbo","status_code":"201"}
{"timestamp":"2024-10-11 16:13:18.349 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/channels","request_id":"dfbw8hcssir8irga8xnutxrbgo","user_id":"ak7rztuwr3fcipqn8kqjsbbcbo","status_code":"201"}
{"timestamp":"2024-10-11 16:13:18.365 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/channels","request_id":"n3knkuae3fbdtbdek9j8fkoc1o","user_id":"ak7rztuwr3fcipqn8kqjsbbcbo","status_code":"201"}
{"timestamp":"2024-10-11 16:13:18.381 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/channels","request_id":"jo87rgsijp865durrmcn74r19c","user_id":"ak7rztuwr3fcipqn8kqjsbbcbo","status_code":"201"}
{"timestamp":"2024-10-11 16:13:18.397 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/channels","request_id":"naiiakcj5fnuffcuufx1k7a7th","user_id":"ak7rztuwr3fcipqn8kqjsbbcbo","status_code":"201"}
{"timestamp":"2024-10-11 16:13:18.412 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/channels","request_id":"om9iish9tpdm7k1d4m8uo69u4h","user_id":"ak7rztuwr3fcipqn8kqjsbbcbo","status_code":"201"}
{"timestamp":"2024-10-11 16:13:18.422 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/posts","request_id":"gqdrwgu66jr77rjittdd3x44wy","user_id":"ak7rztuwr3fcipqn8kqjsbbcbo","status_code":"201"}
{"timestamp":"2024-10-11 16:13:18.558 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"DELETE","url":"/api/v4/channels/6wnjdb7rdide8xjpnq7gem6nay","request_id":"9fkurn5bdbymfphebkgeicjn4o","user_id":"ak7rztuwr3fcipqn8kqjsbbcbo","status_code":"200"}
{"timestamp":"2024-10-11 16:13:19.391 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users/login","request_id":"bnmaieypc7838r6sgtk3yjji9y","user_id":"jughbhp75ins9b6etuhyskmtqr","status_code":"200"}
{"timestamp":"2024-10-11 16:13:19.396 Z","level":"debug","msg":"websocket.NextReader: closing websocket","caller":"platform/web_conn.go:991","user_id":"jughbhp75ins9b6etuhyskmtqr","conn_id":"1juy94z71pyibxj1ozj54ip66y","origin_client":"web","error":"websocket: close 1006 (abnormal closure): unexpected EOF"}
{"timestamp":"2024-10-11 16:13:19.396 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"GET","url":"/api/v4/websocket","request_id":"opkc9x774bbjbprz9mojybxcfe","user_id":""}
{"timestamp":"2024-10-11 16:13:19.402 Z","level":"debug","msg":"websocket.NextReader: closing websocket","caller":"platform/web_conn.go:991","user_id":"jughbhp75ins9b6etuhyskmtqr","conn_id":"1juy94z71pyibxj1ozj54ip66y","origin_client":"web","error":"websocket: close 1006 (abnormal closure): unexpected EOF"}
{"timestamp":"2024-10-11 16:13:19.402 Z","level":"debug","msg":"websocket.NextReader: closing websocket","caller":"platform/web_conn.go:991","user_id":"jughbhp75ins9b6etuhyskmtqr","conn_id":"urinhftc5jfkdb8aimqxbnjp9h","origin_client":"web","error":"read tcp 127.0.0.1:44785->127.0.0.1:45594: read: connection reset by peer"}
{"timestamp":"2024-10-11 16:13:19.402 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"GET","url":"/api/v4/websocket","request_id":"isyi8tpmafbyxno37rbfqjdnxh","user_id":"jughbhp75ins9b6etuhyskmtqr"}
{"timestamp":"2024-10-11 16:13:19.402 Z","level":"debug","msg":"websocket.NextReader: closing websocket","caller":"platform/web_conn.go:991","user_id":"jughbhp75ins9b6etuhyskmtqr","conn_id":"r3nyha41z3nofjx3e7f4cgy4pr","origin_client":"web","error":"websocket: close 1006 (abnormal closure): unexpected EOF"}
{"timestamp":"2024-10-11 16:13:19.402 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"GET","url":"/api/v4/websocket","request_id":"p9ahkb7ah7nmikuqyrzjaz8i4w","user_id":"jughbhp75ins9b6etuhyskmtqr"}
{"timestamp":"2024-10-11 16:13:19.403 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"GET","url":"/api/v4/websocket","request_id":"au8hn334kiy7fffteb6dwszzsh","user_id":"jughbhp75ins9b6etuhyskmtqr"}
{"timestamp":"2024-10-11 16:13:19.410 Z","level":"debug","msg":"websocket.NextReader: closing websocket","caller":"platform/web_conn.go:991","user_id":"jughbhp75ins9b6etuhyskmtqr","conn_id":"kdrbtiqyybr3f8m4rx9ggf447o","origin_client":"web","error":"websocket: close 1006 (abnormal closure): unexpected EOF"}
==================
WARNING: DATA RACE
Read at 0x00c009581d60 by goroutine 377901:
  github.com/mattermost/mattermost/server/v8/channels/app/platform.(*PlatformService).NewWebConn.func1()
      /mattermost/server/channels/app/platform/web_conn.go:258 +0xbc
  github.com/mattermost/mattermost/server/v8/channels/app/platform.(*PlatformService).Go.func1()
      /mattermost/server/channels/app/platform/goroutines.go:14 +0x37

Previous write at 0x00c009581d60 by goroutine 377895:
  github.com/mattermost/mattermost/server/public/model.(*Session).UnmarshalMsg()
      /mattermost/server/public/model/session_serial_gen.go:468 +0xdd
  github.com/mattermost/mattermost/server/v8/platform/services/cache.(*LRU).get()
      /mattermost/server/platform/services/cache/lru.go:205 +0x244
  github.com/mattermost/mattermost/server/v8/platform/services/cache.(*LRU).Get()
      /mattermost/server/platform/services/cache/lru.go:75 +0x109
  github.com/mattermost/mattermost/server/v8/platform/services/cache.LRUStriped.Get()
      /mattermost/server/platform/services/cache/lru_striped.go:78 +0x1a
  github.com/mattermost/mattermost/server/v8/platform/services/cache.(*LRUStriped).Get()
      <autogenerated>:1 +0x106
  github.com/mattermost/mattermost/server/v8/channels/app/platform.(*PlatformService).GetSession()
      /mattermost/server/channels/app/platform/session.go:137 +0x99
  github.com/mattermost/mattermost/server/v8/channels/app.(*App).GetSession()
      /mattermost/server/channels/app/session.go:93 +0x2be
  github.com/mattermost/mattermost/server/v8/channels/web.Handler.ServeHTTP()
      /mattermost/server/channels/web/handlers.go:294 +0x2c0c
  github.com/mattermost/mattermost/server/v8/channels/web.(*Handler).ServeHTTP()
      <autogenerated>:1 +0xe7
  github.com/klauspost/compress/gzhttp.NewWrapper.func1.1()
      /go/pkg/mod/github.com/klauspost/[email protected]/gzhttp/compress.go:501 +0x6e1
  net/http.HandlerFunc.ServeHTTP()
      /usr/local/go/src/net/http/server.go:2171 +0x47
  github.com/gorilla/mux.(*Router).ServeHTTP()
      /go/pkg/mod/github.com/gorilla/[email protected]/mux.go:212 +0x371
  net/http.serverHandler.ServeHTTP()
      /usr/local/go/src/net/http/server.go:3142 +0x2a1
  net/http.(*conn).serve()
      /usr/local/go/src/net/http/server.go:2044 +0x13c4
  net/http.(*Server).Serve.gowrap3()
      /usr/local/go/src/net/http/server.go:3290 +0x4f

Goroutine 377901 (running) created at:
  github.com/mattermost/mattermost/server/v8/channels/app/platform.(*PlatformService).Go()
      /mattermost/server/channels/app/platform/goroutines.go:13 +0xec
  github.com/mattermost/mattermost/server/v8/channels/app/platform.(*PlatformService).NewWebConn()
      /mattermost/server/channels/app/platform/web_conn.go:254 +0xf93
  github.com/mattermost/mattermost/server/v8/channels/api4.connectWebSocket()
      /mattermost/server/channels/api4/websocket.go:79 +0x133c
  github.com/mattermost/mattermost/server/v8/channels/web.Handler.ServeHTTP()
      /mattermost/server/channels/web/handlers.go:387 +0x6645
  github.com/mattermost/mattermost/server/v8/channels/web.(*Handler).ServeHTTP()
      <autogenerated>:1 +0xe7
  github.com/klauspost/compress/gzhttp.NewWrapper.func1.1()
      /go/pkg/mod/github.com/klauspost/[email protected]/gzhttp/compress.go:501 +0x6e1
  net/http.HandlerFunc.ServeHTTP()
      /usr/local/go/src/net/http/server.go:2171 +0x47
  github.com/gorilla/mux.(*Router).ServeHTTP()
      /go/pkg/mod/github.com/gorilla/[email protected]/mux.go:212 +0x371
  net/http.serverHandler.ServeHTTP()
      /usr/local/go/src/net/http/server.go:3142 +0x2a1
  net/http.(*conn).serve()
      /usr/local/go/src/net/http/server.go:2044 +0x13c4
  net/http.(*Server).Serve.gowrap3()
      /usr/local/go/src/net/http/server.go:3290 +0x4f

Goroutine 377895 (running) created at:
  net/http.(*Server).Serve()
      /usr/local/go/src/net/http/server.go:3290 +0x8ec
  github.com/mattermost/mattermost/server/v8/channels/app.(*Server).Start.func2()
      /mattermost/server/channels/app/server.go:1070 +0x224
==================
{"timestamp":"2024-10-11 16:13:19.410 Z","level":"debug","msg":"websocket.NextReader: closing websocket","caller":"platform/web_conn.go:991","user_id":"jughbhp75ins9b6etuhyskmtqr","conn_id":"1juy94z71pyibxj1ozj54ip66y","origin_client":"web","error":"websocket: close 1006 (abnormal closure): unexpected EOF"}
{"timestamp":"2024-10-11 16:13:19.410 Z","level":"debug","msg":"websocket.send: closing websocket","caller":"platform/web_conn.go:991","user_id":"jughbhp75ins9b6etuhyskmtqr","conn_id":"kdrbtiqyybr3f8m4rx9ggf447o","origin_client":"web","error":"write tcp 127.0.0.1:44785->127.0.0.1:45644: use of closed network connection"}
{"timestamp":"2024-10-11 16:13:19.410 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"GET","url":"/api/v4/websocket","request_id":"x8gzt13g33gk88cjnxspadz8ic","user_id":"jughbhp75ins9b6etuhyskmtqr"}
{"timestamp":"2024-10-11 16:13:19.411 Z","level":"debug","msg":"websocket.NextReader: closing websocket","caller":"platform/web_conn.go:991","user_id":"jughbhp75ins9b6etuhyskmtqr","conn_id":"anszo8y143f7jmfz5g4meed7ye","origin_client":"web","error":"websocket: close 1006 (abnormal closure): unexpected EOF"}
{"timestamp":"2024-10-11 16:13:19.411 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"GET","url":"/api/v4/websocket","request_id":"rg4u39yq8tnk3mkw6i8n31sk6e","user_id":"jughbhp75ins9b6etuhyskmtqr"}
{"timestamp":"2024-10-11 16:13:19.412 Z","level":"debug","msg":"websocket.NextReader: closing websocket","caller":"platform/web_conn.go:991","user_id":"jughbhp75ins9b6etuhyskmtqr","conn_id":"1juy94z71pyibxj1ozj54ip66y","origin_client":"web","error":"websocket: close 1006 (abnormal closure): unexpected EOF"}
{"timestamp":"2024-10-11 16:13:19.412 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"GET","url":"/api/v4/websocket","request_id":"9qn9z1hep3yp88ynzfazrsm3kh","user_id":"jughbhp75ins9b6etuhyskmtqr"}
{"timestamp":"2024-10-11 16:13:19.412 Z","level":"debug","msg":"websocket.drainDeadQueue: closing websocket","caller":"platform/web_conn.go:991","user_id":"jughbhp75ins9b6etuhyskmtqr","conn_id":"1juy94z71pyibxj1ozj54ip66y","origin_client":"web","error":"write tcp 127.0.0.1:44785->127.0.0.1:45614: use of closed network connection"}
{"timestamp":"2024-10-11 16:13:19.412 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"GET","url":"/api/v4/websocket","request_id":"pb3b3ywkz7bqprhkicabdrgexo","user_id":"jughbhp75ins9b6etuhyskmtqr"}
{"timestamp":"2024-10-11 16:13:19.412 Z","level":"debug","msg":"websocket.NextReader: closing websocket","caller":"platform/web_conn.go:991","user_id":"jughbhp75ins9b6etuhyskmtqr","conn_id":"d4goj8baniytfcbtie8au1atbc","origin_client":"web","error":"websocket: close 1006 (abnormal closure): unexpected EOF"}
{"timestamp":"2024-10-11 16:13:19.413 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"GET","url":"/api/v4/websocket","request_id":"xriw6qcs7385um7b5dxd9pbdor","user_id":"jughbhp75ins9b6etuhyskmtqr"}
{"timestamp":"2024-10-11 16:13:19.413 Z","level":"debug","msg":"websocket.drainDeadQueue: closing websocket","caller":"platform/web_conn.go:991","user_id":"jughbhp75ins9b6etuhyskmtqr","conn_id":"1juy94z71pyibxj1ozj54ip66y","origin_client":"web","error":"write tcp 127.0.0.1:44785->127.0.0.1:45618: write: connection reset by peer"}
{"timestamp":"2024-10-11 16:13:19.413 Z","level":"debug","msg":"websocket.NextReader: closing websocket","caller":"platform/web_conn.go:991","user_id":"jughbhp75ins9b6etuhyskmtqr","conn_id":"1juy94z71pyibxj1ozj54ip66y","origin_client":"web","error":"read tcp 127.0.0.1:44785->127.0.0.1:45618: use of closed network connection"}
{"timestamp":"2024-10-11 16:13:19.413 Z","level":"info","msg":"Purging all caches","caller":"platform/cluster_handlers.go:114"}
{"timestamp":"2024-10-11 16:13:19.414 Z","level":"debug","msg":"websocket.NextReader: closing websocket","caller":"platform/web_conn.go:991","user_id":"jughbhp75ins9b6etuhyskmtqr","conn_id":"hhsqh7stkff1ug6gq3yiwngb3w","origin_client":"web","error":"websocket: close 1006 (abnormal closure): unexpected EOF"}
{"timestamp":"2024-10-11 16:13:19.414 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"GET","url":"/api/v4/websocket","request_id":"now7rmziu3nd9yew4m76md8wxy","user_id":"jughbhp75ins9b6etuhyskmtqr"}
{"timestamp":"2024-10-11 16:13:19.415 Z","level":"debug","msg":"websocket.send: closing websocket","caller":"platform/web_conn.go:991","user_id":"jughbhp75ins9b6etuhyskmtqr","conn_id":"hhsqh7stkff1ug6gq3yiwngb3w","origin_client":"web","error":"write tcp 127.0.0.1:44785->127.0.0.1:45612: use of closed network connection"}
{"timestamp":"2024-10-11 16:13:19.415 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"GET","url":"/api/v4/websocket","request_id":"yhfmeuqe9inhmxrb7ssr9eyzfa","user_id":"jughbhp75ins9b6etuhyskmtqr"}
{"timestamp":"2024-10-11 16:13:19.415 Z","level":"info","msg":"License key has not been uploaded. Loading license key from disk.","caller":"utils/license.go:107","filename":"/tmp/testlib3283879473/config/mattermost.mattermost-license"}
{"timestamp":"2024-10-11 16:13:19.416 Z","level":"warn","msg":"Failed to get license from disk","caller":"platform/license.go:93","error":"We could not find the license key on disk at /tmp/testlib3283879473/config/mattermost.mattermost-license: stat /tmp/testlib3283879473/config/mattermost.mattermost-license: no such file or directory"}
{"timestamp":"2024-10-11 16:13:19.417 Z","level":"warn","msg":"License key from https://mattermost.com required to unlock enterprise features.","caller":"platform/license.go:105","error":"resource \"License\" not found, id: "}
{"timestamp":"2024-10-11 16:13:19.423 Z","level":"info","msg":"Stopping Server...","caller":"app/server.go:669"}
{"timestamp":"2024-10-11 16:13:19.441 Z","level":"info","msg":"Shutting down Email batching service...","caller":"email/service.go:85"}
{"timestamp":"2024-10-11 16:13:19.441 Z","level":"info","msg":"Shutting down plugins","caller":"app/plugin.go:362"}
{"timestamp":"2024-10-11 16:13:19.441 Z","level":"debug","msg":"Disabling plugin health check job","caller":"plugin/environment.go:670"}
{"timestamp":"2024-10-11 16:13:19.441 Z","level":"info","msg":"stopping websocket hub connections","caller":"platform/web_hub.go:127"}
{"timestamp":"2024-10-11 16:13:19.446 Z","level":"info","msg":"Server stopped","caller":"app/server.go:753"}
    testing.go:1398: race detected during execution of test
--- FAIL: TestWebSocketReconnectRace (2.83s)

Check failure on line 1 in com/mattermost/mattermost/server/v8/channels/api4

See this annotation in the file changed.

@github-actions github-actions / MySQL (Results)

com/mattermost/mattermost/server/v8/channels/api4.TestSyncLdap/SystemAdminClient#01 

Failed
Raw output 
=== RUN   TestSyncLdap/SystemAdminClient#01
{"timestamp":"2024-10-11 16:14:07.030 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/ldap/sync","request_id":"xsunax7di7fbfcogtwcow3c1wr","user_id":"ca799xt653nctrzs6d4b77z11a","status_code":"200"}
==================
WARNING: DATA RACE
Read at 0x00c001b3ec00 by goroutine 319:
  reflect.maplen()
      /usr/local/go/src/runtime/map.go:1406 +0x0
  reflect.Value.lenNonSlice()
      /usr/local/go/src/reflect/value.go:1785 +0x1e9
  reflect.Value.Len()
      /usr/local/go/src/reflect/value.go:1774 +0x137
  internal/fmtsort.Sort()
      /usr/local/go/src/internal/fmtsort/sort.go:58 +0x121
  fmt.(*pp).printValue()
      /usr/local/go/src/fmt/print.go:816 +0x1144
  fmt.(*pp).printValue()
      /usr/local/go/src/fmt/print.go:853 +0x1d3e
  fmt.(*pp).printValue()
      /usr/local/go/src/fmt/print.go:853 +0x1d3e
  fmt.(*pp).printValue()
      /usr/local/go/src/fmt/print.go:921 +0x132a
  fmt.(*pp).printArg()
      /usr/local/go/src/fmt/print.go:759 +0xb84
  fmt.(*pp).doPrintf()
      /usr/local/go/src/fmt/print.go:1174 +0x10ce
  fmt.Sprintf()
      /usr/local/go/src/fmt/print.go:239 +0x5c
  github.com/stretchr/testify/mock.Arguments.Diff()
      /go/pkg/mod/github.com/stretchr/[email protected]/mock/mock.go:939 +0x1b2
  github.com/stretchr/testify/mock.(*Mock).findExpectedCall()
      /go/pkg/mod/github.com/stretchr/[email protected]/mock/mock.go:368 +0x147
  github.com/stretchr/testify/mock.(*Mock).MethodCalled()
      /go/pkg/mod/github.com/stretchr/[email protected]/mock/mock.go:476 +0xac
  github.com/stretchr/testify/mock.(*Mock).Called()
      /go/pkg/mod/github.com/stretchr/[email protected]/mock/mock.go:466 +0x195
  github.com/mattermost/mattermost/server/v8/einterfaces/mocks.(*LdapInterface).StartSynchronizeJob()
      /mattermost/server/einterfaces/mocks/LdapInterface.go:406 +0x1b0
  github.com/mattermost/mattermost/server/v8/channels/app.(*App).SyncLdap.func1()
      /mattermost/server/channels/app/ldap.go:33 +0x1d8
  github.com/mattermost/mattermost/server/v8/channels/app/platform.(*PlatformService).Go.func1()
      /mattermost/server/channels/app/platform/goroutines.go:14 +0x37

Previous write at 0x00c001b3ec00 by goroutine 295:
  runtime.mapclear()
      /usr/local/go/src/runtime/map.go:989 +0x0
  github.com/mattermost/mattermost/server/v8/channels/app/platform.(*PlatformService).ReturnSessionToPool()
      /mattermost/server/channels/app/platform/session.go:25 +0xcf
  github.com/mattermost/mattermost/server/v8/channels/app.(*App).ReturnSessionToPool()
      /mattermost/server/channels/app/session.go:227 +0x26
  github.com/mattermost/mattermost/server/v8/channels/web.Handler.ServeHTTP.deferwrap1()
      /mattermost/server/channels/web/handlers.go:295 +0x55
  runtime.deferreturn()
      /usr/local/go/src/runtime/panic.go:602 +0x5d
  github.com/mattermost/mattermost/server/v8/channels/web.(*Handler).ServeHTTP()
      <autogenerated>:1 +0xe7
  github.com/klauspost/compress/gzhttp.NewWrapper.func1.1()
      /go/pkg/mod/github.com/klauspost/[email protected]/gzhttp/compress.go:495 +0x7c6
  net/http.HandlerFunc.ServeHTTP()
      /usr/local/go/src/net/http/server.go:2171 +0x47
  github.com/gorilla/mux.(*Router).ServeHTTP()
      /go/pkg/mod/github.com/gorilla/[email protected]/mux.go:212 +0x371
  net/http.serverHandler.ServeHTTP()
      /usr/local/go/src/net/http/server.go:3142 +0x2a1
  net/http.(*conn).serve()
      /usr/local/go/src/net/http/server.go:2044 +0x13c4
  net/http.(*Server).Serve.gowrap3()
      /usr/local/go/src/net/http/server.go:3290 +0x4f

Goroutine 319 (running) created at:
  github.com/mattermost/mattermost/server/v8/channels/app/platform.(*PlatformService).Go()
      /mattermost/server/channels/app/platform/goroutines.go:13 +0xec
  github.com/mattermost/mattermost/server/v8/channels/app.(*Server).Go()
      /mattermost/server/channels/app/server.go:806 +0x148
  github.com/mattermost/mattermost/server/v8/channels/app.(*App).SyncLdap()
      /mattermost/server/channels/app/ldap.go:21 +0x5d
  github.com/mattermost/mattermost/server/v8/channels/api4.syncLdap()
      /mattermost/server/channels/api4/ldap.go:69 +0x9de
  github.com/mattermost/mattermost/server/v8/channels/web.Handler.ServeHTTP()
      /mattermost/server/channels/web/handlers.go:387 +0x6645
  github.com/mattermost/mattermost/server/v8/channels/web.(*Handler).ServeHTTP()
      <autogenerated>:1 +0xe7
  github.com/klauspost/compress/gzhttp.NewWrapper.func1.1()
      /go/pkg/mod/github.com/klauspost/[email protected]/gzhttp/compress.go:495 +0x7c6
  net/http.HandlerFunc.ServeHTTP()
      /usr/local/go/src/net/http/server.go:2171 +0x47
  github.com/gorilla/mux.(*Router).ServeHTTP()
      /go/pkg/mod/github.com/gorilla/[email protected]/mux.go:212 +0x371
  net/http.serverHandler.ServeHTTP()
      /usr/local/go/src/net/http/server.go:3142 +0x2a1
  net/http.(*conn).serve()
      /usr/local/go/src/net/http/server.go:2044 +0x13c4
  net/http.(*Server).Serve.gowrap3()
      /usr/local/go/src/net/http/server.go:3290 +0x4f

Goroutine 295 (running) created at:
  net/http.(*Server).Serve()
      /usr/local/go/src/net/http/server.go:3290 +0x8ec
  github.com/mattermost/mattermost/server/v8/channels/app.(*Server).Start.func2()
      /mattermost/server/channels/app/server.go:1070 +0x224
==================
{"timestamp":"2024-10-11 16:14:07.034 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/ldap/sync","request_id":"8jiim31fnpfxixbafqkkqwjchy","user_id":"ca799xt653nctrzs6d4b77z11a","status_code":"200"}
    testing.go:1398: race detected during execution of test
--- FAIL: TestSyncLdap/SystemAdminClient#01 (0.01s)

Check failure on line 1 in com/mattermost/mattermost/server/v8/channels/api4

See this annotation in the file changed.

@github-actions github-actions / MySQL (Results)

com/mattermost/mattermost/server/v8/channels/api4.TestSyncLdap 

Failed
Raw output 
=== RUN   TestSyncLdap
{"timestamp":"2024-10-11 16:14:01.355 Z","level":"debug","msg":"Advanced logging config not provided for notification logging","caller":"platform/config.go:165"}
{"timestamp":"2024-10-11 16:14:01.355 Z","level":"info","msg":"Successfully connected to cache backend","caller":"platform/service.go:191","backend":"lru","result":"OK"}
{"timestamp":"2024-10-11 16:14:01.355 Z","level":"info","msg":"Server is initializing...","caller":"platform/service.go:194","go_version":"go1.22.6"}
{"timestamp":"2024-10-11 16:14:01.369 Z","level":"info","msg":"Starting websocket hubs","caller":"platform/web_hub.go:106","number_of_hubs":4}
{"timestamp":"2024-10-11 16:14:01.370 Z","level":"info","msg":"Loaded system translations","caller":"i18n/i18n.go:175","for locale":"en","from locale":"/mattermost/server/i18n/en.json"}
{"timestamp":"2024-10-11 16:14:01.370 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":0}
{"timestamp":"2024-10-11 16:14:01.371 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":3}
{"timestamp":"2024-10-11 16:14:01.371 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":2}
{"timestamp":"2024-10-11 16:14:01.372 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":1}
{"timestamp":"2024-10-11 16:14:01.379 Z","level":"info","msg":"Ensuring the telemetry ID..","caller":"telemetry/telemetry.go:167"}
{"timestamp":"2024-10-11 16:14:01.381 Z","level":"info","msg":"telemetry ID is set","caller":"telemetry/telemetry.go:177","id":"oy43odap4ibq5nwzihiyq9bfrh"}
{"timestamp":"2024-10-11 16:14:01.400 Z","level":"error","msg":"SiteURL must be set. Some features will operate incorrectly if the SiteURL is not set. See documentation for details: https://mattermost.com/pl/configure-site-url","caller":"app/server.go:390"}
{"timestamp":"2024-10-11 16:14:01.400 Z","level":"info","msg":"Current version is 10.2.0 (///)","caller":"app/server.go:404","current_version":"10.2.0","build_number":"","build_date":"","build_hash":"","build_hash_enterprise":"","service_environment":"dev"}
{"timestamp":"2024-10-11 16:14:01.400 Z","level":"info","msg":"Team Edition Build","caller":"app/server.go:420","enterprise_build":false}
{"timestamp":"2024-10-11 16:14:01.400 Z","level":"info","msg":"Printing current working","caller":"app/server.go:424","directory":"/tmp/testlib3274550795"}
{"timestamp":"2024-10-11 16:14:01.400 Z","level":"info","msg":"Loaded config","caller":"app/server.go:425","source":"memory://"}
{"timestamp":"2024-10-11 16:14:01.636 Z","level":"debug","msg":"initElasticsearchChannelIndexCheck: calling elasticsearchChannelIndexCheckWithRetry before setting up config and license change listeners...","caller":"app/elasticsearch.go:21"}
{"timestamp":"2024-10-11 16:14:01.636 Z","level":"debug","msg":"elasticsearchChannelIndexCheckWithRetry: attempting to check channel index state...","caller":"app/elasticsearch.go:64"}
{"timestamp":"2024-10-11 16:14:01.636 Z","level":"debug","msg":"elasticsearchChannelIndexCheckWithRetry: skipping because elasticsearch indexing is disabled","caller":"app/elasticsearch.go:67"}
{"timestamp":"2024-10-11 16:14:01.670 Z","level":"debug","msg":"Fetching user count for first user account check","caller":"platform/config.go:356"}
{"timestamp":"2024-10-11 16:14:01.673 Z","level":"info","msg":"Post.Message has size restrictions","caller":"sqlstore/post_store.go:2628","max_characters":16383,"max_bytes":65535}
{"timestamp":"2024-10-11 16:14:01.678 Z","level":"debug","msg":"Advanced logging config not provided for notification logging","caller":"platform/config.go:165"}
{"timestamp":"2024-10-11 16:14:01.678 Z","level":"info","msg":"Starting up plugins","caller":"app/plugin.go:191"}
{"timestamp":"2024-10-11 16:14:01.678 Z","level":"debug","msg":"Enabling plugin health check job","caller":"plugin/environment.go:661","interval_s":"30s"}
{"timestamp":"2024-10-11 16:14:01.678 Z","level":"info","msg":"Syncing plugins from the file store","caller":"app/plugin.go:268"}
{"timestamp":"2024-10-11 16:14:01.678 Z","level":"debug","msg":"Plugin health check job starting.","caller":"plugin/health_check.go:31"}
{"timestamp":"2024-10-11 16:14:01.678 Z","level":"info","msg":"No plugins to sync from the file store","caller":"app/plugin.go:311"}
{"timestamp":"2024-10-11 16:14:01.679 Z","level":"debug","msg":"No prepackaged plugins directory found","caller":"app/plugin.go:929"}
{"timestamp":"2024-10-11 16:14:01.679 Z","level":"debug","msg":"Not persisting transitionally prepackaged plugins: none found","caller":"app/plugin.go:1201"}
{"timestamp":"2024-10-11 16:14:01.685 Z","level":"debug","msg":"Able to write files to local storage.","caller":"filestore/localstore.go:82"}
{"timestamp":"2024-10-11 16:14:01.704 Z","level":"info","msg":"Starting Server...","caller":"app/server.go:887"}
{"timestamp":"2024-10-11 16:14:01.704 Z","level":"info","msg":"Server is listening on 127.0.0.1:35401","caller":"app/server.go:963","address":"127.0.0.1:35401"}
{"timestamp":"2024-10-11 16:14:01.705 Z","level":"debug","msg":"No license provided; Remote Cluster services disabled","caller":"app/server.go:580"}
{"timestamp":"2024-10-11 16:14:01.773 Z","level":"debug","msg":"Initializing web routes","caller":"web/web.go:26"}
{"timestamp":"2024-10-11 16:14:01.774 Z","level":"debug","msg":"Skipping update to assets subpath since CI build","caller":"utils/subpath.go:187"}
{"timestamp":"2024-10-11 16:14:01.774 Z","level":"debug","msg":"Using client directory","caller":"web/static.go:34","clientDir":"/tmp/testlib3274550795/client"}
{"timestamp":"2024-10-11 16:14:01.775 Z","level":"debug","msg":"Configured Client4 bool string values","caller":"api4/apitestlib.go:212","true":"True","false":"f"}
{"timestamp":"2024-10-11 16:14:01.777 Z","level":"debug","msg":"Fetching user count for first user account check","caller":"platform/config.go:356"}
{"timestamp":"2024-10-11 16:14:02.591 Z","level":"info","msg":"sending mail","caller":"mail/mail.go:295","to":"yzui1cawrpb7ixnbze46qmqruy@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-10-11 16:14:02.612 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"co5jgkcqfbfhxjbeu4eim41o3o","user_id":"","status_code":"201"}
{"timestamp":"2024-10-11 16:14:02.622 Z","level":"debug","msg":"Fetching user count for first user account check","caller":"platform/config.go:356"}
{"timestamp":"2024-10-11 16:14:03.463 Z","level":"info","msg":"sending mail","caller":"mail/mail.go:295","to":"6k1krq6ecpgrpj9pgypdz4eihy@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-10-11 16:14:03.490 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"kqmpt351gfdo3fm5abifccqu7y","user_id":"","status_code":"201"}
{"timestamp":"2024-10-11 16:14:04.325 Z","level":"info","msg":"sending mail","caller":"mail/mail.go:295","to":"zq1yemp7ipn15nfpjcam3u63da@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-10-11 16:14:04.342 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"k1ec1kfzc7ymmbjub9qknp9qih","user_id":"","status_code":"201"}
{"timestamp":"2024-10-11 16:14:05.200 Z","level":"info","msg":"sending mail","caller":"mail/mail.go:295","to":"9pu7yusuofgd8856qeqqn6wc4e@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-10-11 16:14:05.216 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"mcpmjc7qapfzmc4te7uzhy196a","user_id":"","status_code":"201"}
{"timestamp":"2024-10-11 16:14:06.044 Z","level":"info","msg":"sending mail","caller":"mail/mail.go:295","to":"rrjghjohhpf6dyid1t8dibgsxo@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-10-11 16:14:06.063 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"xqzfkxugwjd95j6tc5dbjftcgy","user_id":"","status_code":"201"}
{"timestamp":"2024-10-11 16:14:06.950 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users/login","request_id":"6bzjphar83be5qk9dtc1qxgo4h","user_id":"ca799xt653nctrzs6d4b77z11a","status_code":"200"}
{"timestamp":"2024-10-11 16:14:06.974 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users/login","request_id":"bftiytudfifpjjjwsx6fbw7wpa","user_id":"t98haugpzfnhfxje3y7um1kguh","status_code":"200"}
{"timestamp":"2024-10-11 16:14:06.975 Z","level":"info","msg":"Set license","caller":"platform/license.go:384","id":"","issued_at":"1970-01-01 00:00:00.000 Z","starts_at":"1970-01-01 00:00:00.000 Z","expires_at":"2025-01-09 16:14:06.975 Z","sku_name":"","sku_short_name":"","is_trial":false,"is_gov_sku":false,"customer_id":"some ID","features.users":0,"features":{"advanced_logging":true,"cloud":false,"cluster":true,"compliance":true,"custom_permissions_schemes":true,"data_retention":true,"elastic_search":true,"email_notification_contents":true,"enterprise_plugins":true,"future":true,"google":true,"guest_accounts":true,"guest_accounts_permissions":true,"id_loaded":true,"ldap":true,"ldap_groups":true,"lock_teammate_name_display":true,"message_export":true,"metrics":true,"mfa":true,"mhpns":true,"office365":true,"openid":true,"outgoing_oauth_connections":true,"remote_cluster_service":true,"saml":true,"shared_channels":true}}
{"timestamp":"2024-10-11 16:14:07.028 Z","level":"debug","msg":"Advanced logging config not provided for notification logging","caller":"platform/config.go:165"}
{"timestamp":"2024-10-11 16:14:07.037 Z","level":"debug","msg":"You do not have the appropriate permissions.","caller":"web/context.go:120","path":"/api/v4/ldap/sync","request_id":"zebybzdy7t8purrzjnga8b5pze","ip_addr":"127.0.0.1","user_id":"t98haugpzfnhfxje3y7um1kguh","method":"POST","err_where":"Permissions","http_code":403,"error":"Permissions: You do not have the appropriate permissions., userId=t98haugpzfnhfxje3y7um1kguh, permission=create_ldap_sync_job"}
{"timestamp":"2024-10-11 16:14:07.037 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/ldap/sync","request_id":"zebybzdy7t8purrzjnga8b5pze","user_id":"t98haugpzfnhfxje3y7um1kguh","status_code":"403"}
{"timestamp":"2024-10-11 16:14:07.038 Z","level":"info","msg":"Purging all caches","caller":"platform/cluster_handlers.go:114"}
{"timestamp":"2024-10-11 16:14:07.039 Z","level":"info","msg":"License key has not been uploaded. Loading license key from disk.","caller":"utils/license.go:107","filename":"/tmp/testlib3274550795/config/mattermost.mattermost-license"}
{"timestamp":"2024-10-11 16:14:07.039 Z","level":"warn","msg":"Failed to get license from disk","caller":"platform/license.go:93","error":"We could not find the license key on disk at /tmp/testlib3274550795/config/mattermost.mattermost-license: stat /tmp/testlib3274550795/config/mattermost.mattermost-license: no such file or directory"}
{"timestamp":"2024-10-11 16:14:07.041 Z","level":"warn","msg":"License key from https://mattermost.com required to unlock enterprise features.","caller":"platform/license.go:105","error":"resource \"License\" not found, id: "}
{"timestamp":"2024-10-11 16:14:07.041 Z","level":"info","msg":"Cleared license","caller":"platform/license.go:384","id":"","issued_at":"1970-01-01 00:00:00.000 Z","starts_at":"1970-01-01 00:00:00.000 Z","expires_at":"2025-01-09 16:14:06.975 Z","sku_name":"","sku_short_name":"","is_trial":false,"is_gov_sku":false,"customer_id":"some ID","features.users":0,"features":{"advanced_logging":true,"cloud":false,"cluster":true,"compliance":true,"custom_permissions_schemes":true,"data_retention":true,"elastic_search":true,"email_notification_contents":true,"enterprise_plugins":true,"future":true,"google":true,"guest_accounts":true,"guest_accounts_permissions":true,"id_loaded":true,"ldap":true,"ldap_groups":true,"lock_teammate_name_display":true,"message_export":true,"metrics":true,"mfa":true,"mhpns":true,"office365":true,"openid":true,"outgoing_oauth_connections":true,"remote_cluster_service":true,"saml":true,"shared_channels":true}}
{"timestamp":"2024-10-11 16:14:07.045 Z","level":"info","msg":"Stopping Server...","caller":"app/server.go:669"}
{"timestamp":"2024-10-11 16:14:07.048 Z","level":"info","msg":"Shutting down Email batching service...","caller":"email/service.go:85"}
{"timestamp":"2024-10-11 16:14:07.048 Z","level":"info","msg":"Shutting down plugins","caller":"app/plugin.go:362"}
{"timestamp":"2024-10-11 16:14:07.048 Z","level":"debug","msg":"Disabling plugin health check job","caller":"plugin/environment.go:670"}
{"timestamp":"2024-10-11 16:14:07.048 Z","level":"info","msg":"stopping websocket hub connections","caller":"platform/web_hub.go:127"}
{"timestamp":"2024-10-11 16:14:07.048 Z","level":"info","msg":"Server stopped","caller":"app/server.go:753"}
--- FAIL: TestSyncLdap (6.12s)

Check failure on line 1 in com/mattermost/mattermost/server/v8/channels/api4

See this annotation in the file changed.

@github-actions github-actions / MySQL (Results)

com/mattermost/mattermost/server/v8/channels/api4.TestWebSocketReconnectRace 

Failed
Raw output 
=== RUN   TestWebSocketReconnectRace
{"timestamp":"2024-10-11 16:14:15.543 Z","level":"debug","msg":"Advanced logging config not provided for notification logging","caller":"platform/config.go:165"}
{"timestamp":"2024-10-11 16:14:15.543 Z","level":"info","msg":"Successfully connected to cache backend","caller":"platform/service.go:191","backend":"lru","result":"OK"}
{"timestamp":"2024-10-11 16:14:15.543 Z","level":"info","msg":"Server is initializing...","caller":"platform/service.go:194","go_version":"go1.22.6"}
{"timestamp":"2024-10-11 16:14:15.556 Z","level":"info","msg":"Starting websocket hubs","caller":"platform/web_hub.go:106","number_of_hubs":4}
{"timestamp":"2024-10-11 16:14:15.558 Z","level":"info","msg":"Loaded system translations","caller":"i18n/i18n.go:175","for locale":"en","from locale":"/mattermost/server/i18n/en.json"}
{"timestamp":"2024-10-11 16:14:15.559 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":1}
{"timestamp":"2024-10-11 16:14:15.559 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":0}
{"timestamp":"2024-10-11 16:14:15.559 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":3}
{"timestamp":"2024-10-11 16:14:15.559 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":2}
{"timestamp":"2024-10-11 16:14:15.566 Z","level":"info","msg":"Ensuring the telemetry ID..","caller":"telemetry/telemetry.go:167"}
{"timestamp":"2024-10-11 16:14:15.568 Z","level":"info","msg":"telemetry ID is set","caller":"telemetry/telemetry.go:177","id":"o51t9m6n9tdypxaexz5uyndn4w"}
{"timestamp":"2024-10-11 16:14:15.588 Z","level":"error","msg":"SiteURL must be set. Some features will operate incorrectly if the SiteURL is not set. See documentation for details: https://mattermost.com/pl/configure-site-url","caller":"app/server.go:390"}
{"timestamp":"2024-10-11 16:14:15.588 Z","level":"info","msg":"Current version is 10.2.0 (///)","caller":"app/server.go:404","current_version":"10.2.0","build_number":"","build_date":"","build_hash":"","build_hash_enterprise":"","service_environment":"dev"}
{"timestamp":"2024-10-11 16:14:15.588 Z","level":"info","msg":"Team Edition Build","caller":"app/server.go:420","enterprise_build":false}
{"timestamp":"2024-10-11 16:14:15.588 Z","level":"info","msg":"Printing current working","caller":"app/server.go:424","directory":"/tmp/testlib1614595663"}
{"timestamp":"2024-10-11 16:14:15.588 Z","level":"info","msg":"Loaded config","caller":"app/server.go:425","source":"memory://"}
{"timestamp":"2024-10-11 16:14:15.817 Z","level":"debug","msg":"initElasticsearchChannelIndexCheck: calling elasticsearchChannelIndexCheckWithRetry before setting up config and license change listeners...","caller":"app/elasticsearch.go:21"}
{"timestamp":"2024-10-11 16:14:15.817 Z","level":"debug","msg":"elasticsearchChannelIndexCheckWithRetry: attempting to check channel index state...","caller":"app/elasticsearch.go:64"}
{"timestamp":"2024-10-11 16:14:15.817 Z","level":"debug","msg":"elasticsearchChannelIndexCheckWithRetry: skipping because elasticsearch indexing is disabled","caller":"app/elasticsearch.go:67"}
{"timestamp":"2024-10-11 16:14:15.851 Z","level":"debug","msg":"Fetching user count for first user account check","caller":"platform/config.go:356"}
{"timestamp":"2024-10-11 16:14:15.853 Z","level":"info","msg":"Post.Message has size restrictions","caller":"sqlstore/post_store.go:2628","max_characters":16383,"max_bytes":65535}
{"timestamp":"2024-10-11 16:14:15.858 Z","level":"debug","msg":"Advanced logging config not provided for notification logging","caller":"platform/config.go:165"}
{"timestamp":"2024-10-11 16:14:15.858 Z","level":"info","msg":"Starting up plugins","caller":"app/plugin.go:191"}
{"timestamp":"2024-10-11 16:14:15.859 Z","level":"debug","msg":"Enabling plugin health check job","caller":"plugin/environment.go:661","interval_s":"30s"}
{"timestamp":"2024-10-11 16:14:15.859 Z","level":"info","msg":"Syncing plugins from the file store","caller":"app/plugin.go:268"}
{"timestamp":"2024-10-11 16:14:15.859 Z","level":"debug","msg":"Plugin health check job starting.","caller":"plugin/health_check.go:31"}
{"timestamp":"2024-10-11 16:14:15.859 Z","level":"info","msg":"No plugins to sync from the file store","caller":"app/plugin.go:311"}
{"timestamp":"2024-10-11 16:14:15.859 Z","level":"debug","msg":"No prepackaged plugins directory found","caller":"app/plugin.go:929"}
{"timestamp":"2024-10-11 16:14:15.859 Z","level":"debug","msg":"Not persisting transitionally prepackaged plugins: none found","caller":"app/plugin.go:1201"}
{"timestamp":"2024-10-11 16:14:15.865 Z","level":"debug","msg":"Able to write files to local storage.","caller":"filestore/localstore.go:82"}
{"timestamp":"2024-10-11 16:14:15.885 Z","level":"info","msg":"Starting Server...","caller":"app/server.go:887"}
{"timestamp":"2024-10-11 16:14:15.885 Z","level":"info","msg":"Server is listening on 127.0.0.1:38039","caller":"app/server.go:963","address":"127.0.0.1:38039"}
{"timestamp":"2024-10-11 16:14:15.885 Z","level":"debug","msg":"No license provided; Remote Cluster services disabled","caller":"app/server.go:580"}
{"timestamp":"2024-10-11 16:14:15.950 Z","level":"debug","msg":"Initializing web routes","caller":"web/web.go:26"}
{"timestamp":"2024-10-11 16:14:15.951 Z","level":"debug","msg":"Skipping update to assets subpath since CI build","caller":"utils/subpath.go:187"}
{"timestamp":"2024-10-11 16:14:15.952 Z","level":"debug","msg":"Using client directory","caller":"web/static.go:34","clientDir":"/tmp/testlib1614595663/client"}
{"timestamp":"2024-10-11 16:14:15.952 Z","level":"debug","msg":"Configured Client4 bool string values","caller":"api4/apitestlib.go:212","true":"true","false":"F"}
{"timestamp":"2024-10-11 16:14:15.955 Z","level":"debug","msg":"Fetching user count for first user account check","caller":"platform/config.go:356"}
{"timestamp":"2024-10-11 16:14:16.795 Z","level":"info","msg":"sending mail","caller":"mail/mail.go:295","to":"ytttubzadfrp9rowjuimw79qho@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-10-11 16:14:16.816 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"cbxinj1djibyprgsrnfeuohynw","user_id":"","status_code":"201"}
{"timestamp":"2024-10-11 16:14:16.825 Z","level":"debug","msg":"Fetching user count for first user account check","caller":"platform/config.go:356"}
{"timestamp":"2024-10-11 16:14:17.653 Z","level":"info","msg":"sending mail","caller":"mail/mail.go:295","to":"i7iomzqzdbyzb84zmhui8j8kor@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-10-11 16:14:17.673 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"z14mp414oirnjmzqpodqbyp4kw","user_id":"","status_code":"201"}
{"timestamp":"2024-10-11 16:14:18.524 Z","level":"info","msg":"sending mail","caller":"mail/mail.go:295","to":"t8ftc1cedif33ckeyu36e4ysmo@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-10-11 16:14:18.543 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"gbwehecaefnbt8migzff4m1p4e","user_id":"","status_code":"201"}
{"timestamp":"2024-10-11 16:14:19.349 Z","level":"info","msg":"sending mail","caller":"mail/mail.go:295","to":"hwsrxsj343rm5ejrdwpfgag44c@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-10-11 16:14:19.385 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"ajmfnawx1iyjtfmu8bza7wdgth","user_id":"","status_code":"201"}
{"timestamp":"2024-10-11 16:14:20.235 Z","level":"info","msg":"sending mail","caller":"mail/mail.go:295","to":"nskmjkwykby8pb8x5mghd3dgoh@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-10-11 16:14:20.252 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"rcz39ehzhjbedkx6cm1m7a31kh","user_id":"","status_code":"201"}
{"timestamp":"2024-10-11 16:14:21.124 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users/login","request_id":"8qtm59oxotr4jphoeayx8bbf8h","user_id":"f61hseg3wbgujcuaddi4faja9e","status_code":"200"}
{"timestamp":"2024-10-11 16:14:21.124 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users/login","request_id":"taoumw1x9tyuznfwtrc3skof7o","user_id":"z9bm3ykc9jnwzym4dxqt4ffx9w","status_code":"200"}
{"timestamp":"2024-10-11 16:14:21.172 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/teams","request_id":"zwd5dngsr3dnjxcims6tzhz3ba","user_id":"f61hseg3wbgujcuaddi4faja9e","status_code":"201"}
{"timestamp":"2024-10-11 16:14:21.192 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/channels","request_id":"extcggs3zbfr3yuc6giheaywby","user_id":"f61hseg3wbgujcuaddi4faja9e","status_code":"201"}
{"timestamp":"2024-10-11 16:14:21.211 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/channels","request_id":"prrdjx5j4fd88xqt9q9sq75o9c","user_id":"f61hseg3wbgujcuaddi4faja9e","status_code":"201"}
{"timestamp":"2024-10-11 16:14:21.229 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/channels","request_id":"aonyxsxnhi8o7jt69mdzhiddno","user_id":"f61hseg3wbgujcuaddi4faja9e","status_code":"201"}
{"timestamp":"2024-10-11 16:14:21.246 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/channels","request_id":"tdj6ksf69iyx7r319itohshxnr","user_id":"f61hseg3wbgujcuaddi4faja9e","status_code":"201"}
{"timestamp":"2024-10-11 16:14:21.263 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/channels","request_id":"4ezzn9xcbjgi7q9prc5fbh85ea","user_id":"f61hseg3wbgujcuaddi4faja9e","status_code":"201"}
{"timestamp":"2024-10-11 16:14:21.274 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/posts","request_id":"hnxpbgjg37n7mqogooy9rn9s5o","user_id":"f61hseg3wbgujcuaddi4faja9e","status_code":"201"}
{"timestamp":"2024-10-11 16:14:21.427 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"DELETE","url":"/api/v4/channels/zj5jr1fizpb67c8je54yji1jwy","request_id":"bk5ih4egzif3mkmjwqinzhscir","user_id":"f61hseg3wbgujcuaddi4faja9e","status_code":"200"}
{"timestamp":"2024-10-11 16:14:22.250 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users/login","request_id":"8aquhch6figfux8936gsqo7cfo","user_id":"zy4b3ewjnfy95cjsbbnc5mdbcw","status_code":"200"}
{"timestamp":"2024-10-11 16:14:22.257 Z","level":"debug","msg":"websocket.NextReader: closing websocket","caller":"platform/web_conn.go:991","user_id":"zy4b3ewjnfy95cjsbbnc5mdbcw","conn_id":"kzmszuoiebgsdfwri1ouxfh8or","origin_client":"web","error":"websocket: close 1006 (abnormal closure): unexpected EOF"}
{"timestamp":"2024-10-11 16:14:22.260 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"GET","url":"/api/v4/websocket","request_id":"xp1a85ywobdcpp8wckppwpum6a","user_id":""}
{"timestamp":"2024-10-11 16:14:22.272 Z","level":"debug","msg":"websocket.NextReader: closing websocket","caller":"platform/web_conn.go:991","user_id":"zy4b3ewjnfy95cjsbbnc5mdbcw","conn_id":"jmfk1kyawpyjdnoh6eqbo6swre","origin_client":"web","error":"websocket: close 1006 (abnormal closure): unexpected EOF"}
{"timestamp":"2024-10-11 16:14:22.272 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"GET","url":"/api/v4/websocket","request_id":"esdnry5butb6zbpb76bzifr1qh","user_id":"zy4b3ewjnfy95cjsbbnc5mdbcw"}
{"timestamp":"2024-10-11 16:14:22.272 Z","level":"debug","msg":"websocket.NextReader: closing websocket","caller":"platform/web_conn.go:991","user_id":"zy4b3ewjnfy95cjsbbnc5mdbcw","conn_id":"kzmszuoiebgsdfwri1ouxfh8or","origin_client":"web","error":"websocket: close 1006 (abnormal closure): unexpected EOF"}
{"timestamp":"2024-10-11 16:14:22.274 Z","level":"debug","msg":"websocket.NextReader: closing websocket","caller":"platform/web_conn.go:991","user_id":"zy4b3ewjnfy95cjsbbnc5mdbcw","conn_id":"hxr3suxmfb8r9yd4coyfxwtbfa","origin_client":"web","error":"websocket: close 1006 (abnormal closure): unexpected EOF"}
{"timestamp":"2024-10-11 16:14:22.275 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"GET","url":"/api/v4/websocket","request_id":"dydy61qazbdf3xwuunbep6n1ao","user_id":"zy4b3ewjnfy95cjsbbnc5mdbcw"}
{"timestamp":"2024-10-11 16:14:22.275 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"GET","url":"/api/v4/websocket","request_id":"kgfhotk5yi8nbgttjbgwz1hgoh","user_id":"zy4b3ewjnfy95cjsbbnc5mdbcw"}
{"timestamp":"2024-10-11 16:14:22.275 Z","level":"debug","msg":"websocket.NextReader: closing websocket","caller":"platform/web_conn.go:991","user_id":"zy4b3ewjnfy95cjsbbnc5mdbcw","conn_id":"t7e5xsf35jygunbqso9sxdtsmy","origin_client":"web","error":"websocket: close 1006 (abnormal closure): unexpected EOF"}
{"timestamp":"2024-10-11 16:14:22.275 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"GET","url":"/api/v4/websocket","request_id":"g4pr58e4wbddfcw7osfwynmhjo","user_id":"zy4b3ewjnfy95cjsbbnc5mdbcw"}
{"timestamp":"2024-10-11 16:14:22.278 Z","level":"debug","msg":"websocket.NextReader: closing websocket","caller":"platform/web_conn.go:991","user_id":"zy4b3ewjnfy95cjsbbnc5mdbcw","conn_id":"kzmszuoiebgsdfwri1ouxfh8or","origin_client":"web","error":"websocket: close 1006 (abnormal closure): unexpected EOF"}
{"timestamp":"2024-10-11 16:14:22.278 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"GET","url":"/api/v4/websocket","request_id":"tykke9ioofdp8x5wbeaudu3f9r","user_id":"zy4b3ewjnfy95cjsbbnc5mdbcw"}
{"timestamp":"2024-10-11 16:14:22.279 Z","level":"debug","msg":"websocket.NextReader: closing websocket","caller":"platform/web_conn.go:991","user_id":"zy4b3ewjnfy95cjsbbnc5mdbcw","conn_id":"b8dj5fzxdfdammys6z9ndf7tio","origin_client":"web","error":"websocket: close 1006 (abnormal closure): unexpected EOF"}
{"timestamp":"2024-10-11 16:14:22.279 Z","level":"debug","msg":"websocket.send: closing websocket","caller":"platform/web_conn.go:991","user_id":"zy4b3ewjnfy95cjsbbnc5mdbcw","conn_id":"b8dj5fzxdfdammys6z9ndf7tio","origin_client":"web","error":"write tcp 127.0.0.1:38039->127.0.0.1:60242: use of closed network connection"}
{"timestamp":"2024-10-11 16:14:22.280 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"GET","url":"/api/v4/websocket","request_id":"zhfq1ghos7yd5fddpepk7yhq1a","user_id":"zy4b3ewjnfy95cjsbbnc5mdbcw"}
{"timestamp":"2024-10-11 16:14:22.281 Z","level":"debug","msg":"websocket.NextReader: closing websocket","caller":"platform/web_conn.go:991","user_id":"zy4b3ewjnfy95cjsbbnc5mdbcw","conn_id":"kzmszuoiebgsdfwri1ouxfh8or","origin_client":"web","error":"read tcp 127.0.0.1:38039->127.0.0.1:60186: read: connection reset by peer"}
{"timestamp":"2024-10-11 16:14:22.281 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"GET","url":"/api/v4/websocket","request_id":"kbg78qx88f87dfo4rs8ig4y1yc","user_id":"zy4b3ewjnfy95cjsbbnc5mdbcw"}
{"timestamp":"2024-10-11 16:14:22.282 Z","level":"debug","msg":"websocket.NextReader: closing websocket","caller":"platform/web_conn.go:991","user_id":"zy4b3ewjnfy95cjsbbnc5mdbcw","conn_id":"kzmszuoiebgsdfwri1ouxfh8or","origin_client":"web","error":"websocket: close 1006 (abnormal closure): unexpected EOF"}
{"timestamp":"2024-10-11 16:14:22.282 Z","level":"debug","msg":"websocket.drainDeadQueue: closing websocket","caller":"platform/web_conn.go:991","user_id":"zy4b3ewjnfy95cjsbbnc5mdbcw","conn_id":"kzmszuoiebgsdfwri1ouxfh8or","origin_client":"web","error":"write tcp 127.0.0.1:38039->127.0.0.1:60214: use of closed network connection"}
{"timestamp":"2024-10-11 16:14:22.282 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"GET","url":"/api/v4/websocket","request_id":"nuou49dqafrfbdd9eq6a17ynxe","user_id":"zy4b3ewjnfy95cjsbbnc5mdbcw"}
{"timestamp":"2024-10-11 16:14:22.284 Z","level":"debug","msg":"websocket.drainDeadQueue: closing websocket","caller":"platform/web_conn.go:991","user_id":"zy4b3ewjnfy95cjsbbnc5mdbcw","conn_id":"kzmszuoiebgsdfwri1ouxfh8or","origin_client":"web","error":"write tcp 127.0.0.1:38039->127.0.0.1:60216: write: connection reset by peer"}
{"timestamp":"2024-10-11 16:14:22.284 Z","level":"debug","msg":"websocket.NextReader: closing websocket","caller":"platform/web_conn.go:991","user_id":"zy4b3ewjnfy95cjsbbnc5mdbcw","conn_id":"kzmszuoiebgsdfwri1ouxfh8or","origin_client":"web","error":"read tcp 127.0.0.1:38039->127.0.0.1:60216: use of closed network connection"}
{"timestamp":"2024-10-11 16:14:22.285 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"GET","url":"/api/v4/websocket","request_id":"sbqtrg3m93gx8pmzhepizkpmac","user_id":"zy4b3ewjnfy95cjsbbnc5mdbcw"}
{"timestamp":"2024-10-11 16:14:22.286 Z","level":"info","msg":"Purging all caches","caller":"platform/cluster_handlers.go:114"}
==================
WARNING: DATA RACE
Write at 0x00c0008ec140 by goroutine 76:
  github.com/mattermost/mattermost/server/v8/channels/app/platform.(*PlatformService).ReturnSessionToPool()
      /mattermost/server/channels/app/platform/session.go:19 +0x9c
  github.com/mattermost/mattermost/server/v8/channels/app/platform.(*hubConnectionIndex).Remove()
      /mattermost/server/channels/app/platform/web_hub.go:651 +0x41
  github.com/mattermost/mattermost/server/v8/channels/app/platform.(*hubConnectionIndex).RemoveInactiveByConnectionID()
      /mattermost/server/channels/app/platform/web_hub.go:725 +0x2c7
  github.com/mattermost/mattermost/server/v8/channels/app/platform.(*Hub).Start.func1()
      /mattermost/server/channels/app/platform/web_hub.go:409 +0x8f4
  github.com/mattermost/mattermost/server/v8/channels/app/platform.(*Hub).Start.func2()
      /mattermost/server/channels/app/platform/web_hub.go:587 +0x58

Previous read at 0x00c0008ec140 by goroutine 792:
  github.com/mattermost/mattermost/server/v8/channels/app/platform.(*PlatformService).NewWebConn.func1()
      /mattermost/server/channels/app/platform/web_conn.go:258 +0xbc
  github.com/mattermost/mattermost/server/v8/channels/app/platform.(*PlatformService).Go.func1()
      /mattermost/server/channels/app/platform/goroutines.go:14 +0x37

Goroutine 76 (running) created at:
  github.com/mattermost/mattermost/server/v8/channels/app/platform.(*Hub).Start()
      /mattermost/server/channels/app/platform/web_hub.go:604 +0x264
  github.com/mattermost/mattermost/server/v8/channels/app/platform.(*PlatformService).hubStart()
      /mattermost/server/channels/app/platform/web_hub.go:114 +0x29b
  github.com/mattermost/mattermost/server/v8/channels/app/platform.(*PlatformService).Start()
      /mattermost/server/channels/app/platform/service.go:377 +0x3a
  github.com/mattermost/mattermost/server/v8/channels/app.NewServer()
      /mattermost/server/channels/app/server.go:240 +0x16e6
  github.com/mattermost/mattermost/server/v8/channels/api4.setupTestHelper()
      /mattermost/server/channels/api4/apitestlib.go:148 +0x148c
  github.com/mattermost/mattermost/server/v8/channels/api4.Setup()
      /mattermost/server/channels/api4/apitestlib.go:268 +0x1fc
  github.com/mattermost/mattermost/server/v8/channels/api4.TestWebSocketReconnectRace()
      /mattermost/server/channels/api4/websocket_test.go:207 +0x45
  testing.tRunner()
      /usr/local/go/src/testing/testing.go:1689 +0x21e
  testing.(*T).Run.gowrap1()
      /usr/local/go/src/testing/testing.go:1742 +0x44

Goroutine 792 (running) created at:
  github.com/mattermost/mattermost/server/v8/channels/app/platform.(*PlatformService).Go()
      /mattermost/server/channels/app/platform/goroutines.go:13 +0xec
  github.com/mattermost/mattermost/server/v8/channels/app/platform.(*PlatformService).NewWebConn()
      /mattermost/server/channels/app/platform/web_conn.go:254 +0xf93
  github.com/mattermost/mattermost/server/v8/channels/api4.connectWebSocket()
      /mattermost/server/channels/api4/websocket.go:79 +0x133c
  github.com/mattermost/mattermost/server/v8/channels/web.Handler.ServeHTTP()
      /mattermost/server/channels/web/handlers.go:387 +0x6645
  github.com/mattermost/mattermost/server/v8/channels/web.(*Handler).ServeHTTP()
      <autogenerated>:1 +0xe7
  github.com/klauspost/compress/gzhttp.NewWrapper.func1.1()
      /go/pkg/mod/github.com/klauspost/[email protected]/gzhttp/compress.go:501 +0x6e1
  net/http.HandlerFunc.ServeHTTP()
      /usr/local/go/src/net/http/server.go:2171 +0x47
  github.com/gorilla/mux.(*Router).ServeHTTP()
      /go/pkg/mod/github.com/gorilla/[email protected]/mux.go:212 +0x371
  net/http.serverHandler.ServeHTTP()
      /usr/local/go/src/net/http/server.go:3142 +0x2a1
  net/http.(*conn).serve()
      /usr/local/go/src/net/http/server.go:2044 +0x13c4
  net/http.(*Server).Serve.gowrap3()
      /usr/local/go/src/net/http/server.go:3290 +0x4f
==================
{"timestamp":"2024-10-11 16:14:22.289 Z","level":"debug","msg":"websocket.drainDeadQueue: closing websocket","caller":"platform/web_conn.go:991","user_id":"zy4b3ewjnfy95cjsbbnc5mdbcw","conn_id":"kzmszuoiebgsdfwri1ouxfh8or","origin_client":"web","error":"write tcp 127.0.0.1:38039->127.0.0.1:60228: write: broken pipe"}
{"timestamp":"2024-10-11 16:14:22.289 Z","level":"debug","msg":"websocket.NextReader: closing websocket","caller":"platform/web_conn.go:991","user_id":"zy4b3ewjnfy95cjsbbnc5mdbcw","conn_id":"kzmszuoiebgsdfwri1ouxfh8or","origin_client":"web","error":"read tcp 127.0.0.1:38039->127.0.0.1:60228: use of closed network connection"}
{"timestamp":"2024-10-11 16:14:22.289 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"GET","url":"/api/v4/websocket","request_id":"d9f6333m5pgdfguehwyjx375pc","user_id":"zy4b3ewjnfy95cjsbbnc5mdbcw"}
{"timestamp":"2024-10-11 16:14:22.291 Z","level":"info","msg":"License key has not been uploaded. Loading license key from disk.","caller":"utils/license.go:107","filename":"/tmp/testlib1614595663/config/mattermost.mattermost-license"}
{"timestamp":"2024-10-11 16:14:22.291 Z","level":"warn","msg":"Failed to get license from disk","caller":"platform/license.go:93","error":"We could not find the license key on disk at /tmp/testlib1614595663/config/mattermost.mattermost-license: stat /tmp/testlib1614595663/config/mattermost.mattermost-license: no such file or directory"}
{"timestamp":"2024-10-11 16:14:22.292 Z","level":"warn","msg":"License key from https://mattermost.com required to unlock enterprise features.","caller":"platform/license.go:105","error":"resource \"License\" not found, id: "}
{"timestamp":"2024-10-11 16:14:22.296 Z","level":"info","msg":"Stopping Server...","caller":"app/server.go:669"}
{"timestamp":"2024-10-11 16:14:22.298 Z","level":"info","msg":"Shutting down Email batching service...","caller":"email/service.go:85"}
{"timestamp":"2024-10-11 16:14:22.298 Z","level":"info","msg":"Shutting down plugins","caller":"app/plugin.go:362"}
{"timestamp":"2024-10-11 16:14:22.298 Z","level":"debug","msg":"Disabling plugin health check job","caller":"plugin/environment.go:670"}
{"timestamp":"2024-10-11 16:14:22.298 Z","level":"info","msg":"stopping websocket hub connections","caller":"platform/web_hub.go:127"}
{"timestamp":"2024-10-11 16:14:22.302 Z","level":"info","msg":"Server stopped","caller":"app/server.go:753"}
    testing.go:1398: race detected during execution of test
--- FAIL: TestWebSocketReconnectRace (7.29s)

Check failure on line 1 in com/mattermost/mattermost/server/v8/channels/api4

See this annotation in the file changed.

@github-actions github-actions / MySQL (Results)

com/mattermost/mattermost/server/v8/channels/api4.TestSyncLdap/SystemAdminClient#01 

Failed
Raw output 
=== RUN   TestSyncLdap/SystemAdminClient#01
==================
WARNING: DATA RACE
Read at 0x00c0053935c0 by goroutine 319:
  reflect.maplen()
      /usr/local/go/src/runtime/map.go:1406 +0x0
  reflect.Value.lenNonSlice()
      /usr/local/go/src/reflect/value.go:1785 +0x1e9
  reflect.Value.Len()
      /usr/local/go/src/reflect/value.go:1774 +0x137
  internal/fmtsort.Sort()
      /usr/local/go/src/internal/fmtsort/sort.go:58 +0x121
  fmt.(*pp).printValue()
      /usr/local/go/src/fmt/print.go:816 +0x1144
  fmt.(*pp).printValue()
      /usr/local/go/src/fmt/print.go:853 +0x1d3e
  fmt.(*pp).printValue()
      /usr/local/go/src/fmt/print.go:853 +0x1d3e
  fmt.(*pp).printValue()
      /usr/local/go/src/fmt/print.go:921 +0x132a
  fmt.(*pp).printArg()
      /usr/local/go/src/fmt/print.go:759 +0xb84
  fmt.(*pp).doPrintf()
      /usr/local/go/src/fmt/print.go:1174 +0x10ce
  fmt.Sprintf()
      /usr/local/go/src/fmt/print.go:239 +0x5c
  github.com/stretchr/testify/mock.Arguments.Diff()
      /go/pkg/mod/github.com/stretchr/[email protected]/mock/mock.go:939 +0x1b2
  github.com/stretchr/testify/mock.(*Mock).findExpectedCall()
      /go/pkg/mod/github.com/stretchr/[email protected]/mock/mock.go:368 +0x147
  github.com/stretchr/testify/mock.(*Mock).MethodCalled()
      /go/pkg/mod/github.com/stretchr/[email protected]/mock/mock.go:476 +0xac
  github.com/stretchr/testify/mock.(*Mock).Called()
      /go/pkg/mod/github.com/stretchr/[email protected]/mock/mock.go:466 +0x195
  github.com/mattermost/mattermost/server/v8/einterfaces/mocks.(*LdapInterface).StartSynchronizeJob()
      /mattermost/server/einterfaces/mocks/LdapInterface.go:406 +0x1b0
  github.com/mattermost/mattermost/server/v8/channels/app.(*App).SyncLdap.func1()
      /mattermost/server/channels/app/ldap.go:33 +0x1d8
  github.com/mattermost/mattermost/server/v8/channels/app/platform.(*PlatformService).Go.func1()
      /mattermost/server/channels/app/platform/goroutines.go:14 +0x37

Previous write at 0x00c0053935c0 by goroutine 190:
  runtime.mapclear()
      /usr/local/go/src/runtime/map.go:989 +0x0
  github.com/mattermost/mattermost/server/v8/channels/app/platform.(*PlatformService).ReturnSessionToPool()
      /mattermost/server/channels/app/platform/session.go:25 +0xcf
  github.com/mattermost/mattermost/server/v8/channels/app.(*App).ReturnSessionToPool()
      /mattermost/server/channels/app/session.go:227 +0x26
  github.com/mattermost/mattermost/server/v8/channels/web.Handler.ServeHTTP.deferwrap1()
      /mattermost/server/channels/web/handlers.go:295 +0x55
  runtime.deferreturn()
      /usr/local/go/src/runtime/panic.go:602 +0x5d
  github.com/mattermost/mattermost/server/v8/channels/web.(*Handler).ServeHTTP()
      <autogenerated>:1 +0xe7
  github.com/klauspost/compress/gzhttp.NewWrapper.func1.1()
      /go/pkg/mod/github.com/klauspost/[email protected]/gzhttp/compress.go:495 +0x7c6
  net/http.HandlerFunc.ServeHTTP()
      /usr/local/go/src/net/http/server.go:2171 +0x47
  github.com/gorilla/mux.(*Router).ServeHTTP()
      /go/pkg/mod/github.com/gorilla/[email protected]/mux.go:212 +0x371
  net/http.serverHandler.ServeHTTP()
      /usr/local/go/src/net/http/server.go:3142 +0x2a1
  net/http.(*conn).serve()
      /usr/local/go/src/net/http/server.go:2044 +0x13c4
  net/http.(*Server).Serve.gowrap3()
      /usr/local/go/src/net/http/server.go:3290 +0x4f

Goroutine 319 (running) created at:
  github.com/mattermost/mattermost/server/v8/channels/app/platform.(*PlatformService).Go()
      /mattermost/server/channels/app/platform/goroutines.go:13 +0xec
  github.com/mattermost/mattermost/server/v8/channels/app.(*Server).Go()
      /mattermost/server/channels/app/server.go:806 +0x148
  github.com/mattermost/mattermost/server/v8/channels/app.(*App).SyncLdap()
      /mattermost/server/channels/app/ldap.go:21 +0x5d
  github.com/mattermost/mattermost/server/v8/channels/api4.syncLdap()
      /mattermost/server/channels/api4/ldap.go:69 +0x9de
  github.com/mattermost/mattermost/server/v8/channels/web.Handler.ServeHTTP()
      /mattermost/server/channels/web/handlers.go:387 +0x6645
  github.com/mattermost/mattermost/server/v8/channels/web.(*Handler).ServeHTTP()
      <autogenerated>:1 +0xe7
  github.com/klauspost/compress/gzhttp.NewWrapper.func1.1()
      /go/pkg/mod/github.com/klauspost/[email protected]/gzhttp/compress.go:495 +0x7c6
  net/http.HandlerFunc.ServeHTTP()
      /usr/local/go/src/net/http/server.go:2171 +0x47
  github.com/gorilla/mux.(*Router).ServeHTTP()
      /go/pkg/mod/github.com/gorilla/[email protected]/mux.go:212 +0x371
  net/http.serverHandler.ServeHTTP()
      /usr/local/go/src/net/http/server.go:3142 +0x2a1
  net/http.(*conn).serve()
      /usr/local/go/src/net/http/server.go:2044 +0x13c4
  net/http.(*Server).Serve.gowrap3()
      /usr/local/go/src/net/http/server.go:3290 +0x4f

Goroutine 190 (running) created at:
  net/http.(*Server).Serve()
      /usr/local/go/src/net/http/server.go:3290 +0x8ec
  github.com/mattermost/mattermost/server/v8/channels/app.(*Server).Start.func2()
      /mattermost/server/channels/app/server.go:1070 +0x224
==================
{"timestamp":"2024-10-11 16:14:36.280 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/ldap/sync","request_id":"dhw595do9py9tpt1o34p9o5bor","user_id":"5y54135c7pg8in6fdjyt7kmftr","status_code":"200"}
{"timestamp":"2024-10-11 16:14:36.290 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/ldap/sync","request_id":"dphuzzgtjirrzpsc9an4s7krnw","user_id":"5y54135c7pg8in6fdjyt7kmftr","status_code":"200"}
    testing.go:1398: race detected during execution of test
--- FAIL: TestSyncLdap/SystemAdminClient#01 (0.01s)

Check failure on line 1 in com/mattermost/mattermost/server/v8/channels/api4

See this annotation in the file changed.

@github-actions github-actions / MySQL (Results)

com/mattermost/mattermost/server/v8/channels/api4.TestSyncLdap 

Failed
Raw output 
=== RUN   TestSyncLdap
{"timestamp":"2024-10-11 16:14:30.535 Z","level":"debug","msg":"Advanced logging config not provided for notification logging","caller":"platform/config.go:165"}
{"timestamp":"2024-10-11 16:14:30.535 Z","level":"info","msg":"Successfully connected to cache backend","caller":"platform/service.go:191","backend":"lru","result":"OK"}
{"timestamp":"2024-10-11 16:14:30.535 Z","level":"info","msg":"Server is initializing...","caller":"platform/service.go:194","go_version":"go1.22.6"}
{"timestamp":"2024-10-11 16:14:30.552 Z","level":"info","msg":"Starting websocket hubs","caller":"platform/web_hub.go:106","number_of_hubs":4}
{"timestamp":"2024-10-11 16:14:30.552 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":2}
{"timestamp":"2024-10-11 16:14:30.552 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":0}
{"timestamp":"2024-10-11 16:14:30.552 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":1}
{"timestamp":"2024-10-11 16:14:30.552 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":3}
{"timestamp":"2024-10-11 16:14:30.553 Z","level":"info","msg":"Loaded system translations","caller":"i18n/i18n.go:175","for locale":"en","from locale":"/mattermost/server/i18n/en.json"}
{"timestamp":"2024-10-11 16:14:30.562 Z","level":"info","msg":"Ensuring the telemetry ID..","caller":"telemetry/telemetry.go:167"}
{"timestamp":"2024-10-11 16:14:30.564 Z","level":"info","msg":"telemetry ID is set","caller":"telemetry/telemetry.go:177","id":"n7qu65j817njtbkd9rez8xqfro"}
{"timestamp":"2024-10-11 16:14:30.583 Z","level":"error","msg":"SiteURL must be set. Some features will operate incorrectly if the SiteURL is not set. See documentation for details: https://mattermost.com/pl/configure-site-url","caller":"app/server.go:390"}
{"timestamp":"2024-10-11 16:14:30.583 Z","level":"info","msg":"Current version is 10.2.0 (///)","caller":"app/server.go:404","current_version":"10.2.0","build_number":"","build_date":"","build_hash":"","build_hash_enterprise":"","service_environment":"dev"}
{"timestamp":"2024-10-11 16:14:30.583 Z","level":"info","msg":"Team Edition Build","caller":"app/server.go:420","enterprise_build":false}
{"timestamp":"2024-10-11 16:14:30.583 Z","level":"info","msg":"Printing current working","caller":"app/server.go:424","directory":"/tmp/testlib665783420"}
{"timestamp":"2024-10-11 16:14:30.583 Z","level":"info","msg":"Loaded config","caller":"app/server.go:425","source":"memory://"}
{"timestamp":"2024-10-11 16:14:30.814 Z","level":"debug","msg":"initElasticsearchChannelIndexCheck: calling elasticsearchChannelIndexCheckWithRetry before setting up config and license change listeners...","caller":"app/elasticsearch.go:21"}
{"timestamp":"2024-10-11 16:14:30.815 Z","level":"debug","msg":"elasticsearchChannelIndexCheckWithRetry: attempting to check channel index state...","caller":"app/elasticsearch.go:64"}
{"timestamp":"2024-10-11 16:14:30.815 Z","level":"debug","msg":"elasticsearchChannelIndexCheckWithRetry: skipping because elasticsearch indexing is disabled","caller":"app/elasticsearch.go:67"}
{"timestamp":"2024-10-11 16:14:30.848 Z","level":"debug","msg":"Fetching user count for first user account check","caller":"platform/config.go:356"}
{"timestamp":"2024-10-11 16:14:30.850 Z","level":"info","msg":"Post.Message has size restrictions","caller":"sqlstore/post_store.go:2628","max_characters":16383,"max_bytes":65535}
{"timestamp":"2024-10-11 16:14:30.855 Z","level":"debug","msg":"Advanced logging config not provided for notification logging","caller":"platform/config.go:165"}
{"timestamp":"2024-10-11 16:14:30.855 Z","level":"info","msg":"Starting up plugins","caller":"app/plugin.go:191"}
{"timestamp":"2024-10-11 16:14:30.855 Z","level":"debug","msg":"Enabling plugin health check job","caller":"plugin/environment.go:661","interval_s":"30s"}
{"timestamp":"2024-10-11 16:14:30.855 Z","level":"info","msg":"Syncing plugins from the file store","caller":"app/plugin.go:268"}
{"timestamp":"2024-10-11 16:14:30.855 Z","level":"debug","msg":"Plugin health check job starting.","caller":"plugin/health_check.go:31"}
{"timestamp":"2024-10-11 16:14:30.855 Z","level":"info","msg":"No plugins to sync from the file store","caller":"app/plugin.go:311"}
{"timestamp":"2024-10-11 16:14:30.856 Z","level":"debug","msg":"No prepackaged plugins directory found","caller":"app/plugin.go:929"}
{"timestamp":"2024-10-11 16:14:30.856 Z","level":"debug","msg":"Not persisting transitionally prepackaged plugins: none found","caller":"app/plugin.go:1201"}
{"timestamp":"2024-10-11 16:14:30.862 Z","level":"debug","msg":"Able to write files to local storage.","caller":"filestore/localstore.go:82"}
{"timestamp":"2024-10-11 16:14:30.887 Z","level":"info","msg":"Starting Server...","caller":"app/server.go:887"}
{"timestamp":"2024-10-11 16:14:30.887 Z","level":"info","msg":"Server is listening on 127.0.0.1:46419","caller":"app/server.go:963","address":"127.0.0.1:46419"}
{"timestamp":"2024-10-11 16:14:30.888 Z","level":"debug","msg":"No license provided; Remote Cluster services disabled","caller":"app/server.go:580"}
{"timestamp":"2024-10-11 16:14:30.943 Z","level":"debug","msg":"Initializing web routes","caller":"web/web.go:26"}
{"timestamp":"2024-10-11 16:14:30.944 Z","level":"debug","msg":"Skipping update to assets subpath since CI build","caller":"utils/subpath.go:187"}
{"timestamp":"2024-10-11 16:14:30.944 Z","level":"debug","msg":"Using client directory","caller":"web/static.go:34","clientDir":"/tmp/testlib665783420/client"}
{"timestamp":"2024-10-11 16:14:30.944 Z","level":"debug","msg":"Configured Client4 bool string values","caller":"api4/apitestlib.go:212","true":"true","false":"f"}
{"timestamp":"2024-10-11 16:14:30.947 Z","level":"debug","msg":"Fetching user count for first user account check","caller":"platform/config.go:356"}
{"timestamp":"2024-10-11 16:14:31.737 Z","level":"info","msg":"sending mail","caller":"mail/mail.go:295","to":"hihue9fzgtdt8g8en9cedjr91h@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-10-11 16:14:31.755 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"oi9eboh4tjg7mc9cysjmnjkdrc","user_id":"","status_code":"201"}
{"timestamp":"2024-10-11 16:14:31.765 Z","level":"debug","msg":"Fetching user count for first user account check","caller":"platform/config.go:356"}
{"timestamp":"2024-10-11 16:14:32.564 Z","level":"info","msg":"sending mail","caller":"mail/mail.go:295","to":"5smf6jedg7yk5csn3t4fc4xery@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-10-11 16:14:32.582 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"5b13q7j3htr6tqc1ymaajmo1ph","user_id":"","status_code":"201"}
{"timestamp":"2024-10-11 16:14:33.420 Z","level":"info","msg":"sending mail","caller":"mail/mail.go:295","to":"m91kgs6i738izexhzsu33rh43w@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-10-11 16:14:33.444 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"6ntee8gqfb8rbja4acapscp9mc","user_id":"","status_code":"201"}
{"timestamp":"2024-10-11 16:14:34.273 Z","level":"info","msg":"sending mail","caller":"mail/mail.go:295","to":"9txc1szsdignfpfm6ceha7sn1h@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-10-11 16:14:34.294 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"z8nqa4ktefbwigtu9yf1wpd9ah","user_id":"","status_code":"201"}
{"timestamp":"2024-10-11 16:14:35.151 Z","level":"info","msg":"sending mail","caller":"mail/mail.go:295","to":"fs1mjuajuifujduhr7xtcunmqw@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-10-11 16:14:35.171 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"3g1p18tyojndpg8y43dhzexshc","user_id":"","status_code":"201"}
{"timestamp":"2024-10-11 16:14:36.219 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users/login","request_id":"4epmdgjkkfdkzeegkiwy8brnfo","user_id":"tjh3qohq3pdcmekamumsiqmmja","status_code":"200"}
{"timestamp":"2024-10-11 16:14:36.219 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users/login","request_id":"7qtzoptudfyddqxaknr7c7ex1h","user_id":"5y54135c7pg8in6fdjyt7kmftr","status_code":"200"}
{"timestamp":"2024-10-11 16:14:36.221 Z","level":"info","msg":"Set license","caller":"platform/license.go:384","id":"","issued_at":"1970-01-01 00:00:00.000 Z","starts_at":"1970-01-01 00:00:00.000 Z","expires_at":"2025-01-09 16:14:36.220 Z","sku_name":"","sku_short_name":"","is_trial":false,"is_gov_sku":false,"customer_id":"some ID","features.users":0,"features":{"advanced_logging":true,"cloud":false,"cluster":true,"compliance":true,"custom_permissions_schemes":true,"data_retention":true,"elastic_search":true,"email_notification_contents":true,"enterprise_plugins":true,"future":true,"google":true,"guest_accounts":true,"guest_accounts_permissions":true,"id_loaded":true,"ldap":true,"ldap_groups":true,"lock_teammate_name_display":true,"message_export":true,"metrics":true,"mfa":true,"mhpns":true,"office365":true,"openid":true,"outgoing_oauth_connections":true,"remote_cluster_service":true,"saml":true,"shared_channels":true}}
{"timestamp":"2024-10-11 16:14:36.277 Z","level":"debug","msg":"Advanced logging config not provided for notification logging","caller":"platform/config.go:165"}
{"timestamp":"2024-10-11 16:14:36.293 Z","level":"debug","msg":"You do not have the appropriate permissions.","caller":"web/context.go:120","path":"/api/v4/ldap/sync","request_id":"d3q3xawj6ifc88acdpkuti8pmo","ip_addr":"127.0.0.1","user_id":"tjh3qohq3pdcmekamumsiqmmja","method":"POST","err_where":"Permissions","http_code":403,"error":"Permissions: You do not have the appropriate permissions., userId=tjh3qohq3pdcmekamumsiqmmja, permission=create_ldap_sync_job"}
{"timestamp":"2024-10-11 16:14:36.293 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/ldap/sync","request_id":"d3q3xawj6ifc88acdpkuti8pmo","user_id":"tjh3qohq3pdcmekamumsiqmmja","status_code":"403"}
{"timestamp":"2024-10-11 16:14:36.294 Z","level":"info","msg":"Purging all caches","caller":"platform/cluster_handlers.go:114"}
{"timestamp":"2024-10-11 16:14:36.303 Z","level":"info","msg":"License key has not been uploaded. Loading license key from disk.","caller":"utils/license.go:107","filename":"/tmp/testlib665783420/config/mattermost.mattermost-license"}
{"timestamp":"2024-10-11 16:14:36.303 Z","level":"warn","msg":"Failed to get license from disk","caller":"platform/license.go:93","error":"We could not find the license key on disk at /tmp/testlib665783420/config/mattermost.mattermost-license: stat /tmp/testlib665783420/config/mattermost.mattermost-license: no such file or directory"}
{"timestamp":"2024-10-11 16:14:36.306 Z","level":"warn","msg":"License key from https://mattermost.com required to unlock enterprise features.","caller":"platform/license.go:105","error":"resource \"License\" not found, id: "}
{"timestamp":"2024-10-11 16:14:36.306 Z","level":"info","msg":"Cleared license","caller":"platform/license.go:384","id":"","issued_at":"1970-01-01 00:00:00.000 Z","starts_at":"1970-01-01 00:00:00.000 Z","expires_at":"2025-01-09 16:14:36.220 Z","sku_name":"","sku_short_name":"","is_trial":false,"is_gov_sku":false,"customer_id":"some ID","features.users":0,"features":{"advanced_logging":true,"cloud":false,"cluster":true,"compliance":true,"custom_permissions_schemes":true,"data_retention":true,"elastic_search":true,"email_notification_contents":true,"enterprise_plugins":true,"future":true,"google":true,"guest_accounts":true,"guest_accounts_permissions":true,"id_loaded":true,"ldap":true,"ldap_groups":true,"lock_teammate_name_display":true,"message_export":true,"metrics":true,"mfa":true,"mhpns":true,"office365":true,"openid":true,"outgoing_oauth_connections":true,"remote_cluster_service":true,"saml":true,"shared_channels":true}}
{"timestamp":"2024-10-11 16:14:36.311 Z","level":"info","msg":"Stopping Server...","caller":"app/server.go:669"}
{"timestamp":"2024-10-11 16:14:36.328 Z","level":"info","msg":"Shutting down Email batching service...","caller":"email/service.go:85"}
{"timestamp":"2024-10-11 16:14:36.328 Z","level":"info","msg":"Shutting down plugins","caller":"app/plugin.go:362"}
{"timestamp":"2024-10-11 16:14:36.328 Z","level":"debug","msg":"Disabling plugin health check job","caller":"plugin/environment.go:670"}
{"timestamp":"2024-10-11 16:14:36.328 Z","level":"info","msg":"stopping websocket hub connections","caller":"platform/web_hub.go:127"}
{"timestamp":"2024-10-11 16:14:36.328 Z","level":"info","msg":"Server stopped","caller":"app/server.go:753"}
--- FAIL: TestSyncLdap (6.25s)

Check failure on line 1 in com/mattermost/mattermost/server/v8/channels/api4

See this annotation in the file changed.

@github-actions github-actions / MySQL (Results)

com/mattermost/mattermost/server/v8/channels/api4.TestSyncLdap/SystemAdminClient#01 

Failed
Raw output 
=== RUN   TestSyncLdap/SystemAdminClient#01
{"timestamp":"2024-10-11 16:15:06.520 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/ldap/sync","request_id":"oo3b6d7th3gdurg5b8t41598ne","user_id":"7isb8osjapy5fxrksd7caewqty","status_code":"200"}
==================
WARNING: DATA RACE
Read at 0x00c002688960 by goroutine 319:
  reflect.maplen()
      /usr/local/go/src/runtime/map.go:1406 +0x0
  reflect.Value.lenNonSlice()
      /usr/local/go/src/reflect/value.go:1785 +0x1e9
  reflect.Value.Len()
      /usr/local/go/src/reflect/value.go:1774 +0x137
  internal/fmtsort.Sort()
      /usr/local/go/src/internal/fmtsort/sort.go:58 +0x121
  fmt.(*pp).printValue()
      /usr/local/go/src/fmt/print.go:816 +0x1144
  fmt.(*pp).printValue()
      /usr/local/go/src/fmt/print.go:853 +0x1d3e
  fmt.(*pp).printValue()
      /usr/local/go/src/fmt/print.go:853 +0x1d3e
  fmt.(*pp).printValue()
      /usr/local/go/src/fmt/print.go:921 +0x132a
  fmt.(*pp).printArg()
      /usr/local/go/src/fmt/print.go:759 +0xb84
  fmt.(*pp).doPrintf()
      /usr/local/go/src/fmt/print.go:1174 +0x10ce
  fmt.Sprintf()
      /usr/local/go/src/fmt/print.go:239 +0x5c
  github.com/stretchr/testify/mock.Arguments.Diff()
      /go/pkg/mod/github.com/stretchr/[email protected]/mock/mock.go:939 +0x1b2
  github.com/stretchr/testify/mock.(*Mock).findExpectedCall()
      /go/pkg/mod/github.com/stretchr/[email protected]/mock/mock.go:368 +0x147
  github.com/stretchr/testify/mock.(*Mock).MethodCalled()
      /go/pkg/mod/github.com/stretchr/[email protected]/mock/mock.go:476 +0xac
  github.com/stretchr/testify/mock.(*Mock).Called()
      /go/pkg/mod/github.com/stretchr/[email protected]/mock/mock.go:466 +0x195
  github.com/mattermost/mattermost/server/v8/einterfaces/mocks.(*LdapInterface).StartSynchronizeJob()
      /mattermost/server/einterfaces/mocks/LdapInterface.go:406 +0x1b0
  github.com/mattermost/mattermost/server/v8/channels/app.(*App).SyncLdap.func1()
      /mattermost/server/channels/app/ldap.go:33 +0x1d8
  github.com/mattermost/mattermost/server/v8/channels/app/platform.(*PlatformService).Go.func1()
      /mattermost/server/channels/app/platform/goroutines.go:14 +0x37

Previous write at 0x00c002688960 by goroutine 190:
  runtime.mapclear()
      /usr/local/go/src/runtime/map.go:989 +0x0
  github.com/mattermost/mattermost/server/v8/channels/app/platform.(*PlatformService).ReturnSessionToPool()
      /mattermost/server/channels/app/platform/session.go:25 +0xcf
  github.com/mattermost/mattermost/server/v8/channels/app.(*App).ReturnSessionToPool()
      /mattermost/server/channels/app/session.go:227 +0x26
  github.com/mattermost/mattermost/server/v8/channels/web.Handler.ServeHTTP.deferwrap1()
      /mattermost/server/channels/web/handlers.go:295 +0x55
  runtime.deferreturn()
      /usr/local/go/src/runtime/panic.go:602 +0x5d
  github.com/mattermost/mattermost/server/v8/channels/web.(*Handler).ServeHTTP()
      <autogenerated>:1 +0xe7
  github.com/klauspost/compress/gzhttp.NewWrapper.func1.1()
      /go/pkg/mod/github.com/klauspost/[email protected]/gzhttp/compress.go:495 +0x7c6
  net/http.HandlerFunc.ServeHTTP()
      /usr/local/go/src/net/http/server.go:2171 +0x47
  github.com/gorilla/mux.(*Router).ServeHTTP()
      /go/pkg/mod/github.com/gorilla/[email protected]/mux.go:212 +0x371
  net/http.serverHandler.ServeHTTP()
      /usr/local/go/src/net/http/server.go:3142 +0x2a1
  net/http.(*conn).serve()
      /usr/local/go/src/net/http/server.go:2044 +0x13c4
  net/http.(*Server).Serve.gowrap3()
      /usr/local/go/src/net/http/server.go:3290 +0x4f

Goroutine 319 (running) created at:
  github.com/mattermost/mattermost/server/v8/channels/app/platform.(*PlatformService).Go()
      /mattermost/server/channels/app/platform/goroutines.go:13 +0xec
  github.com/mattermost/mattermost/server/v8/channels/app.(*Server).Go()
      /mattermost/server/channels/app/server.go:806 +0x148
  github.com/mattermost/mattermost/server/v8/channels/app.(*App).SyncLdap()
      /mattermost/server/channels/app/ldap.go:21 +0x5d
  github.com/mattermost/mattermost/server/v8/channels/api4.syncLdap()
      /mattermost/server/channels/api4/ldap.go:69 +0x9de
  github.com/mattermost/mattermost/server/v8/channels/web.Handler.ServeHTTP()
      /mattermost/server/channels/web/handlers.go:387 +0x6645
  github.com/mattermost/mattermost/server/v8/channels/web.(*Handler).ServeHTTP()
      <autogenerated>:1 +0xe7
  github.com/klauspost/compress/gzhttp.NewWrapper.func1.1()
      /go/pkg/mod/github.com/klauspost/[email protected]/gzhttp/compress.go:495 +0x7c6
  net/http.HandlerFunc.ServeHTTP()
      /usr/local/go/src/net/http/server.go:2171 +0x47
  github.com/gorilla/mux.(*Router).ServeHTTP()
      /go/pkg/mod/github.com/gorilla/[email protected]/mux.go:212 +0x371
  net/http.serverHandler.ServeHTTP()
      /usr/local/go/src/net/http/server.go:3142 +0x2a1
  net/http.(*conn).serve()
      /usr/local/go/src/net/http/server.go:2044 +0x13c4
  net/http.(*Server).Serve.gowrap3()
      /usr/local/go/src/net/http/server.go:3290 +0x4f

Goroutine 190 (running) created at:
  net/http.(*Server).Serve()
      /usr/local/go/src/net/http/server.go:3290 +0x8ec
  github.com/mattermost/mattermost/server/v8/channels/app.(*Server).Start.func2()
      /mattermost/server/channels/app/server.go:1070 +0x224
==================
{"timestamp":"2024-10-11 16:15:06.523 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/ldap/sync","request_id":"s1ia1ugni388mmoy1m33u1rbec","user_id":"7isb8osjapy5fxrksd7caewqty","status_code":"200"}
    testing.go:1398: race detected during execution of test
--- FAIL: TestSyncLdap/SystemAdminClient#01 (0.01s)

Check failure on line 1 in com/mattermost/mattermost/server/v8/channels/api4

See this annotation in the file changed.

@github-actions github-actions / MySQL (Results)

com/mattermost/mattermost/server/v8/channels/api4.TestSyncLdap 

Failed
Raw output 
=== RUN   TestSyncLdap
{"timestamp":"2024-10-11 16:15:00.708 Z","level":"debug","msg":"Advanced logging config not provided for notification logging","caller":"platform/config.go:165"}
{"timestamp":"2024-10-11 16:15:00.708 Z","level":"info","msg":"Successfully connected to cache backend","caller":"platform/service.go:191","backend":"lru","result":"OK"}
{"timestamp":"2024-10-11 16:15:00.708 Z","level":"info","msg":"Server is initializing...","caller":"platform/service.go:194","go_version":"go1.22.6"}
{"timestamp":"2024-10-11 16:15:00.727 Z","level":"info","msg":"Starting websocket hubs","caller":"platform/web_hub.go:106","number_of_hubs":4}
{"timestamp":"2024-10-11 16:15:00.729 Z","level":"info","msg":"Loaded system translations","caller":"i18n/i18n.go:175","for locale":"en","from locale":"/mattermost/server/i18n/en.json"}
{"timestamp":"2024-10-11 16:15:00.732 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":1}
{"timestamp":"2024-10-11 16:15:00.732 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":0}
{"timestamp":"2024-10-11 16:15:00.732 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":2}
{"timestamp":"2024-10-11 16:15:00.733 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":3}
{"timestamp":"2024-10-11 16:15:00.740 Z","level":"info","msg":"Ensuring the telemetry ID..","caller":"telemetry/telemetry.go:167"}
{"timestamp":"2024-10-11 16:15:00.741 Z","level":"info","msg":"telemetry ID is set","caller":"telemetry/telemetry.go:177","id":"ijiuqdujhtrutybaz6wfxfrjha"}
{"timestamp":"2024-10-11 16:15:00.761 Z","level":"error","msg":"SiteURL must be set. Some features will operate incorrectly if the SiteURL is not set. See documentation for details: https://mattermost.com/pl/configure-site-url","caller":"app/server.go:390"}
{"timestamp":"2024-10-11 16:15:00.761 Z","level":"info","msg":"Current version is 10.2.0 (///)","caller":"app/server.go:404","current_version":"10.2.0","build_number":"","build_date":"","build_hash":"","build_hash_enterprise":"","service_environment":"dev"}
{"timestamp":"2024-10-11 16:15:00.761 Z","level":"info","msg":"Team Edition Build","caller":"app/server.go:420","enterprise_build":false}
{"timestamp":"2024-10-11 16:15:00.761 Z","level":"info","msg":"Printing current working","caller":"app/server.go:424","directory":"/tmp/testlib3569824158"}
{"timestamp":"2024-10-11 16:15:00.761 Z","level":"info","msg":"Loaded config","caller":"app/server.go:425","source":"memory://"}
{"timestamp":"2024-10-11 16:15:00.995 Z","level":"debug","msg":"initElasticsearchChannelIndexCheck: calling elasticsearchChannelIndexCheckWithRetry before setting up config and license change listeners...","caller":"app/elasticsearch.go:21"}
{"timestamp":"2024-10-11 16:15:00.995 Z","level":"debug","msg":"elasticsearchChannelIndexCheckWithRetry: attempting to check channel index state...","caller":"app/elasticsearch.go:64"}
{"timestamp":"2024-10-11 16:15:00.995 Z","level":"debug","msg":"elasticsearchChannelIndexCheckWithRetry: skipping because elasticsearch indexing is disabled","caller":"app/elasticsearch.go:67"}
{"timestamp":"2024-10-11 16:15:01.033 Z","level":"debug","msg":"Fetching user count for first user account check","caller":"platform/config.go:356"}
{"timestamp":"2024-10-11 16:15:01.035 Z","level":"info","msg":"Post.Message has size restrictions","caller":"sqlstore/post_store.go:2628","max_characters":16383,"max_bytes":65535}
{"timestamp":"2024-10-11 16:15:01.040 Z","level":"debug","msg":"Advanced logging config not provided for notification logging","caller":"platform/config.go:165"}
{"timestamp":"2024-10-11 16:15:01.040 Z","level":"info","msg":"Starting up plugins","caller":"app/plugin.go:191"}
{"timestamp":"2024-10-11 16:15:01.040 Z","level":"debug","msg":"Enabling plugin health check job","caller":"plugin/environment.go:661","interval_s":"30s"}
{"timestamp":"2024-10-11 16:15:01.040 Z","level":"info","msg":"Syncing plugins from the file store","caller":"app/plugin.go:268"}
{"timestamp":"2024-10-11 16:15:01.040 Z","level":"debug","msg":"Plugin health check job starting.","caller":"plugin/health_check.go:31"}
{"timestamp":"2024-10-11 16:15:01.040 Z","level":"info","msg":"No plugins to sync from the file store","caller":"app/plugin.go:311"}
{"timestamp":"2024-10-11 16:15:01.041 Z","level":"debug","msg":"No prepackaged plugins directory found","caller":"app/plugin.go:929"}
{"timestamp":"2024-10-11 16:15:01.041 Z","level":"debug","msg":"Not persisting transitionally prepackaged plugins: none found","caller":"app/plugin.go:1201"}
{"timestamp":"2024-10-11 16:15:01.047 Z","level":"debug","msg":"Able to write files to local storage.","caller":"filestore/localstore.go:82"}
{"timestamp":"2024-10-11 16:15:01.066 Z","level":"info","msg":"Starting Server...","caller":"app/server.go:887"}
{"timestamp":"2024-10-11 16:15:01.067 Z","level":"info","msg":"Server is listening on 127.0.0.1:42103","caller":"app/server.go:963","address":"127.0.0.1:42103"}
{"timestamp":"2024-10-11 16:15:01.067 Z","level":"debug","msg":"No license provided; Remote Cluster services disabled","caller":"app/server.go:580"}
{"timestamp":"2024-10-11 16:15:01.133 Z","level":"debug","msg":"Initializing web routes","caller":"web/web.go:26"}
{"timestamp":"2024-10-11 16:15:01.134 Z","level":"debug","msg":"Skipping update to assets subpath since CI build","caller":"utils/subpath.go:187"}
{"timestamp":"2024-10-11 16:15:01.134 Z","level":"debug","msg":"Using client directory","caller":"web/static.go:34","clientDir":"/tmp/testlib3569824158/client"}
{"timestamp":"2024-10-11 16:15:01.134 Z","level":"debug","msg":"Configured Client4 bool string values","caller":"api4/apitestlib.go:212","true":"TRUE","false":"False"}
{"timestamp":"2024-10-11 16:15:01.138 Z","level":"debug","msg":"Fetching user count for first user account check","caller":"platform/config.go:356"}
{"timestamp":"2024-10-11 16:15:01.970 Z","level":"info","msg":"sending mail","caller":"mail/mail.go:295","to":"i5xn3hyxbp8sjqz3pfzsimbdfa@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-10-11 16:15:01.990 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"3yfe9tqcnpd638954sco1dpg8h","user_id":"","status_code":"201"}
{"timestamp":"2024-10-11 16:15:01.999 Z","level":"debug","msg":"Fetching user count for first user account check","caller":"platform/config.go:356"}
{"timestamp":"2024-10-11 16:15:02.838 Z","level":"info","msg":"sending mail","caller":"mail/mail.go:295","to":"yphytsofbifg5nyynz6zkbsa7h@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-10-11 16:15:02.857 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"fked7cfnijg79nifw55sm8a6hc","user_id":"","status_code":"201"}
{"timestamp":"2024-10-11 16:15:03.695 Z","level":"info","msg":"sending mail","caller":"mail/mail.go:295","to":"sqeubiwbwiyrbrrapnm7qkgjqy@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-10-11 16:15:03.712 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"78bmt8yt77nb3q534dhyuyrunw","user_id":"","status_code":"201"}
{"timestamp":"2024-10-11 16:15:04.552 Z","level":"info","msg":"sending mail","caller":"mail/mail.go:295","to":"hg1c1ogkxjgibczzw7gp4bsory@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-10-11 16:15:04.569 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"87reut68ijfq9csxrzw6d5ntce","user_id":"","status_code":"201"}
{"timestamp":"2024-10-11 16:15:05.426 Z","level":"info","msg":"sending mail","caller":"mail/mail.go:295","to":"fxu5pfs67pnq8xghr1rowr3tdr@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-10-11 16:15:05.452 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"bcc514pxajgx8f9bkc9qeptcrw","user_id":"","status_code":"201"}
{"timestamp":"2024-10-11 16:15:06.463 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users/login","request_id":"rxkeopqe1b8pzgohb1aowbaxte","user_id":"k5d47hmxqprz7kkw4buok7em1y","status_code":"200"}
{"timestamp":"2024-10-11 16:15:06.479 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users/login","request_id":"ic6fx8edi7namjk4wzmerqr1yw","user_id":"7isb8osjapy5fxrksd7caewqty","status_code":"200"}
{"timestamp":"2024-10-11 16:15:06.480 Z","level":"info","msg":"Set license","caller":"platform/license.go:384","id":"","issued_at":"1970-01-01 00:00:00.000 Z","starts_at":"1970-01-01 00:00:00.000 Z","expires_at":"2025-01-09 16:15:06.480 Z","sku_name":"","sku_short_name":"","is_trial":false,"is_gov_sku":false,"customer_id":"some ID","features.users":0,"features":{"advanced_logging":true,"cloud":false,"cluster":true,"compliance":true,"custom_permissions_schemes":true,"data_retention":true,"elastic_search":true,"email_notification_contents":true,"enterprise_plugins":true,"future":true,"google":true,"guest_accounts":true,"guest_accounts_permissions":true,"id_loaded":true,"ldap":true,"ldap_groups":true,"lock_teammate_name_display":true,"message_export":true,"metrics":true,"mfa":true,"mhpns":true,"office365":true,"openid":true,"outgoing_oauth_connections":true,"remote_cluster_service":true,"saml":true,"shared_channels":true}}
{"timestamp":"2024-10-11 16:15:06.517 Z","level":"debug","msg":"Advanced logging config not provided for notification logging","caller":"platform/config.go:165"}
{"timestamp":"2024-10-11 16:15:06.526 Z","level":"debug","msg":"You do not have the appropriate permissions.","caller":"web/context.go:120","path":"/api/v4/ldap/sync","request_id":"tppyg6yysin7fdbu6py4eqquye","ip_addr":"127.0.0.1","user_id":"k5d47hmxqprz7kkw4buok7em1y","method":"POST","err_where":"Permissions","http_code":403,"error":"Permissions: You do not have the appropriate permissions., userId=k5d47hmxqprz7kkw4buok7em1y, permission=create_ldap_sync_job"}
{"timestamp":"2024-10-11 16:15:06.526 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/ldap/sync","request_id":"tppyg6yysin7fdbu6py4eqquye","user_id":"k5d47hmxqprz7kkw4buok7em1y","status_code":"403"}
{"timestamp":"2024-10-11 16:15:06.527 Z","level":"info","msg":"Purging all caches","caller":"platform/cluster_handlers.go:114"}
{"timestamp":"2024-10-11 16:15:06.528 Z","level":"info","msg":"License key has not been uploaded. Loading license key from disk.","caller":"utils/license.go:107","filename":"/tmp/testlib3569824158/config/mattermost.mattermost-license"}
{"timestamp":"2024-10-11 16:15:06.528 Z","level":"warn","msg":"Failed to get license from disk","caller":"platform/license.go:93","error":"We could not find the license key on disk at /tmp/testlib3569824158/config/mattermost.mattermost-license: stat /tmp/testlib3569824158/config/mattermost.mattermost-license: no such file or directory"}
{"timestamp":"2024-10-11 16:15:06.529 Z","level":"warn","msg":"License key from https://mattermost.com required to unlock enterprise features.","caller":"platform/license.go:105","error":"resource \"License\" not found, id: "}
{"timestamp":"2024-10-11 16:15:06.529 Z","level":"info","msg":"Cleared license","caller":"platform/license.go:384","id":"","issued_at":"1970-01-01 00:00:00.000 Z","starts_at":"1970-01-01 00:00:00.000 Z","expires_at":"2025-01-09 16:15:06.480 Z","sku_name":"","sku_short_name":"","is_trial":false,"is_gov_sku":false,"customer_id":"some ID","features.users":0,"features":{"advanced_logging":true,"cloud":false,"cluster":true,"compliance":true,"custom_permissions_schemes":true,"data_retention":true,"elastic_search":true,"email_notification_contents":true,"enterprise_plugins":true,"future":true,"google":true,"guest_accounts":true,"guest_accounts_permissions":true,"id_loaded":true,"ldap":true,"ldap_groups":true,"lock_teammate_name_display":true,"message_export":true,"metrics":true,"mfa":true,"mhpns":true,"office365":true,"openid":true,"outgoing_oauth_connections":true,"remote_cluster_service":true,"saml":true,"shared_channels":true}}
{"timestamp":"2024-10-11 16:15:06.534 Z","level":"info","msg":"Stopping Server...","caller":"app/server.go:669"}
{"timestamp":"2024-10-11 16:15:06.548 Z","level":"info","msg":"Shutting down Email batching service...","caller":"email/service.go:85"}
{"timestamp":"2024-10-11 16:15:06.548 Z","level":"info","msg":"Shutting down plugins","caller":"app/plugin.go:362"}
{"timestamp":"2024-10-11 16:15:06.548 Z","level":"debug","msg":"Disabling plugin health check job","caller":"plugin/environment.go:670"}
{"timestamp":"2024-10-11 16:15:06.548 Z","level":"info","msg":"stopping websocket hub connections","caller":"platform/web_hub.go:127"}
{"timestamp":"2024-10-11 16:15:06.548 Z","level":"info","msg":"Server stopped","caller":"app/server.go:753"}
--- FAIL: TestSyncLdap (6.29s)
View more details on GitHub Actions