--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-25 17:42:22] Verification-idm pre : N/A ________________________________________________________________________________ [2024-04-25 17:42:22] - INFO: Timeout for this Task has changed to 5m (300 seconds) [2024-04-25 17:42:22] - INFO: timeout was set to Task default (2x global_duration or 300 seconds (whatever is higher)), because it was unset ________________________________________________________________________________ [2024-04-25 17:42:22] Verification-idm step1 : Wait until pods are in Running state and check number of running pods ________________________________________________________________________________ 2024-04-25 17:42:22,096 INFO 2024-04-25 17:42:22,097 INFO --------------------- Get expected number of pods --------------------- 2024-04-25 17:42:22,098 INFO 2024-04-25 17:42:22,098 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-25 17:42:22,098 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-25 17:42:23,176 INFO [loop_until]: OK (rc = 0) 2024-04-25 17:42:23,177 DEBUG --- stdout --- 2024-04-25 17:42:23,177 DEBUG 2 2024-04-25 17:42:23,177 DEBUG --- stderr --- 2024-04-25 17:42:23,177 DEBUG 2024-04-25 17:42:23,178 INFO 2024-04-25 17:42:23,178 INFO -------------- Waiting for 2 expected pod(s) to be ready -------------- 2024-04-25 17:42:23,178 INFO 2024-04-25 17:42:23,182 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-25 17:42:23,182 INFO [loop_until]: (max_time=900, interval=30, expected_rc=[0] 2024-04-25 17:42:24,101 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-25 17:42:24,102 INFO [loop_until]: OK (rc = 0) 2024-04-25 17:42:24,102 DEBUG --- stdout --- 2024-04-25 17:42:24,102 DEBUG ready:2 replicas:2 2024-04-25 17:42:24,102 DEBUG --- stderr --- 2024-04-25 17:42:24,102 DEBUG ________________________________________________________________________________ [2024-04-25 17:42:24] Verification-idm step2 : Discovering pods ________________________________________________________________________________ 2024-04-25 17:42:24,104 INFO 2024-04-25 17:42:24,104 INFO --------------------- Get expected number of pods --------------------- 2024-04-25 17:42:24,104 INFO 2024-04-25 17:42:24,104 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-25 17:42:24,104 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-25 17:42:25,102 INFO [loop_until]: OK (rc = 0) 2024-04-25 17:42:25,102 DEBUG --- stdout --- 2024-04-25 17:42:25,102 DEBUG 2 2024-04-25 17:42:25,102 DEBUG --- stderr --- 2024-04-25 17:42:25,102 DEBUG 2024-04-25 17:42:25,102 INFO 2024-04-25 17:42:25,102 INFO ---------------------------- Get pod list ---------------------------- 2024-04-25 17:42:25,103 INFO 2024-04-25 17:42:25,103 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-25 17:42:25,103 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-25 17:42:25,709 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-25 17:42:25,709 INFO [loop_until]: OK (rc = 0) 2024-04-25 17:42:25,709 DEBUG --- stdout --- 2024-04-25 17:42:25,710 DEBUG idm-6c95d7588f-lrdb9 idm-6c95d7588f-n2wtz 2024-04-25 17:42:25,710 DEBUG --- stderr --- 2024-04-25 17:42:25,710 DEBUG ________________________________________________________________________________ [2024-04-25 17:42:25] Verification-idm step3 : Check component is alive ________________________________________________________________________________ 2024-04-25 17:42:25,710 INFO Livecheck to https://xlou.iam.xlou-cdm.engineeringpit.com/openidm/info/ping 2024-04-25 17:42:25,710 INFO Set admin password: 5mJPuBy5SKQArBAWsyeAFp1l 2024-04-25 17:42:25,711 INFO [http_cmd]: curl -L --request GET "https://xlou.iam.xlou-cdm.engineeringpit.com/openidm/info/ping" 2024-04-25 17:42:25,961 INFO [http_cmd]: http status code OK 2024-04-25 17:42:25,961 DEBUG --- status code --- 2024-04-25 17:42:25,961 DEBUG http status code is 200 (expected 200) 2024-04-25 17:42:25,961 DEBUG --- http response --- 2024-04-25 17:42:25,962 DEBUG { "_id": "", "_rev": "", "shortDesc": "OpenIDM ready", "state": "ACTIVE_READY" } 2024-04-25 17:42:25,962 INFO [LoginSession] Get "access_token" token for user "amadmin" 2024-04-25 17:42:25,962 DEBUG [LoginSession] re-using "access_token" token that remains valid for 40m 56s 2024-04-25 17:42:25,962 INFO Additional check to sync/queue to ensure sync with DS is ok (see LODESTAR-1245) 2024-04-25 17:42:25,963 INFO [http_cmd]: curl -L --request GET "https://xlou.iam.xlou-cdm.engineeringpit.com/openidm/sync/queue?_queryFilter=true&_pageSize=1" 2024-04-25 17:42:26,185 INFO [http_cmd]: http status code OK 2024-04-25 17:42:26,185 DEBUG --- status code --- 2024-04-25 17:42:26,185 DEBUG http status code is 200 (expected 200) 2024-04-25 17:42:26,185 DEBUG --- http response --- 2024-04-25 17:42:26,185 DEBUG { "result": [], "resultCount": 0, "pagedResultsCookie": null, "totalPagedResultsPolicy": "NONE", "totalPagedResults": -1, "remainingPagedResults": -1 } 2024-04-25 17:42:26,186 INFO Try to create, query and delete a user 2024-04-25 17:42:26,187 INFO Create user idm_ireb4nfbu5 in IDM 2024-04-25 17:42:26,187 INFO [http_cmd]: curl --header "Authorization: Bearer tS2OTAXTi0UAOEc_g9j8Wex-PGA" --header "Content-Type: application/json" --insecure -L --request POST --data '{"userName": "idm_ireb4nfbu5", "givenName": "Peter", "sn": "Parker", "password": "T35tr0ck123", "telephoneNumber": "6669876987", "description": "IDM Test User", "roles": [], "mail": "idm_ireb4nfbu5@forgerock.com"}' "https://xlou.iam.xlou-cdm.engineeringpit.com/openidm/managed/user?_action=create" 2024-04-25 17:42:26,493 INFO [http_cmd]: http status code OK 2024-04-25 17:42:26,493 DEBUG --- status code --- 2024-04-25 17:42:26,493 DEBUG http status code is 201 (expected [200, 201]) 2024-04-25 17:42:26,493 DEBUG --- http response --- 2024-04-25 17:42:26,494 DEBUG [print_head_tail]: Print head (10 lines) and tail (10 lines) of input string (34 lines): 2024-04-25 17:42:26,494 DEBUG ----- output ----- 2024-04-25 17:42:26,494 DEBUG { 2024-04-25 17:42:26,494 DEBUG "_id": "4df3a4a8-453d-4483-8ebc-bc21c777653a", 2024-04-25 17:42:26,494 DEBUG "_rev": "6f54a19c-d22f-42df-9991-6dcd72f78ec4-1000532", 2024-04-25 17:42:26,494 DEBUG "country": null, 2024-04-25 17:42:26,494 DEBUG "mail": "idm_ireb4nfbu5@forgerock.com", 2024-04-25 17:42:26,494 DEBUG "memberOfOrgIDs": [], 2024-04-25 17:42:26,494 DEBUG "postalCode": null, 2024-04-25 17:42:26,494 DEBUG "profileImage": null, 2024-04-25 17:42:26,494 DEBUG "expireAccount": null, 2024-04-25 17:42:26,494 DEBUG "activeDate": null, 2024-04-25 17:42:26,494 DEBUG 2024-04-25 17:42:26,494 DEBUG 2024-04-25 17:42:26,494 DEBUG 2024-04-25 17:42:26,494 DEBUG [...] 2024-04-25 17:42:26,494 DEBUG 2024-04-25 17:42:26,494 DEBUG 2024-04-25 17:42:26,494 DEBUG 2024-04-25 17:42:26,494 DEBUG "aliasList": [], 2024-04-25 17:42:26,494 DEBUG "kbaInfo": [], 2024-04-25 17:42:26,494 DEBUG "inactiveDate": null, 2024-04-25 17:42:26,494 DEBUG "sn": "Parker", 2024-04-25 17:42:26,494 DEBUG "preferences": null, 2024-04-25 17:42:26,494 DEBUG "userName": "idm_ireb4nfbu5", 2024-04-25 17:42:26,494 DEBUG "passwordLastChangedTime": "2024-04-25T17:42:26.330Z", 2024-04-25 17:42:26,494 DEBUG "effectiveRoles": [], 2024-04-25 17:42:26,494 DEBUG "activateAccount": null 2024-04-25 17:42:26,494 DEBUG } 2024-04-25 17:42:26,495 INFO Query IDM to verify UserID 4df3a4a8-453d-4483-8ebc-bc21c777653a is visible 2024-04-25 17:42:26,495 INFO [http_cmd]: curl --insecure -L --request GET "https://xlou.iam.xlou-cdm.engineeringpit.com/openidm/managed/user/4df3a4a8-453d-4483-8ebc-bc21c777653a" 2024-04-25 17:42:26,717 INFO [http_cmd]: http status code OK 2024-04-25 17:42:26,717 DEBUG --- status code --- 2024-04-25 17:42:26,717 DEBUG http status code is 200 (expected 200) 2024-04-25 17:42:26,717 DEBUG --- http response --- 2024-04-25 17:42:26,718 DEBUG [print_head_tail]: Print head (10 lines) and tail (10 lines) of input string (34 lines): 2024-04-25 17:42:26,718 DEBUG ----- output ----- 2024-04-25 17:42:26,718 DEBUG { 2024-04-25 17:42:26,718 DEBUG "_id": "4df3a4a8-453d-4483-8ebc-bc21c777653a", 2024-04-25 17:42:26,718 DEBUG "_rev": "6f54a19c-d22f-42df-9991-6dcd72f78ec4-1000532", 2024-04-25 17:42:26,718 DEBUG "country": null, 2024-04-25 17:42:26,718 DEBUG "mail": "idm_ireb4nfbu5@forgerock.com", 2024-04-25 17:42:26,718 DEBUG "memberOfOrgIDs": [], 2024-04-25 17:42:26,718 DEBUG "postalCode": null, 2024-04-25 17:42:26,718 DEBUG "profileImage": null, 2024-04-25 17:42:26,718 DEBUG "expireAccount": null, 2024-04-25 17:42:26,718 DEBUG "activeDate": null, 2024-04-25 17:42:26,718 DEBUG 2024-04-25 17:42:26,718 DEBUG 2024-04-25 17:42:26,718 DEBUG 2024-04-25 17:42:26,718 DEBUG [...] 2024-04-25 17:42:26,718 DEBUG 2024-04-25 17:42:26,718 DEBUG 2024-04-25 17:42:26,718 DEBUG 2024-04-25 17:42:26,719 DEBUG "aliasList": [], 2024-04-25 17:42:26,719 DEBUG "kbaInfo": [], 2024-04-25 17:42:26,719 DEBUG "inactiveDate": null, 2024-04-25 17:42:26,719 DEBUG "sn": "Parker", 2024-04-25 17:42:26,719 DEBUG "preferences": null, 2024-04-25 17:42:26,719 DEBUG "userName": "idm_ireb4nfbu5", 2024-04-25 17:42:26,719 DEBUG "passwordLastChangedTime": "2024-04-25T17:42:26.330Z", 2024-04-25 17:42:26,719 DEBUG "effectiveRoles": [], 2024-04-25 17:42:26,719 DEBUG "activateAccount": null 2024-04-25 17:42:26,719 DEBUG } 2024-04-25 17:42:26,720 INFO Authenticate as user idm_ireb4nfbu5 2024-04-25 17:42:26,720 INFO [http_cmd]: curl --insecure -L --request POST "https://xlou.iam.xlou-cdm.engineeringpit.com/openidm/authentication?_action=login" 2024-04-25 17:42:26,942 INFO [http_cmd]: http status code OK 2024-04-25 17:42:26,943 DEBUG --- status code --- 2024-04-25 17:42:26,943 DEBUG http status code is 200 (expected 200) 2024-04-25 17:42:26,943 DEBUG --- http response --- 2024-04-25 17:42:26,943 DEBUG { "_id": "login", "authorization": { "id": "anonymous", "roles": [ "internal/role/openidm-reg" ], "component": "internal/user" }, "authenticationId": "anonymous" } 2024-04-25 17:42:26,944 INFO [http_cmd]: curl --insecure -L --request DELETE "https://xlou.iam.xlou-cdm.engineeringpit.com/openidm/managed/user/4df3a4a8-453d-4483-8ebc-bc21c777653a" 2024-04-25 17:42:27,208 INFO [http_cmd]: http status code OK 2024-04-25 17:42:27,208 DEBUG --- status code --- 2024-04-25 17:42:27,208 DEBUG http status code is 200 (expected 200) 2024-04-25 17:42:27,208 DEBUG --- http response --- 2024-04-25 17:42:27,208 DEBUG [print_head_tail]: Print head (10 lines) and tail (10 lines) of input string (34 lines): 2024-04-25 17:42:27,208 DEBUG ----- output ----- 2024-04-25 17:42:27,208 DEBUG { 2024-04-25 17:42:27,208 DEBUG "_id": "4df3a4a8-453d-4483-8ebc-bc21c777653a", 2024-04-25 17:42:27,208 DEBUG "_rev": "6f54a19c-d22f-42df-9991-6dcd72f78ec4-1000532", 2024-04-25 17:42:27,208 DEBUG "country": null, 2024-04-25 17:42:27,209 DEBUG "mail": "idm_ireb4nfbu5@forgerock.com", 2024-04-25 17:42:27,209 DEBUG "memberOfOrgIDs": [], 2024-04-25 17:42:27,209 DEBUG "postalCode": null, 2024-04-25 17:42:27,209 DEBUG "profileImage": null, 2024-04-25 17:42:27,209 DEBUG "expireAccount": null, 2024-04-25 17:42:27,209 DEBUG "activeDate": null, 2024-04-25 17:42:27,209 DEBUG 2024-04-25 17:42:27,209 DEBUG 2024-04-25 17:42:27,209 DEBUG 2024-04-25 17:42:27,209 DEBUG [...] 2024-04-25 17:42:27,209 DEBUG 2024-04-25 17:42:27,209 DEBUG 2024-04-25 17:42:27,209 DEBUG 2024-04-25 17:42:27,209 DEBUG "aliasList": [], 2024-04-25 17:42:27,209 DEBUG "kbaInfo": [], 2024-04-25 17:42:27,209 DEBUG "inactiveDate": null, 2024-04-25 17:42:27,209 DEBUG "sn": "Parker", 2024-04-25 17:42:27,209 DEBUG "preferences": null, 2024-04-25 17:42:27,209 DEBUG "userName": "idm_ireb4nfbu5", 2024-04-25 17:42:27,209 DEBUG "passwordLastChangedTime": "2024-04-25T17:42:26.330Z", 2024-04-25 17:42:27,209 DEBUG "effectiveRoles": [], 2024-04-25 17:42:27,209 DEBUG "activateAccount": null 2024-04-25 17:42:27,209 DEBUG } PASS : component idm is alive ________________________________________________________________________________ [2024-04-25 17:42:27] Verification-idm step4 : Record version ________________________________________________________________________________ 2024-04-25 17:42:27,212 INFO 2024-04-25 17:42:27,212 INFO ---------------------- Get IDM software version ---------------------- 2024-04-25 17:42:27,212 INFO Getting product version from https://xlou.iam.xlou-cdm.engineeringpit.com/openidm/info/version 2024-04-25 17:42:27,213 INFO [http_cmd]: curl -L --request GET "https://xlou.iam.xlou-cdm.engineeringpit.com/openidm/info/version" 2024-04-25 17:42:27,438 INFO [http_cmd]: http status code OK 2024-04-25 17:42:27,439 DEBUG --- status code --- 2024-04-25 17:42:27,439 DEBUG http status code is 200 (expected 200) 2024-04-25 17:42:27,439 DEBUG --- http response --- 2024-04-25 17:42:27,439 DEBUG { "_id": "version", "productVersion": "7.6.0-SNAPSHOT", "productBuildDate": "20240422163948", "productRevision": "2167d67043" } Version is 7.6.0-SNAPSHOT Revision is 2167d67043 ________________________________________________________________________________ [2024-04-25 17:42:27] Verification-idm step5 : Checking pod restart counts ________________________________________________________________________________ 2024-04-25 17:42:27,443 INFO 2024-04-25 17:42:27,443 INFO ------------ Check pod idm-6c95d7588f-lrdb9 restart count ------------ 2024-04-25 17:42:27,443 INFO 2024-04-25 17:42:27,443 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pod idm-6c95d7588f-lrdb9 --output jsonpath={.status.containerStatuses[*].restartCount} 2024-04-25 17:42:27,443 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-25 17:42:27,902 INFO [loop_until]: OK (rc = 0) 2024-04-25 17:42:27,902 DEBUG --- stdout --- 2024-04-25 17:42:27,902 DEBUG 0 2024-04-25 17:42:27,902 DEBUG --- stderr --- 2024-04-25 17:42:27,902 DEBUG 2024-04-25 17:42:27,903 INFO Pod idm-6c95d7588f-lrdb9 has been restarted 0 times. SUCCESS : pod restart count (0) did not change between validation and verification phases 2024-04-25 17:42:27,903 INFO 2024-04-25 17:42:27,903 INFO ------------ Check pod idm-6c95d7588f-n2wtz restart count ------------ 2024-04-25 17:42:27,903 INFO 2024-04-25 17:42:27,903 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pod idm-6c95d7588f-n2wtz --output jsonpath={.status.containerStatuses[*].restartCount} 2024-04-25 17:42:27,904 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-25 17:42:28,435 INFO [loop_until]: OK (rc = 0) 2024-04-25 17:42:28,435 DEBUG --- stdout --- 2024-04-25 17:42:28,436 DEBUG 0 2024-04-25 17:42:28,436 DEBUG --- stderr --- 2024-04-25 17:42:28,436 DEBUG 2024-04-25 17:42:28,436 INFO Pod idm-6c95d7588f-n2wtz has been restarted 0 times. SUCCESS : pod restart count (0) did not change between validation and verification phases ________________________________________________________________________________ [2024-04-25 17:42:28] Verification-idm post : Post method ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped