--Task-- name: Verification-idm enabled: True class_name: VerificationTask source_name: idm source_namespace: >default< target_name: idm target_namespace: >default< start: 0.0 stop: None timeout: not yet initialized for this Task as timeout was not explicitly defined, see updates bellow loop: False interval: None dependencies: [] wait_for: [] preceding_task: None options: {} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-15 22:46:48] Verification-idm pre : N/A ________________________________________________________________________________ [2024-04-15 22:46:48] - INFO: Timeout for this Task has changed to 2h (7200 seconds) [2024-04-15 22:46:48] - INFO: timeout was set to Task default (2x global_duration or 300 seconds (whatever is higher)), because it was unset ________________________________________________________________________________ [2024-04-15 22:46:48] Verification-idm step1 : Wait until pods are in Running state and check number of running pods ________________________________________________________________________________ 2024-04-15 22:46:48,672 INFO 2024-04-15 22:46:48,673 INFO --------------------- Get expected number of pods --------------------- 2024-04-15 22:46:48,673 INFO 2024-04-15 22:46:48,674 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get deployment --selector app=idm --output jsonpath={.items[*].spec.replicas} 2024-04-15 22:46:48,674 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-15 22:46:49,745 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:46:49,749 DEBUG --- stdout --- 2024-04-15 22:46:49,749 DEBUG 2 2024-04-15 22:46:49,749 DEBUG --- stderr --- 2024-04-15 22:46:49,749 DEBUG 2024-04-15 22:46:49,750 INFO 2024-04-15 22:46:49,750 INFO -------------- Waiting for 2 expected pod(s) to be ready -------------- 2024-04-15 22:46:49,755 INFO 2024-04-15 22:46:49,755 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get deployment idm --output jsonpath="ready:{.status.readyReplicas} replicas:{.status.replicas}" | grep "ready:2 replicas:2" 2024-04-15 22:46:49,755 INFO [loop_until]: (max_time=900, interval=30, expected_rc=[0] 2024-04-15 22:46:50,624 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-15 22:46:50,624 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:46:50,624 DEBUG --- stdout --- 2024-04-15 22:46:50,624 DEBUG ready:2 replicas:2 2024-04-15 22:46:50,624 DEBUG --- stderr --- 2024-04-15 22:46:50,624 DEBUG ________________________________________________________________________________ [2024-04-15 22:46:50] Verification-idm step2 : Discovering pods ________________________________________________________________________________ 2024-04-15 22:46:50,625 INFO 2024-04-15 22:46:50,626 INFO --------------------- Get expected number of pods --------------------- 2024-04-15 22:46:50,626 INFO 2024-04-15 22:46:50,626 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get deployment --selector app=idm --output jsonpath={.items[*].spec.replicas} 2024-04-15 22:46:50,626 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-15 22:46:51,564 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:46:51,564 DEBUG --- stdout --- 2024-04-15 22:46:51,565 DEBUG 2 2024-04-15 22:46:51,565 DEBUG --- stderr --- 2024-04-15 22:46:51,565 DEBUG 2024-04-15 22:46:51,565 INFO 2024-04-15 22:46:51,565 INFO ---------------------------- Get pod list ---------------------------- 2024-04-15 22:46:51,566 INFO 2024-04-15 22:46:51,566 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=idm --output jsonpath={.items[*].metadata.name}` | grep 2 2024-04-15 22:46:51,566 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:46:52,459 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:46:52,459 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:46:52,460 DEBUG --- stdout --- 2024-04-15 22:46:52,460 DEBUG idm-b85f9d978-j5pbm idm-b85f9d978-rl76f 2024-04-15 22:46:52,460 DEBUG --- stderr --- 2024-04-15 22:46:52,460 DEBUG ________________________________________________________________________________ [2024-04-15 22:46:52] Verification-idm step3 : Check component is alive ________________________________________________________________________________ 2024-04-15 22:46:52,460 INFO Livecheck to https://xlou.iam.xlou-cdm.engineeringpit.com/openidm/info/ping 2024-04-15 22:46:52,460 INFO Set admin password: 1UXqmgfePB4NHghbGcuasRXC 2024-04-15 22:46:52,461 INFO [http_cmd]: curl -L --request GET "https://xlou.iam.xlou-cdm.engineeringpit.com/openidm/info/ping" 2024-04-15 22:46:52,706 INFO [http_cmd]: http status code OK 2024-04-15 22:46:52,706 DEBUG --- status code --- 2024-04-15 22:46:52,707 DEBUG http status code is 200 (expected 200) 2024-04-15 22:46:52,707 DEBUG --- http response --- 2024-04-15 22:46:52,707 DEBUG { "_id": "", "_rev": "", "shortDesc": "OpenIDM ready", "state": "ACTIVE_READY" } 2024-04-15 22:46:52,707 INFO [LoginSession] Get "access_token" token for user "amadmin" 2024-04-15 22:46:52,707 DEBUG [LoginSession] re-using "access_token" token that remains valid for 18m 47s 2024-04-15 22:46:52,707 INFO Additional check to sync/queue to ensure sync with DS is ok (see LODESTAR-1245) 2024-04-15 22:46:52,708 INFO [http_cmd]: curl -L --request GET "https://xlou.iam.xlou-cdm.engineeringpit.com/openidm/sync/queue?_queryFilter=true&_pageSize=1" 2024-04-15 22:46:52,965 INFO [http_cmd]: http status code OK 2024-04-15 22:46:52,965 DEBUG --- status code --- 2024-04-15 22:46:52,965 DEBUG http status code is 200 (expected 200) 2024-04-15 22:46:52,965 DEBUG --- http response --- 2024-04-15 22:46:52,966 DEBUG { "result": [], "resultCount": 0, "pagedResultsCookie": null, "totalPagedResultsPolicy": "NONE", "totalPagedResults": -1, "remainingPagedResults": -1 } 2024-04-15 22:46:52,967 INFO Try to create, query and delete a user 2024-04-15 22:46:52,968 INFO Create user idm_08zxcyfw4c in IDM 2024-04-15 22:46:52,969 INFO [http_cmd]: curl --header "Authorization: Bearer 8qg7Tnf1e3DnLsV0hX2yI0kzga8" --header "Content-Type: application/json" --insecure -L --request POST --data '{"userName": "idm_08zxcyfw4c", "givenName": "Peter", "sn": "Parker", "password": "T35tr0ck123", "telephoneNumber": "6669876987", "description": "IDM Test User", "roles": [], "mail": "idm_08zxcyfw4c@forgerock.com"}' "https://xlou.iam.xlou-cdm.engineeringpit.com/openidm/managed/user?_action=create" 2024-04-15 22:46:53,226 INFO [http_cmd]: http status code OK 2024-04-15 22:46:53,229 DEBUG --- status code --- 2024-04-15 22:46:53,229 DEBUG http status code is 201 (expected [200, 201]) 2024-04-15 22:46:53,237 DEBUG --- http response --- 2024-04-15 22:46:53,239 DEBUG [print_head_tail]: Print head (10 lines) and tail (10 lines) of input string (34 lines): 2024-04-15 22:46:53,239 DEBUG ----- output ----- 2024-04-15 22:46:53,239 DEBUG { 2024-04-15 22:46:53,239 DEBUG "_id": "d345ee6e-1187-4644-aa6f-ec75b684924a", 2024-04-15 22:46:53,239 DEBUG "_rev": "d2dd67f6-c775-4646-8c43-6112461729ba-1069415", 2024-04-15 22:46:53,239 DEBUG "country": null, 2024-04-15 22:46:53,239 DEBUG "mail": "idm_08zxcyfw4c@forgerock.com", 2024-04-15 22:46:53,239 DEBUG "memberOfOrgIDs": [], 2024-04-15 22:46:53,240 DEBUG "postalCode": null, 2024-04-15 22:46:53,240 DEBUG "profileImage": null, 2024-04-15 22:46:53,240 DEBUG "expireAccount": null, 2024-04-15 22:46:53,240 DEBUG "activeDate": null, 2024-04-15 22:46:53,240 DEBUG 2024-04-15 22:46:53,240 DEBUG 2024-04-15 22:46:53,240 DEBUG 2024-04-15 22:46:53,240 DEBUG [...] 2024-04-15 22:46:53,240 DEBUG 2024-04-15 22:46:53,240 DEBUG 2024-04-15 22:46:53,240 DEBUG 2024-04-15 22:46:53,240 DEBUG "aliasList": [], 2024-04-15 22:46:53,240 DEBUG "kbaInfo": [], 2024-04-15 22:46:53,240 DEBUG "inactiveDate": null, 2024-04-15 22:46:53,240 DEBUG "sn": "Parker", 2024-04-15 22:46:53,240 DEBUG "preferences": null, 2024-04-15 22:46:53,240 DEBUG "userName": "idm_08zxcyfw4c", 2024-04-15 22:46:53,241 DEBUG "passwordLastChangedTime": "2024-04-15T22:46:53.135Z", 2024-04-15 22:46:53,241 DEBUG "effectiveRoles": [], 2024-04-15 22:46:53,241 DEBUG "activateAccount": null 2024-04-15 22:46:53,241 DEBUG } 2024-04-15 22:46:53,242 INFO Query IDM to verify UserID d345ee6e-1187-4644-aa6f-ec75b684924a is visible 2024-04-15 22:46:53,244 INFO [http_cmd]: curl --insecure -L --request GET "https://xlou.iam.xlou-cdm.engineeringpit.com/openidm/managed/user/d345ee6e-1187-4644-aa6f-ec75b684924a" 2024-04-15 22:46:53,468 INFO [http_cmd]: http status code OK 2024-04-15 22:46:53,468 DEBUG --- status code --- 2024-04-15 22:46:53,468 DEBUG http status code is 200 (expected 200) 2024-04-15 22:46:53,469 DEBUG --- http response --- 2024-04-15 22:46:53,469 DEBUG [print_head_tail]: Print head (10 lines) and tail (10 lines) of input string (34 lines): 2024-04-15 22:46:53,469 DEBUG ----- output ----- 2024-04-15 22:46:53,469 DEBUG { 2024-04-15 22:46:53,469 DEBUG "_id": "d345ee6e-1187-4644-aa6f-ec75b684924a", 2024-04-15 22:46:53,469 DEBUG "_rev": "d2dd67f6-c775-4646-8c43-6112461729ba-1069415", 2024-04-15 22:46:53,469 DEBUG "country": null, 2024-04-15 22:46:53,469 DEBUG "mail": "idm_08zxcyfw4c@forgerock.com", 2024-04-15 22:46:53,469 DEBUG "memberOfOrgIDs": [], 2024-04-15 22:46:53,469 DEBUG "postalCode": null, 2024-04-15 22:46:53,469 DEBUG "profileImage": null, 2024-04-15 22:46:53,469 DEBUG "expireAccount": null, 2024-04-15 22:46:53,469 DEBUG "activeDate": null, 2024-04-15 22:46:53,469 DEBUG 2024-04-15 22:46:53,469 DEBUG 2024-04-15 22:46:53,469 DEBUG 2024-04-15 22:46:53,469 DEBUG [...] 2024-04-15 22:46:53,469 DEBUG 2024-04-15 22:46:53,469 DEBUG 2024-04-15 22:46:53,470 DEBUG 2024-04-15 22:46:53,470 DEBUG "aliasList": [], 2024-04-15 22:46:53,470 DEBUG "kbaInfo": [], 2024-04-15 22:46:53,470 DEBUG "inactiveDate": null, 2024-04-15 22:46:53,470 DEBUG "sn": "Parker", 2024-04-15 22:46:53,470 DEBUG "preferences": null, 2024-04-15 22:46:53,470 DEBUG "userName": "idm_08zxcyfw4c", 2024-04-15 22:46:53,470 DEBUG "passwordLastChangedTime": "2024-04-15T22:46:53.135Z", 2024-04-15 22:46:53,470 DEBUG "effectiveRoles": [], 2024-04-15 22:46:53,470 DEBUG "activateAccount": null 2024-04-15 22:46:53,470 DEBUG } 2024-04-15 22:46:53,471 INFO Authenticate as user idm_08zxcyfw4c 2024-04-15 22:46:53,472 INFO [http_cmd]: curl --insecure -L --request POST "https://xlou.iam.xlou-cdm.engineeringpit.com/openidm/authentication?_action=login" 2024-04-15 22:46:53,685 INFO [http_cmd]: http status code OK 2024-04-15 22:46:53,685 DEBUG --- status code --- 2024-04-15 22:46:53,685 DEBUG http status code is 200 (expected 200) 2024-04-15 22:46:53,685 DEBUG --- http response --- 2024-04-15 22:46:53,686 DEBUG { "_id": "login", "authorization": { "id": "anonymous", "roles": [ "internal/role/openidm-reg" ], "component": "internal/user" }, "authenticationId": "anonymous" } 2024-04-15 22:46:53,689 INFO [http_cmd]: curl --insecure -L --request DELETE "https://xlou.iam.xlou-cdm.engineeringpit.com/openidm/managed/user/d345ee6e-1187-4644-aa6f-ec75b684924a" 2024-04-15 22:46:53,919 INFO [http_cmd]: http status code OK 2024-04-15 22:46:53,919 DEBUG --- status code --- 2024-04-15 22:46:53,919 DEBUG http status code is 200 (expected 200) 2024-04-15 22:46:53,919 DEBUG --- http response --- 2024-04-15 22:46:53,920 DEBUG [print_head_tail]: Print head (10 lines) and tail (10 lines) of input string (34 lines): 2024-04-15 22:46:53,920 DEBUG ----- output ----- 2024-04-15 22:46:53,920 DEBUG { 2024-04-15 22:46:53,920 DEBUG "_id": "d345ee6e-1187-4644-aa6f-ec75b684924a", 2024-04-15 22:46:53,920 DEBUG "_rev": "d2dd67f6-c775-4646-8c43-6112461729ba-1069415", 2024-04-15 22:46:53,920 DEBUG "country": null, 2024-04-15 22:46:53,920 DEBUG "mail": "idm_08zxcyfw4c@forgerock.com", 2024-04-15 22:46:53,920 DEBUG "memberOfOrgIDs": [], 2024-04-15 22:46:53,920 DEBUG "postalCode": null, 2024-04-15 22:46:53,920 DEBUG "profileImage": null, 2024-04-15 22:46:53,920 DEBUG "expireAccount": null, 2024-04-15 22:46:53,920 DEBUG "activeDate": null, 2024-04-15 22:46:53,920 DEBUG 2024-04-15 22:46:53,920 DEBUG 2024-04-15 22:46:53,920 DEBUG 2024-04-15 22:46:53,920 DEBUG [...] 2024-04-15 22:46:53,920 DEBUG 2024-04-15 22:46:53,920 DEBUG 2024-04-15 22:46:53,920 DEBUG 2024-04-15 22:46:53,920 DEBUG "aliasList": [], 2024-04-15 22:46:53,920 DEBUG "kbaInfo": [], 2024-04-15 22:46:53,920 DEBUG "inactiveDate": null, 2024-04-15 22:46:53,920 DEBUG "sn": "Parker", 2024-04-15 22:46:53,920 DEBUG "preferences": null, 2024-04-15 22:46:53,920 DEBUG "userName": "idm_08zxcyfw4c", 2024-04-15 22:46:53,920 DEBUG "passwordLastChangedTime": "2024-04-15T22:46:53.135Z", 2024-04-15 22:46:53,920 DEBUG "effectiveRoles": [], 2024-04-15 22:46:53,920 DEBUG "activateAccount": null 2024-04-15 22:46:53,920 DEBUG } PASS : component idm is alive ________________________________________________________________________________ [2024-04-15 22:46:53] Verification-idm step4 : Record version ________________________________________________________________________________ 2024-04-15 22:46:53,923 INFO 2024-04-15 22:46:53,923 INFO ---------------------- Get IDM software version ---------------------- 2024-04-15 22:46:53,923 INFO Getting product version from https://xlou.iam.xlou-cdm.engineeringpit.com/openidm/info/version 2024-04-15 22:46:53,924 INFO [http_cmd]: curl -L --request GET "https://xlou.iam.xlou-cdm.engineeringpit.com/openidm/info/version" 2024-04-15 22:46:54,138 INFO [http_cmd]: http status code OK 2024-04-15 22:46:54,139 DEBUG --- status code --- 2024-04-15 22:46:54,139 DEBUG http status code is 200 (expected 200) 2024-04-15 22:46:54,139 DEBUG --- http response --- 2024-04-15 22:46:54,139 DEBUG { "_id": "version", "productVersion": "7.6.0-SNAPSHOT", "productBuildDate": "20240411202127", "productRevision": "8554e0d1c5" } Version is 7.6.0-SNAPSHOT Revision is 8554e0d1c5 ________________________________________________________________________________ [2024-04-15 22:46:54] Verification-idm step5 : Checking pod restart counts ________________________________________________________________________________ 2024-04-15 22:46:54,143 INFO 2024-04-15 22:46:54,143 INFO ------------- Check pod idm-b85f9d978-j5pbm restart count ------------- 2024-04-15 22:46:54,144 INFO 2024-04-15 22:46:54,144 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pod idm-b85f9d978-j5pbm --output jsonpath={.status.containerStatuses[*].restartCount} 2024-04-15 22:46:54,144 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-15 22:46:54,700 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:46:54,700 DEBUG --- stdout --- 2024-04-15 22:46:54,700 DEBUG 0 2024-04-15 22:46:54,700 DEBUG --- stderr --- 2024-04-15 22:46:54,700 DEBUG 2024-04-15 22:46:54,700 INFO Pod idm-b85f9d978-j5pbm has been restarted 0 times. SUCCESS : pod restart count (0) did not change between validation and verification phases 2024-04-15 22:46:54,700 INFO 2024-04-15 22:46:54,700 INFO ------------- Check pod idm-b85f9d978-rl76f restart count ------------- 2024-04-15 22:46:54,701 INFO 2024-04-15 22:46:54,701 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pod idm-b85f9d978-rl76f --output jsonpath={.status.containerStatuses[*].restartCount} 2024-04-15 22:46:54,701 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-15 22:46:55,358 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:46:55,358 DEBUG --- stdout --- 2024-04-15 22:46:55,358 DEBUG 0 2024-04-15 22:46:55,358 DEBUG --- stderr --- 2024-04-15 22:46:55,359 DEBUG 2024-04-15 22:46:55,359 INFO Pod idm-b85f9d978-rl76f has been restarted 0 times. SUCCESS : pod restart count (0) did not change between validation and verification phases ________________________________________________________________________________ [2024-04-15 22:46:55] Verification-idm post : Post method ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped