[MM-60808] Replace FormattedMarkdownMessage in 'webapp/channels/src/c… #1
GitHub Actions / Postgres (Results)
failed
Oct 11, 2024 in 0s
12973 tests run, 12929 passed, 36 skipped, 8 failed, 2 retried.
Annotations
Check failure on line 1 in com/mattermost/mattermost/server/v8/channels/api4
github-actions / Postgres (Results)
com/mattermost/mattermost/server/v8/channels/api4.TestSyncLdap/SystemAdminClient#01
Failed
Raw output
=== RUN TestSyncLdap/SystemAdminClient#01
{"timestamp":"2024-10-11 13:40:17.783 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/ldap/sync","request_id":"gqfiu1gd67rj5rax4m4sfsu7xo","user_id":"yojjzeits381fqw83bktz4zz3y","status_code":"200"}
==================
WARNING: DATA RACE
Read at 0x00c00649ed50 by goroutine 325723:
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 0x00c00649ed50 by goroutine 325661:
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 325723 (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 325661 (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 13:40:17.786 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/ldap/sync","request_id":"ioa87shgsib13qqz93dyc1ozay","user_id":"yojjzeits381fqw83bktz4zz3y","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
github-actions / Postgres (Results)
com/mattermost/mattermost/server/v8/channels/api4.TestSyncLdap
Failed
Raw output
=== RUN TestSyncLdap
{"timestamp":"2024-10-11 13:40:16.452 Z","level":"debug","msg":"Advanced logging config not provided for notification logging","caller":"platform/config.go:165"}
{"timestamp":"2024-10-11 13:40:16.453 Z","level":"info","msg":"Successfully connected to cache backend","caller":"platform/service.go:191","backend":"redis","result":"PONG"}
{"timestamp":"2024-10-11 13:40:16.453 Z","level":"info","msg":"Server is initializing...","caller":"platform/service.go:194","go_version":"go1.22.6"}
{"timestamp":"2024-10-11 13:40:16.455 Z","level":"info","msg":"Starting websocket hubs","caller":"platform/web_hub.go:106","number_of_hubs":4}
{"timestamp":"2024-10-11 13:40:16.455 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":1}
{"timestamp":"2024-10-11 13:40:16.455 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":2}
{"timestamp":"2024-10-11 13:40:16.455 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":0}
{"timestamp":"2024-10-11 13:40:16.455 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":3}
{"timestamp":"2024-10-11 13:40:16.456 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 13:40:16.463 Z","level":"info","msg":"Ensuring the telemetry ID..","caller":"telemetry/telemetry.go:167"}
{"timestamp":"2024-10-11 13:40:16.464 Z","level":"info","msg":"telemetry ID is set","caller":"telemetry/telemetry.go:177","id":"h5oey6ojjtfhmn4a94s9ogig1c"}
{"timestamp":"2024-10-11 13:40:16.483 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 13:40:16.484 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 13:40:16.484 Z","level":"info","msg":"Team Edition Build","caller":"app/server.go:420","enterprise_build":false}
{"timestamp":"2024-10-11 13:40:16.484 Z","level":"info","msg":"Printing current working","caller":"app/server.go:424","directory":"/tmp/testlib191380950"}
{"timestamp":"2024-10-11 13:40:16.484 Z","level":"info","msg":"Loaded config","caller":"app/server.go:425","source":"memory://"}
{"timestamp":"2024-10-11 13:40:16.727 Z","level":"debug","msg":"initElasticsearchChannelIndexCheck: calling elasticsearchChannelIndexCheckWithRetry before setting up config and license change listeners...","caller":"app/elasticsearch.go:21"}
{"timestamp":"2024-10-11 13:40:16.727 Z","level":"debug","msg":"elasticsearchChannelIndexCheckWithRetry: attempting to check channel index state...","caller":"app/elasticsearch.go:64"}
{"timestamp":"2024-10-11 13:40:16.727 Z","level":"debug","msg":"elasticsearchChannelIndexCheckWithRetry: skipping because elasticsearch indexing is disabled","caller":"app/elasticsearch.go:67"}
{"timestamp":"2024-10-11 13:40:16.759 Z","level":"debug","msg":"Fetching user count for first user account check","caller":"platform/config.go:356"}
{"timestamp":"2024-10-11 13:40:16.765 Z","level":"debug","msg":"Advanced logging config not provided for notification logging","caller":"platform/config.go:165"}
{"timestamp":"2024-10-11 13:40:16.765 Z","level":"info","msg":"Starting up plugins","caller":"app/plugin.go:191"}
{"timestamp":"2024-10-11 13:40:16.766 Z","level":"debug","msg":"Enabling plugin health check job","caller":"plugin/environment.go:661","interval_s":"30s"}
{"timestamp":"2024-10-11 13:40:16.766 Z","level":"info","msg":"Syncing plugins from the file store","caller":"app/plugin.go:268"}
{"timestamp":"2024-10-11 13:40:16.766 Z","level":"debug","msg":"Plugin health check job starting.","caller":"plugin/health_check.go:31"}
{"timestamp":"2024-10-11 13:40:16.766 Z","level":"info","msg":"No plugins to sync from the file store","caller":"app/plugin.go:311"}
{"timestamp":"2024-10-11 13:40:16.766 Z","level":"debug","msg":"No prepackaged plugins directory found","caller":"app/plugin.go:929"}
{"timestamp":"2024-10-11 13:40:16.766 Z","level":"debug","msg":"Not persisting transitionally prepackaged plugins: none found","caller":"app/plugin.go:1201"}
{"timestamp":"2024-10-11 13:40:16.771 Z","level":"debug","msg":"Able to write files to local storage.","caller":"filestore/localstore.go:82"}
{"timestamp":"2024-10-11 13:40:16.789 Z","level":"info","msg":"Starting Server...","caller":"app/server.go:887"}
{"timestamp":"2024-10-11 13:40:16.790 Z","level":"info","msg":"Server is listening on 127.0.0.1:35969","caller":"app/server.go:963","address":"127.0.0.1:35969"}
{"timestamp":"2024-10-11 13:40:16.790 Z","level":"debug","msg":"No license provided; Remote Cluster services disabled","caller":"app/server.go:580"}
{"timestamp":"2024-10-11 13:40:16.846 Z","level":"debug","msg":"Initializing web routes","caller":"web/web.go:26"}
{"timestamp":"2024-10-11 13:40:16.848 Z","level":"debug","msg":"Skipping update to assets subpath since CI build","caller":"utils/subpath.go:187"}
{"timestamp":"2024-10-11 13:40:16.848 Z","level":"debug","msg":"Using client directory","caller":"web/static.go:34","clientDir":"/tmp/testlib191380950/client"}
{"timestamp":"2024-10-11 13:40:16.848 Z","level":"debug","msg":"Configured Client4 bool string values","caller":"api4/apitestlib.go:212","true":"1","false":"False"}
{"timestamp":"2024-10-11 13:40:17.717 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users/login","request_id":"zzg8p6ow43nodgfcrmwdmp6rta","user_id":"yojjzeits381fqw83bktz4zz3y","status_code":"200"}
{"timestamp":"2024-10-11 13:40:17.737 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users/login","request_id":"94x5s8fg8fd9zntzbwh1xm6rfo","user_id":"7gbojwadtiypiyjs4hfeh96zxy","status_code":"200"}
{"timestamp":"2024-10-11 13:40:17.740 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 13:40:17.740 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 13:40:17.771 Z","level":"debug","msg":"Fetching user count for first user account check","caller":"platform/config.go:356"}
{"timestamp":"2024-10-11 13:40:17.777 Z","level":"debug","msg":"Advanced logging config not provided for notification logging","caller":"platform/config.go:165"}
{"timestamp":"2024-10-11 13:40:17.792 Z","level":"debug","msg":"You do not have the appropriate permissions.","caller":"web/context.go:120","path":"/api/v4/ldap/sync","request_id":"mybebps98pn9zb593w4d447mqy","ip_addr":"127.0.0.1","user_id":"7gbojwadtiypiyjs4hfeh96zxy","method":"POST","err_where":"Permissions","http_code":403,"error":"Permissions: You do not have the appropriate permissions., userId=7gbojwadtiypiyjs4hfeh96zxy, permission=create_ldap_sync_job"}
{"timestamp":"2024-10-11 13:40:17.793 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/ldap/sync","request_id":"mybebps98pn9zb593w4d447mqy","user_id":"7gbojwadtiypiyjs4hfeh96zxy","status_code":"403"}
{"timestamp":"2024-10-11 13:40:17.793 Z","level":"info","msg":"Purging all caches","caller":"platform/cluster_handlers.go:114"}
{"timestamp":"2024-10-11 13:40:17.796 Z","level":"info","msg":"License key has not been uploaded. Loading license key from disk.","caller":"utils/license.go:107","filename":"/tmp/testlib191380950/config/mattermost.mattermost-license"}
{"timestamp":"2024-10-11 13:40:17.796 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/testlib191380950/config/mattermost.mattermost-license: stat /tmp/testlib191380950/config/mattermost.mattermost-license: no such file or directory"}
{"timestamp":"2024-10-11 13:40:17.796 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 13:40:17.796 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 13:40:17.740 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 13:40:17.799 Z","level":"info","msg":"Stopping Server...","caller":"app/server.go:669"}
{"timestamp":"2024-10-11 13:40:17.807 Z","level":"info","msg":"Shutting down Email batching service...","caller":"email/service.go:85"}
{"timestamp":"2024-10-11 13:40:17.807 Z","level":"info","msg":"Shutting down plugins","caller":"app/plugin.go:362"}
{"timestamp":"2024-10-11 13:40:17.807 Z","level":"debug","msg":"Disabling plugin health check job","caller":"plugin/environment.go:670"}
{"timestamp":"2024-10-11 13:40:17.807 Z","level":"info","msg":"stopping websocket hub connections","caller":"platform/web_hub.go:127"}
{"timestamp":"2024-10-11 13:40:17.808 Z","level":"info","msg":"Server stopped","caller":"app/server.go:753"}
--- FAIL: TestSyncLdap (1.44s)
Check failure on line 1 in com/mattermost/mattermost/server/v8/channels/api4
github-actions / Postgres (Results)
com/mattermost/mattermost/server/v8/channels/api4.TestSyncLdap/SystemAdminClient#01
Failed
Raw output
=== RUN TestSyncLdap/SystemAdminClient#01
{"timestamp":"2024-10-11 14:34:33.068 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/ldap/sync","request_id":"ymg88j1r73njzmwqd3yzfdoe4y","user_id":"ewy5zjmoobffpegwute9nz5p3r","status_code":"200"}
==================
WARNING: DATA RACE
Read at 0x00c005676d20 by goroutine 1201:
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 0x00c005676d20 by goroutine 1155:
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 1201 (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 1155 (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 14:34:33.071 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/ldap/sync","request_id":"kx6enzhn6f8kxdtm3dcgces6fo","user_id":"ewy5zjmoobffpegwute9nz5p3r","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
github-actions / Postgres (Results)
com/mattermost/mattermost/server/v8/channels/api4.TestSyncLdap
Failed
Raw output
=== RUN TestSyncLdap
{"timestamp":"2024-10-11 14:34:27.196 Z","level":"debug","msg":"Advanced logging config not provided for notification logging","caller":"platform/config.go:165"}
{"timestamp":"2024-10-11 14:34:27.196 Z","level":"info","msg":"Successfully connected to cache backend","caller":"platform/service.go:191","backend":"redis","result":"PONG"}
{"timestamp":"2024-10-11 14:34:27.196 Z","level":"info","msg":"Server is initializing...","caller":"platform/service.go:194","go_version":"go1.22.6"}
{"timestamp":"2024-10-11 14:34:27.199 Z","level":"info","msg":"Starting websocket hubs","caller":"platform/web_hub.go:106","number_of_hubs":4}
{"timestamp":"2024-10-11 14:34:27.199 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":0}
{"timestamp":"2024-10-11 14:34:27.199 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":1}
{"timestamp":"2024-10-11 14:34:27.199 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":2}
{"timestamp":"2024-10-11 14:34:27.200 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":3}
{"timestamp":"2024-10-11 14:34:27.200 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 14:34:27.210 Z","level":"info","msg":"Ensuring the telemetry ID..","caller":"telemetry/telemetry.go:167"}
{"timestamp":"2024-10-11 14:34:27.211 Z","level":"info","msg":"telemetry ID is set","caller":"telemetry/telemetry.go:177","id":"8dfkhiy1i7fd3egkbr9d171nry"}
{"timestamp":"2024-10-11 14:34:27.231 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 14:34:27.231 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 14:34:27.231 Z","level":"info","msg":"Team Edition Build","caller":"app/server.go:420","enterprise_build":false}
{"timestamp":"2024-10-11 14:34:27.231 Z","level":"info","msg":"Printing current working","caller":"app/server.go:424","directory":"/tmp/testlib488397229"}
{"timestamp":"2024-10-11 14:34:27.231 Z","level":"info","msg":"Loaded config","caller":"app/server.go:425","source":"memory://"}
{"timestamp":"2024-10-11 14:34:27.504 Z","level":"debug","msg":"initElasticsearchChannelIndexCheck: calling elasticsearchChannelIndexCheckWithRetry before setting up config and license change listeners...","caller":"app/elasticsearch.go:21"}
{"timestamp":"2024-10-11 14:34:27.505 Z","level":"debug","msg":"elasticsearchChannelIndexCheckWithRetry: attempting to check channel index state...","caller":"app/elasticsearch.go:64"}
{"timestamp":"2024-10-11 14:34:27.505 Z","level":"debug","msg":"elasticsearchChannelIndexCheckWithRetry: skipping because elasticsearch indexing is disabled","caller":"app/elasticsearch.go:67"}
{"timestamp":"2024-10-11 14:34:27.546 Z","level":"debug","msg":"Fetching user count for first user account check","caller":"platform/config.go:356"}
{"timestamp":"2024-10-11 14:34:27.549 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 14:34:27.556 Z","level":"debug","msg":"Advanced logging config not provided for notification logging","caller":"platform/config.go:165"}
{"timestamp":"2024-10-11 14:34:27.556 Z","level":"info","msg":"Starting up plugins","caller":"app/plugin.go:191"}
{"timestamp":"2024-10-11 14:34:27.556 Z","level":"debug","msg":"Enabling plugin health check job","caller":"plugin/environment.go:661","interval_s":"30s"}
{"timestamp":"2024-10-11 14:34:27.556 Z","level":"info","msg":"Syncing plugins from the file store","caller":"app/plugin.go:268"}
{"timestamp":"2024-10-11 14:34:27.557 Z","level":"debug","msg":"Plugin health check job starting.","caller":"plugin/health_check.go:31"}
{"timestamp":"2024-10-11 14:34:27.557 Z","level":"info","msg":"No plugins to sync from the file store","caller":"app/plugin.go:311"}
{"timestamp":"2024-10-11 14:34:27.557 Z","level":"debug","msg":"No prepackaged plugins directory found","caller":"app/plugin.go:929"}
{"timestamp":"2024-10-11 14:34:27.557 Z","level":"debug","msg":"Not persisting transitionally prepackaged plugins: none found","caller":"app/plugin.go:1201"}
{"timestamp":"2024-10-11 14:34:27.562 Z","level":"debug","msg":"Able to write files to local storage.","caller":"filestore/localstore.go:82"}
{"timestamp":"2024-10-11 14:34:27.581 Z","level":"info","msg":"Starting Server...","caller":"app/server.go:887"}
{"timestamp":"2024-10-11 14:34:27.582 Z","level":"info","msg":"Server is listening on 127.0.0.1:39907","caller":"app/server.go:963","address":"127.0.0.1:39907"}
{"timestamp":"2024-10-11 14:34:27.582 Z","level":"debug","msg":"No license provided; Remote Cluster services disabled","caller":"app/server.go:580"}
{"timestamp":"2024-10-11 14:34:27.648 Z","level":"debug","msg":"Initializing web routes","caller":"web/web.go:26"}
{"timestamp":"2024-10-11 14:34:27.649 Z","level":"debug","msg":"Skipping update to assets subpath since CI build","caller":"utils/subpath.go:187"}
{"timestamp":"2024-10-11 14:34:27.649 Z","level":"debug","msg":"Using client directory","caller":"web/static.go:34","clientDir":"/tmp/testlib488397229/client"}
{"timestamp":"2024-10-11 14:34:27.650 Z","level":"debug","msg":"Configured Client4 bool string values","caller":"api4/apitestlib.go:212","true":"T","false":"False"}
{"timestamp":"2024-10-11 14:34:27.652 Z","level":"debug","msg":"Fetching user count for first user account check","caller":"platform/config.go:356"}
{"timestamp":"2024-10-11 14:34:28.509 Z","level":"info","msg":"sending mail","caller":"mail/mail.go:295","to":"y6tzbmenyjdnuk8aaeamu84ssy@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-10-11 14:34:28.527 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"yyknfqwfn7yh9ja6o9ibyxhjhe","user_id":"","status_code":"201"}
{"timestamp":"2024-10-11 14:34:28.542 Z","level":"debug","msg":"Fetching user count for first user account check","caller":"platform/config.go:356"}
{"timestamp":"2024-10-11 14:34:29.371 Z","level":"info","msg":"sending mail","caller":"mail/mail.go:295","to":"s7efh6hxj38qxp5y4k6psx9f1r@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-10-11 14:34:29.395 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"ohko1wwrq3rtijzwbzpexkyb7y","user_id":"","status_code":"201"}
{"timestamp":"2024-10-11 14:34:30.244 Z","level":"info","msg":"sending mail","caller":"mail/mail.go:295","to":"c5b67di8yj8qxnmqicxneoagre@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-10-11 14:34:30.264 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"mwxg4n8ut7r35epoq41j44yswh","user_id":"","status_code":"201"}
{"timestamp":"2024-10-11 14:34:31.136 Z","level":"info","msg":"sending mail","caller":"mail/mail.go:295","to":"1jy67q68kpfgpdhtwg35etknwo@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-10-11 14:34:31.157 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"fww7y7f57jro78jn47a5iszpur","user_id":"","status_code":"201"}
{"timestamp":"2024-10-11 14:34:32.068 Z","level":"info","msg":"sending mail","caller":"mail/mail.go:295","to":"jms8jag9w7r58cdg5fn7fxmdwc@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-10-11 14:34:32.088 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"445eee1amp8ntrgqwftmfwyxie","user_id":"","status_code":"201"}
{"timestamp":"2024-10-11 14:34:32.989 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users/login","request_id":"k1wpm4z7k78tpdtcgydw64cbjo","user_id":"nrqno6cfzpd88ddsiz9qhk34kr","status_code":"200"}
{"timestamp":"2024-10-11 14:34:33.019 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users/login","request_id":"udkdfzkpt7dn3jpxg9hw6aediy","user_id":"ewy5zjmoobffpegwute9nz5p3r","status_code":"200"}
{"timestamp":"2024-10-11 14:34:33.020 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 14:34:33.020 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 14:34:33.064 Z","level":"debug","msg":"Advanced logging config not provided for notification logging","caller":"platform/config.go:165"}
{"timestamp":"2024-10-11 14:34:33.073 Z","level":"debug","msg":"You do not have the appropriate permissions.","caller":"web/context.go:120","path":"/api/v4/ldap/sync","request_id":"ymg1o15arjn89q11qmpgitm33h","ip_addr":"127.0.0.1","user_id":"nrqno6cfzpd88ddsiz9qhk34kr","method":"POST","err_where":"Permissions","http_code":403,"error":"Permissions: You do not have the appropriate permissions., userId=nrqno6cfzpd88ddsiz9qhk34kr, permission=create_ldap_sync_job"}
{"timestamp":"2024-10-11 14:34:33.074 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/ldap/sync","request_id":"ymg1o15arjn89q11qmpgitm33h","user_id":"nrqno6cfzpd88ddsiz9qhk34kr","status_code":"403"}
{"timestamp":"2024-10-11 14:34:33.074 Z","level":"info","msg":"Purging all caches","caller":"platform/cluster_handlers.go:114"}
{"timestamp":"2024-10-11 14:34:33.078 Z","level":"info","msg":"License key has not been uploaded. Loading license key from disk.","caller":"utils/license.go:107","filename":"/tmp/testlib488397229/config/mattermost.mattermost-license"}
{"timestamp":"2024-10-11 14:34:33.078 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/testlib488397229/config/mattermost.mattermost-license: stat /tmp/testlib488397229/config/mattermost.mattermost-license: no such file or directory"}
{"timestamp":"2024-10-11 14:34:33.079 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 14:34:33.079 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 14:34:33.020 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 14:34:33.084 Z","level":"info","msg":"Stopping Server...","caller":"app/server.go:669"}
{"timestamp":"2024-10-11 14:34:33.091 Z","level":"info","msg":"Shutting down Email batching service...","caller":"email/service.go:85"}
{"timestamp":"2024-10-11 14:34:33.091 Z","level":"info","msg":"Shutting down plugins","caller":"app/plugin.go:362"}
{"timestamp":"2024-10-11 14:34:33.091 Z","level":"debug","msg":"Disabling plugin health check job","caller":"plugin/environment.go:670"}
{"timestamp":"2024-10-11 14:34:33.091 Z","level":"info","msg":"stopping websocket hub connections","caller":"platform/web_hub.go:127"}
{"timestamp":"2024-10-11 14:34:33.092 Z","level":"info","msg":"Server stopped","caller":"app/server.go:753"}
--- FAIL: TestSyncLdap (5.98s)
Check failure on line 1 in com/mattermost/mattermost/server/v8/channels/api4
github-actions / Postgres (Results)
com/mattermost/mattermost/server/v8/channels/api4.TestSyncLdap/SystemAdminClient#01
Failed
Raw output
=== RUN TestSyncLdap/SystemAdminClient#01
{"timestamp":"2024-10-11 14:34:44.428 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/ldap/sync","request_id":"6mnoacbiiibpzjems8b574pqtr","user_id":"5ssykptbwiy8uny44a86kmbrse","status_code":"200"}
==================
WARNING: DATA RACE
Read at 0x00c0010e35c0 by goroutine 1203:
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 0x00c0010e35c0 by goroutine 1155:
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 1203 (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 1155 (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 14:34:44.432 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/ldap/sync","request_id":"p71bdd876jyh3bu5omhufeapiy","user_id":"5ssykptbwiy8uny44a86kmbrse","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
github-actions / Postgres (Results)
com/mattermost/mattermost/server/v8/channels/api4.TestSyncLdap
Failed
Raw output
=== RUN TestSyncLdap
{"timestamp":"2024-10-11 14:34:38.537 Z","level":"debug","msg":"Advanced logging config not provided for notification logging","caller":"platform/config.go:165"}
{"timestamp":"2024-10-11 14:34:38.537 Z","level":"info","msg":"Successfully connected to cache backend","caller":"platform/service.go:191","backend":"redis","result":"PONG"}
{"timestamp":"2024-10-11 14:34:38.537 Z","level":"info","msg":"Server is initializing...","caller":"platform/service.go:194","go_version":"go1.22.6"}
{"timestamp":"2024-10-11 14:34:38.540 Z","level":"info","msg":"Starting websocket hubs","caller":"platform/web_hub.go:106","number_of_hubs":4}
{"timestamp":"2024-10-11 14:34:38.540 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":0}
{"timestamp":"2024-10-11 14:34:38.540 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":1}
{"timestamp":"2024-10-11 14:34:38.540 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":2}
{"timestamp":"2024-10-11 14:34:38.540 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":3}
{"timestamp":"2024-10-11 14:34:38.541 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 14:34:38.548 Z","level":"info","msg":"Ensuring the telemetry ID..","caller":"telemetry/telemetry.go:167"}
{"timestamp":"2024-10-11 14:34:38.550 Z","level":"info","msg":"telemetry ID is set","caller":"telemetry/telemetry.go:177","id":"67m5mkw6ttd5tchrozz5tndipa"}
{"timestamp":"2024-10-11 14:34:38.569 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 14:34:38.569 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 14:34:38.569 Z","level":"info","msg":"Team Edition Build","caller":"app/server.go:420","enterprise_build":false}
{"timestamp":"2024-10-11 14:34:38.569 Z","level":"info","msg":"Printing current working","caller":"app/server.go:424","directory":"/tmp/testlib3746427010"}
{"timestamp":"2024-10-11 14:34:38.569 Z","level":"info","msg":"Loaded config","caller":"app/server.go:425","source":"memory://"}
{"timestamp":"2024-10-11 14:34:38.826 Z","level":"debug","msg":"initElasticsearchChannelIndexCheck: calling elasticsearchChannelIndexCheckWithRetry before setting up config and license change listeners...","caller":"app/elasticsearch.go:21"}
{"timestamp":"2024-10-11 14:34:38.826 Z","level":"debug","msg":"elasticsearchChannelIndexCheckWithRetry: attempting to check channel index state...","caller":"app/elasticsearch.go:64"}
{"timestamp":"2024-10-11 14:34:38.826 Z","level":"debug","msg":"elasticsearchChannelIndexCheckWithRetry: skipping because elasticsearch indexing is disabled","caller":"app/elasticsearch.go:67"}
{"timestamp":"2024-10-11 14:34:38.860 Z","level":"debug","msg":"Fetching user count for first user account check","caller":"platform/config.go:356"}
{"timestamp":"2024-10-11 14:34:38.863 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 14:34:38.868 Z","level":"debug","msg":"Advanced logging config not provided for notification logging","caller":"platform/config.go:165"}
{"timestamp":"2024-10-11 14:34:38.869 Z","level":"info","msg":"Starting up plugins","caller":"app/plugin.go:191"}
{"timestamp":"2024-10-11 14:34:38.869 Z","level":"debug","msg":"Enabling plugin health check job","caller":"plugin/environment.go:661","interval_s":"30s"}
{"timestamp":"2024-10-11 14:34:38.869 Z","level":"info","msg":"Syncing plugins from the file store","caller":"app/plugin.go:268"}
{"timestamp":"2024-10-11 14:34:38.869 Z","level":"info","msg":"No plugins to sync from the file store","caller":"app/plugin.go:311"}
{"timestamp":"2024-10-11 14:34:38.869 Z","level":"debug","msg":"Plugin health check job starting.","caller":"plugin/health_check.go:31"}
{"timestamp":"2024-10-11 14:34:38.870 Z","level":"debug","msg":"No prepackaged plugins directory found","caller":"app/plugin.go:929"}
{"timestamp":"2024-10-11 14:34:38.870 Z","level":"debug","msg":"Not persisting transitionally prepackaged plugins: none found","caller":"app/plugin.go:1201"}
{"timestamp":"2024-10-11 14:34:38.874 Z","level":"debug","msg":"Able to write files to local storage.","caller":"filestore/localstore.go:82"}
{"timestamp":"2024-10-11 14:34:38.893 Z","level":"info","msg":"Starting Server...","caller":"app/server.go:887"}
{"timestamp":"2024-10-11 14:34:38.894 Z","level":"info","msg":"Server is listening on 127.0.0.1:42799","caller":"app/server.go:963","address":"127.0.0.1:42799"}
{"timestamp":"2024-10-11 14:34:38.894 Z","level":"debug","msg":"No license provided; Remote Cluster services disabled","caller":"app/server.go:580"}
{"timestamp":"2024-10-11 14:34:38.959 Z","level":"debug","msg":"Initializing web routes","caller":"web/web.go:26"}
{"timestamp":"2024-10-11 14:34:38.961 Z","level":"debug","msg":"Skipping update to assets subpath since CI build","caller":"utils/subpath.go:187"}
{"timestamp":"2024-10-11 14:34:38.961 Z","level":"debug","msg":"Using client directory","caller":"web/static.go:34","clientDir":"/tmp/testlib3746427010/client"}
{"timestamp":"2024-10-11 14:34:38.961 Z","level":"debug","msg":"Configured Client4 bool string values","caller":"api4/apitestlib.go:212","true":"true","false":"false"}
{"timestamp":"2024-10-11 14:34:38.964 Z","level":"debug","msg":"Fetching user count for first user account check","caller":"platform/config.go:356"}
{"timestamp":"2024-10-11 14:34:39.793 Z","level":"info","msg":"sending mail","caller":"mail/mail.go:295","to":"bxn9d4ze4jg5tpn58p9p1wf7we@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-10-11 14:34:39.826 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"j8o8144577b68qywxugzxk7q3y","user_id":"","status_code":"201"}
{"timestamp":"2024-10-11 14:34:39.840 Z","level":"debug","msg":"Fetching user count for first user account check","caller":"platform/config.go:356"}
{"timestamp":"2024-10-11 14:34:40.664 Z","level":"info","msg":"sending mail","caller":"mail/mail.go:295","to":"ck7ia3qggpfr7qo4icxb97oqze@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-10-11 14:34:40.681 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"3mmng4436tfai8pqmwmgr44afw","user_id":"","status_code":"201"}
{"timestamp":"2024-10-11 14:34:41.532 Z","level":"info","msg":"sending mail","caller":"mail/mail.go:295","to":"f66egixbif8pfmcy7byg964xue@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-10-11 14:34:41.554 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"nd7oo78a7tbr3p7uytth9xpeca","user_id":"","status_code":"201"}
{"timestamp":"2024-10-11 14:34:42.430 Z","level":"info","msg":"sending mail","caller":"mail/mail.go:295","to":"pm9jnn65efgx9y4oi5gkir699e@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-10-11 14:34:42.451 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"p8dqodmyxbd6bjst5cn9ifgcqo","user_id":"","status_code":"201"}
{"timestamp":"2024-10-11 14:34:43.326 Z","level":"info","msg":"sending mail","caller":"mail/mail.go:295","to":"5shhedm48fr8ugpks1im7g6suo@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-10-11 14:34:43.351 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"u5grymeh6tgzmg67b6ncqiursw","user_id":"","status_code":"201"}
{"timestamp":"2024-10-11 14:34:44.255 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users/login","request_id":"1axuez8ahf8fzkhgq14q988ndy","user_id":"5ssykptbwiy8uny44a86kmbrse","status_code":"200"}
{"timestamp":"2024-10-11 14:34:44.381 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users/login","request_id":"5jhbuhkq1tn1bg1uxnuwwf4o6c","user_id":"ydaytuxzxjg8ib3mo5q5d4zb4a","status_code":"200"}
{"timestamp":"2024-10-11 14:34:44.382 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 14:34:44.382 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 14:34:44.425 Z","level":"debug","msg":"Advanced logging config not provided for notification logging","caller":"platform/config.go:165"}
{"timestamp":"2024-10-11 14:34:44.435 Z","level":"debug","msg":"You do not have the appropriate permissions.","caller":"web/context.go:120","path":"/api/v4/ldap/sync","request_id":"qy4956rj77ygfdk11dxebodumo","ip_addr":"127.0.0.1","user_id":"ydaytuxzxjg8ib3mo5q5d4zb4a","method":"POST","err_where":"Permissions","http_code":403,"error":"Permissions: You do not have the appropriate permissions., userId=ydaytuxzxjg8ib3mo5q5d4zb4a, permission=create_ldap_sync_job"}
{"timestamp":"2024-10-11 14:34:44.435 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/ldap/sync","request_id":"qy4956rj77ygfdk11dxebodumo","user_id":"ydaytuxzxjg8ib3mo5q5d4zb4a","status_code":"403"}
{"timestamp":"2024-10-11 14:34:44.435 Z","level":"info","msg":"Purging all caches","caller":"platform/cluster_handlers.go:114"}
{"timestamp":"2024-10-11 14:34:44.438 Z","level":"info","msg":"License key has not been uploaded. Loading license key from disk.","caller":"utils/license.go:107","filename":"/tmp/testlib3746427010/config/mattermost.mattermost-license"}
{"timestamp":"2024-10-11 14:34:44.438 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/testlib3746427010/config/mattermost.mattermost-license: stat /tmp/testlib3746427010/config/mattermost.mattermost-license: no such file or directory"}
{"timestamp":"2024-10-11 14:34:44.438 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 14:34:44.439 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 14:34:44.382 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 14:34:44.442 Z","level":"info","msg":"Stopping Server...","caller":"app/server.go:669"}
{"timestamp":"2024-10-11 14:34:44.459 Z","level":"info","msg":"Shutting down Email batching service...","caller":"email/service.go:85"}
{"timestamp":"2024-10-11 14:34:44.459 Z","level":"info","msg":"Shutting down plugins","caller":"app/plugin.go:362"}
{"timestamp":"2024-10-11 14:34:44.459 Z","level":"debug","msg":"Disabling plugin health check job","caller":"plugin/environment.go:670"}
{"timestamp":"2024-10-11 14:34:44.459 Z","level":"info","msg":"stopping websocket hub connections","caller":"platform/web_hub.go:127"}
{"timestamp":"2024-10-11 14:34:44.460 Z","level":"info","msg":"Server stopped","caller":"app/server.go:753"}
--- FAIL: TestSyncLdap (6.00s)
Check failure on line 1 in com/mattermost/mattermost/server/v8/channels/api4
github-actions / Postgres (Results)
com/mattermost/mattermost/server/v8/channels/api4.TestSyncLdap/SystemAdminClient#01
Failed
Raw output
=== RUN TestSyncLdap/SystemAdminClient#01
{"timestamp":"2024-10-11 14:34:55.993 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/ldap/sync","request_id":"ka3c7jzsbjr3xfn7hx6iy6ucxw","user_id":"menwtqbogprzdq3cwx8mn5qpsh","status_code":"200"}
==================
WARNING: DATA RACE
Read at 0x00c003271a10 by goroutine 1201:
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 0x00c003271a10 by goroutine 989:
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 1201 (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 989 (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 14:34:55.996 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/ldap/sync","request_id":"3caku6x6qirbzcbe1bdiodyr4e","user_id":"menwtqbogprzdq3cwx8mn5qpsh","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
github-actions / Postgres (Results)
com/mattermost/mattermost/server/v8/channels/api4.TestSyncLdap
Failed
Raw output
=== RUN TestSyncLdap
{"timestamp":"2024-10-11 14:34:50.220 Z","level":"debug","msg":"Advanced logging config not provided for notification logging","caller":"platform/config.go:165"}
{"timestamp":"2024-10-11 14:34:50.220 Z","level":"info","msg":"Successfully connected to cache backend","caller":"platform/service.go:191","backend":"redis","result":"PONG"}
{"timestamp":"2024-10-11 14:34:50.220 Z","level":"info","msg":"Server is initializing...","caller":"platform/service.go:194","go_version":"go1.22.6"}
{"timestamp":"2024-10-11 14:34:50.231 Z","level":"info","msg":"Starting websocket hubs","caller":"platform/web_hub.go:106","number_of_hubs":4}
{"timestamp":"2024-10-11 14:34:50.231 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":1}
{"timestamp":"2024-10-11 14:34:50.231 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":0}
{"timestamp":"2024-10-11 14:34:50.232 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":3}
{"timestamp":"2024-10-11 14:34:50.232 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":2}
{"timestamp":"2024-10-11 14:34:50.233 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 14:34:50.241 Z","level":"info","msg":"Ensuring the telemetry ID..","caller":"telemetry/telemetry.go:167"}
{"timestamp":"2024-10-11 14:34:50.243 Z","level":"info","msg":"telemetry ID is set","caller":"telemetry/telemetry.go:177","id":"etdduy1i7pywuf45znu5z93osa"}
{"timestamp":"2024-10-11 14:34:50.262 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 14:34:50.262 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 14:34:50.262 Z","level":"info","msg":"Team Edition Build","caller":"app/server.go:420","enterprise_build":false}
{"timestamp":"2024-10-11 14:34:50.262 Z","level":"info","msg":"Printing current working","caller":"app/server.go:424","directory":"/tmp/testlib1031708945"}
{"timestamp":"2024-10-11 14:34:50.262 Z","level":"info","msg":"Loaded config","caller":"app/server.go:425","source":"memory://"}
{"timestamp":"2024-10-11 14:34:50.525 Z","level":"debug","msg":"initElasticsearchChannelIndexCheck: calling elasticsearchChannelIndexCheckWithRetry before setting up config and license change listeners...","caller":"app/elasticsearch.go:21"}
{"timestamp":"2024-10-11 14:34:50.525 Z","level":"debug","msg":"elasticsearchChannelIndexCheckWithRetry: attempting to check channel index state...","caller":"app/elasticsearch.go:64"}
{"timestamp":"2024-10-11 14:34:50.525 Z","level":"debug","msg":"elasticsearchChannelIndexCheckWithRetry: skipping because elasticsearch indexing is disabled","caller":"app/elasticsearch.go:67"}
{"timestamp":"2024-10-11 14:34:50.559 Z","level":"debug","msg":"Fetching user count for first user account check","caller":"platform/config.go:356"}
{"timestamp":"2024-10-11 14:34:50.562 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 14:34:50.568 Z","level":"debug","msg":"Advanced logging config not provided for notification logging","caller":"platform/config.go:165"}
{"timestamp":"2024-10-11 14:34:50.569 Z","level":"info","msg":"Starting up plugins","caller":"app/plugin.go:191"}
{"timestamp":"2024-10-11 14:34:50.569 Z","level":"debug","msg":"Enabling plugin health check job","caller":"plugin/environment.go:661","interval_s":"30s"}
{"timestamp":"2024-10-11 14:34:50.569 Z","level":"info","msg":"Syncing plugins from the file store","caller":"app/plugin.go:268"}
{"timestamp":"2024-10-11 14:34:50.569 Z","level":"debug","msg":"Plugin health check job starting.","caller":"plugin/health_check.go:31"}
{"timestamp":"2024-10-11 14:34:50.569 Z","level":"info","msg":"No plugins to sync from the file store","caller":"app/plugin.go:311"}
{"timestamp":"2024-10-11 14:34:50.569 Z","level":"debug","msg":"No prepackaged plugins directory found","caller":"app/plugin.go:929"}
{"timestamp":"2024-10-11 14:34:50.569 Z","level":"debug","msg":"Not persisting transitionally prepackaged plugins: none found","caller":"app/plugin.go:1201"}
{"timestamp":"2024-10-11 14:34:50.575 Z","level":"debug","msg":"Able to write files to local storage.","caller":"filestore/localstore.go:82"}
{"timestamp":"2024-10-11 14:34:50.594 Z","level":"info","msg":"Starting Server...","caller":"app/server.go:887"}
{"timestamp":"2024-10-11 14:34:50.594 Z","level":"info","msg":"Server is listening on 127.0.0.1:46833","caller":"app/server.go:963","address":"127.0.0.1:46833"}
{"timestamp":"2024-10-11 14:34:50.594 Z","level":"debug","msg":"No license provided; Remote Cluster services disabled","caller":"app/server.go:580"}
{"timestamp":"2024-10-11 14:34:50.654 Z","level":"debug","msg":"Initializing web routes","caller":"web/web.go:26"}
{"timestamp":"2024-10-11 14:34:50.655 Z","level":"debug","msg":"Skipping update to assets subpath since CI build","caller":"utils/subpath.go:187"}
{"timestamp":"2024-10-11 14:34:50.656 Z","level":"debug","msg":"Using client directory","caller":"web/static.go:34","clientDir":"/tmp/testlib1031708945/client"}
{"timestamp":"2024-10-11 14:34:50.656 Z","level":"debug","msg":"Configured Client4 bool string values","caller":"api4/apitestlib.go:212","true":"t","false":"false"}
{"timestamp":"2024-10-11 14:34:50.659 Z","level":"debug","msg":"Fetching user count for first user account check","caller":"platform/config.go:356"}
{"timestamp":"2024-10-11 14:34:51.510 Z","level":"info","msg":"sending mail","caller":"mail/mail.go:295","to":"88qtc64iji838jh1gbkm1qwixw@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-10-11 14:34:51.531 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"53dnui7mkbbhi83c1xz5pm1rpy","user_id":"","status_code":"201"}
{"timestamp":"2024-10-11 14:34:51.545 Z","level":"debug","msg":"Fetching user count for first user account check","caller":"platform/config.go:356"}
{"timestamp":"2024-10-11 14:34:52.382 Z","level":"info","msg":"sending mail","caller":"mail/mail.go:295","to":"dnzqn7jnk7dufxz843ym164fto@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-10-11 14:34:52.414 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"aiwpxmwdrif89r71wskt9im3yw","user_id":"","status_code":"201"}
{"timestamp":"2024-10-11 14:34:53.271 Z","level":"info","msg":"sending mail","caller":"mail/mail.go:295","to":"j9pjsndurjdmfcrkk4cdpcegyc@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-10-11 14:34:53.288 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"9a84oftwcf8n9jbd56zt9ghaiw","user_id":"","status_code":"201"}
{"timestamp":"2024-10-11 14:34:54.122 Z","level":"info","msg":"sending mail","caller":"mail/mail.go:295","to":"amu3m89oi7fm3br5o1j8hfcozw@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-10-11 14:34:54.139 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"ahsiuibgc7badetidosqwm5wnc","user_id":"","status_code":"201"}
{"timestamp":"2024-10-11 14:34:54.968 Z","level":"info","msg":"sending mail","caller":"mail/mail.go:295","to":"wgq1xzeg3prdmkkerepjap8s3o@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-10-11 14:34:54.987 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"yw8jjf4rubdapg9ouxtxi3aqsr","user_id":"","status_code":"201"}
{"timestamp":"2024-10-11 14:34:55.947 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users/login","request_id":"sykabh8mdjnyfyccmrswxxdisc","user_id":"menwtqbogprzdq3cwx8mn5qpsh","status_code":"200"}
{"timestamp":"2024-10-11 14:34:55.948 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users/login","request_id":"k98e157e4fnf3q6hj8kbx3g6je","user_id":"8rf6pound3fspyocthxhfypijc","status_code":"200"}
{"timestamp":"2024-10-11 14:34:55.949 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 14:34:55.949 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 14:34:55.990 Z","level":"debug","msg":"Advanced logging config not provided for notification logging","caller":"platform/config.go:165"}
{"timestamp":"2024-10-11 14:34:56.001 Z","level":"debug","msg":"You do not have the appropriate permissions.","caller":"web/context.go:120","path":"/api/v4/ldap/sync","request_id":"spukbx59f3ftbnwwe5do6z96xw","ip_addr":"127.0.0.1","user_id":"8rf6pound3fspyocthxhfypijc","method":"POST","err_where":"Permissions","http_code":403,"error":"Permissions: You do not have the appropriate permissions., userId=8rf6pound3fspyocthxhfypijc, permission=create_ldap_sync_job"}
{"timestamp":"2024-10-11 14:34:56.001 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/ldap/sync","request_id":"spukbx59f3ftbnwwe5do6z96xw","user_id":"8rf6pound3fspyocthxhfypijc","status_code":"403"}
{"timestamp":"2024-10-11 14:34:56.001 Z","level":"info","msg":"Purging all caches","caller":"platform/cluster_handlers.go:114"}
{"timestamp":"2024-10-11 14:34:56.004 Z","level":"info","msg":"License key has not been uploaded. Loading license key from disk.","caller":"utils/license.go:107","filename":"/tmp/testlib1031708945/config/mattermost.mattermost-license"}
{"timestamp":"2024-10-11 14:34:56.004 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/testlib1031708945/config/mattermost.mattermost-license: stat /tmp/testlib1031708945/config/mattermost.mattermost-license: no such file or directory"}
{"timestamp":"2024-10-11 14:34:56.005 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 14:34:56.005 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 14:34:55.949 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 14:34:56.008 Z","level":"info","msg":"Stopping Server...","caller":"app/server.go:669"}
{"timestamp":"2024-10-11 14:34:56.010 Z","level":"info","msg":"Shutting down Email batching service...","caller":"email/service.go:85"}
{"timestamp":"2024-10-11 14:34:56.010 Z","level":"info","msg":"Shutting down plugins","caller":"app/plugin.go:362"}
{"timestamp":"2024-10-11 14:34:56.010 Z","level":"debug","msg":"Disabling plugin health check job","caller":"plugin/environment.go:670"}
{"timestamp":"2024-10-11 14:34:56.011 Z","level":"info","msg":"stopping websocket hub connections","caller":"platform/web_hub.go:127"}
{"timestamp":"2024-10-11 14:34:56.012 Z","level":"info","msg":"Server stopped","caller":"app/server.go:753"}
--- FAIL: TestSyncLdap (5.87s)
Loading