MM-59950 - add deletedAt column to users export (#28137) #175
GitHub Actions / MySQL (Results)
failed
Sep 23, 2024 in 0s
12890 tests run, 12822 passed, 60 skipped, 8 failed, 2 retried.
Annotations
Check failure on line 1 in com/mattermost/mattermost/server/v8/channels/api4
github-actions / MySQL (Results)
com/mattermost/mattermost/server/v8/channels/api4.TestSyncLdap/SystemAdminClient#01
Failed
Raw output
=== RUN TestSyncLdap/SystemAdminClient#01
{"timestamp":"2024-09-23 13:58:14.551 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/ldap/sync","request_id":"bw5x5pfwopr7x8oeuakjdwjmbr","user_id":"93u8r3j8sfdoindbx84ffqhsfe","status_code":"200"}
==================
WARNING: DATA RACE
Read at 0x00c00f59a8a0 by goroutine 141077:
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 0x00c00f59a8a0 by goroutine 141044:
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:22 +0xd1
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 141077 (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:805 +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 141044 (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:1069 +0x224
==================
{"timestamp":"2024-09-23 13:58:14.554 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/ldap/sync","request_id":"txiz3nq14pgztk3q8kriqwwmkw","user_id":"93u8r3j8sfdoindbx84ffqhsfe","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 / MySQL (Results)
com/mattermost/mattermost/server/v8/channels/api4.TestSyncLdap
Failed
Raw output
=== RUN TestSyncLdap
{"timestamp":"2024-09-23 13:58:13.238 Z","level":"debug","msg":"Advanced logging config not provided for notification logging","caller":"platform/config.go:165"}
{"timestamp":"2024-09-23 13:58:13.238 Z","level":"info","msg":"Successfully connected to cache backend","caller":"platform/service.go:191","backend":"lru","result":"OK"}
{"timestamp":"2024-09-23 13:58:13.238 Z","level":"info","msg":"Server is initializing...","caller":"platform/service.go:194","go_version":"go1.22.6"}
{"timestamp":"2024-09-23 13:58:13.242 Z","level":"info","msg":"Starting websocket hubs","caller":"platform/web_hub.go:106","number_of_hubs":4}
{"timestamp":"2024-09-23 13:58:13.242 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":0}
{"timestamp":"2024-09-23 13:58:13.242 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":1}
{"timestamp":"2024-09-23 13:58:13.242 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":3}
{"timestamp":"2024-09-23 13:58:13.242 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":2}
{"timestamp":"2024-09-23 13:58:13.243 Z","level":"info","msg":"Loaded system translations","caller":"i18n/i18n.go:175","for locale":"en","from locale":"/mattermost/server/i18n/en.json"}
{"timestamp":"2024-09-23 13:58:13.251 Z","level":"info","msg":"Ensuring the telemetry ID..","caller":"telemetry/telemetry.go:147"}
{"timestamp":"2024-09-23 13:58:13.252 Z","level":"info","msg":"telemetry ID is set","caller":"telemetry/telemetry.go:157","id":"fgfxkq7xc7847gjskztut9rwuo"}
{"timestamp":"2024-09-23 13:58:13.271 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:389"}
{"timestamp":"2024-09-23 13:58:13.271 Z","level":"info","msg":"Current version is 10.1.0 (///)","caller":"app/server.go:403","current_version":"10.1.0","build_number":"","build_date":"","build_hash":"","build_hash_enterprise":"","service_environment":"dev"}
{"timestamp":"2024-09-23 13:58:13.271 Z","level":"info","msg":"Team Edition Build","caller":"app/server.go:419","enterprise_build":false}
{"timestamp":"2024-09-23 13:58:13.272 Z","level":"info","msg":"Printing current working","caller":"app/server.go:423","directory":"/tmp/testlib699364284"}
{"timestamp":"2024-09-23 13:58:13.272 Z","level":"info","msg":"Loaded config","caller":"app/server.go:424","source":"memory://"}
{"timestamp":"2024-09-23 13:58:13.482 Z","level":"debug","msg":"initElasticsearchChannelIndexCheck: calling elasticsearchChannelIndexCheckWithRetry before setting up config and license change listeners...","caller":"app/elasticsearch.go:21"}
{"timestamp":"2024-09-23 13:58:13.482 Z","level":"debug","msg":"elasticsearchChannelIndexCheckWithRetry: attempting to check channel index state...","caller":"app/elasticsearch.go:64"}
{"timestamp":"2024-09-23 13:58:13.482 Z","level":"debug","msg":"elasticsearchChannelIndexCheckWithRetry: skipping because elasticsearch indexing is disabled","caller":"app/elasticsearch.go:67"}
{"timestamp":"2024-09-23 13:58:13.515 Z","level":"debug","msg":"Fetching user count for first user account check","caller":"platform/config.go:356"}
{"timestamp":"2024-09-23 13:58:13.521 Z","level":"debug","msg":"Advanced logging config not provided for notification logging","caller":"platform/config.go:165"}
{"timestamp":"2024-09-23 13:58:13.521 Z","level":"info","msg":"Starting up plugins","caller":"app/plugin.go:191"}
{"timestamp":"2024-09-23 13:58:13.521 Z","level":"debug","msg":"Enabling plugin health check job","caller":"plugin/environment.go:661","interval_s":"30s"}
{"timestamp":"2024-09-23 13:58:13.521 Z","level":"info","msg":"Syncing plugins from the file store","caller":"app/plugin.go:268"}
{"timestamp":"2024-09-23 13:58:13.521 Z","level":"debug","msg":"Plugin health check job starting.","caller":"plugin/health_check.go:31"}
{"timestamp":"2024-09-23 13:58:13.521 Z","level":"info","msg":"No plugins to sync from the file store","caller":"app/plugin.go:311"}
{"timestamp":"2024-09-23 13:58:13.521 Z","level":"debug","msg":"No prepackaged plugins directory found","caller":"app/plugin.go:912"}
{"timestamp":"2024-09-23 13:58:13.521 Z","level":"debug","msg":"Not persisting transitionally prepackaged plugins: none found","caller":"app/plugin.go:1184"}
{"timestamp":"2024-09-23 13:58:13.527 Z","level":"debug","msg":"Able to write files to local storage.","caller":"filestore/localstore.go:82"}
{"timestamp":"2024-09-23 13:58:13.545 Z","level":"info","msg":"Starting Server...","caller":"app/server.go:886"}
{"timestamp":"2024-09-23 13:58:13.545 Z","level":"info","msg":"Server is listening on 127.0.0.1:43033","caller":"app/server.go:962","address":"127.0.0.1:43033"}
{"timestamp":"2024-09-23 13:58:13.545 Z","level":"debug","msg":"No license provided; Remote Cluster services disabled","caller":"app/server.go:579"}
{"timestamp":"2024-09-23 13:58:13.601 Z","level":"debug","msg":"Initializing web routes","caller":"web/web.go:26"}
{"timestamp":"2024-09-23 13:58:13.603 Z","level":"debug","msg":"Skipping update to assets subpath since CI build","caller":"utils/subpath.go:187"}
{"timestamp":"2024-09-23 13:58:13.603 Z","level":"debug","msg":"Using client directory","caller":"web/static.go:34","clientDir":"/tmp/testlib699364284/client"}
{"timestamp":"2024-09-23 13:58:13.603 Z","level":"debug","msg":"Configured Client4 bool string values","caller":"api4/apitestlib.go:212","true":"true","false":"F"}
{"timestamp":"2024-09-23 13:58:14.481 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users/login","request_id":"hjjf4w7utt8r8fz7ec6ijt1foo","user_id":"mbjuhfd14fg75jnczymwbzi8by","status_code":"200"}
{"timestamp":"2024-09-23 13:58:14.506 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users/login","request_id":"6rju9zxdyb8ebfz673gxs6tzpo","user_id":"93u8r3j8sfdoindbx84ffqhsfe","status_code":"200"}
{"timestamp":"2024-09-23 13:58:14.541 Z","level":"debug","msg":"Fetching user count for first user account check","caller":"platform/config.go:356"}
{"timestamp":"2024-09-23 13:58:14.546 Z","level":"debug","msg":"Advanced logging config not provided for notification logging","caller":"platform/config.go:165"}
{"timestamp":"2024-09-23 13:58:14.558 Z","level":"debug","msg":"You do not have the appropriate permissions.","caller":"web/context.go:120","path":"/api/v4/ldap/sync","request_id":"1xfhr4qkqjdq9kck5wbdgiubpc","ip_addr":"127.0.0.1","user_id":"mbjuhfd14fg75jnczymwbzi8by","method":"POST","err_where":"Permissions","http_code":403,"error":"Permissions: You do not have the appropriate permissions., userId=mbjuhfd14fg75jnczymwbzi8by, permission=create_ldap_sync_job"}
{"timestamp":"2024-09-23 13:58:14.558 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/ldap/sync","request_id":"1xfhr4qkqjdq9kck5wbdgiubpc","user_id":"mbjuhfd14fg75jnczymwbzi8by","status_code":"403"}
{"timestamp":"2024-09-23 13:58:14.559 Z","level":"info","msg":"Purging all caches","caller":"platform/cluster_handlers.go:114"}
{"timestamp":"2024-09-23 13:58:14.560 Z","level":"info","msg":"License key has not been uploaded. Loading license key from disk.","caller":"utils/license.go:107","filename":"/tmp/testlib699364284/config/mattermost.mattermost-license"}
{"timestamp":"2024-09-23 13:58:14.560 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/testlib699364284/config/mattermost.mattermost-license: stat /tmp/testlib699364284/config/mattermost.mattermost-license: no such file or directory"}
{"timestamp":"2024-09-23 13:58:14.561 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-09-23 13:58:14.563 Z","level":"info","msg":"Stopping Server...","caller":"app/server.go:668"}
{"timestamp":"2024-09-23 13:58:14.566 Z","level":"info","msg":"Shutting down Email batching service...","caller":"email/service.go:85"}
{"timestamp":"2024-09-23 13:58:14.566 Z","level":"info","msg":"Shutting down plugins","caller":"app/plugin.go:362"}
{"timestamp":"2024-09-23 13:58:14.566 Z","level":"debug","msg":"Disabling plugin health check job","caller":"plugin/environment.go:670"}
{"timestamp":"2024-09-23 13:58:14.566 Z","level":"info","msg":"stopping websocket hub connections","caller":"platform/web_hub.go:127"}
{"timestamp":"2024-09-23 13:58:14.566 Z","level":"info","msg":"Server stopped","caller":"app/server.go:752"}
--- FAIL: TestSyncLdap (1.71s)
Check failure on line 1 in com/mattermost/mattermost/server/v8/channels/api4
github-actions / MySQL (Results)
com/mattermost/mattermost/server/v8/channels/api4.TestSyncLdap/SystemAdminClient#01
Failed
Raw output
=== RUN TestSyncLdap/SystemAdminClient#01
{"timestamp":"2024-09-23 15:00:18.883 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/ldap/sync","request_id":"pzf8q8pojpfsib911ok7k4kzra","user_id":"bhgdy9zappy19fje8qch4ubuwr","status_code":"200"}
==================
WARNING: DATA RACE
Read at 0x00c001269590 by goroutine 317:
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 0x00c001269590 by goroutine 293:
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:22 +0xd1
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 317 (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:805 +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 293 (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:1069 +0x224
==================
{"timestamp":"2024-09-23 15:00:18.887 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/ldap/sync","request_id":"uc1dc5otztremg9h9pi886o55w","user_id":"bhgdy9zappy19fje8qch4ubuwr","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 / MySQL (Results)
com/mattermost/mattermost/server/v8/channels/api4.TestSyncLdap
Failed
Raw output
=== RUN TestSyncLdap
{"timestamp":"2024-09-23 15:00:13.154 Z","level":"debug","msg":"Advanced logging config not provided for notification logging","caller":"platform/config.go:165"}
{"timestamp":"2024-09-23 15:00:13.154 Z","level":"info","msg":"Successfully connected to cache backend","caller":"platform/service.go:191","backend":"lru","result":"OK"}
{"timestamp":"2024-09-23 15:00:13.154 Z","level":"info","msg":"Server is initializing...","caller":"platform/service.go:194","go_version":"go1.22.6"}
{"timestamp":"2024-09-23 15:00:13.173 Z","level":"info","msg":"Starting websocket hubs","caller":"platform/web_hub.go:106","number_of_hubs":4}
{"timestamp":"2024-09-23 15:00:13.175 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":2}
{"timestamp":"2024-09-23 15:00:13.175 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":1}
{"timestamp":"2024-09-23 15:00:13.175 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":3}
{"timestamp":"2024-09-23 15:00:13.175 Z","level":"info","msg":"Loaded system translations","caller":"i18n/i18n.go:175","for locale":"en","from locale":"/mattermost/server/i18n/en.json"}
{"timestamp":"2024-09-23 15:00:13.175 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":0}
{"timestamp":"2024-09-23 15:00:13.187 Z","level":"info","msg":"Ensuring the telemetry ID..","caller":"telemetry/telemetry.go:147"}
{"timestamp":"2024-09-23 15:00:13.188 Z","level":"info","msg":"telemetry ID is set","caller":"telemetry/telemetry.go:157","id":"enccdth15tnf5m68dmeh79rdhy"}
{"timestamp":"2024-09-23 15:00:13.207 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:389"}
{"timestamp":"2024-09-23 15:00:13.207 Z","level":"info","msg":"Current version is 10.1.0 (///)","caller":"app/server.go:403","current_version":"10.1.0","build_number":"","build_date":"","build_hash":"","build_hash_enterprise":"","service_environment":"dev"}
{"timestamp":"2024-09-23 15:00:13.207 Z","level":"info","msg":"Team Edition Build","caller":"app/server.go:419","enterprise_build":false}
{"timestamp":"2024-09-23 15:00:13.208 Z","level":"info","msg":"Printing current working","caller":"app/server.go:423","directory":"/tmp/testlib1506327815"}
{"timestamp":"2024-09-23 15:00:13.208 Z","level":"info","msg":"Loaded config","caller":"app/server.go:424","source":"memory://"}
{"timestamp":"2024-09-23 15:00:13.456 Z","level":"debug","msg":"initElasticsearchChannelIndexCheck: calling elasticsearchChannelIndexCheckWithRetry before setting up config and license change listeners...","caller":"app/elasticsearch.go:21"}
{"timestamp":"2024-09-23 15:00:13.456 Z","level":"debug","msg":"elasticsearchChannelIndexCheckWithRetry: attempting to check channel index state...","caller":"app/elasticsearch.go:64"}
{"timestamp":"2024-09-23 15:00:13.456 Z","level":"debug","msg":"elasticsearchChannelIndexCheckWithRetry: skipping because elasticsearch indexing is disabled","caller":"app/elasticsearch.go:67"}
{"timestamp":"2024-09-23 15:00:13.493 Z","level":"debug","msg":"Fetching user count for first user account check","caller":"platform/config.go:356"}
{"timestamp":"2024-09-23 15:00:13.495 Z","level":"info","msg":"Post.Message has size restrictions","caller":"sqlstore/post_store.go:2615","max_characters":16383,"max_bytes":65535}
{"timestamp":"2024-09-23 15:00:13.501 Z","level":"debug","msg":"Advanced logging config not provided for notification logging","caller":"platform/config.go:165"}
{"timestamp":"2024-09-23 15:00:13.501 Z","level":"info","msg":"Starting up plugins","caller":"app/plugin.go:191"}
{"timestamp":"2024-09-23 15:00:13.501 Z","level":"debug","msg":"Enabling plugin health check job","caller":"plugin/environment.go:661","interval_s":"30s"}
{"timestamp":"2024-09-23 15:00:13.501 Z","level":"info","msg":"Syncing plugins from the file store","caller":"app/plugin.go:268"}
{"timestamp":"2024-09-23 15:00:13.501 Z","level":"info","msg":"No plugins to sync from the file store","caller":"app/plugin.go:311"}
{"timestamp":"2024-09-23 15:00:13.501 Z","level":"debug","msg":"Plugin health check job starting.","caller":"plugin/health_check.go:31"}
{"timestamp":"2024-09-23 15:00:13.502 Z","level":"debug","msg":"No prepackaged plugins directory found","caller":"app/plugin.go:912"}
{"timestamp":"2024-09-23 15:00:13.502 Z","level":"debug","msg":"Not persisting transitionally prepackaged plugins: none found","caller":"app/plugin.go:1184"}
{"timestamp":"2024-09-23 15:00:13.508 Z","level":"debug","msg":"Able to write files to local storage.","caller":"filestore/localstore.go:82"}
{"timestamp":"2024-09-23 15:00:13.527 Z","level":"info","msg":"Starting Server...","caller":"app/server.go:886"}
{"timestamp":"2024-09-23 15:00:13.528 Z","level":"info","msg":"Server is listening on 127.0.0.1:38953","caller":"app/server.go:962","address":"127.0.0.1:38953"}
{"timestamp":"2024-09-23 15:00:13.528 Z","level":"debug","msg":"No license provided; Remote Cluster services disabled","caller":"app/server.go:579"}
{"timestamp":"2024-09-23 15:00:13.599 Z","level":"debug","msg":"Initializing web routes","caller":"web/web.go:26"}
{"timestamp":"2024-09-23 15:00:13.600 Z","level":"debug","msg":"Skipping update to assets subpath since CI build","caller":"utils/subpath.go:187"}
{"timestamp":"2024-09-23 15:00:13.601 Z","level":"debug","msg":"Using client directory","caller":"web/static.go:34","clientDir":"/tmp/testlib1506327815/client"}
{"timestamp":"2024-09-23 15:00:13.601 Z","level":"debug","msg":"Configured Client4 bool string values","caller":"api4/apitestlib.go:212","true":"True","false":"0"}
{"timestamp":"2024-09-23 15:00:13.604 Z","level":"debug","msg":"Fetching user count for first user account check","caller":"platform/config.go:356"}
{"timestamp":"2024-09-23 15:00:14.463 Z","level":"debug","msg":"sending mail","caller":"mail/mail.go:295","to":"qsxpatjk5j8sjerdqzcnzat9br@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-09-23 15:00:14.491 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"xdgm5jdugpnc5q91yea9q333rh","user_id":"","status_code":"201"}
{"timestamp":"2024-09-23 15:00:14.500 Z","level":"debug","msg":"Fetching user count for first user account check","caller":"platform/config.go:356"}
{"timestamp":"2024-09-23 15:00:15.361 Z","level":"debug","msg":"sending mail","caller":"mail/mail.go:295","to":"i3sqqipzyi8m5n83enmfkpr9uy@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-09-23 15:00:15.397 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"57dkc7pfifdninwiunf77b3h4o","user_id":"","status_code":"201"}
{"timestamp":"2024-09-23 15:00:16.248 Z","level":"debug","msg":"sending mail","caller":"mail/mail.go:295","to":"u4krgdpicfyp7pno4rsnf163oe@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-09-23 15:00:16.264 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"b5ce3x8u4ffsfk6qcdzj5347kw","user_id":"","status_code":"201"}
{"timestamp":"2024-09-23 15:00:17.110 Z","level":"debug","msg":"sending mail","caller":"mail/mail.go:295","to":"z83pg9wn67rtxr6z7quzucijuc@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-09-23 15:00:17.128 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"u1wj8xyu7pbsd8hf8w8pig58wa","user_id":"","status_code":"201"}
{"timestamp":"2024-09-23 15:00:17.972 Z","level":"debug","msg":"sending mail","caller":"mail/mail.go:295","to":"s8q9xzz15b8xjcp4xqc4hwifrr@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-09-23 15:00:17.989 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"p194iu5gffrjmxys16jwx3649h","user_id":"","status_code":"201"}
{"timestamp":"2024-09-23 15:00:18.842 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users/login","request_id":"ufp7x4aerbg3dm5htecpkxbmzh","user_id":"bhgdy9zappy19fje8qch4ubuwr","status_code":"200"}
{"timestamp":"2024-09-23 15:00:18.842 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users/login","request_id":"9boubkyzjfnhucx7qohmikozea","user_id":"xmznh9dqc3r9jx5tygtbcycnzc","status_code":"200"}
{"timestamp":"2024-09-23 15:00:18.880 Z","level":"debug","msg":"Advanced logging config not provided for notification logging","caller":"platform/config.go:165"}
{"timestamp":"2024-09-23 15:00:18.889 Z","level":"debug","msg":"You do not have the appropriate permissions.","caller":"web/context.go:120","path":"/api/v4/ldap/sync","request_id":"jpmokys347nh3drb7zjyj1nuzc","ip_addr":"127.0.0.1","user_id":"xmznh9dqc3r9jx5tygtbcycnzc","method":"POST","err_where":"Permissions","http_code":403,"error":"Permissions: You do not have the appropriate permissions., userId=xmznh9dqc3r9jx5tygtbcycnzc, permission=create_ldap_sync_job"}
{"timestamp":"2024-09-23 15:00:18.889 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/ldap/sync","request_id":"jpmokys347nh3drb7zjyj1nuzc","user_id":"xmznh9dqc3r9jx5tygtbcycnzc","status_code":"403"}
{"timestamp":"2024-09-23 15:00:18.889 Z","level":"info","msg":"Purging all caches","caller":"platform/cluster_handlers.go:114"}
{"timestamp":"2024-09-23 15:00:18.891 Z","level":"info","msg":"License key has not been uploaded. Loading license key from disk.","caller":"utils/license.go:107","filename":"/tmp/testlib1506327815/config/mattermost.mattermost-license"}
{"timestamp":"2024-09-23 15:00:18.891 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/testlib1506327815/config/mattermost.mattermost-license: stat /tmp/testlib1506327815/config/mattermost.mattermost-license: no such file or directory"}
{"timestamp":"2024-09-23 15:00:18.892 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-09-23 15:00:18.895 Z","level":"info","msg":"Stopping Server...","caller":"app/server.go:668"}
{"timestamp":"2024-09-23 15:00:18.901 Z","level":"info","msg":"Shutting down Email batching service...","caller":"email/service.go:85"}
{"timestamp":"2024-09-23 15:00:18.901 Z","level":"info","msg":"Shutting down plugins","caller":"app/plugin.go:362"}
{"timestamp":"2024-09-23 15:00:18.901 Z","level":"debug","msg":"Disabling plugin health check job","caller":"plugin/environment.go:670"}
{"timestamp":"2024-09-23 15:00:18.901 Z","level":"info","msg":"stopping websocket hub connections","caller":"platform/web_hub.go:127"}
{"timestamp":"2024-09-23 15:00:18.901 Z","level":"info","msg":"Server stopped","caller":"app/server.go:752"}
--- FAIL: TestSyncLdap (6.32s)
Check failure on line 1 in com/mattermost/mattermost/server/v8/channels/api4
github-actions / MySQL (Results)
com/mattermost/mattermost/server/v8/channels/api4.TestSyncLdap/SystemAdminClient#01
Failed
Raw output
=== RUN TestSyncLdap/SystemAdminClient#01
{"timestamp":"2024-09-23 15:00:31.849 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/ldap/sync","request_id":"8r5ssfpj4tbb9mwnwdwc77scir","user_id":"mrgx1fz1nt8fimqwj5u34wqc5a","status_code":"200"}
==================
WARNING: DATA RACE
Read at 0x00c001efda70 by goroutine 318:
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 0x00c001efda70 by goroutine 189:
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:22 +0xd1
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 318 (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:805 +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 189 (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:1069 +0x224
==================
{"timestamp":"2024-09-23 15:00:31.853 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/ldap/sync","request_id":"scor3bwunpggiq47wka3ixps6y","user_id":"mrgx1fz1nt8fimqwj5u34wqc5a","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 / MySQL (Results)
com/mattermost/mattermost/server/v8/channels/api4.TestSyncLdap
Failed
Raw output
=== RUN TestSyncLdap
{"timestamp":"2024-09-23 15:00:26.294 Z","level":"debug","msg":"Advanced logging config not provided for notification logging","caller":"platform/config.go:165"}
{"timestamp":"2024-09-23 15:00:26.294 Z","level":"info","msg":"Successfully connected to cache backend","caller":"platform/service.go:191","backend":"lru","result":"OK"}
{"timestamp":"2024-09-23 15:00:26.294 Z","level":"info","msg":"Server is initializing...","caller":"platform/service.go:194","go_version":"go1.22.6"}
{"timestamp":"2024-09-23 15:00:26.308 Z","level":"info","msg":"Starting websocket hubs","caller":"platform/web_hub.go:106","number_of_hubs":4}
{"timestamp":"2024-09-23 15:00:26.309 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":0}
{"timestamp":"2024-09-23 15:00:26.309 Z","level":"info","msg":"Loaded system translations","caller":"i18n/i18n.go:175","for locale":"en","from locale":"/mattermost/server/i18n/en.json"}
{"timestamp":"2024-09-23 15:00:26.309 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":1}
{"timestamp":"2024-09-23 15:00:26.310 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":2}
{"timestamp":"2024-09-23 15:00:26.310 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":3}
{"timestamp":"2024-09-23 15:00:26.318 Z","level":"info","msg":"Ensuring the telemetry ID..","caller":"telemetry/telemetry.go:147"}
{"timestamp":"2024-09-23 15:00:26.320 Z","level":"info","msg":"telemetry ID is set","caller":"telemetry/telemetry.go:157","id":"ha41pdfpjt86tmewyedd56xsjy"}
{"timestamp":"2024-09-23 15:00:26.339 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:389"}
{"timestamp":"2024-09-23 15:00:26.339 Z","level":"info","msg":"Current version is 10.1.0 (///)","caller":"app/server.go:403","current_version":"10.1.0","build_number":"","build_date":"","build_hash":"","build_hash_enterprise":"","service_environment":"dev"}
{"timestamp":"2024-09-23 15:00:26.339 Z","level":"info","msg":"Team Edition Build","caller":"app/server.go:419","enterprise_build":false}
{"timestamp":"2024-09-23 15:00:26.339 Z","level":"info","msg":"Printing current working","caller":"app/server.go:423","directory":"/tmp/testlib3991773379"}
{"timestamp":"2024-09-23 15:00:26.339 Z","level":"info","msg":"Loaded config","caller":"app/server.go:424","source":"memory://"}
{"timestamp":"2024-09-23 15:00:26.568 Z","level":"debug","msg":"initElasticsearchChannelIndexCheck: calling elasticsearchChannelIndexCheckWithRetry before setting up config and license change listeners...","caller":"app/elasticsearch.go:21"}
{"timestamp":"2024-09-23 15:00:26.569 Z","level":"debug","msg":"elasticsearchChannelIndexCheckWithRetry: attempting to check channel index state...","caller":"app/elasticsearch.go:64"}
{"timestamp":"2024-09-23 15:00:26.569 Z","level":"debug","msg":"elasticsearchChannelIndexCheckWithRetry: skipping because elasticsearch indexing is disabled","caller":"app/elasticsearch.go:67"}
{"timestamp":"2024-09-23 15:00:26.612 Z","level":"debug","msg":"Fetching user count for first user account check","caller":"platform/config.go:356"}
{"timestamp":"2024-09-23 15:00:26.614 Z","level":"info","msg":"Post.Message has size restrictions","caller":"sqlstore/post_store.go:2615","max_characters":16383,"max_bytes":65535}
{"timestamp":"2024-09-23 15:00:26.620 Z","level":"debug","msg":"Advanced logging config not provided for notification logging","caller":"platform/config.go:165"}
{"timestamp":"2024-09-23 15:00:26.620 Z","level":"info","msg":"Starting up plugins","caller":"app/plugin.go:191"}
{"timestamp":"2024-09-23 15:00:26.620 Z","level":"debug","msg":"Enabling plugin health check job","caller":"plugin/environment.go:661","interval_s":"30s"}
{"timestamp":"2024-09-23 15:00:26.620 Z","level":"info","msg":"Syncing plugins from the file store","caller":"app/plugin.go:268"}
{"timestamp":"2024-09-23 15:00:26.620 Z","level":"info","msg":"No plugins to sync from the file store","caller":"app/plugin.go:311"}
{"timestamp":"2024-09-23 15:00:26.620 Z","level":"debug","msg":"Plugin health check job starting.","caller":"plugin/health_check.go:31"}
{"timestamp":"2024-09-23 15:00:26.621 Z","level":"debug","msg":"No prepackaged plugins directory found","caller":"app/plugin.go:912"}
{"timestamp":"2024-09-23 15:00:26.621 Z","level":"debug","msg":"Not persisting transitionally prepackaged plugins: none found","caller":"app/plugin.go:1184"}
{"timestamp":"2024-09-23 15:00:26.627 Z","level":"debug","msg":"Able to write files to local storage.","caller":"filestore/localstore.go:82"}
{"timestamp":"2024-09-23 15:00:26.646 Z","level":"info","msg":"Starting Server...","caller":"app/server.go:886"}
{"timestamp":"2024-09-23 15:00:26.647 Z","level":"info","msg":"Server is listening on 127.0.0.1:34591","caller":"app/server.go:962","address":"127.0.0.1:34591"}
{"timestamp":"2024-09-23 15:00:26.647 Z","level":"debug","msg":"No license provided; Remote Cluster services disabled","caller":"app/server.go:579"}
{"timestamp":"2024-09-23 15:00:26.714 Z","level":"debug","msg":"Initializing web routes","caller":"web/web.go:26"}
{"timestamp":"2024-09-23 15:00:26.715 Z","level":"debug","msg":"Skipping update to assets subpath since CI build","caller":"utils/subpath.go:187"}
{"timestamp":"2024-09-23 15:00:26.715 Z","level":"debug","msg":"Using client directory","caller":"web/static.go:34","clientDir":"/tmp/testlib3991773379/client"}
{"timestamp":"2024-09-23 15:00:26.716 Z","level":"debug","msg":"Configured Client4 bool string values","caller":"api4/apitestlib.go:212","true":"t","false":"FALSE"}
{"timestamp":"2024-09-23 15:00:26.719 Z","level":"debug","msg":"Fetching user count for first user account check","caller":"platform/config.go:356"}
{"timestamp":"2024-09-23 15:00:27.505 Z","level":"debug","msg":"sending mail","caller":"mail/mail.go:295","to":"gb3r3d6j8pg4br7kiu1hsd6ckw@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-09-23 15:00:27.526 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"e9be7t68z3gtpg3nh7k4r5u7bo","user_id":"","status_code":"201"}
{"timestamp":"2024-09-23 15:00:27.541 Z","level":"debug","msg":"Fetching user count for first user account check","caller":"platform/config.go:356"}
{"timestamp":"2024-09-23 15:00:28.381 Z","level":"debug","msg":"sending mail","caller":"mail/mail.go:295","to":"gq39geqop7dobpem975sbdwqke@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-09-23 15:00:28.398 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"f1yfi69mrpdu8bs5pm8j9fw3yc","user_id":"","status_code":"201"}
{"timestamp":"2024-09-23 15:00:29.225 Z","level":"debug","msg":"sending mail","caller":"mail/mail.go:295","to":"kbsryu3qwbnf5pyt4pj1mud8dw@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-09-23 15:00:29.243 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"j4q8dorb83yzmg1upf3o44weuy","user_id":"","status_code":"201"}
{"timestamp":"2024-09-23 15:00:30.059 Z","level":"debug","msg":"sending mail","caller":"mail/mail.go:295","to":"61tyy3okm7dgt85qz79aem4kne@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-09-23 15:00:30.077 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"6hgccyrnojy6zekdnjqic5i6nw","user_id":"","status_code":"201"}
{"timestamp":"2024-09-23 15:00:30.926 Z","level":"debug","msg":"sending mail","caller":"mail/mail.go:295","to":"o75ixxskjbbdteo79scxyeuqxa@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-09-23 15:00:30.946 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"nmpcgu5ysjrc5miywsd99867zo","user_id":"","status_code":"201"}
{"timestamp":"2024-09-23 15:00:31.804 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users/login","request_id":"65dg8yjrfprz5r8nbrp9nsmzcr","user_id":"nsgcy1p1hbgz9ffcm8axeqge3e","status_code":"200"}
{"timestamp":"2024-09-23 15:00:31.807 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users/login","request_id":"psoajpbstpnej8j47tuukhfipe","user_id":"mrgx1fz1nt8fimqwj5u34wqc5a","status_code":"200"}
{"timestamp":"2024-09-23 15:00:31.847 Z","level":"debug","msg":"Advanced logging config not provided for notification logging","caller":"platform/config.go:165"}
{"timestamp":"2024-09-23 15:00:31.856 Z","level":"debug","msg":"You do not have the appropriate permissions.","caller":"web/context.go:120","path":"/api/v4/ldap/sync","request_id":"gm3a43gs978fzdu8gue115d5or","ip_addr":"127.0.0.1","user_id":"nsgcy1p1hbgz9ffcm8axeqge3e","method":"POST","err_where":"Permissions","http_code":403,"error":"Permissions: You do not have the appropriate permissions., userId=nsgcy1p1hbgz9ffcm8axeqge3e, permission=create_ldap_sync_job"}
{"timestamp":"2024-09-23 15:00:31.856 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/ldap/sync","request_id":"gm3a43gs978fzdu8gue115d5or","user_id":"nsgcy1p1hbgz9ffcm8axeqge3e","status_code":"403"}
{"timestamp":"2024-09-23 15:00:31.856 Z","level":"info","msg":"Purging all caches","caller":"platform/cluster_handlers.go:114"}
{"timestamp":"2024-09-23 15:00:31.858 Z","level":"info","msg":"License key has not been uploaded. Loading license key from disk.","caller":"utils/license.go:107","filename":"/tmp/testlib3991773379/config/mattermost.mattermost-license"}
{"timestamp":"2024-09-23 15:00:31.858 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/testlib3991773379/config/mattermost.mattermost-license: stat /tmp/testlib3991773379/config/mattermost.mattermost-license: no such file or directory"}
{"timestamp":"2024-09-23 15:00:31.859 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-09-23 15:00:31.864 Z","level":"info","msg":"Stopping Server...","caller":"app/server.go:668"}
{"timestamp":"2024-09-23 15:00:31.870 Z","level":"info","msg":"Shutting down Email batching service...","caller":"email/service.go:85"}
{"timestamp":"2024-09-23 15:00:31.870 Z","level":"info","msg":"Shutting down plugins","caller":"app/plugin.go:362"}
{"timestamp":"2024-09-23 15:00:31.870 Z","level":"debug","msg":"Disabling plugin health check job","caller":"plugin/environment.go:670"}
{"timestamp":"2024-09-23 15:00:31.870 Z","level":"info","msg":"stopping websocket hub connections","caller":"platform/web_hub.go:127"}
{"timestamp":"2024-09-23 15:00:31.870 Z","level":"info","msg":"Server stopped","caller":"app/server.go:752"}
--- FAIL: TestSyncLdap (5.97s)
Check failure on line 1 in com/mattermost/mattermost/server/v8/channels/api4
github-actions / MySQL (Results)
com/mattermost/mattermost/server/v8/channels/api4.TestSyncLdap/SystemAdminClient#01
Failed
Raw output
=== RUN TestSyncLdap/SystemAdminClient#01
{"timestamp":"2024-09-23 15:00:45.147 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/ldap/sync","request_id":"1a5b65smubrp3n3ye9debrxn3h","user_id":"6ucnob4xbjryxn78fwy6ck79tw","status_code":"200"}
==================
WARNING: DATA RACE
Read at 0x00c002728f30 by goroutine 318:
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 0x00c002728f30 by goroutine 189:
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:22 +0xd1
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 318 (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:805 +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 189 (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:1069 +0x224
==================
{"timestamp":"2024-09-23 15:00:45.150 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/ldap/sync","request_id":"dhm3k9u9fbra7b5qwak1aqq7do","user_id":"6ucnob4xbjryxn78fwy6ck79tw","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 / MySQL (Results)
com/mattermost/mattermost/server/v8/channels/api4.TestSyncLdap
Failed
Raw output
=== RUN TestSyncLdap
{"timestamp":"2024-09-23 15:00:39.366 Z","level":"debug","msg":"Advanced logging config not provided for notification logging","caller":"platform/config.go:165"}
{"timestamp":"2024-09-23 15:00:39.366 Z","level":"info","msg":"Successfully connected to cache backend","caller":"platform/service.go:191","backend":"lru","result":"OK"}
{"timestamp":"2024-09-23 15:00:39.366 Z","level":"info","msg":"Server is initializing...","caller":"platform/service.go:194","go_version":"go1.22.6"}
{"timestamp":"2024-09-23 15:00:39.386 Z","level":"info","msg":"Starting websocket hubs","caller":"platform/web_hub.go:106","number_of_hubs":4}
{"timestamp":"2024-09-23 15:00:39.387 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":2}
{"timestamp":"2024-09-23 15:00:39.387 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":0}
{"timestamp":"2024-09-23 15:00:39.387 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":1}
{"timestamp":"2024-09-23 15:00:39.387 Z","level":"debug","msg":"Hub is starting","caller":"platform/web_hub.go:386","index":3}
{"timestamp":"2024-09-23 15:00:39.388 Z","level":"info","msg":"Loaded system translations","caller":"i18n/i18n.go:175","for locale":"en","from locale":"/mattermost/server/i18n/en.json"}
{"timestamp":"2024-09-23 15:00:39.395 Z","level":"info","msg":"Ensuring the telemetry ID..","caller":"telemetry/telemetry.go:147"}
{"timestamp":"2024-09-23 15:00:39.397 Z","level":"info","msg":"telemetry ID is set","caller":"telemetry/telemetry.go:157","id":"xzod3736kp8t7mjk79w4uoiagr"}
{"timestamp":"2024-09-23 15:00:39.416 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:389"}
{"timestamp":"2024-09-23 15:00:39.416 Z","level":"info","msg":"Current version is 10.1.0 (///)","caller":"app/server.go:403","current_version":"10.1.0","build_number":"","build_date":"","build_hash":"","build_hash_enterprise":"","service_environment":"dev"}
{"timestamp":"2024-09-23 15:00:39.416 Z","level":"info","msg":"Team Edition Build","caller":"app/server.go:419","enterprise_build":false}
{"timestamp":"2024-09-23 15:00:39.416 Z","level":"info","msg":"Printing current working","caller":"app/server.go:423","directory":"/tmp/testlib673400014"}
{"timestamp":"2024-09-23 15:00:39.416 Z","level":"info","msg":"Loaded config","caller":"app/server.go:424","source":"memory://"}
{"timestamp":"2024-09-23 15:00:39.654 Z","level":"debug","msg":"initElasticsearchChannelIndexCheck: calling elasticsearchChannelIndexCheckWithRetry before setting up config and license change listeners...","caller":"app/elasticsearch.go:21"}
{"timestamp":"2024-09-23 15:00:39.654 Z","level":"debug","msg":"elasticsearchChannelIndexCheckWithRetry: attempting to check channel index state...","caller":"app/elasticsearch.go:64"}
{"timestamp":"2024-09-23 15:00:39.654 Z","level":"debug","msg":"elasticsearchChannelIndexCheckWithRetry: skipping because elasticsearch indexing is disabled","caller":"app/elasticsearch.go:67"}
{"timestamp":"2024-09-23 15:00:39.689 Z","level":"debug","msg":"Fetching user count for first user account check","caller":"platform/config.go:356"}
{"timestamp":"2024-09-23 15:00:39.690 Z","level":"info","msg":"Post.Message has size restrictions","caller":"sqlstore/post_store.go:2615","max_characters":16383,"max_bytes":65535}
{"timestamp":"2024-09-23 15:00:39.696 Z","level":"debug","msg":"Advanced logging config not provided for notification logging","caller":"platform/config.go:165"}
{"timestamp":"2024-09-23 15:00:39.696 Z","level":"info","msg":"Starting up plugins","caller":"app/plugin.go:191"}
{"timestamp":"2024-09-23 15:00:39.696 Z","level":"debug","msg":"Enabling plugin health check job","caller":"plugin/environment.go:661","interval_s":"30s"}
{"timestamp":"2024-09-23 15:00:39.696 Z","level":"info","msg":"Syncing plugins from the file store","caller":"app/plugin.go:268"}
{"timestamp":"2024-09-23 15:00:39.697 Z","level":"debug","msg":"Plugin health check job starting.","caller":"plugin/health_check.go:31"}
{"timestamp":"2024-09-23 15:00:39.697 Z","level":"info","msg":"No plugins to sync from the file store","caller":"app/plugin.go:311"}
{"timestamp":"2024-09-23 15:00:39.697 Z","level":"debug","msg":"No prepackaged plugins directory found","caller":"app/plugin.go:912"}
{"timestamp":"2024-09-23 15:00:39.697 Z","level":"debug","msg":"Not persisting transitionally prepackaged plugins: none found","caller":"app/plugin.go:1184"}
{"timestamp":"2024-09-23 15:00:39.703 Z","level":"debug","msg":"Able to write files to local storage.","caller":"filestore/localstore.go:82"}
{"timestamp":"2024-09-23 15:00:39.723 Z","level":"info","msg":"Starting Server...","caller":"app/server.go:886"}
{"timestamp":"2024-09-23 15:00:39.724 Z","level":"info","msg":"Server is listening on 127.0.0.1:43937","caller":"app/server.go:962","address":"127.0.0.1:43937"}
{"timestamp":"2024-09-23 15:00:39.724 Z","level":"debug","msg":"No license provided; Remote Cluster services disabled","caller":"app/server.go:579"}
{"timestamp":"2024-09-23 15:00:39.789 Z","level":"debug","msg":"Initializing web routes","caller":"web/web.go:26"}
{"timestamp":"2024-09-23 15:00:39.791 Z","level":"debug","msg":"Skipping update to assets subpath since CI build","caller":"utils/subpath.go:187"}
{"timestamp":"2024-09-23 15:00:39.791 Z","level":"debug","msg":"Using client directory","caller":"web/static.go:34","clientDir":"/tmp/testlib673400014/client"}
{"timestamp":"2024-09-23 15:00:39.792 Z","level":"debug","msg":"Configured Client4 bool string values","caller":"api4/apitestlib.go:212","true":"1","false":"false"}
{"timestamp":"2024-09-23 15:00:39.795 Z","level":"debug","msg":"Fetching user count for first user account check","caller":"platform/config.go:356"}
{"timestamp":"2024-09-23 15:00:40.653 Z","level":"debug","msg":"sending mail","caller":"mail/mail.go:295","to":"sxgox46rhtn6bp86g8m1f15qth@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-09-23 15:00:40.674 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"b8hf4uq9qprdx8aenzhxpop9nc","user_id":"","status_code":"201"}
{"timestamp":"2024-09-23 15:00:40.683 Z","level":"debug","msg":"Fetching user count for first user account check","caller":"platform/config.go:356"}
{"timestamp":"2024-09-23 15:00:41.527 Z","level":"debug","msg":"sending mail","caller":"mail/mail.go:295","to":"39ijbnz8i3ne5g8y8fhx3654or@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-09-23 15:00:41.554 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"hwy6bew4gjg5trrtnyr3a6zo7a","user_id":"","status_code":"201"}
{"timestamp":"2024-09-23 15:00:42.404 Z","level":"debug","msg":"sending mail","caller":"mail/mail.go:295","to":"defanomnwprs9nifrmswqzjaeo@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-09-23 15:00:42.422 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"deu385ax9bbpxrrn7r7czb3oho","user_id":"","status_code":"201"}
{"timestamp":"2024-09-23 15:00:43.265 Z","level":"debug","msg":"sending mail","caller":"mail/mail.go:295","to":"pffc6jzkofg77btzzzbu4sikue@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-09-23 15:00:43.283 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"omhj4747pprdxrqiuj48uf7zyc","user_id":"","status_code":"201"}
{"timestamp":"2024-09-23 15:00:44.127 Z","level":"debug","msg":"sending mail","caller":"mail/mail.go:295","to":"bnjfu6jwbbfo7midu187y8dkuw@localhost","subject":"[Mattermost] You joined "}
{"timestamp":"2024-09-23 15:00:44.149 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users","request_id":"gprrdyrhetde5pjp5cmstz9ipe","user_id":"","status_code":"201"}
{"timestamp":"2024-09-23 15:00:45.092 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users/login","request_id":"ymidr7a1st8mimycardmysqrac","user_id":"6ucnob4xbjryxn78fwy6ck79tw","status_code":"200"}
{"timestamp":"2024-09-23 15:00:45.105 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/users/login","request_id":"ze5a8nqc7p8u7k7mth6zdsnu9c","user_id":"13wym9ymubbgirsmy5bxttgiuc","status_code":"200"}
{"timestamp":"2024-09-23 15:00:45.144 Z","level":"debug","msg":"Advanced logging config not provided for notification logging","caller":"platform/config.go:165"}
{"timestamp":"2024-09-23 15:00:45.153 Z","level":"debug","msg":"You do not have the appropriate permissions.","caller":"web/context.go:120","path":"/api/v4/ldap/sync","request_id":"r5s7o9o1tp87bqk867i396gzpo","ip_addr":"127.0.0.1","user_id":"13wym9ymubbgirsmy5bxttgiuc","method":"POST","err_where":"Permissions","http_code":403,"error":"Permissions: You do not have the appropriate permissions., userId=13wym9ymubbgirsmy5bxttgiuc, permission=create_ldap_sync_job"}
{"timestamp":"2024-09-23 15:00:45.153 Z","level":"debug","msg":"Received HTTP request","caller":"web/handlers.go:185","method":"POST","url":"/api/v4/ldap/sync","request_id":"r5s7o9o1tp87bqk867i396gzpo","user_id":"13wym9ymubbgirsmy5bxttgiuc","status_code":"403"}
{"timestamp":"2024-09-23 15:00:45.154 Z","level":"info","msg":"Purging all caches","caller":"platform/cluster_handlers.go:114"}
{"timestamp":"2024-09-23 15:00:45.155 Z","level":"info","msg":"License key has not been uploaded. Loading license key from disk.","caller":"utils/license.go:107","filename":"/tmp/testlib673400014/config/mattermost.mattermost-license"}
{"timestamp":"2024-09-23 15:00:45.155 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/testlib673400014/config/mattermost.mattermost-license: stat /tmp/testlib673400014/config/mattermost.mattermost-license: no such file or directory"}
{"timestamp":"2024-09-23 15:00:45.156 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-09-23 15:00:45.159 Z","level":"info","msg":"Stopping Server...","caller":"app/server.go:668"}
{"timestamp":"2024-09-23 15:00:45.165 Z","level":"info","msg":"Shutting down Email batching service...","caller":"email/service.go:85"}
{"timestamp":"2024-09-23 15:00:45.165 Z","level":"info","msg":"Shutting down plugins","caller":"app/plugin.go:362"}
{"timestamp":"2024-09-23 15:00:45.165 Z","level":"debug","msg":"Disabling plugin health check job","caller":"plugin/environment.go:670"}
{"timestamp":"2024-09-23 15:00:45.165 Z","level":"info","msg":"stopping websocket hub connections","caller":"platform/web_hub.go:127"}
{"timestamp":"2024-09-23 15:00:45.165 Z","level":"info","msg":"Server stopped","caller":"app/server.go:752"}
--- FAIL: TestSyncLdap (6.21s)
Loading