rspec migration pg14 3/8
Passed Started
by
@edith007
Siddharth Asthana
1Running with gitlab-runner 16.1.0~beta.59.g83c66823 (83c66823)2 on green-3.shared-gitlab-org.runners-manager.gitlab.com/default EuhiQzPR, system ID: s_360bc95445273 feature flags: FF_NETWORK_PER_BUILD:true, FF_USE_FASTZIP:true, FF_USE_IMPROVED_URL_MASKING:true6Using Docker executor with image registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bullseye-ruby-3.0.patched-golang-1.20-rust-1.65-node-18.17-postgresql-14:rubygems-3.4-git-2.36-lfs-2.9-chrome-113-yarn-1.22-graphicsmagick-1.3.36 ...7Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1 ...8Authenticating with credentials from job payload (GitLab Registry)9Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1 ...10Using docker image sha256:2e09e9db92541ed81b1c00f1d5186f8c262d8b84e8d1755676ea4b25614f30c4 for registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1 with digest registry.gitlab.com/gitlab-org/gitlab-build-images@sha256:afc7bd7a60824044709543a6a700385e412d750d9ab4fc72b427e335e1d0f3ec ...11WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.12WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.13Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 ...14Authenticating with credentials from job payload (GitLab Registry)15Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 ...16Using docker image sha256:a9a90ece30d9630d694ab1997cd103ea8ec729789451b983a75c7b58b0062d45 for registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 with digest registry.gitlab.com/gitlab-org/gitlab-build-images@sha256:7ef36177d5d0bc554fbb63d8210ae751bcc538bea7905b51d078d9ab90a755fa ...17Starting service redis:6.2-alpine ...18Pulling docker image redis:6.2-alpine ...19Using docker image sha256:d0675d033b87382205d08b598a004a99a1f6c4bd377cc86bb6df8957ce7dbb1c for redis:6.2-alpine with digest redis@sha256:740b5c973d1c9eea6e124a3905ba73d32354d0549f98ccc9e67af1af2a9ba478 ...20Waiting for services to be up and running (timeout 30 seconds)...21Authenticating with credentials from job payload (GitLab Registry)22Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bullseye-ruby-3.0.patched-golang-1.20-rust-1.65-node-18.17-postgresql-14:rubygems-3.4-git-2.36-lfs-2.9-chrome-113-yarn-1.22-graphicsmagick-1.3.36 ...23Using docker image sha256:24a9e92645b17c878623efd51b7564f81e480bd281e94d7f33971082e0ca80e0 for registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bullseye-ruby-3.0.patched-golang-1.20-rust-1.65-node-18.17-postgresql-14:rubygems-3.4-git-2.36-lfs-2.9-chrome-113-yarn-1.22-graphicsmagick-1.3.36 with digest registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bullseye-ruby-3.0.patched-golang-1.20-rust-1.65-node-18.17-postgresql-14@sha256:ca349d228348f199cb57f574476584f42aca338747393ed93a9032a0261afc4d ...25Running on runner-euhiqzpr-project-27783254-concurrent-0 via runner-euhiqzpr-shared-gitlab-org-1691907268-c12654d7...27Fetching changes with git depth set to 20...28Initialized empty Git repository in /builds/edith007/gitlab/.git/29Created fresh repository.30Checking out 199b254b as detached HEAD (ref is master)...31Skipping Git submodules setup32$ git remote set-url origin "${CI_REPOSITORY_URL}"34Checking cache for ruby-gems-debian-bullseye-ruby-3.0-protected...35WARNING: file does not exist 36Failed to extract cache38Downloading artifacts for compile-test-assets (4863613909)...39Downloading artifacts from coordinator... ok host=storage.googleapis.com id=4863613909 responseStatus=200 OK token=64_xdKei40Downloading artifacts for retrieve-tests-metadata (4863613915)...41Downloading artifacts from coordinator... ok host=storage.googleapis.com id=4863613915 responseStatus=200 OK token=64_xdKei42Downloading artifacts for setup-test-env (4863613912)...43Downloading artifacts from coordinator... ok host=storage.googleapis.com id=4863613912 responseStatus=200 OK token=64_xdKei45Using docker image sha256:24a9e92645b17c878623efd51b7564f81e480bd281e94d7f33971082e0ca80e0 for registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bullseye-ruby-3.0.patched-golang-1.20-rust-1.65-node-18.17-postgresql-14:rubygems-3.4-git-2.36-lfs-2.9-chrome-113-yarn-1.22-graphicsmagick-1.3.36 with digest registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bullseye-ruby-3.0.patched-golang-1.20-rust-1.65-node-18.17-postgresql-14@sha256:ca349d228348f199cb57f574476584f42aca338747393ed93a9032a0261afc4d ...46$ echo $FOSS_ONLY47$ [ "$FOSS_ONLY" = "1" ] && rm -rf ee/ qa/spec/ee/ qa/qa/specs/features/ee/ qa/qa/ee/ qa/qa/ee.rb48$ export GOPATH=$CI_PROJECT_DIR/.go49$ mkdir -p $GOPATH50$ source scripts/utils.sh51$ source scripts/prepare_build.sh1362Using decomposed database config (config/database.yml.decomposed-postgresql)1363Geo DB won't be set up.1364Embedding DB won't be set up.1376$ source ./scripts/rspec_helpers.sh1377$ run_timed_command "gem install knapsack --no-document"1378$ gem install knapsack --no-document1379Successfully installed knapsack-4.0.013801 gem installed1381==> 'gem install knapsack --no-document' succeeded in 1 seconds.1382$ section_start "gitaly-test-spawn" "Spawning Gitaly"; scripts/gitaly-test-spawn; section_end "gitaly-test-spawn"1387$ export RSPEC_SKIPPED_TESTS_REPORT_PATH="rspec/skipped_tests-${CI_JOB_ID}.txt"1388$ export RSPEC_RETRIED_TESTS_REPORT_PATH="rspec/retried_tests-${CI_JOB_ID}.txt"1389$ rspec_paralellized_job "--fail-fast=${RSPEC_FAIL_FAST_THRESHOLD} --tag ~quarantine --tag ~zoekt --tag ~click_house"1390SKIP_FLAKY_TESTS_AUTOMATICALLY: 1391RETRY_FAILED_TESTS_IN_NEW_PROCESS: true1392KNAPSACK_GENERATE_REPORT: true1393FLAKY_RSPEC_GENERATE_REPORT: true1394KNAPSACK_TEST_FILE_PATTERN: spec/{migrations}{,/**/}*_spec.rb1395KNAPSACK_LOG_LEVEL: debug1396KNAPSACK_REPORT_PATH: knapsack/rspec_migration_pg14_3_8_report.json1397FLAKY_RSPEC_SUITE_REPORT_PATH: rspec/flaky/report-suite.json1398FLAKY_RSPEC_REPORT_PATH: rspec/flaky/all_rspec_migration_pg14_3_8_report.json1399NEW_FLAKY_RSPEC_REPORT_PATH: rspec/flaky/new_rspec_migration_pg14_3_8_report.json1400RSPEC_SKIPPED_TESTS_REPORT_PATH: rspec/skipped_tests-4863614000.txt1401CRYSTALBALL: 1402RSPEC_TESTS_MAPPING_ENABLED: 1403RSPEC_TESTS_FILTER_FILE: 1404Shell set options (set -o) enabled:1405braceexpand on1406hashall on1407interactive-comments on1408pipefail on1409Knapsack report generator started!1410/builds/edith007/gitlab/ee/app/services/remote_development/service_response_factory.rb:41: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1411/builds/edith007/gitlab/ee/app/services/remote_development/workspaces/create_service.rb:28: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1412/builds/edith007/gitlab/ee/app/services/remote_development/workspaces/reconcile_service.rb:38: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1413/builds/edith007/gitlab/ee/app/services/remote_development/workspaces/update_service.rb:28: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1414/builds/edith007/gitlab/ee/lib/remote_development/agent_config/main.rb:32: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1415/builds/edith007/gitlab/ee/lib/remote_development/agent_config/updater.rb:11: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1416/builds/edith007/gitlab/ee/lib/remote_development/workspaces/create/authorizer.rb:12: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1417/builds/edith007/gitlab/ee/lib/remote_development/workspaces/create/authorizer.rb:13: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1418/builds/edith007/gitlab/ee/lib/remote_development/workspaces/create/creator.rb:17: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1419/builds/edith007/gitlab/ee/lib/remote_development/workspaces/create/creator.rb:24: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1420/builds/edith007/gitlab/ee/lib/remote_development/workspaces/create/creator.rb:25: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1421/builds/edith007/gitlab/ee/lib/remote_development/workspaces/create/creator.rb:28: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1422/builds/edith007/gitlab/ee/lib/remote_development/workspaces/create/devfile_fetcher.rb:15: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1423/builds/edith007/gitlab/ee/lib/remote_development/workspaces/create/devfile_fetcher.rb:16: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1424/builds/edith007/gitlab/ee/lib/remote_development/workspaces/create/devfile_flattener.rb:12: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1425/builds/edith007/gitlab/ee/lib/remote_development/workspaces/create/editor_component_injector.rb:12: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1426/builds/edith007/gitlab/ee/lib/remote_development/workspaces/create/editor_component_injector.rb:17: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1427/builds/edith007/gitlab/ee/lib/remote_development/workspaces/create/editor_component_injector.rb:18: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1428/builds/edith007/gitlab/ee/lib/remote_development/workspaces/create/post_flatten_devfile_validator.rb:43: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1429/builds/edith007/gitlab/ee/lib/remote_development/workspaces/create/post_flatten_devfile_validator.rb:54: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1430/builds/edith007/gitlab/ee/lib/remote_development/workspaces/create/post_flatten_devfile_validator.rb:102: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1431/builds/edith007/gitlab/ee/lib/remote_development/workspaces/create/post_flatten_devfile_validator.rb:126: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1432/builds/edith007/gitlab/ee/lib/remote_development/workspaces/create/post_flatten_devfile_validator.rb:159: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1433/builds/edith007/gitlab/ee/lib/remote_development/workspaces/create/post_flatten_devfile_validator.rb:202: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1434/builds/edith007/gitlab/ee/lib/remote_development/workspaces/create/post_flatten_devfile_validator.rb:235: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1435/builds/edith007/gitlab/ee/lib/remote_development/workspaces/create/pre_flatten_devfile_validator.rb:24: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1436/builds/edith007/gitlab/ee/lib/remote_development/workspaces/create/pre_flatten_devfile_validator.rb:52: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1437/builds/edith007/gitlab/ee/lib/remote_development/workspaces/create/project_cloner_component_injector.rb:12: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1438/builds/edith007/gitlab/ee/lib/remote_development/workspaces/create/project_cloner_component_injector.rb:17: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1439/builds/edith007/gitlab/ee/lib/remote_development/workspaces/create/project_cloner_component_injector.rb:18: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1440/builds/edith007/gitlab/ee/lib/remote_development/workspaces/create/project_cloner_component_injector.rb:23: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1441/builds/edith007/gitlab/ee/lib/remote_development/workspaces/create/volume_component_injector.rb:12: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1442/builds/edith007/gitlab/ee/lib/remote_development/workspaces/create/volume_component_injector.rb:13: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1443/builds/edith007/gitlab/ee/lib/remote_development/workspaces/create/volume_component_injector.rb:14: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1444/builds/edith007/gitlab/ee/lib/remote_development/workspaces/reconcile/main.rb:43: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1445/builds/edith007/gitlab/ee/lib/remote_development/workspaces/update/authorizer.rb:12: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1446/builds/edith007/gitlab/ee/lib/remote_development/workspaces/update/updater.rb:12: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1447/builds/edith007/gitlab/ee/lib/remote_development/workspaces/reconcile/input/agent_infos_observer.rb:15: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1448/builds/edith007/gitlab/ee/lib/remote_development/workspaces/reconcile/input/params_extractor.rb:14: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1449/builds/edith007/gitlab/ee/lib/remote_development/workspaces/reconcile/input/params_extractor.rb:16: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1450/builds/edith007/gitlab/ee/lib/remote_development/workspaces/reconcile/input/params_to_infos_converter.rb:14: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1451/builds/edith007/gitlab/ee/lib/remote_development/workspaces/reconcile/input/params_validator.rb:15: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1452/builds/edith007/gitlab/ee/lib/remote_development/workspaces/reconcile/output/rails_infos_observer.rb:11: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1453/builds/edith007/gitlab/ee/lib/remote_development/workspaces/reconcile/output/workspaces_to_rails_infos_converter.rb:15: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1454/builds/edith007/gitlab/ee/lib/remote_development/workspaces/reconcile/persistence/orphaned_workspaces_observer.rb:12: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1455/builds/edith007/gitlab/ee/lib/remote_development/workspaces/reconcile/persistence/workspaces_from_agent_infos_updater.rb:15: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1456/builds/edith007/gitlab/ee/lib/remote_development/workspaces/reconcile/persistence/workspaces_to_be_returned_finder.rb:16: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1457/builds/edith007/gitlab/ee/lib/remote_development/workspaces/reconcile/persistence/workspaces_to_be_returned_updater.rb:11: warning: One-line pattern matching is experimental, and the behavior may change in future versions of Ruby!1458Run options: exclude {:quarantine=>true, :zoekt=>true, :click_house=>true}1459Test environment set up in 0.624945861 seconds1460SyncNewAmountUsedForCiProjectMonthlyUsages1461 # order random1462ci: == [advisory_lock_connection] object_id: 36888840, pg_backend_pid: 14371463ci: == 20221011062254 SyncNewAmountUsedForCiProjectMonthlyUsages: migrating =======1464ci: == 20221011062254 SyncNewAmountUsedForCiProjectMonthlyUsages: migrated (0.0097s) 1465ci: == [advisory_lock_connection] object_id: 36888840, pg_backend_pid: 14371466 updates `new_amount_used` with values from `amount_used`1467I, [2023-08-13T07:14:16.635766 #45749] INFO -- : Database: 'main', Table: 'ci_runner_machines': Lock Writes1468I, [2023-08-13T07:14:23.058957 #45749] INFO -- : Database: 'main', Table: 'p_ci_runner_machine_builds': Lock Writes1469I, [2023-08-13T07:14:23.322234 #45749] INFO -- : Database: 'main', Table: 'ci_cost_settings': Lock Writes1470I, [2023-08-13T07:14:34.578818 #45749] INFO -- : Database: 'main', Table: 'ci_editor_ai_conversation_messages': Lock Writes1471I, [2023-08-13T07:14:38.580039 #45749] INFO -- : Database: 'main', Table: 'p_ci_builds': Lock Writes1472I, [2023-08-13T07:14:38.581101 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1473I, [2023-08-13T07:14:38.582076 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1474I, [2023-08-13T07:14:51.991131 #45749] INFO -- : Database: 'main', Table: 'p_ci_job_annotations': Lock Writes1475I, [2023-08-13T07:15:17.245683 #45749] INFO -- : Database: 'ci', Table: 'dast_pre_scan_verification_steps': Lock Writes1476I, [2023-08-13T07:15:17.345230 #45749] INFO -- : Database: 'ci', Table: 'achievements': Lock Writes1477I, [2023-08-13T07:15:17.771219 #45749] INFO -- : Database: 'ci', Table: 'slack_api_scopes': Lock Writes1478I, [2023-08-13T07:15:17.772994 #45749] INFO -- : Database: 'ci', Table: 'slack_integrations_scopes': Lock Writes1479I, [2023-08-13T07:15:18.098567 #45749] INFO -- : Database: 'ci', Table: 'dast_scanner_profiles_tags': Lock Writes1480I, [2023-08-13T07:15:18.576872 #45749] INFO -- : Database: 'ci', Table: 'user_achievements': Lock Writes1481I, [2023-08-13T07:15:19.259674 #45749] INFO -- : Database: 'ci', Table: 'elastic_group_index_statuses': Lock Writes1482I, [2023-08-13T07:15:19.419945 #45749] INFO -- : Database: 'ci', Table: 'analytics_dashboards_pointers': Lock Writes1483I, [2023-08-13T07:15:19.421149 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1484I, [2023-08-13T07:15:19.421994 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1485I, [2023-08-13T07:15:19.490244 #45749] INFO -- : Database: 'ci', Table: 'workspaces': Lock Writes1486I, [2023-08-13T07:15:19.626980 #45749] INFO -- : Database: 'ci', Table: 'remote_development_agent_configs': Lock Writes1487I, [2023-08-13T07:15:20.593464 #45749] INFO -- : Database: 'ci', Table: 'zoekt_shards': Lock Writes1488I, [2023-08-13T07:15:20.595318 #45749] INFO -- : Database: 'ci', Table: 'zoekt_indexed_namespaces': Lock Writes1489I, [2023-08-13T07:15:21.038412 #45749] INFO -- : Database: 'ci', Table: 'dast_profiles_tags': Lock Writes1490I, [2023-08-13T07:15:21.104645 #45749] INFO -- : Database: 'ci', Table: 'namespaces_storage_limit_exclusions': Lock Writes1491I, [2023-08-13T07:15:22.164059 #45749] INFO -- : Database: 'ci', Table: 'namespace_ldap_settings': Lock Writes1492I, [2023-08-13T07:15:22.787093 #45749] INFO -- : Database: 'ci', Table: 'project_data_transfers': Lock Writes1493I, [2023-08-13T07:15:22.788211 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1494I, [2023-08-13T07:15:22.788938 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1495I, [2023-08-13T07:15:22.966231 #45749] INFO -- : Database: 'ci', Table: 'service_desk_custom_email_verifications': Lock Writes1496I, [2023-08-13T07:15:23.552479 #45749] INFO -- : Database: 'ci', Table: 'pm_checkpoints': Lock Writes1497I, [2023-08-13T07:15:23.644432 #45749] INFO -- : Database: 'ci', Table: 'scan_result_policies': Lock Writes1498I, [2023-08-13T07:15:25.193588 #45749] INFO -- : Database: 'ci', Table: 'work_item_widget_definitions': Lock Writes1499I, [2023-08-13T07:15:26.244907 #45749] INFO -- : Database: 'ci', Table: 'container_repository_states': Lock Writes1500I, [2023-08-13T07:15:26.246064 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1501I, [2023-08-13T07:15:26.246810 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1502I, [2023-08-13T07:15:26.596718 #45749] INFO -- : Database: 'ci', Table: 'automation_rules': Lock Writes1503I, [2023-08-13T07:15:27.667818 #45749] INFO -- : Database: 'ci', Table: 'bulk_import_export_batches': Lock Writes1504I, [2023-08-13T07:15:27.771571 #45749] INFO -- : Database: 'ci', Table: 'bulk_import_batch_trackers': Lock Writes1505I, [2023-08-13T07:15:29.142761 #45749] INFO -- : Database: 'ci', Table: 'catalog_resources': Lock Writes1506I, [2023-08-13T07:15:31.142954 #45749] INFO -- : Database: 'ci', Table: 'design_management_repositories': Lock Writes1507I, [2023-08-13T07:15:31.647953 #45749] INFO -- : Database: 'ci', Table: 'container_registry_data_repair_details': Lock Writes1508I, [2023-08-13T07:15:32.072750 #45749] INFO -- : Database: 'ci', Table: 'search_indices': Lock Writes1509I, [2023-08-13T07:15:32.101028 #45749] INFO -- : Database: 'ci', Table: 'search_namespace_index_assignments': Lock Writes1510I, [2023-08-13T07:15:32.166914 #45749] INFO -- : Database: 'ci', Table: 'resource_link_events': Lock Writes1511I, [2023-08-13T07:15:32.439915 #45749] INFO -- : Database: 'ci', Table: 'packages_npm_metadata_caches': Lock Writes1512I, [2023-08-13T07:15:32.817159 #45749] INFO -- : Database: 'ci', Table: 'service_desk_custom_email_credentials': Lock Writes1513I, [2023-08-13T07:15:34.690138 #45749] INFO -- : Database: 'ci', Table: 'design_management_repository_states': Lock Writes1514I, [2023-08-13T07:15:35.151470 #45749] INFO -- : Database: 'ci', Table: 'dora_performance_scores': Lock Writes1515I, [2023-08-13T07:15:39.832359 #45749] INFO -- : Database: 'ci', Table: 'audit_events_instance_external_audit_event_destinations': Lock Writes1516I, [2023-08-13T07:15:39.999726 #45749] INFO -- : Database: 'ci', Table: 'agent_user_access_project_authorizations': Lock Writes1517I, [2023-08-13T07:15:40.024638 #45749] INFO -- : Database: 'ci', Table: 'agent_user_access_group_authorizations': Lock Writes1518I, [2023-08-13T07:15:40.343108 #45749] INFO -- : Database: 'ci', Table: 'abuse_trust_scores': Lock Writes1519I, [2023-08-13T07:15:40.376957 #45749] INFO -- : Database: 'ci', Table: 'pm_advisories': Lock Writes1520I, [2023-08-13T07:15:40.378847 #45749] INFO -- : Database: 'ci', Table: 'pm_affected_packages': Lock Writes1521I, [2023-08-13T07:15:40.424136 #45749] INFO -- : Database: 'ci', Table: 'issue_assignment_events': Lock Writes1522I, [2023-08-13T07:15:41.430652 #45749] INFO -- : Database: 'ci', Table: 'merge_request_assignment_events': Lock Writes1523I, [2023-08-13T07:15:41.902172 #45749] INFO -- : Database: 'ci', Table: 'project_states': Lock Writes1524I, [2023-08-13T07:15:42.602523 #45749] INFO -- : Database: 'ci', Table: 'organizations': Lock Writes1525I, [2023-08-13T07:15:43.223358 #45749] INFO -- : Database: 'ci', Table: 'value_stream_dashboard_counts': Lock Writes1526I, [2023-08-13T07:15:43.473453 #45749] INFO -- : Database: 'ci', Table: 'note_metadata': Lock Writes1527I, [2023-08-13T07:15:44.146523 #45749] INFO -- : Database: 'ci', Table: 'merge_request_diff_llm_summaries': Lock Writes1528I, [2023-08-13T07:15:44.493096 #45749] INFO -- : Database: 'ci', Table: 'value_stream_dashboard_aggregations': Lock Writes1529I, [2023-08-13T07:15:44.662195 #45749] INFO -- : Database: 'ci', Table: 'abuse_report_events': Lock Writes1530I, [2023-08-13T07:15:45.517361 #45749] INFO -- : Database: 'ci', Table: 'audit_events_google_cloud_logging_configurations': Lock Writes1531I, [2023-08-13T07:15:47.920441 #45749] INFO -- : Database: 'ci', Table: 'dependency_proxy_packages_settings': Lock Writes1532I, [2023-08-13T07:15:47.921672 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1533I, [2023-08-13T07:15:47.922375 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1534I, [2023-08-13T07:15:48.004343 #45749] INFO -- : Database: 'ci', Table: 'instance_audit_events_streaming_headers': Lock Writes1535I, [2023-08-13T07:15:49.220481 #45749] INFO -- : Database: 'ci', Table: 'abuse_events': Lock Writes1536I, [2023-08-13T07:16:00.734852 #45749] INFO -- : Database: 'ci', Table: 'subscription_add_ons': Lock Writes1537I, [2023-08-13T07:16:00.760945 #45749] INFO -- : Database: 'ci', Table: 'subscription_add_on_purchases': Lock Writes1538I, [2023-08-13T07:16:01.032195 #45749] INFO -- : Database: 'ci', Table: 'project_compliance_standards_adherence': Lock Writes1539I, [2023-08-13T07:16:03.006845 #45749] INFO -- : Database: 'ci', Table: 'organization_settings': Lock Writes1540I, [2023-08-13T07:16:03.278426 #45749] INFO -- : Database: 'ci', Table: 'organization_users': Lock Writes1541I, [2023-08-13T07:16:03.802864 #45749] INFO -- : Database: 'ci', Table: 'audit_events_streaming_instance_event_type_filters': Lock Writes1542I, [2023-08-13T07:16:05.929003 #45749] INFO -- : Database: 'ci', Table: 'subscription_user_add_on_assignments': Lock Writes1543I, [2023-08-13T07:16:06.045938 #45749] INFO -- : Database: 'ci', Table: 'system_access_microsoft_applications': Lock Writes1544I, [2023-08-13T07:16:06.069929 #45749] INFO -- : Database: 'ci', Table: 'system_access_microsoft_graph_access_tokens': Lock Writes1545I, [2023-08-13T07:16:07.326077 #45749] INFO -- : Database: 'ci', Table: 'merge_request_review_llm_summaries': Lock Writes1546I, [2023-08-13T07:16:08.063580 #45749] INFO -- : Database: 'ci', Table: 'catalog_resource_versions': Lock Writes1547I, [2023-08-13T07:16:09.213143 #45749] INFO -- : Database: 'ci', Table: 'p_batched_git_ref_updates_deletions': Lock Writes1548I, [2023-08-13T07:16:09.239540 #45749] INFO -- : Database: 'ci', Table: 'service_access_tokens': Lock Writes1549I, [2023-08-13T07:16:09.267819 #45749] INFO -- : Database: 'ci', Table: 'ml_models': Lock Writes1550I, [2023-08-13T07:16:09.760259 #45749] INFO -- : Database: 'ci', Table: 'ml_model_versions': Lock Writes1551I, [2023-08-13T07:16:12.164346 #45749] INFO -- : Database: 'ci', Table: 'group_wiki_repository_states': Lock Writes1552I, [2023-08-13T07:16:12.487586 #45749] INFO -- : Database: 'ci', Table: 'target_branch_rules': Lock Writes1553FixPartitionIdsForCiPipelineVariable1554 # order random1555 when on saas1556 #up1557ci: == [advisory_lock_connection] object_id: 93554680, pg_backend_pid: 37051558ci: == 20230208100917 FixPartitionIdsForCiPipelineVariable: migrating =============1559ci: == 20230208100917 FixPartitionIdsForCiPipelineVariable: migrated (0.0630s) ====1560ci: == [advisory_lock_connection] object_id: 93554680, pg_backend_pid: 37051561 schedules background jobs for each batch of ci_pipeline_variables1562 #down1563ci: == [advisory_lock_connection] object_id: 94601880, pg_backend_pid: 37111564ci: == 20230208100917 FixPartitionIdsForCiPipelineVariable: migrating =============1565ci: == 20230208100917 FixPartitionIdsForCiPipelineVariable: migrated (0.0658s) ====1566ci: == [advisory_lock_connection] object_id: 94601880, pg_backend_pid: 37111567 deletes all batched migration records1568 when on self-managed instance1569 #up1570 does not schedule background job1571 #down1572 does not delete background job1573I, [2023-08-13T07:18:12.000669 #45749] INFO -- : Database: 'main', Table: 'p_ci_runner_machine_builds': Lock Writes1574I, [2023-08-13T07:18:12.203907 #45749] INFO -- : Database: 'main', Table: 'ci_cost_settings': Lock Writes1575I, [2023-08-13T07:18:22.889912 #45749] INFO -- : Database: 'main', Table: 'ci_editor_ai_conversation_messages': Lock Writes1576I, [2023-08-13T07:18:25.815882 #45749] INFO -- : Database: 'main', Table: 'p_ci_builds': Lock Writes1577I, [2023-08-13T07:18:25.816977 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1578I, [2023-08-13T07:18:25.817697 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1579I, [2023-08-13T07:18:39.882564 #45749] INFO -- : Database: 'main', Table: 'p_ci_job_annotations': Lock Writes1580I, [2023-08-13T07:19:01.598244 #45749] INFO -- : Database: 'ci', Table: 'bulk_import_export_batches': Lock Writes1581I, [2023-08-13T07:19:01.705838 #45749] INFO -- : Database: 'ci', Table: 'bulk_import_batch_trackers': Lock Writes1582I, [2023-08-13T07:19:03.065199 #45749] INFO -- : Database: 'ci', Table: 'catalog_resources': Lock Writes1583I, [2023-08-13T07:19:05.039699 #45749] INFO -- : Database: 'ci', Table: 'design_management_repositories': Lock Writes1584I, [2023-08-13T07:19:05.557935 #45749] INFO -- : Database: 'ci', Table: 'container_registry_data_repair_details': Lock Writes1585I, [2023-08-13T07:19:05.982553 #45749] INFO -- : Database: 'ci', Table: 'search_indices': Lock Writes1586I, [2023-08-13T07:19:06.010045 #45749] INFO -- : Database: 'ci', Table: 'search_namespace_index_assignments': Lock Writes1587I, [2023-08-13T07:19:06.073425 #45749] INFO -- : Database: 'ci', Table: 'resource_link_events': Lock Writes1588I, [2023-08-13T07:19:06.333226 #45749] INFO -- : Database: 'ci', Table: 'packages_npm_metadata_caches': Lock Writes1589I, [2023-08-13T07:19:06.706560 #45749] INFO -- : Database: 'ci', Table: 'service_desk_custom_email_credentials': Lock Writes1590I, [2023-08-13T07:19:08.531669 #45749] INFO -- : Database: 'ci', Table: 'design_management_repository_states': Lock Writes1591I, [2023-08-13T07:19:08.962892 #45749] INFO -- : Database: 'ci', Table: 'dora_performance_scores': Lock Writes1592I, [2023-08-13T07:19:13.448766 #45749] INFO -- : Database: 'ci', Table: 'audit_events_instance_external_audit_event_destinations': Lock Writes1593I, [2023-08-13T07:19:13.605413 #45749] INFO -- : Database: 'ci', Table: 'agent_user_access_project_authorizations': Lock Writes1594I, [2023-08-13T07:19:13.629006 #45749] INFO -- : Database: 'ci', Table: 'agent_user_access_group_authorizations': Lock Writes1595I, [2023-08-13T07:19:13.932447 #45749] INFO -- : Database: 'ci', Table: 'abuse_trust_scores': Lock Writes1596I, [2023-08-13T07:19:13.963368 #45749] INFO -- : Database: 'ci', Table: 'pm_advisories': Lock Writes1597I, [2023-08-13T07:19:13.965339 #45749] INFO -- : Database: 'ci', Table: 'pm_affected_packages': Lock Writes1598I, [2023-08-13T07:19:14.007540 #45749] INFO -- : Database: 'ci', Table: 'issue_assignment_events': Lock Writes1599I, [2023-08-13T07:19:14.943519 #45749] INFO -- : Database: 'ci', Table: 'merge_request_assignment_events': Lock Writes1600I, [2023-08-13T07:19:15.382879 #45749] INFO -- : Database: 'ci', Table: 'project_states': Lock Writes1601I, [2023-08-13T07:19:15.984203 #45749] INFO -- : Database: 'ci', Table: 'organizations': Lock Writes1602I, [2023-08-13T07:19:16.610545 #45749] INFO -- : Database: 'ci', Table: 'value_stream_dashboard_counts': Lock Writes1603I, [2023-08-13T07:19:16.821682 #45749] INFO -- : Database: 'ci', Table: 'note_metadata': Lock Writes1604I, [2023-08-13T07:19:17.494417 #45749] INFO -- : Database: 'ci', Table: 'merge_request_diff_llm_summaries': Lock Writes1605I, [2023-08-13T07:19:17.840502 #45749] INFO -- : Database: 'ci', Table: 'value_stream_dashboard_aggregations': Lock Writes1606I, [2023-08-13T07:19:17.997687 #45749] INFO -- : Database: 'ci', Table: 'abuse_report_events': Lock Writes1607I, [2023-08-13T07:19:18.899882 #45749] INFO -- : Database: 'ci', Table: 'audit_events_google_cloud_logging_configurations': Lock Writes1608I, [2023-08-13T07:19:21.251732 #45749] INFO -- : Database: 'ci', Table: 'dependency_proxy_packages_settings': Lock Writes1609I, [2023-08-13T07:19:21.252863 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1610I, [2023-08-13T07:19:21.253556 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1611I, [2023-08-13T07:19:21.332911 #45749] INFO -- : Database: 'ci', Table: 'instance_audit_events_streaming_headers': Lock Writes1612I, [2023-08-13T07:19:22.462738 #45749] INFO -- : Database: 'ci', Table: 'abuse_events': Lock Writes1613I, [2023-08-13T07:19:34.019686 #45749] INFO -- : Database: 'ci', Table: 'subscription_add_ons': Lock Writes1614I, [2023-08-13T07:19:34.043978 #45749] INFO -- : Database: 'ci', Table: 'subscription_add_on_purchases': Lock Writes1615I, [2023-08-13T07:19:34.306898 #45749] INFO -- : Database: 'ci', Table: 'project_compliance_standards_adherence': Lock Writes1616I, [2023-08-13T07:19:36.117038 #45749] INFO -- : Database: 'ci', Table: 'organization_settings': Lock Writes1617I, [2023-08-13T07:19:36.371213 #45749] INFO -- : Database: 'ci', Table: 'organization_users': Lock Writes1618I, [2023-08-13T07:19:36.823813 #45749] INFO -- : Database: 'ci', Table: 'audit_events_streaming_instance_event_type_filters': Lock Writes1619I, [2023-08-13T07:19:38.790718 #45749] INFO -- : Database: 'ci', Table: 'subscription_user_add_on_assignments': Lock Writes1620I, [2023-08-13T07:19:38.893494 #45749] INFO -- : Database: 'ci', Table: 'system_access_microsoft_applications': Lock Writes1621I, [2023-08-13T07:19:38.917320 #45749] INFO -- : Database: 'ci', Table: 'system_access_microsoft_graph_access_tokens': Lock Writes1622I, [2023-08-13T07:19:40.154395 #45749] INFO -- : Database: 'ci', Table: 'merge_request_review_llm_summaries': Lock Writes1623I, [2023-08-13T07:19:40.824991 #45749] INFO -- : Database: 'ci', Table: 'catalog_resource_versions': Lock Writes1624I, [2023-08-13T07:19:41.908861 #45749] INFO -- : Database: 'ci', Table: 'p_batched_git_ref_updates_deletions': Lock Writes1625I, [2023-08-13T07:19:41.932288 #45749] INFO -- : Database: 'ci', Table: 'service_access_tokens': Lock Writes1626I, [2023-08-13T07:19:41.957565 #45749] INFO -- : Database: 'ci', Table: 'ml_models': Lock Writes1627I, [2023-08-13T07:19:42.387688 #45749] INFO -- : Database: 'ci', Table: 'ml_model_versions': Lock Writes1628I, [2023-08-13T07:19:44.637257 #45749] INFO -- : Database: 'ci', Table: 'group_wiki_repository_states': Lock Writes1629I, [2023-08-13T07:19:44.924698 #45749] INFO -- : Database: 'ci', Table: 'target_branch_rules': Lock Writes1630EnsureTaskNoteRenamingBackgroundMigrationFinished1631 # order random1632 #up1633 when migration is missing1634main: == [advisory_lock_connection] object_id: 123608640, pg_backend_pid: 47031635main: == 20221018193635 EnsureTaskNoteRenamingBackgroundMigrationFinished: migrating 1636main: == 20221018193635 EnsureTaskNoteRenamingBackgroundMigrationFinished: migrated (0.0088s) 1637main: == [advisory_lock_connection] object_id: 123608640, pg_backend_pid: 47031638 warns migration not found1639 with migration present1640 when migration finished successfully1641main: == [advisory_lock_connection] object_id: 124685420, pg_backend_pid: 47051642main: == 20221018193635 EnsureTaskNoteRenamingBackgroundMigrationFinished: migrating 1643main: == 20221018193635 EnsureTaskNoteRenamingBackgroundMigrationFinished: migrated (0.0160s) 1644main: == [advisory_lock_connection] object_id: 124685420, pg_backend_pid: 47051645 does not raise exception1646 with different migration statuses1647 status: 0, description: "paused"1648 behaves like finalizes the migration1649main: == [advisory_lock_connection] object_id: 125272100, pg_backend_pid: 47071650main: == 20221018193635 EnsureTaskNoteRenamingBackgroundMigrationFinished: migrating 1651main: == 20221018193635 EnsureTaskNoteRenamingBackgroundMigrationFinished: migrated (0.1970s) 1652main: == [advisory_lock_connection] object_id: 125272100, pg_backend_pid: 47071653 finalizes the migration1654 status: 1, description: "active"1655 behaves like finalizes the migration1656main: == [advisory_lock_connection] object_id: 125552100, pg_backend_pid: 47091657main: == 20221018193635 EnsureTaskNoteRenamingBackgroundMigrationFinished: migrating 1658main: == 20221018193635 EnsureTaskNoteRenamingBackgroundMigrationFinished: migrated (0.1484s) 1659main: == [advisory_lock_connection] object_id: 125552100, pg_backend_pid: 47091660 finalizes the migration1661 status: 4, description: "failed"1662 behaves like finalizes the migration1663main: == [advisory_lock_connection] object_id: 125912280, pg_backend_pid: 47111664main: == 20221018193635 EnsureTaskNoteRenamingBackgroundMigrationFinished: migrating 1665main: == 20221018193635 EnsureTaskNoteRenamingBackgroundMigrationFinished: migrated (0.1479s) 1666main: == [advisory_lock_connection] object_id: 125912280, pg_backend_pid: 47111667 finalizes the migration1668 status: 5, description: "finalizing"1669 behaves like finalizes the migration1670main: == [advisory_lock_connection] object_id: 126273180, pg_backend_pid: 47131671main: == 20221018193635 EnsureTaskNoteRenamingBackgroundMigrationFinished: migrating 1672main: == 20221018193635 EnsureTaskNoteRenamingBackgroundMigrationFinished: migrated (0.1422s) 1673main: == [advisory_lock_connection] object_id: 126273180, pg_backend_pid: 47131674 finalizes the migration1675I, [2023-08-13T07:21:01.448603 #45749] INFO -- : Database: 'main', Table: 'ci_runner_machines': Lock Writes1676I, [2023-08-13T07:21:07.221950 #45749] INFO -- : Database: 'main', Table: 'p_ci_runner_machine_builds': Lock Writes1677I, [2023-08-13T07:21:07.447929 #45749] INFO -- : Database: 'main', Table: 'ci_cost_settings': Lock Writes1678I, [2023-08-13T07:21:17.888899 #45749] INFO -- : Database: 'main', Table: 'ci_editor_ai_conversation_messages': Lock Writes1679I, [2023-08-13T07:21:20.797077 #45749] INFO -- : Database: 'main', Table: 'p_ci_builds': Lock Writes1680I, [2023-08-13T07:21:20.798198 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1681I, [2023-08-13T07:21:20.798888 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1682I, [2023-08-13T07:21:35.114453 #45749] INFO -- : Database: 'main', Table: 'p_ci_job_annotations': Lock Writes1683SetEmailConfirmationSettingBeforeRemovingSendUserConfirmationEmailColumn1684 # order random1685 #down1686 updates 'email_confirmation_setting' to default value: '0' (off)1687 #up1688 when 'send_user_confirmation_email' is set to 'true'1689 updates 'email_confirmation_setting' to '2' (hard)1690 when 'send_user_confirmation_email' is set to 'false'1691 updates 'email_confirmation_setting' to '0' (off)1692I, [2023-08-13T07:22:51.304229 #45749] INFO -- : Database: 'main', Table: 'ci_runner_machines': Lock Writes1693I, [2023-08-13T07:22:57.034929 #45749] INFO -- : Database: 'main', Table: 'p_ci_runner_machine_builds': Lock Writes1694I, [2023-08-13T07:22:57.252269 #45749] INFO -- : Database: 'main', Table: 'ci_cost_settings': Lock Writes1695I, [2023-08-13T07:23:08.005165 #45749] INFO -- : Database: 'main', Table: 'ci_editor_ai_conversation_messages': Lock Writes1696I, [2023-08-13T07:23:10.978618 #45749] INFO -- : Database: 'main', Table: 'p_ci_builds': Lock Writes1697I, [2023-08-13T07:23:10.979641 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1698I, [2023-08-13T07:23:10.980326 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1699I, [2023-08-13T07:23:25.671500 #45749] INFO -- : Database: 'main', Table: 'p_ci_job_annotations': Lock Writes1700AddNamespacesEmailsEnabledColumnData1701 # order random1702main: == [advisory_lock_connection] object_id: 169767100, pg_backend_pid: 47431703main: == 20221116134611 AddNamespacesEmailsEnabledColumnData: migrating =============1704main: == 20221116134611 AddNamespacesEmailsEnabledColumnData: migrated (0.0433s) ====1705main: == [advisory_lock_connection] object_id: 169767100, pg_backend_pid: 47431706 sets emails_enabled to be the opposite of emails_disabled1707main: == [advisory_lock_connection] object_id: 170784360, pg_backend_pid: 47451708main: == 20221116134611 AddNamespacesEmailsEnabledColumnData: migrating =============1709main: == 20221116134611 AddNamespacesEmailsEnabledColumnData: migrated (0.0509s) ====1710main: == [advisory_lock_connection] object_id: 170784360, pg_backend_pid: 47451711 schedules background migrations1712 #down1713main: == [advisory_lock_connection] object_id: 171155300, pg_backend_pid: 47471714main: == 20221116134611 AddNamespacesEmailsEnabledColumnData: migrating =============1715main: == 20221116134611 AddNamespacesEmailsEnabledColumnData: migrated (0.0470s) ====1716main: == [advisory_lock_connection] object_id: 171155300, pg_backend_pid: 47471717 deletes all batched migration records1718I, [2023-08-13T07:24:44.797132 #45749] INFO -- : Database: 'main', Table: 'ci_runner_machines': Lock Writes1719I, [2023-08-13T07:24:50.687752 #45749] INFO -- : Database: 'main', Table: 'p_ci_runner_machine_builds': Lock Writes1720I, [2023-08-13T07:24:50.909351 #45749] INFO -- : Database: 'main', Table: 'ci_cost_settings': Lock Writes1721I, [2023-08-13T07:25:01.638280 #45749] INFO -- : Database: 'main', Table: 'ci_editor_ai_conversation_messages': Lock Writes1722I, [2023-08-13T07:25:04.714483 #45749] INFO -- : Database: 'main', Table: 'p_ci_builds': Lock Writes1723I, [2023-08-13T07:25:04.715581 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1724I, [2023-08-13T07:25:04.716323 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1725I, [2023-08-13T07:25:19.970151 #45749] INFO -- : Database: 'main', Table: 'p_ci_job_annotations': Lock Writes1726QueueFixIncoherentPackagesSizeOnProjectStatistics1727 # order random1728 with no packages1729main: == [advisory_lock_connection] object_id: 190868500, pg_backend_pid: 47631730main: == 20230119123937 QueueFixIncoherentPackagesSizeOnProjectStatistics: migrating 1731main: == 20230119123937 QueueFixIncoherentPackagesSizeOnProjectStatistics: migrated (0.0120s) 1732main: == [advisory_lock_connection] object_id: 190868500, pg_backend_pid: 47631733 does not schedule a new batched migration1734 with some packages1735main: == [advisory_lock_connection] object_id: 191694640, pg_backend_pid: 47661736main: == 20230119123937 QueueFixIncoherentPackagesSizeOnProjectStatistics: migrating 1737main: == 20230119123937 QueueFixIncoherentPackagesSizeOnProjectStatistics: migrated (0.0500s) 1738main: == [advisory_lock_connection] object_id: 191694640, pg_backend_pid: 47661739 schedules a new batched migration1740I, [2023-08-13T07:26:34.112261 #45749] INFO -- : Database: 'main', Table: 'p_ci_runner_machine_builds': Lock Writes1741I, [2023-08-13T07:26:34.351941 #45749] INFO -- : Database: 'main', Table: 'ci_cost_settings': Lock Writes1742I, [2023-08-13T07:26:45.249615 #45749] INFO -- : Database: 'main', Table: 'ci_editor_ai_conversation_messages': Lock Writes1743I, [2023-08-13T07:26:49.137816 #45749] INFO -- : Database: 'main', Table: 'p_ci_builds': Lock Writes1744I, [2023-08-13T07:26:49.138854 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1745I, [2023-08-13T07:26:49.139570 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1746I, [2023-08-13T07:27:05.233435 #45749] INFO -- : Database: 'main', Table: 'p_ci_job_annotations': Lock Writes1747AddObjectiveAndKeyresultToWorkItemTypes1748 # order random1749main: == [advisory_lock_connection] object_id: 210968060, pg_backend_pid: 47821750main: == 20221018050323 AddObjectiveAndKeyresultToWorkItemTypes: migrating ==========1751main: -- execute("INSERT INTO work_item_types (base_type, icon_name, name, created_at, updated_at) VALUES(\n 5, 'issue-type-objective', 'Objective', NOW(), NOW()\n ) ON CONFLICT DO NOTHING;\n")1752main: -> 0.0013s1753main: -- execute("INSERT INTO work_item_types (base_type, icon_name, name, created_at, updated_at) VALUES(\n 6, 'issue-type-keyresult', 'Key Result', NOW(), NOW()\n ) ON CONFLICT DO NOTHING;\n")1754main: -> 0.0013s1755main: == 20221018050323 AddObjectiveAndKeyresultToWorkItemTypes: migrated (0.0077s) =1756main: == [advisory_lock_connection] object_id: 210968060, pg_backend_pid: 47821757 adds both objective & keyresult to base work item types1758main: == [advisory_lock_connection] object_id: 212106340, pg_backend_pid: 47841759main: == 20221018050323 AddObjectiveAndKeyresultToWorkItemTypes: migrating ==========1760main: -- Objective item record exist, skipping creation1761main: -- Keyresult item record exist, skipping creation1762main: == 20221018050323 AddObjectiveAndKeyresultToWorkItemTypes: migrated (0.0090s) =1763main: == [advisory_lock_connection] object_id: 212106340, pg_backend_pid: 47841764 skips creating both objective & keyresult type record if it already exists1765The application_settings (main) table has 1212 columns.1766Recreating the database1767Dropped database 'gitlabhq_test'1768Dropped database 'gitlabhq_test_ci'1769Created database 'gitlabhq_test'1770Created database 'gitlabhq_test_ci'1771main: == [advisory_lock_connection] object_id: 212554000, pg_backend_pid: 47941772main: == [advisory_lock_connection] object_id: 212554000, pg_backend_pid: 47941773ci: == [advisory_lock_connection] object_id: 212576000, pg_backend_pid: 47961774ci: == [advisory_lock_connection] object_id: 212576000, pg_backend_pid: 47961775Databases re-creation done in 12.1999877099997321776BumpDefaultPartitionIdValueForCiTables1777 # order random1778 when on sass1779main: == [advisory_lock_connection] object_id: 220057820, pg_backend_pid: 48041780main: == 20221220131020 BumpDefaultPartitionIdValueForCiTables: migrating ===========1781main: -- change_column_default(:ci_build_needs, :partition_id, {:from=>100, :to=>101})1782main: -> 0.0052s1783main: -- change_column_default(:ci_build_pending_states, :partition_id, {:from=>100, :to=>101})1784main: -> 0.0048s1785main: -- change_column_default(:ci_build_report_results, :partition_id, {:from=>100, :to=>101})1786main: -> 0.0046s1787main: -- change_column_default(:ci_build_trace_chunks, :partition_id, {:from=>100, :to=>101})1788main: -> 0.0049s1789main: -- change_column_default(:ci_build_trace_metadata, :partition_id, {:from=>100, :to=>101})1790main: -> 0.0049s1791main: -- change_column_default(:ci_builds, :partition_id, {:from=>100, :to=>101})1792main: -> 0.0068s1793main: -- change_column_default(:ci_builds_runner_session, :partition_id, {:from=>100, :to=>101})1794main: -> 0.0049s1795main: -- change_column_default(:ci_job_artifacts, :partition_id, {:from=>100, :to=>101})1796main: -> 0.0057s1797main: -- change_column_default(:ci_job_variables, :partition_id, {:from=>100, :to=>101})1798main: -> 0.0052s1799main: -- change_column_default(:ci_pending_builds, :partition_id, {:from=>100, :to=>101})1800main: -> 0.0049s1801main: -- change_column_default(:ci_pipeline_variables, :partition_id, {:from=>100, :to=>101})1802main: -> 0.0041s1803main: -- change_column_default(:ci_pipelines, :partition_id, {:from=>100, :to=>101})1804main: -> 0.0048s1805main: -- change_column_default(:ci_running_builds, :partition_id, {:from=>100, :to=>101})1806main: -> 0.0041s1807main: -- change_column_default(:ci_sources_pipelines, :partition_id, {:from=>100, :to=>101})1808main: -> 0.0043s1809main: -- change_column_default(:ci_sources_pipelines, :source_partition_id, {:from=>100, :to=>101})1810main: -> 0.0038s1811main: -- change_column_default(:ci_stages, :partition_id, {:from=>100, :to=>101})1812main: -> 0.0041s1813main: -- change_column_default(:ci_unit_test_failures, :partition_id, {:from=>100, :to=>101})1814main: -> 0.0037s1815main: -- change_column_default(:p_ci_builds_metadata, :partition_id, {:from=>100, :to=>101})1816main: -> 0.0044s1817main: == 20221220131020 BumpDefaultPartitionIdValueForCiTables: migrated (0.2306s) ==1818main: == [advisory_lock_connection] object_id: 220057820, pg_backend_pid: 48041819 changes default values1820 with tables already changed1821main: == [advisory_lock_connection] object_id: 221039660, pg_backend_pid: 48071822main: == 20221220131020 BumpDefaultPartitionIdValueForCiTables: migrating ===========1823main: -- change_column_default(:ci_build_needs, :partition_id, {:from=>100, :to=>101})1824main: -> 0.0049s1825main: -- change_column_default(:ci_build_pending_states, :partition_id, {:from=>100, :to=>101})1826main: -> 0.0051s1827main: -- change_column_default(:ci_build_report_results, :partition_id, {:from=>100, :to=>101})1828main: -> 0.0050s1829main: -- change_column_default(:ci_build_trace_chunks, :partition_id, {:from=>100, :to=>101})1830main: -> 0.0047s1831main: -- change_column_default(:ci_build_trace_metadata, :partition_id, {:from=>100, :to=>101})1832main: -> 0.0048s1833main: -- change_column_default(:ci_builds_runner_session, :partition_id, {:from=>100, :to=>101})1834main: -> 0.0047s1835main: -- change_column_default(:ci_job_artifacts, :partition_id, {:from=>100, :to=>101})1836main: -> 0.0050s1837main: -- change_column_default(:ci_job_variables, :partition_id, {:from=>100, :to=>101})1838main: -> 0.0048s1839main: -- change_column_default(:ci_pending_builds, :partition_id, {:from=>100, :to=>101})1840main: -> 0.0052s1841main: -- change_column_default(:ci_pipeline_variables, :partition_id, {:from=>100, :to=>101})1842main: -> 0.0049s1843main: -- change_column_default(:ci_pipelines, :partition_id, {:from=>100, :to=>101})1844main: -> 0.0062s1845main: -- change_column_default(:ci_running_builds, :partition_id, {:from=>100, :to=>101})1846main: -> 0.0050s1847main: -- change_column_default(:ci_sources_pipelines, :partition_id, {:from=>100, :to=>101})1848main: -> 0.0052s1849main: -- change_column_default(:ci_sources_pipelines, :source_partition_id, {:from=>100, :to=>101})1850main: -> 0.0050s1851main: -- change_column_default(:ci_stages, :partition_id, {:from=>100, :to=>101})1852main: -> 0.0051s1853main: -- change_column_default(:ci_unit_test_failures, :partition_id, {:from=>100, :to=>101})1854main: -> 0.0045s1855main: -- change_column_default(:p_ci_builds_metadata, :partition_id, {:from=>100, :to=>101})1856main: -> 0.0055s1857main: == 20221220131020 BumpDefaultPartitionIdValueForCiTables: migrated (0.2379s) ==1858main: == [advisory_lock_connection] object_id: 221039660, pg_backend_pid: 48071859 skips updating already changed tables1860 when self-managed1861main: == [advisory_lock_connection] object_id: 221954400, pg_backend_pid: 48101862main: == 20221220131020 BumpDefaultPartitionIdValueForCiTables: migrating ===========1863main: == 20221220131020 BumpDefaultPartitionIdValueForCiTables: migrated (0.0081s) ==1864main: == [advisory_lock_connection] object_id: 221954400, pg_backend_pid: 48101865 does not change default values1866I, [2023-08-13T07:29:30.902212 #45749] INFO -- : Database: 'main', Table: 'ci_runner_machines': Lock Writes1867I, [2023-08-13T07:29:37.429197 #45749] INFO -- : Database: 'main', Table: 'p_ci_runner_machine_builds': Lock Writes1868I, [2023-08-13T07:29:37.651255 #45749] INFO -- : Database: 'main', Table: 'ci_cost_settings': Lock Writes1869I, [2023-08-13T07:29:49.451167 #45749] INFO -- : Database: 'main', Table: 'ci_editor_ai_conversation_messages': Lock Writes1870I, [2023-08-13T07:29:53.810997 #45749] INFO -- : Database: 'main', Table: 'p_ci_builds': Lock Writes1871I, [2023-08-13T07:29:53.812336 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1872I, [2023-08-13T07:29:53.813142 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1873I, [2023-08-13T07:30:08.716253 #45749] INFO -- : Database: 'main', Table: 'p_ci_job_annotations': Lock Writes1874ReMigrateRedisSlotKeys1875 # order random1876 #up1877main: == [advisory_lock_connection] object_id: 239140720, pg_backend_pid: 48251878main: == 20230328111013 ReMigrateRedisSlotKeys: migrating ===========================1879main: == 20230328111013 ReMigrateRedisSlotKeys: migrated (0.0292s) ==================1880main: == [advisory_lock_connection] object_id: 239140720, pg_backend_pid: 48251881 rename keys1882main: == [advisory_lock_connection] object_id: 239898300, pg_backend_pid: 48271883main: == 20230328111013 ReMigrateRedisSlotKeys: migrating ===========================1884main: == 20230328111013 ReMigrateRedisSlotKeys: migrated (2.0523s) ==================1885main: == [advisory_lock_connection] object_id: 239898300, pg_backend_pid: 48271886 runs without errors1887I, [2023-08-13T07:31:17.603459 #45749] INFO -- : Database: 'main', Table: 'ci_editor_ai_conversation_messages': Lock Writes1888I, [2023-08-13T07:31:21.064277 #45749] INFO -- : Database: 'main', Table: 'p_ci_builds': Lock Writes1889I, [2023-08-13T07:31:21.065488 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1890I, [2023-08-13T07:31:21.066341 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1891I, [2023-08-13T07:31:36.733237 #45749] INFO -- : Database: 'main', Table: 'p_ci_job_annotations': Lock Writes1892SwapMergeRequestUserMentionsNoteIdToBigint21893 # order random1894 #up1895 swaps the integer and bigint columns for GitLab.com, dev, or test1896 is a no-op for other instances1897main: == [advisory_lock_connection] object_id: 255035480, pg_backend_pid: 48471898main: == 20230310020356 SwapMergeRequestUserMentionsNoteIdToBigint2: migrating ======1899main: == 20230310020356 SwapMergeRequestUserMentionsNoteIdToBigint2: migrated (0.0083s) 1900main: == [advisory_lock_connection] object_id: 255035480, pg_backend_pid: 48471901 is a no-op if columns are already swapped1902I, [2023-08-13T07:32:54.915248 #45749] INFO -- : Database: 'main', Table: 'ci_editor_ai_conversation_messages': Lock Writes1903I, [2023-08-13T07:32:58.730911 #45749] INFO -- : Database: 'main', Table: 'p_ci_builds': Lock Writes1904I, [2023-08-13T07:32:58.734252 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1905I, [2023-08-13T07:32:58.737223 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1906I, [2023-08-13T07:33:13.718849 #45749] INFO -- : Database: 'main', Table: 'p_ci_job_annotations': Lock Writes1907QueueBackfillComplianceViolations1908 # order random1909 #up1910main: == [advisory_lock_connection] object_id: 270617900, pg_backend_pid: 48611911main: == 20230125195503 QueueBackfillComplianceViolations: migrating ================1912main: == 20230125195503 QueueBackfillComplianceViolations: migrated (0.0543s) =======1913main: == [advisory_lock_connection] object_id: 270617900, pg_backend_pid: 48611914 schedules background jobs for each batch of merge_request_compliance_violations1915 #down1916main: == [advisory_lock_connection] object_id: 271280280, pg_backend_pid: 48631917main: == 20230125195503 QueueBackfillComplianceViolations: migrating ================1918main: == 20230125195503 QueueBackfillComplianceViolations: migrated (0.0608s) =======1919main: == [advisory_lock_connection] object_id: 271280280, pg_backend_pid: 48631920 deletes all batched migration records1921I, [2023-08-13T07:34:30.056030 #45749] INFO -- : Database: 'main', Table: 'p_ci_runner_machine_builds': Lock Writes1922I, [2023-08-13T07:34:30.348023 #45749] INFO -- : Database: 'main', Table: 'ci_cost_settings': Lock Writes1923I, [2023-08-13T07:34:42.219865 #45749] INFO -- : Database: 'main', Table: 'ci_editor_ai_conversation_messages': Lock Writes1924I, [2023-08-13T07:34:46.427308 #45749] INFO -- : Database: 'main', Table: 'p_ci_builds': Lock Writes1925I, [2023-08-13T07:34:46.428473 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1926I, [2023-08-13T07:34:46.429336 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1927I, [2023-08-13T07:35:01.796648 #45749] INFO -- : Database: 'main', Table: 'p_ci_job_annotations': Lock Writes1928MigrateEvidencesFromRawMetadata1929 # order random1930 #down1931main: == [advisory_lock_connection] object_id: 288013960, pg_backend_pid: 48781932main: == 20230224144233 MigrateEvidencesFromRawMetadata: migrating ==================1933main: == 20230224144233 MigrateEvidencesFromRawMetadata: migrated (0.0066s) =========1934main: == [advisory_lock_connection] object_id: 288013960, pg_backend_pid: 48781935 deletes all batched migration records1936 #up1937main: == [advisory_lock_connection] object_id: 289120980, pg_backend_pid: 48811938main: == 20230224144233 MigrateEvidencesFromRawMetadata: migrating ==================1939main: == 20230224144233 MigrateEvidencesFromRawMetadata: migrated (0.0085s) =========1940main: == [advisory_lock_connection] object_id: 289120980, pg_backend_pid: 48811941 schedules a batched background migration1942I, [2023-08-13T07:36:21.957215 #45749] INFO -- : Database: 'main', Table: 'ci_editor_ai_conversation_messages': Lock Writes1943I, [2023-08-13T07:36:26.488992 #45749] INFO -- : Database: 'main', Table: 'p_ci_builds': Lock Writes1944I, [2023-08-13T07:36:26.490306 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1945I, [2023-08-13T07:36:26.491502 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1946I, [2023-08-13T07:36:41.707063 #45749] INFO -- : Database: 'main', Table: 'p_ci_job_annotations': Lock Writes1947EnsureSuggestionsNoteIdBigintBackfillIsFinishedForGitlabDotCom1948 # order random1949 #up1950main: == [advisory_lock_connection] object_id: 303034560, pg_backend_pid: 48951951main: == 20230404023648 EnsureSuggestionsNoteIdBigintBackfillIsFinishedForGitlabDotCom: migrating 1952main: == 20230404023648 EnsureSuggestionsNoteIdBigintBackfillIsFinishedForGitlabDotCom: migrated (0.0078s) 1953main: == [advisory_lock_connection] object_id: 303034560, pg_backend_pid: 48951954 ensures the migration is completed for GitLab.com, dev, or test1955main: == [advisory_lock_connection] object_id: 303712060, pg_backend_pid: 48971956main: == 20230404023648 EnsureSuggestionsNoteIdBigintBackfillIsFinishedForGitlabDotCom: migrating 1957main: == 20230404023648 EnsureSuggestionsNoteIdBigintBackfillIsFinishedForGitlabDotCom: migrated (0.0092s) 1958main: == [advisory_lock_connection] object_id: 303712060, pg_backend_pid: 48971959 skips the check for other instances1960I, [2023-08-13T07:37:50.672820 #45749] INFO -- : Database: 'main', Table: 'ci_editor_ai_conversation_messages': Lock Writes1961I, [2023-08-13T07:37:54.350905 #45749] INFO -- : Database: 'main', Table: 'p_ci_builds': Lock Writes1962I, [2023-08-13T07:37:54.352100 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1963I, [2023-08-13T07:37:54.353066 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1964I, [2023-08-13T07:38:09.862788 #45749] INFO -- : Database: 'main', Table: 'p_ci_job_annotations': Lock Writes1965QueueFixVulnerabilityReadsHasIssues1966 # order random1967main: == [advisory_lock_connection] object_id: 317484580, pg_backend_pid: 49101968main: == 20230302185739 QueueFixVulnerabilityReadsHasIssues: migrating ==============1969main: == 20230302185739 QueueFixVulnerabilityReadsHasIssues: migrated (0.0584s) =====1970main: == [advisory_lock_connection] object_id: 317484580, pg_backend_pid: 49101971 schedules a new batched migration1972I, [2023-08-13T07:39:29.179815 #45749] INFO -- : Database: 'main', Table: 'ci_editor_ai_conversation_messages': Lock Writes1973I, [2023-08-13T07:39:33.714181 #45749] INFO -- : Database: 'main', Table: 'p_ci_builds': Lock Writes1974I, [2023-08-13T07:39:33.715294 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1975I, [2023-08-13T07:39:33.716109 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1976I, [2023-08-13T07:39:50.517032 #45749] INFO -- : Database: 'main', Table: 'p_ci_job_annotations': Lock Writes1977QueueDeleteOrphanedPackagesDependencies1978 # order random1979main: == [advisory_lock_connection] object_id: 333038540, pg_backend_pid: 49251980main: == 20230303105806 QueueDeleteOrphanedPackagesDependencies: migrating ==========1981main: == 20230303105806 QueueDeleteOrphanedPackagesDependencies: migrated (0.0535s) =1982main: == [advisory_lock_connection] object_id: 333038540, pg_backend_pid: 49251983 schedules a new batched migration1984I, [2023-08-13T07:41:11.802975 #45749] INFO -- : Database: 'main', Table: 'ci_editor_ai_conversation_messages': Lock Writes1985I, [2023-08-13T07:41:15.305989 #45749] INFO -- : Database: 'main', Table: 'p_ci_builds': Lock Writes1986I, [2023-08-13T07:41:15.307325 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1987I, [2023-08-13T07:41:15.308400 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1988I, [2023-08-13T07:41:32.361888 #45749] INFO -- : Database: 'main', Table: 'p_ci_job_annotations': Lock Writes1989EnsureTimelogsNoteIdBigintBackfillIsFinishedForGitlabDotCom1990 # order random1991 #up1992main: == [advisory_lock_connection] object_id: 348729040, pg_backend_pid: 49411993main: == 20230228021910 EnsureTimelogsNoteIdBigintBackfillIsFinishedForGitlabDotCom: migrating 1994main: == 20230228021910 EnsureTimelogsNoteIdBigintBackfillIsFinishedForGitlabDotCom: migrated (0.0088s) 1995main: == [advisory_lock_connection] object_id: 348729040, pg_backend_pid: 49411996 ensures the migration is completed for GitLab.com, dev, or test1997main: == [advisory_lock_connection] object_id: 349516180, pg_backend_pid: 49431998main: == 20230228021910 EnsureTimelogsNoteIdBigintBackfillIsFinishedForGitlabDotCom: migrating 1999main: == 20230228021910 EnsureTimelogsNoteIdBigintBackfillIsFinishedForGitlabDotCom: migrated (0.0088s) 2000main: == [advisory_lock_connection] object_id: 349516180, pg_backend_pid: 49432001 skips the check for other instances2002The application_settings (main) table has 1276 columns.2003Recreating the database2004Dropped database 'gitlabhq_test'2005Dropped database 'gitlabhq_test_ci'2006Created database 'gitlabhq_test'2007Created database 'gitlabhq_test_ci'2008main: == [advisory_lock_connection] object_id: 349857340, pg_backend_pid: 49532009main: == [advisory_lock_connection] object_id: 349857340, pg_backend_pid: 49532010ci: == [advisory_lock_connection] object_id: 349857420, pg_backend_pid: 49552011ci: == [advisory_lock_connection] object_id: 349857420, pg_backend_pid: 49552012Databases re-creation done in 11.8980401489998262013RemoveScimTokenAndScimIdentityNonRootGroup2014 # order random2015main: == [advisory_lock_connection] object_id: 354501740, pg_backend_pid: 49622016main: == 20230405132855 RemoveScimTokenAndScimIdentityNonRootGroup: migrating =======2017main: -- execute("DELETE FROM scim_identities\nWHERE scim_identities.group_id\nIN\n(\n SELECT namespaces.id FROM scim_oauth_access_tokens\n INNER JOIN namespaces ON namespaces.id=scim_oauth_access_tokens.group_id\n WHERE namespaces.type='Group' AND namespaces.parent_id IS NOT NULL\n AND scim_oauth_access_tokens.id BETWEEN 1 AND 2\n);\n\nDELETE FROM scim_oauth_access_tokens\nUSING namespaces\nWHERE namespaces.id=scim_oauth_access_tokens.group_id\nAND namespaces.type='Group' AND namespaces.parent_id IS NOT NULL\nAND scim_oauth_access_tokens.id BETWEEN 1 AND 2;\n")2018main: -> 0.0028s2019main: == 20230405132855 RemoveScimTokenAndScimIdentityNonRootGroup: migrated (0.0180s) 2020main: == [advisory_lock_connection] object_id: 354501740, pg_backend_pid: 49622021 removes scim_oauth_access_tokens that belong to non-root group and related scim_identities2022I, [2023-08-13T07:43:34.915979 #45749] INFO -- : Database: 'main', Table: 'ci_editor_ai_conversation_messages': Lock Writes2023I, [2023-08-13T07:43:38.449794 #45749] INFO -- : Database: 'main', Table: 'p_ci_builds': Lock Writes2024I, [2023-08-13T07:43:38.451304 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}2025I, [2023-08-13T07:43:38.452113 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}2026I, [2023-08-13T07:43:52.435872 #45749] INFO -- : Database: 'main', Table: 'p_ci_job_annotations': Lock Writes2027SwapSuggestionsNoteIdToBigintForGitlabDotCom2028 # order random2029 #up2030 swaps the integer and bigint columns for GitLab.com, dev, or test2031 is a no-op for other instances2032I, [2023-08-13T07:44:57.759943 #45749] INFO -- : Database: 'main', Table: 'ci_editor_ai_conversation_messages': Lock Writes2033I, [2023-08-13T07:45:01.460575 #45749] INFO -- : Database: 'main', Table: 'p_ci_builds': Lock Writes2034I, [2023-08-13T07:45:01.461966 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}2035I, [2023-08-13T07:45:01.462846 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}2036I, [2023-08-13T07:45:15.829638 #45749] INFO -- : Database: 'main', Table: 'p_ci_job_annotations': Lock Writes2037RescheduleLinksAvoidingDuplication2038 # order random2039 #down2040main: == [advisory_lock_connection] object_id: 380728400, pg_backend_pid: 49922041main: == 20230412141541 RescheduleLinksAvoidingDuplication: migrating ===============2042main: == 20230412141541 RescheduleLinksAvoidingDuplication: migrated (0.0071s) ======2043main: == [advisory_lock_connection] object_id: 380728400, pg_backend_pid: 49922044 deletes all batched migration records2045 #up2046main: == [advisory_lock_connection] object_id: 381482320, pg_backend_pid: 49952047main: == 20230412141541 RescheduleLinksAvoidingDuplication: migrating ===============2048main: == 20230412141541 RescheduleLinksAvoidingDuplication: migrated (0.0073s) ======2049main: == [advisory_lock_connection] object_id: 381482320, pg_backend_pid: 49952050 schedules a batched background migration2051I, [2023-08-13T07:46:19.572419 #45749] INFO -- : Database: 'main', Table: 'ci_editor_ai_conversation_messages': Lock Writes2052I, [2023-08-13T07:46:22.913334 #45749] INFO -- : Database: 'main', Table: 'p_ci_builds': Lock Writes2053I, [2023-08-13T07:46:22.914589 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}2054I, [2023-08-13T07:46:22.915417 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}2055I, [2023-08-13T07:46:37.632721 #45749] INFO -- : Database: 'main', Table: 'p_ci_job_annotations': Lock Writes2056DropPackagesEventsTable2057 # order random2058main: -- table_exists?(:packages_events)2059main: -> 0.0022s2060main: == [advisory_lock_connection] object_id: 394311640, pg_backend_pid: 50092061main: == 20230316185746 DropPackagesEventsTable: migrating ==========================2062main: -- drop_table(:packages_events, {:if_exists=>true})2063main: -> 0.0020s2064main: == 20230316185746 DropPackagesEventsTable: migrated (0.0108s) =================2065main: == [advisory_lock_connection] object_id: 394311640, pg_backend_pid: 50092066main: -- table_exists?(:packages_events)2067main: -> 0.0026s2068main: -- table_exists?(:packages_events)2069main: -> 0.0022s2070 drops and creates the packages_events table2071I, [2023-08-13T07:47:51.066651 #45749] INFO -- : Database: 'main', Table: 'ci_editor_ai_conversation_messages': Lock Writes2072I, [2023-08-13T07:47:55.570787 #45749] INFO -- : Database: 'main', Table: 'p_ci_builds': Lock Writes2073I, [2023-08-13T07:47:55.572258 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}2074I, [2023-08-13T07:47:55.573083 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}2075I, [2023-08-13T07:48:10.619829 #45749] INFO -- : Database: 'main', Table: 'p_ci_job_annotations': Lock Writes2076ScheduleIndexToMembersOnSourceAndTypeAndAccessLevel2077 # order random2078main: == [advisory_lock_connection] object_id: 407047620, pg_backend_pid: 50232079main: == 20230502102832 ScheduleIndexToMembersOnSourceAndTypeAndAccessLevel: migrating 2080main: -- index_exists?(:members, [:source_id, :source_type, :type, :access_level], {:name=>"index_members_on_source_and_type_and_access_level", :algorithm=>:concurrently})2081main: -> 0.0210s2082main: -- add_index_options(:members, [:source_id, :source_type, :type, :access_level], {:name=>"index_members_on_source_and_type_and_access_level", :algorithm=>:concurrently})2083main: -> 0.0001s2084main: == 20230502102832 ScheduleIndexToMembersOnSourceAndTypeAndAccessLevel: migrated (0.0455s) 2085main: == [advisory_lock_connection] object_id: 407047620, pg_backend_pid: 50232086 schedules the index2087I, [2023-08-13T07:49:12.694752 #45749] INFO -- : Database: 'main', Table: 'p_ci_builds': Lock Writes2088I, [2023-08-13T07:49:12.695790 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}2089I, [2023-08-13T07:49:12.696499 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}2090I, [2023-08-13T07:49:27.403154 #45749] INFO -- : Database: 'main', Table: 'p_ci_job_annotations': Lock Writes2091CleanupBigintConversionForSentNotifications2092 # order random2093main: == [advisory_lock_connection] object_id: 419025440, pg_backend_pid: 50372094main: == 20230411153310 CleanupBigintConversionForSentNotifications: migrating ======2095main: -- remove_column(:sent_notifications, "id_convert_to_bigint")2096main: -> 0.0013s2097main: == 20230411153310 CleanupBigintConversionForSentNotifications: migrated (0.0113s) 2098main: == [advisory_lock_connection] object_id: 419025440, pg_backend_pid: 50372099 correctly migrates up and down2100I, [2023-08-13T07:50:30.710590 #45749] INFO -- : Database: 'main', Table: 'ci_editor_ai_conversation_messages': Lock Writes2101I, [2023-08-13T07:50:34.416262 #45749] INFO -- : Database: 'main', Table: 'p_ci_builds': Lock Writes2102I, [2023-08-13T07:50:34.417553 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}2103I, [2023-08-13T07:50:34.418478 #45749] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}2104I, [2023-08-13T07:50:49.705529 #45749] INFO -- : Database: 'main', Table: 'p_ci_job_annotations': Lock Writes2105UpdateRequeueWorkersInApplicationSettingsForGitlabCom2106 # order random2107 #down2108main: == [advisory_lock_connection] object_id: 428302340, pg_backend_pid: 50502109main: == 20230621070810 UpdateRequeueWorkersInApplicationSettingsForGitlabCom: migrating 2110main: == 20230621070810 UpdateRequeueWorkersInApplicationSettingsForGitlabCom: migrated (0.0095s) 2111main: == [advisory_lock_connection] object_id: 428302340, pg_backend_pid: 50502112 does nothing2113main: == [advisory_lock_connection] object_id: 429048140, pg_backend_pid: 50532114main: == 20230621070810 UpdateRequeueWorkersInApplicationSettingsForGitlabCom: migrating 2115main: -- execute("UPDATE application_settings SET elasticsearch_requeue_workers = true")2116main: -> 0.0021s2117main: == 20230621070810 UpdateRequeueWorkersInApplicationSettingsForGitlabCom: migrated (0.0115s) 2118main: == [advisory_lock_connection] object_id: 429048140, pg_backend_pid: 50532119 updates elasticsearch_requeue_workers when gitlab.com2120 #up2121main: == [advisory_lock_connection] object_id: 429353980, pg_backend_pid: 50562122main: == 20230621070810 UpdateRequeueWorkersInApplicationSettingsForGitlabCom: migrating 2123main: == 20230621070810 UpdateRequeueWorkersInApplicationSettingsForGitlabCom: migrated (0.0087s) 2124main: == [advisory_lock_connection] object_id: 429353980, pg_backend_pid: 50562125 does nothing2126main: == [advisory_lock_connection] object_id: 429639660, pg_backend_pid: 50582127main: == 20230621070810 UpdateRequeueWorkersInApplicationSettingsForGitlabCom: migrating 2128main: -- execute("UPDATE application_settings SET elasticsearch_requeue_workers = true")2129main: -> 0.0019s2130main: == 20230621070810 UpdateRequeueWorkersInApplicationSettingsForGitlabCom: migrated (0.0105s) 2131main: == [advisory_lock_connection] object_id: 429639660, pg_backend_pid: 50582132 updates elasticsearch_requeue_workers when gitlab.com2133RedoRemoveCreateLearnGitlabWorkerJobInstances2134 # order random2135 #up2136main: == [advisory_lock_connection] object_id: 433855720, pg_backend_pid: 50702137main: == 20230608195429 RedoRemoveCreateLearnGitlabWorkerJobInstances: migrating ====2138main: == 20230608195429 RedoRemoveCreateLearnGitlabWorkerJobInstances: migrated (0.0083s) 2139main: == [advisory_lock_connection] object_id: 433855720, pg_backend_pid: 50702140 calls sidekiq_remove_jobs with correct argument2141QueueBackfillDismissalReasonInVulnerabilityReads2142 # order random2143main: == [advisory_lock_connection] object_id: 439174240, pg_backend_pid: 50812144main: == 20230612232000 QueueBackfillDismissalReasonInVulnerabilityReads: migrating =2145main: == 20230612232000 QueueBackfillDismissalReasonInVulnerabilityReads: migrated (0.0583s) 2146main: == [advisory_lock_connection] object_id: 439174240, pg_backend_pid: 50812147 schedules a new batched migration2148AddEpicWorkItemType2149 # order random2150main: == [advisory_lock_connection] object_id: 443782200, pg_backend_pid: 50932151main: == 20230728174927 AddEpicWorkItemType: migrating ==============================2152main: == 20230728174927 AddEpicWorkItemType: migrated (0.0608s) =====================2153main: == [advisory_lock_connection] object_id: 443782200, pg_backend_pid: 50932154 adds the epic type, widget definitions and hierarchy restrictions2155main: == [advisory_lock_connection] object_id: 443794060, pg_backend_pid: 50952156main: == 20230728174927 AddEpicWorkItemType: migrating ==============================2157main: -- Epic work item type record exists, skipping creation2158main: == 20230728174927 AddEpicWorkItemType: migrated (0.0150s) =====================2159main: == [advisory_lock_connection] object_id: 443794060, pg_backend_pid: 50952160 skips creating the new type an it's definitions2161QueueFixAllowDescendantsOverrideDisabledSharedRunners2162 # order random2163main: == [advisory_lock_connection] object_id: 444824420, pg_backend_pid: 51042164main: == 20230802085923 QueueFixAllowDescendantsOverrideDisabledSharedRunners: migrating 2165main: == 20230802085923 QueueFixAllowDescendantsOverrideDisabledSharedRunners: migrated (0.0564s) 2166main: == [advisory_lock_connection] object_id: 444824420, pg_backend_pid: 51042167 schedules a new batched migration2168Knapsack report was generated. Preview:2169{2170 "spec/migrations/sync_new_amount_used_for_ci_project_monthly_usages_spec.rb": 131.72151891199974,2171 "spec/migrations/20230208100917_fix_partition_ids_for_ci_pipeline_variable_spec.rb": 102.2786721829998,2172 "spec/migrations/20221018193635_ensure_task_note_renaming_background_migration_finished_spec.rb": 54.81168823200005,2173 "spec/migrations/set_email_confirmation_setting_before_removing_send_user_confirmation_email_column_spec.rb": 49.34898841900031,2174 "spec/migrations/add_namespaces_emails_enabled_column_data_spec.rb": 51.09366261100058,2175 "spec/migrations/20221226153252_queue_fix_incoherent_packages_size_on_project_statistics_spec.rb": 45.3528171629996,2176 "spec/migrations/20221018050323_add_objective_and_keyresult_to_work_item_types_spec.rb": 55.77601853299984,2177 "spec/migrations/20221220131020_bump_default_partition_id_value_for_ci_tables_spec.rb": 49.752991192999616,2178 "spec/migrations/20230302811133_re_migrate_redis_slot_keys_spec.rb": 39.83238466500006,2179 "spec/migrations/swap_merge_request_user_mentions_note_id_to_bigint_2_spec.rb": 43.33667917299954,2180 "spec/migrations/20230125195503_queue_backfill_compliance_violations_spec.rb": 46.37033979900025,2181 "spec/migrations/20230224144233_migrate_evidences_from_raw_metadata_spec.rb": 43.29478779600049,2182 "spec/migrations/ensure_suggestions_note_id_bigint_backfill_is_finished_for_gitlab_dot_com_spec.rb": 39.2839990679995,2183 "spec/migrations/20230302185739_queue_fix_vulnerability_reads_has_issues_spec.rb": 42.846131433000664,2184 "spec/migrations/20230303105806_queue_delete_orphaned_packages_dependencies_spec.rb": 43.847107392,2185 "spec/migrations/ensure_timelogs_note_id_bigint_backfill_is_finished_for_gitlab_dot_com_spec.rb": 45.26114112600044,2186 "spec/migrations/remove_scim_token_and_scim_identity_non_root_group_spec.rb": 34.40391679500044,2187 "spec/migrations/swap_suggestions_note_id_to_bigint_for_gitlab_dot_com_spec.rb": 36.96367464600007,2188 "spec/migrations/20230412141541_reschedule_links_avoiding_duplication_spec.rb": 36.27541767799994,2189 "spec/migrations/drop_packages_events_table_spec.rb": 38.40109469399977,2190 "spec/migrations/20230502102832_schedule_index_to_members_on_source_and_type_and_access_level_spec.rb": 33.199087966999286,2191 "spec/migrations/20230411153310_cleanup_bigint_conversion_for_sent_notifications_spec.rb": 35.82466980499976,2192 "spec/migrations/20230621070810_update_requeue_workers_in_application_settings_for_gitlab_com_spec.rb": 25.296612897999694,2193 "spec/migrations/20230608195429_redo_remove_create_learn_gitlab_worker_job_instances_spec.rb": 19.924089459999777,2194 "spec/migrations/20230612232000_queue_backfill_dismissal_reason_in_vulnerability_reads_spec.rb": 18.997260935000668,2195 "spec/migrations/20230728174927_add_epic_work_item_type_spec.rb": 7.2679812470005345,2196 "spec/migrations/20230802085923_queue_fix_allow_descendants_override_disabled_shared_runners_spec.rb": 5.3364293289996572197}2198Knapsack global time execution for tests: 19m 36s2199Finished in 42 minutes 11 seconds (files took 1 minute 30.66 seconds to load)220056 examples, 0 failures2201Randomized with seed 470532202[TEST PROF INFO] Time spent in factories: 00:00.394 (0.02% of total time)2203RSpec exited with 0.2204No examples to retry, congrats! 2205 Running after_script 2206Running after script...2207$ echo -e "\e[0Ksection_start:`date +%s`:report_results_section[collapsed=true]\r\e[0KReport results"2232Not uploading cache ruby-gems-debian-bullseye-ruby-3.0-protected due to policy2234Uploading artifacts...2235WARNING: auto_explain/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/edith007/gitlab) 2236coverage/: found 5 matching artifact files and directories 2237WARNING: crystalball/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/edith007/gitlab) 2238WARNING: deprecations/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/edith007/gitlab) 2239knapsack/: found 4 matching artifact files and directories 2240rspec/: found 10 matching artifact files and directories 2241WARNING: tmp/capybara/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/edith007/gitlab) 2242log/*.log: found 14 matching artifact files and directories 2243WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/4863614000/artifacts?artifact_format=zip&artifact_type=archive&expire_in=31d new-url=https://gitlab.com2244WARNING: Retrying... context=artifacts-uploader error=request redirected2245Uploading artifacts as "archive" to coordinator... 201 Created id=4863614000 responseStatus=201 Created token=64_xdKei2246Uploading artifacts...2247rspec/rspec-*.xml: found 1 matching artifact files and directories 2248WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/4863614000/artifacts?artifact_format=gzip&artifact_type=junit&expire_in=31d new-url=https://gitlab.com2249WARNING: Retrying... context=artifacts-uploader error=request redirected2250Uploading artifacts as "junit" to coordinator... 201 Created id=4863614000 responseStatus=201 Created token=64_xdKei2252Job succeeded