flake.lock: Update #305
Merged
flake.lock: Update #305
Garnix CI / check vm_ldap_auth [x86_64-linux]
succeeded
Sep 16, 2024 in 5m 13s
Run results
Build succeeded
Details
Last 100 lines of logs:
�[2mserver # [ 12.470134] lldap-start[957]: 2024-09-16T01:30:00.226959430+00:00 DEBUG │ ┕━ 🐛 [debug]: | return: []�[0m
�[2mserver # [ 12.471116] lldap-start[957]: 2024-09-16T01:30:00.226972840+00:00 WARN ┝━ 🚧 [warn]: Could not find an admin user, trying to create the user "admin" with the config-provided password�[0m
�[2mserver # [ 12.472714] lldap-start[957]: 2024-09-16T01:30:00.234817133+00:00 DEBUG ┝━ create_user [ 4.10ms | 1.45% ] request: CreateUserRequest { user_id: UserId(CaseInsensitiveString("admin")), email: Email("admin@example.com"), display_name: Some("Administrator"), first_name: None, last_name: None, avatar: None, attributes: [] } | user_id: "admin"�[0m
�[2mserver # [ 12.475391] lldap-start[957]: 2024-09-16T01:30:00.265236083+00:00 DEBUG ┝━ register_password [ 72.7ms | 22.99% / 25.76% ] username: admin�[0m
�[2mserver # [ 12.476558] lldap-start[957]: 2024-09-16T01:30:00.265446445+00:00 DEBUG │ ┝━ registration_start [ 504µs | 0.18% ]�[0m
�[2mserver # [ 12.477648] lldap-start[957]: 2024-09-16T01:30:00.330580421+00:00 DEBUG │ ┕━ registration_finish [ 7.32ms | 2.59% ]�[0m
�[2mserver # [ 12.478677] lldap-start[957]: 2024-09-16T01:30:00.340619127+00:00 DEBUG ┝━ list_groups [ 16.3ms | 5.79% ] filters: Some(DisplayName(GroupName("lldap_admin")))�[0m
�[2mserver # [ 12.479992] lldap-start[957]: 2024-09-16T01:30:00.357269567+00:00 DEBUG │ ┕━ 🐛 [debug]: | return: [Group { id: GroupId(1), display_name: GroupName("lldap_admin"), creation_date: 2024-09-16T01:30:00.088705546, uuid: Uuid("68f04c8c-c6b8-3b56-9e77-8a6c0f4f0de4"), users: [], attributes: [] }]�[0m
�[2mserver # [ 12.482219] lldap-start[957]: 2024-09-16T01:30:00.357526024+00:00 DEBUG ┝━ add_user_to_group [ 744µs | 0.26% ] user_id: "admin"�[0m
�[2mserver # [ 12.483274] lldap-start[957]: 2024-09-16T01:30:00.369399321+00:00 INFO ┝━ ｉ [info]: Starting the LDAP server on port 3890�[0m
�[2mserver # [ 12.484317] lldap-start[957]: 2024-09-16T01:30:00.369674496+00:00 DEBUG ┝━ get_jwt_blacklist [ 200µs | 0.07% ]�[0m
�[2mserver # [ 12.485430] lldap-start[957]: 2024-09-16T01:30:00.370088515+00:00 INFO ┕━ ｉ [info]: Starting the API/web server on port 17170�[0m
�[2mserver # [ 12.486788] lldap-start[957]: 2024-09-16T01:30:00.373658522+00:00 INFO ｉ [info]: starting 1 workers�[0m
�[2mserver # [ 12.487863] lldap-start[957]: 2024-09-16T01:30:00.375770522+00:00 INFO ｉ [info]: Actix runtime found; starting in Actix runtime�[0m
�[2mserver # [ 12.489187] lldap-start[957]: 2024-09-16T01:30:00.417223556+00:00 INFO ｉ [info]: DB Cleanup Cron started�[0m
(finished: waiting for unit lldap.service, in 13.03 seconds)
server: �[1m�[32mwaiting for TCP port 17170 on localhost�[0m
�[2mserver # Connection to localhost (127.0.0.1) 17170 port [tcp/*] succeeded!�[0m
(finished: waiting for TCP port 17170 on localhost, in 0.03 seconds)
�[1m�[32msubtest: fail without authenticating�[0m
client: �[1m�[32mmust fail: curl -f -s -X GET -H "Content-type: application/json" -H "Host: ldap.example.com" http://server/api/graphql�[0m
client: �[1m�[32mwaiting for the VM to finish booting�[0m
client: Guest shell says: b'Spawning backdoor root shell...\n'
client: connected to guest root shell
client: (connecting took 0.00 seconds)
(finished: waiting for the VM to finish booting, in 0.00 seconds)
�[2mserver # [ 12.674153] lldap-start[957]: 2024-09-16T01:30:00.615023835+00:00 DEBUG HTTP request [ 503µs | 100.00% ] method: "GET" | uri: "/api/graphql"�[0m
�[2mserver # [ 12.675882] lldap-start[957]: 2024-09-16T01:30:00.615598768+00:00 ERROR ┝━ 🚨 [error]: | error: AuthenticationError { challenge: Bearer { scope: None, realm: None, error: None, error_description: None, error_uri: None }, status_code: 401 }�[0m
(finished: must fail: curl -f -s -X GET -H "Content-type: application/json" -H "Host: ldap.example.com" http://server/api/graphql, in 0.04 seconds)
(finished: subtest: fail without authenticating, in 0.04 seconds)
�[1m�[32msubtest: fail authenticating with wrong credentials�[0m
client: �[1m�[32mmust fail: curl -f -s -X POST -H "Content-type: application/json" -H "Host: ldap.example.com" http://server/auth/simple/login -d '{"username": "admin", "password": "wrong"}'�[0m
�[2mserver # [ 12.677826] lldap-start[957]: 2024-09-16T01:30:00.615608546+00:00 WARN ┕━ 🚧 [warn]: Error encountered while processing the incoming HTTP request: AuthenticationError { challenge: Bearer { scope: None, realm: None, error: None, error_description: None, error_uri: None }, status_code: 401 }�[0m
�[2mserver # [ 12.699449] lldap-start[957]: 2024-09-16T01:30:00.641291978+00:00 DEBUG 🐛 [debug]: | summary: "SELECT \"users\".\"password_hash\" FROM \"users\" …" | db.statement: "\n\nSELECT\n \"users\".\"password_hash\"\nFROM\n \"users\"\nWHERE\n \"users\".\"user_id\" = ?\nLIMIT\n ?\n" | rows_affected: 1 | rows_returned: 1 | elapsed: 130.184µs�[0m
�[2mserver # [ 12.762714] lldap-start[957]: 2024-09-16T01:30:00.640586022+00:00 DEBUG HTTP request [ 63.9ms | 0.61% / 100.00% ] method: "POST" | uri: "/auth/simple/login"�[0m
�[2mserver # [ 12.764349] lldap-start[957]: 2024-09-16T01:30:00.640944448+00:00 DEBUG ┝━ simple_login [ 63.5ms | 0.19% / 99.39% ]�[0m
�[2mserver # [ 12.765595] lldap-start[957]: 2024-09-16T01:30:00.640975457+00:00 DEBUG │ ┕━ bind [ 63.4ms | 0.08% / 99.20% ]�[0m
�[2mserver # [ 12.766744] lldap-start[957]: 2024-09-16T01:30:00.640992499+00:00 DEBUG │ ┝━ get_password_file_for_user [ 3.05ms | 4.77% ] user_id: UserId(CaseInsensitiveString("admin"))�[0m
(finished: must fail: curl -f -s -X POST -H "Content-type: application/json" -H "Host: ldap.example.com" http://server/auth/simple/login -d '{"username": "admin", "password": "wrong"}', in 0.09 seconds)
(finished: subtest: fail authenticating with wrong credentials, in 0.09 seconds)
�[1m�[32msubtest: succeed with correct authentication�[0m
client: �[1m�[32mmust succeed: curl -f -s -X POST -H "Content-type: application/json" -H "Host: ldap.example.com" http://server/auth/simple/login -d '{"username": "admin", "password": "securepw"}' �[0m
�[2mserver # [ 12.768222] lldap-start[957]: 2024-09-16T01:30:00.644058810+00:00 DEBUG │ ┝━ passwords_match [ 60.3ms | 94.35% ] username: admin�[0m
�[2mserver # [ 12.769439] lldap-start[957]: 2024-09-16T01:30:00.704334907+00:00 ERROR │ │ ┕━ 🚨 [error]: | error: Authentication protocol error for `Protocol error: `This error results from an error during password verification``�[0m
�[2mserver # [ 12.771264] lldap-start[957]: 2024-09-16T01:30:00.704354183+00:00 DEBUG │ ┝━ 🐛 [debug]: Invalid password for "admin": Authentication protocol error for `Protocol error: `This error results from an error during password verification``�[0m
�[2mserver # [ 12.773265] lldap-start[957]: 2024-09-16T01:30:00.704359770+00:00 ERROR │ ┕━ 🚨 [error]: | error: Authentication error: ` for user 'admin'`�[0m
�[2mserver # [ 12.774607] lldap-start[957]: 2024-09-16T01:30:00.704483808+00:00 DEBUG ┕━ 🐛 [debug]: | status_code: 401�[0m
�[2mserver # [ 12.789817] lldap-start[957]: 2024-09-16T01:30:00.731664637+00:00 DEBUG 🐛 [debug]: | summary: "SELECT \"users\".\"password_hash\" FROM \"users\" …" | db.statement: "\n\nSELECT\n \"users\".\"password_hash\"\nFROM\n \"users\"\nWHERE\n \"users\".\"user_id\" = ?\nLIMIT\n ?\n" | rows_affected: 1 | rows_returned: 1 | elapsed: 117.054µs�[0m
�[2mserver # [ 12.857571] lldap-start[957]: 2024-09-16T01:30:00.799205623+00:00 DEBUG 🐛 [debug]: | summary: "SELECT \"users\".\"user_id\", \"users\".\"email\", \"users\".\"lowercase_email\", …" | db.statement: "\n\nSELECT\n \"users\".\"user_id\",\n \"users\".\"email\",\n \"users\".\"lowercase_email\",\n \"users\".\"display_name\",\n \"users\".\"creation_date\",\n \"users\".\"password_hash\",\n \"users\".\"totp_secret\",\n \"users\".\"mfa_type\",\n \"users\".\"uuid\"\nFROM\n \"users\"\nWHERE\n \"users\".\"user_id\" = ?\nLIMIT\n ?\n" | rows_affected: 1 | rows_returned: 1 | elapsed: 140.52µs�[0m
�[2mserver # [ 12.862748] lldap-start[957]: 2024-09-16T01:30:00.804606589+00:00 DEBUG 🐛 [debug]: | summary: "SELECT \"groups\".\"group_id\", \"groups\".\"display_name\", \"groups\".\"lowercase_display_name\", …" | db.statement: "\n\nSELECT\n \"groups\".\"group_id\",\n \"groups\".\"display_name\",\n \"groups\".\"lowercase_display_name\",\n \"groups\".\"creation_date\",\n \"groups\".\"uuid\"\nFROM\n \"groups\"\n INNER JOIN \"memberships\" AS \"r0\" ON \"r0\".\"group_id\" = \"groups\".\"group_id\"\n INNER JOIN \"users\" AS \"r1\" ON \"r1\".\"user_id\" = \"r0\".\"user_id\"\nWHERE\n \"r1\".\"user_id\" = ?\n" | rows_affected: 1 | r…
�[2mserver # [ 12.872496] lldap-start[957]: 2024-09-16T01:30:00.814187695+00:00 DEBUG 🐛 [debug]: | summary: "INSERT INTO \"jwt_refresh_storage\" (\"refresh_token_hash\", …" | db.statement: "\n\nINSERT INTO\n \"jwt_refresh_storage\" (\"refresh_token_hash\", \"user_id\", \"expiry_date\")\nVALUES\n (?, ?, ?)\n" | rows_affected: 1 | rows_returned: 0 | elapsed: 2.809575ms�[0m
�[2mserver # [ 12.876106] lldap-start[957]: 2024-09-16T01:30:00.817790108+00:00 DEBUG 🐛 [debug]: | summary: "SELECT \"jwt_refresh_storage\".\"refresh_token_hash\", \"jwt_refresh_storage\".\"user_id\", \"jwt_refresh_storage\".\"expiry_date\" …" | db.statement: "\n\nSELECT\n \"jwt_refresh_storage\".\"refresh_token_hash\",\n \"jwt_refresh_storage\".\"user_id\",\n \"jwt_refresh_storage\".\"expiry_date\"\nFROM\n \"jwt_refresh_storage\"\nWHERE\n \"jwt_refresh_storage\".\"refresh_token_hash\" = ?\nLIMIT\n ?\n" | rows_affected: 1 | rows_returned: 1 | elapsed: 77.384µs�[0m
�[2mserver # [ 12.883118] lldap-start[957]: 2024-09-16T01:30:00.824743506+00:00 DEBUG 🐛 [debug]: | summary: "INSERT INTO \"jwt_storage\" (\"jwt_hash\", …" | db.statement: "\n\nINSERT INTO\n \"jwt_storage\" (\n \"jwt_hash\",\n \"user_id\",\n \"expiry_date\",\n \"blacklisted\"\n )\nVALUES\n (?, ?, ?, ?)\n" | rows_affected: 1 | rows_returned: 0 | elapsed: 2.386895ms�[0m
�[2mserver # [ 12.885783] lldap-start[957]: 2024-09-16T01:30:00.824979570+00:00 DEBUG 🐛 [debug]: | summary: "SELECT \"jwt_storage\".\"jwt_hash\", \"jwt_storage\".\"user_id\", \"jwt_storage\".\"expiry_date\", …" | db.statement: "\n\nSELECT\n \"jwt_storage\".\"jwt_hash\",\n \"jwt_storage\".\"user_id\",\n \"jwt_storage\".\"expiry_date\",\n \"jwt_storage\".\"blacklisted\"\nFROM\n \"jwt_storage\"\nWHERE\n \"jwt_storage\".\"jwt_hash\" = ?\nLIMIT\n ?\n" | rows_affected: 1 | rows_returned: 1 | elapsed: 100.012µs�[0m
�[2mserver # [ 12.889810] lldap-start[957]: 2024-09-16T01:30:00.731303697+00:00 DEBUG HTTP request [ 91.6ms | 0.12% / 100.00% ] method: "POST" | uri: "/auth/simple/login"�[0m
�[2mserver # [ 12.891283] lldap-start[957]: 2024-09-16T01:30:00.731353145+00:00 DEBUG ┝━ simple_login [ 91.5ms | 0.11% / 99.88% ]�[0m
�[2mserver # [ 12.892316] lldap-start[957]: 2024-09-16T01:30:00.731373539+00:00 DEBUG │ ┝━ bind [ 67.3ms | 0.05% / 73.46% ]�[0m
�[2mserver # [ 12.893271] lldap-start[957]: 2024-09-16T01:30:00.731388345+00:00 DEBUG │ │ ┝━ get_password_file_for_user [ 2.81ms | 3.07% ] user_id: UserId(CaseInsensitiveString("admin"))�[0m
(finished: must succeed: curl -f -s -X POST -H "Content-type: application/json" -H "Host: ldap.example.com" http://server/auth/simple/login -d '{"username": "admin", "password": "securepw"}' , in 0.13 seconds)
client: �[1m�[32mmust succeed: curl -f -s -X POST -H "Content-type: application/json" -H "Host: ldap.example.com" -H "Authorization: Bearer eyJhbGciOiJIUzUxMiJ9.eyJleHAiOiIyMDI0LTA5LTE3VDAxOjMwOjAwLjgxNzg0MjM1MFoiLCJpYXQiOiIyMDI0LTA5LTE2VDAxOjMwOjAwLjgxNzg0NzM3OFoiLCJ1c2VyIjoiYWRtaW4iLCJncm91cHMiOlsibGxkYXBfYWRtaW4iXX0.-V3NI2XpTHJwjhp7qvpzs2o3UxEdqKumrwKVrasp1yXKPdO9Uo2V4VJEP6taI9QjO1h29DtXcWXtQDYCMNuBbQ" http://server/api/graphql -d '{"variables": {"id": "admin"}, "query":"query($id:String!){user(userId:$id){displayName groups{displayName}}}"}' �[0m
�[2mserver # [ 12.894651] lldap-start[957]: 2024-09-16T01:30:00.734212168+00:00 DEBUG │ │ ┕━ passwords_match [ 64.5ms | 70.35% ] username: admin�[0m
�[2mserver # [ 12.895671] lldap-start[957]: 2024-09-16T01:30:00.798694385+00:00 DEBUG │ ┕━ get_login_successful_response [ 24.1ms | 5.15% / 26.31% ]�[0m
�[2mserver # [ 12.896790] lldap-start[957]: 2024-09-16T01:30:00.798841890+00:00 DEBUG │ ┝━ get_user_groups [ 11.8ms | 12.90% ] user_id: "admin"�[0m
�[2mserver # [ 12.897894] lldap-start[957]: 2024-09-16T01:30:00.810798438+00:00 DEBUG │ │ ┕━ 🐛 [debug]: | return: {GroupDetails { group_id: GroupId(1), display_name: GroupName("lldap_admin"), creation_date: 2024-09-16T01:30:00.088705546, uuid: Uuid("68f04c8c-c6b8-3b56-9e77-8a6c0f4f0de4"), attributes: [] }}�[0m
�[2mserver # [ 12.900128] lldap-start[957]: 2024-09-16T01:30:00.810881130+00:00 DEBUG │ ┝━ create_refresh_token [ 632µs | 0.69% ]�[0m
�[2mserver # [ 12.901139] lldap-start[957]: 2024-09-16T01:30:00.810899009+00:00 DEBUG │ │ ┕━ 🐛 [debug]: | user: UserId(CaseInsensitiveString("admin"))�[0m
�[2mserver # [ 12.902331] lldap-start[957]: 2024-09-16T01:30:00.821947061+00:00 DEBUG │ ┕━ register_jwt [ 6.94ms | 7.57% ]�[0m
�[2mserver # [ 12.903295] lldap-start[957]: 2024-09-16T01:30:00.821969410+00:00 DEBUG │ ┕━ 🐛 [debug]: | user: UserId(CaseInsensitiveString("admin")) | jwt_hash: 5097530389898270999�[0m
�[2mserver # [ 12.904659] lldap-start[957]: 2024-09-16T01:30:00.831381780+00:00 DEBUG ┕━ 🐛 [debug]: | status_code: 200�[0m
�[2mserver # [ 12.921981] lldap-start[957]: 2024-09-16T01:30:00.863814114+00:00 DEBUG 🐛 [debug]: | summary: "SELECT \"user_attribute_schema\".\"user_attribute_schema_name\", \"user_attribute_schema\".\"user_attribute_schema_type\", \"user_attribute_schema\".\"user_attribute_schema_is_list\", …" | db.statement: "\n\nSELECT\n \"user_attribute_schema\".\"user_attribute_schema_name\",\n \"user_attribute_schema\".\"user_attribute_schema_type\",\n \"user_attribute_schema\".\"user_attribute_schema_is_list\",\n \"user_attribute_schema\".\"user_attribute_schema_is_user_visible\",\n \"user_attribute_schema\".\"user_attribute_schema_is_user…
�[2mserver # [ 12.929177] lldap-start[957]: 2024-09-16T01:30:00.870803829+00:00 DEBUG 🐛 [debug]: | summary: "SELECT \"group_attribute_schema\".\"group_attribute_schema_name\", \"group_attribute_schema\".\"group_attribute_schema_type\", \"group_attribute_schema\".\"group_attribute_schema_is_list\", …" | db.statement: "\n\nSELECT\n \"group_attribute_schema\".\"group_attribute_schema_name\",\n \"group_attribute_schema\".\"group_attribute_schema_type\",\n \"group_attribute_schema\".\"group_attribute_schema_is_list\",\n \"group_attribute_schema\".\"group_attribute_schema_is_group_visible\",\n \"group_attribute_schema\".\"group_attribu…
�[2mserver # [ 12.935992] lldap-start[957]: 2024-09-16T01:30:00.870992401+00:00 DEBUG 🐛 [debug]: | summary: "SELECT \"user_object_classes\".\"lower_object_class\", \"user_object_classes\".\"object_class\" FROM …" | db.statement: "\n\nSELECT\n \"user_object_classes\".\"lower_object_class\",\n \"user_object_classes\".\"object_class\"\nFROM\n \"user_object_classes\"\nORDER BY\n \"user_object_classes\".\"object_class\" ASC\n" | rows_affected: 1 | rows_returned: 0 | elapsed: 62.019µs�[0m
�[2mserver # [ 12.939844] lldap-start[957]: 2024-09-16T01:30:00.877708338+00:00 DEBUG 🐛 [debug]: | summary: "SELECT \"group_object_classes\".\"lower_object_class\", \"group_object_classes\".\"object_class\" FROM …" | db.statement: "\n\nSELECT\n \"group_object_classes\".\"lower_object_class\",\n \"group_object_classes\".\"object_class\"\nFROM\n \"group_object_classes\"\nORDER BY\n \"group_object_classes\".\"object_class\" ASC\n" | rows_affected: 1 | rows_returned: 0 | elapsed: 26.261µs�[0m
�[2mserver # [ 12.943664] lldap-start[957]: 2024-09-16T01:30:00.885428872+00:00 DEBUG 🐛 [debug]: | summary: "SELECT \"users\".\"user_id\", \"users\".\"email\", \"users\".\"lowercase_email\", …" | db.statement: "\n\nSELECT\n \"users\".\"user_id\",\n \"users\".\"email\",\n \"users\".\"lowercase_email\",\n \"users\".\"display_name\",\n \"users\".\"creation_date\",\n \"users\".\"password_hash\",\n \"users\".\"totp_secret\",\n \"users\".\"mfa_type\",\n \"users\".\"uuid\"\nFROM\n \"users\"\nWHERE\n \"users\".\"user_id\" = ?\nLIMIT\n ?\n" | rows_affected: 1 | rows_returned: 1 | elapsed: 52.521µs�[0m
�[2mserver # [ 12.948399] lldap-start[957]: 2024-09-16T01:30:00.890118854+00:00 DEBUG 🐛 [debug]: | summary: "SELECT \"user_attributes\".\"user_attribute_user_id\", \"user_attributes\".\"user_attribute_name\", \"user_attributes\".\"user_attribute_value\" …" | db.statement: "\n\nSELECT\n \"user_attributes\".\"user_attribute_user_id\",\n \"user_attributes\".\"user_attribute_name\",\n \"user_attributes\".\"user_attribute_value\"\nFROM\n \"user_attributes\"\nWHERE\n \"user_attributes\".\"user_attribute_user_id\" = ?\nORDER BY\n \"user_attributes\".\"user_attribute_name\" ASC\n" | rows_affected: 1 | rows_returned: 0 | elapsed: 92.191µ…
�[2mserver # [ 12.954070] lldap-start[957]: 2024-09-16T01:30:00.895820137+00:00 DEBUG 🐛 [debug]: | summary: "SELECT \"users\".\"user_id\", \"users\".\"email\", \"users\".\"lowercase_email\", …" | db.statement: "\n\nSELECT\n \"users\".\"user_id\",\n \"users\".\"email\",\n \"users\".\"lowercase_email\",\n \"users\".\"display_name\",\n \"users\".\"creation_date\",\n \"users\".\"password_hash\",\n \"users\".\"totp_secret\",\n \"users\".\"mfa_type\",\n \"users\".\"uuid\"\nFROM\n \"users\"\nWHERE\n \"users\".\"user_id\" = ?\nLIMIT\n ?\n" | rows_affected: 1 | rows_returned: 1 | elapsed: 91.073µs�[0m
�[2mserver # [ 12.959122] lldap-start[957]: 2024-09-16T01:30:00.900802893+00:00 DEBUG 🐛 [debug]: | summary: "SELECT \"groups\".\"group_id\", \"groups\".\"display_name\", \"groups\".\"lowercase_display_name\", …" | db.statement: "\n\nSELECT\n \"groups\".\"group_id\",\n \"groups\".\"display_name\",\n \"groups\".\"lowercase_display_name\",\n \"groups\".\"creation_date\",\n \"groups\".\"uuid\"\nFROM\n \"groups\"\n INNER JOIN \"memberships\" AS \"r0\" ON \"r0\".\"group_id\" = \"groups\".\"group_id\"\n INNER JOIN \"users\" AS \"r1\" ON \"r1\".\"user_id\" = \"r0\".\"user_id\"\nWHERE\n \"r1\".\"user_id\" = ?\n" | rows_affected: 1 | r…
�[2mserver # [ 12.965061] lldap-start[957]: 2024-09-16T01:30:00.859115752+00:00 DEBUG HTTP request [ 33.4ms | 32.68% / 100.00% ] method: "POST" | uri: "/api/graphql"�[0m
�[2mserver # [ 12.966556] lldap-start[957]: 2024-09-16T01:30:00.859266050+00:00 DEBUG ┝━ check_if_token_is_valid [ 256µs | 0.77% ]�[0m
(finished: must succeed: curl -f -s -X POST -H "Content-type: application/json" -H "Host: ldap.example.com" -H "Authorization: Bearer eyJhbGciOiJIUzUxMiJ9.eyJleHAiOiIyMDI0LTA5LTE3VDAxOjMwOjAwLjgxNzg0MjM1MFoiLCJpYXQiOiIyMDI0LTA5LTE2VDAxOjMwOjAwLjgxNzg0NzM3OFoiLCJ1c2VyIjoiYWRtaW4iLCJncm91cHMiOlsibGxkYXBfYWRtaW4iXX0.-V3NI2XpTHJwjhp7qvpzs2o3UxEdqKumrwKVrasp1yXKPdO9Uo2V4VJEP6taI9QjO1h29DtXcWXtQDYCMNuBbQ" http://server/api/graphql -d '{"variables": {"id": "admin"}, "query":"query($id:String!){user(userId:$id){displayName groups{displayName}}}"}' , in 0.08 seconds)
�[2mserver # [ 12.967741] lldap-start[957]: 2024-09-16T01:30:00.859525580+00:00 DEBUG │ ┕━ 🐛 [debug]: | return: ValidationResults { user: UserId(CaseInsensitiveString("admin")), permission: Admin }�[0m
(finished: subtest: succeed with correct authentication, in 0.20 seconds)
�[2mserver # [ 12.969379] lldap-start[957]: 2024-09-16T01:30:00.863269632+00:00 DEBUG ┝━ [GraphQL query] user [ 17.7ms | 2.67% / 52.87% ]�[0m
(finished: run the VM test script, in 13.65 seconds)
�[2mserver # [ 12.970451] lldap-start[957]: 2024-09-16T01:30:00.863282762+00:00 DEBUG │ ┝━ 🐛 [debug]: | user_id: "admin"�[0m
�[2mserver # [ 12.971415] lldap-start[957]: 2024-09-16T01:30:00.878058103+00:00 DEBUG │ ┕━ get_user_details [ 16.8ms | 50.19% ] user_id: "admin"�[0m
�[2mserver # [ 12.972571] lldap-start[957]: 2024-09-16T01:30:00.894842080+00:00 DEBUG │ ┕━ 🐛 [debug]: | return: Ok(User { user_id: UserId(CaseInsensitiveString("admin")), email: Email("admin@example.com"), display_name: Some("Administrator"), creation_date: 2024-09-16T01:30:00.234828587, uuid: Uuid("0fe5e8ec-333c-3957-abf0-6df66ae3058b"), attributes: [] })�[0m
�[2mserver # [ 12.975350] lldap-start[957]: 2024-09-16T01:30:00.895522055+00:00 DEBUG ┝━ [GraphQL query] user::groups [ 4.57ms | 0.16% / 13.68% ]�[0m
�[2mserver # [ 12.976532] lldap-start[957]: 2024-09-16T01:30:00.895535744+00:00 DEBUG │ ┝━ 🐛 [debug]: | user_id: UserId(CaseInsensitiveString("admin"))�[0m
�[2mserver # [ 12.977769] lldap-start[957]: 2024-09-16T01:30:00.895550550+00:00 DEBUG │ ┕━ get_user_groups [ 4.52ms | 13.52% ] user_id: "admin"�[0m
�[2mserver # [ 12.978953] lldap-start[957]: 2024-09-16T01:30:00.900864074+00:00 DEBUG │ ┕━ 🐛 [debug]: | return: {GroupDetails { group_id: GroupId(1), display_name: GroupName("lldap_admin"), creation_date: 2024-09-16T01:30:00.088705546, uuid: Uuid("68f04c8c-c6b8-3b56-9e77-8a6c0f4f0de4"), attributes: [] }}�[0m
�[2mserver # [ 12.981316] lldap-start[957]: 2024-09-16T01:30:00.906755885+00:00 DEBUG ┕━ 🐛 [debug]: | status_code: 200�[0m
test script finished in 13.70s
�[1m�[32mcleanup�[0m
kill machine (pid 8)
qemu-kvm: terminating on signal 15 from pid 5 (/nix/store/h3i0acpmr8mrjx07519xxmidv8mpax4y-python3-3.12.5/bin/python3.12)
kill machine (pid 29)
qemu-kvm: terminating on signal 15 from pid 5 (/nix/store/h3i0acpmr8mrjx07519xxmidv8mpax4y-python3-3.12.5/bin/python3.12)
(finished: cleanup, in 0.02 seconds)
kill vlan (pid 6)
Loading