[12:03:52.977] New invocation is queued and will start shortly
[12:03:52.977] Triggered by user:infra-codesearch@chops-service-accounts.iam.gserviceaccount.com
[12:03:54.097] Starting the invocation (attempt 1)
[12:03:54.135] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:03:54.135] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:03:54.135] Buildbucket request:
{
"bucket": "luci.infra.codesearch",
"client_operation_id": "9017671380368392736",
"parameters_json": "{\"builder_name\":\"codesearch-gen-chromium-linux\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"codesearch_mirror_revision\":\"2f8de60875d9d2efe0bb85b46e0300b5ca48ca30\",\"codesearch_mirror_revision_timestamp\":1616236255,\"root_solution_revision\":\"3b053d73cee5a918cae3203f77c3e3ffb315b40c\",\"root_solution_revision_timestamp\":1616236144},\"tags\":[\"parent_buildername:codesearch-gen-chromium-initiator\",\"parent_buildnumber:12579\",\"user_agent:recipe\"]},\"id\":\"5d974313-0e0f-40fc-b7b1-3849baee7afa\",\"title\":\"codesearch-gen-chromium-initiator/12579\"}]},\"codesearch_mirror_revision\":\"2f8de60875d9d2efe0bb85b46e0300b5ca48ca30\",\"codesearch_mirror_revision_timestamp\":1616236255,\"root_solution_revision\":\"3b053d73cee5a918cae3203f77c3e3ffb315b40c\",\"root_solution_revision_timestamp\":1616236144}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:codesearch-gen-chromium-linux",
"scheduler_invocation_id:9017671380368392736",
"scheduler_job_id:infra/codesearch-gen-chromium-linux",
"user_agent:luci-scheduler",
"parent_buildername:codesearch-gen-chromium-initiator",
"parent_buildnumber:12579",
"user_agent:recipe"
]
}
[12:03:55.400] Buildbucket response:
{
"build": {
"bucket": "luci.infra.codesearch",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1616241834230575",
"id": "8852241318389085680",
"parameters_json": "{\"builder_name\": \"codesearch-gen-chromium-linux\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"codesearch_mirror_revision\": \"2f8de60875d9d2efe0bb85b46e0300b5ca48ca30\", \"codesearch_mirror_revision_timestamp\": 1616236255.0, \"root_solution_revision\": \"3b053d73cee5a918cae3203f77c3e3ffb315b40c\", \"root_solution_revision_timestamp\": 1616236144.0}, \"tags\": [\"parent_buildername:codesearch-gen-chromium-initiator\", \"parent_buildnumber:12579\", \"user_agent:recipe\"]}, \"id\": \"5d974313-0e0f-40fc-b7b1-3849baee7afa\", \"title\": \"codesearch-gen-chromium-initiator/12579\"}]}, \"codesearch_mirror_revision\": \"2f8de60875d9d2efe0bb85b46e0300b5ca48ca30\", \"codesearch_mirror_revision_timestamp\": 1616236255.0, \"root_solution_revision\": \"3b053d73cee5a918cae3203f77c3e3ffb315b40c\", \"root_solution_revision_timestamp\": 1616236144.0}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "infra-codesearch@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1616241835105524",
"tags": [
"build_address:luci.infra.codesearch/codesearch-gen-chromium-linux/10240",
"builder:codesearch-gen-chromium-linux",
"parent_buildername:codesearch-gen-chromium-initiator",
"parent_buildnumber:12579",
"scheduler_invocation_id:9017671380368392736",
"scheduler_job_id:infra/codesearch-gen-chromium-linux",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/infra/buildbucket/cr-buildbucket.appspot.com/8852241318389085680/+/annotations",
"swarming_tag:luci_project:infra",
"swarming_tag:recipe_name:chromium_codesearch",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1616241835105620",
"url": "https://ci.chromium.org/b/8852241318389085680",
"utcnow_ts": "1616241835344513"
}
}
[12:03:55.400] Task URL: https://ci.chromium.org/b/8852241318389085680
[12:03:55.400] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:2:0) after 1m0s
[12:04:45.766] Received PubSub notification, asking Buildbucket for the build status
[12:04:45.795] Build status: STARTED
[12:04:55.414] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:2:0)
[12:04:55.439] Build status: STARTED
[12:04:55.439] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:4:0) after 8m12s
[12:13:07.454] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:4:0)
[12:13:07.454] Timer tick, asking Buildbucket for the build status
[12:13:07.597] Build 8852241318389085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:07.597] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:5:0) after 1m0s
[12:14:07.613] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:5:0)
[12:14:07.642] Build status: STARTED
[12:14:07.642] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:6:0) after 1m34s
[12:15:41.893] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:6:0)
[12:15:42.304] Build status: STARTED
[12:15:42.304] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:7:0) after 7m38s
[12:23:20.325] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:7:0)
[12:23:20.325] Timer tick, asking Buildbucket for the build status
[12:23:20.659] Build 8852241318389085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:20.659] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:8:0) after 1m0s
[12:24:20.674] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:8:0)
[12:24:20.704] Build status: STARTED
[12:24:20.704] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:9:0) after 1m0s
[12:25:20.829] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:9:0)
[12:25:20.862] Build status: STARTED
[12:25:20.862] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:10:0) after 3m45s
[12:29:05.965] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:10:0)
[12:29:05.965] Timer tick, asking Buildbucket for the build status
[12:29:06.105] Build 8852241318389085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:06.105] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:11:0) after 1m0s
[12:30:06.109] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:11:0)
[12:30:06.109] Timer tick, asking Buildbucket for the build status
[12:30:06.385] Build 8852241318389085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:06.385] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:12:0) after 1m0s
[12:31:06.884] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:12:0)
[12:31:07.005] Build status: STARTED
[12:31:07.005] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:13:0) after 4m18s
[12:35:25.093] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:13:0)
[12:35:25.093] Timer tick, asking Buildbucket for the build status
[12:35:25.182] Build 8852241318389085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:25.182] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:14:0) after 1m0s
[12:36:25.216] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:14:0)
[12:36:25.216] Timer tick, asking Buildbucket for the build status
[12:36:25.428] Build 8852241318389085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:25.428] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:15:0) after 1m0s
[12:37:25.457] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:15:0)
[12:37:25.487] Build status: STARTED
[12:37:25.487] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:16:0) after 8m37s
[12:46:03.117] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:16:0)
[12:46:03.155] Build status: STARTED
[12:46:03.156] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:17:0) after 9m52s
[12:55:55.188] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:17:0)
[12:55:55.188] Timer tick, asking Buildbucket for the build status
[12:55:55.520] Build 8852241318389085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:55.520] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:18:0) after 1m0s
[12:56:55.545] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:18:0)
[12:56:55.545] Timer tick, asking Buildbucket for the build status
[12:56:55.692] Build 8852241318389085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:55.692] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:19:0) after 1m0s
[12:57:55.723] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:19:0)
[12:57:55.751] Build status: STARTED
[12:57:55.751] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:20:0) after 2m59s
[13:00:54.780] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:20:0)
[13:00:54.811] Build status: STARTED
[13:00:54.811] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:21:0) after 2m27s
[13:03:21.904] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:21:0)
[13:03:21.935] Build status: STARTED
[13:03:21.935] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:22:0) after 5m18s
[13:08:39.972] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:22:0)
[13:08:39.972] Timer tick, asking Buildbucket for the build status
[13:08:40.184] Build 8852241318389085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:40.184] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:23:0) after 1m0s
[13:09:40.204] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:23:0)
[13:09:40.204] Timer tick, asking Buildbucket for the build status
[13:09:40.325] Build 8852241318389085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:40.325] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:24:0) after 1m0s
[13:10:40.364] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:24:0)
[13:10:40.364] Timer tick, asking Buildbucket for the build status
[13:10:40.784] Build 8852241318389085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:40.784] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:25:0) after 1m0s
[13:11:40.808] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:25:0)
[13:11:40.808] Timer tick, asking Buildbucket for the build status
[13:11:40.908] Build 8852241318389085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:40.908] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:26:0) after 1m0s
[13:12:40.931] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:26:0)
[13:12:40.962] Build status: STARTED
[13:12:40.962] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:27:0) after 8m3s
[13:20:43.981] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:27:0)
[13:20:44.007] Build status: STARTED
[13:20:44.007] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:28:0) after 5m49s
[13:26:33.033] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:28:0)
[13:26:33.033] Timer tick, asking Buildbucket for the build status
[13:26:33.132] Build 8852241318389085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:33.132] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:29:0) after 1m0s
[13:27:33.114] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:29:0)
[13:27:33.138] Build status: STARTED
[13:27:33.138] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:30:0) after 1m46s
[13:29:19.167] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:30:0)
[13:29:19.167] Timer tick, asking Buildbucket for the build status
[13:29:19.426] Build 8852241318389085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:19.427] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:31:0) after 1m0s
[13:30:19.481] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:31:0)
[13:30:19.526] Build status: STARTED
[13:30:19.526] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:32:0) after 8m32s
[13:38:51.659] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:32:0)
[13:38:51.659] Timer tick, asking Buildbucket for the build status
[13:38:51.841] Build 8852241318389085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:51.841] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:33:0) after 1m0s
[13:39:51.864] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:33:0)
[13:39:51.905] Build status: STARTED
[13:39:51.905] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:34:0) after 6m29s
[13:46:21.129] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:34:0)
[13:46:21.163] Build status: STARTED
[13:46:21.163] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:35:0) after 9m29s
[13:55:50.201] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:35:0)
[13:55:50.201] Timer tick, asking Buildbucket for the build status
[13:55:50.426] Build 8852241318389085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:50.426] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:36:0) after 1m0s
[13:56:50.449] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:36:0)
[13:56:50.449] Timer tick, asking Buildbucket for the build status
[13:56:50.872] Build 8852241318389085680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:50.872] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:37:0) after 1m0s
[13:57:51.057] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:37:0)
[13:57:51.081] Build status: STARTED
[13:57:51.081] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:38:0) after 4m3s
[14:01:54.221] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:38:0)
[14:01:54.252] Build status: STARTED
[14:01:54.252] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017671380368392736:39:0) after 6m25s
[14:02:43.205] Received PubSub notification, asking Buildbucket for the build status
[14:02:43.231] Build:
{
"id": "8852241318389085680",
"builder": {
"project": "infra",
"bucket": "codesearch",
"builder": "codesearch-gen-chromium-linux"
},
"number": 10240,
"createdBy": "project:infra",
"createTime": "2021-03-20T12:03:54.230575Z",
"startTime": "2021-03-20T12:04:44.985833Z",
"endTime": "2021-03-20T14:02:42.760408712Z",
"updateTime": "2021-03-20T14:02:42.760735701Z",
"status": "SUCCESS",
"input": {
}
}
[14:02:43.231] Invocation finished in 1h58m50.273274815s with status SUCCEEDED