[20:03:47.026] New invocation is queued and will start shortly
[20:03:47.026] Triggered by user:infra-codesearch@chops-service-accounts.iam.gserviceaccount.com
[20:03:48.281] Starting the invocation (attempt 1)
[20:03:48.315] Ignoring gitiles_ref tag without the buildset tag
[20:03:48.315] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:03:48.315] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:03:48.315] Buildbucket request:
{
"requestId": "9017550590649453008",
"builder": {
"project": "infra",
"bucket": "codesearch",
"builder": "codesearch-gen-chromium-linux"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"triggers": [
{
"buildbucket": {
"properties": {
"codesearch_mirror_revision": "6d5945a81fdd82da5812702e99194043b74e12ba",
"codesearch_mirror_revision_timestamp": 1616353876,
"root_solution_revision": "e8451687bb55c14bfd8df2f35d93e436f1b84cef",
"root_solution_revision_timestamp": 1616353791
},
"tags": [
"parent_buildername:codesearch-gen-chromium-initiator",
"parent_buildnumber:12587",
"user_agent:recipe"
]
},
"id": "6523a80f-7a06-4e93-aca5-0e2f0e3c7aa6",
"title": "codesearch-gen-chromium-initiator/12587"
}
]
},
"codesearch_mirror_revision": "6d5945a81fdd82da5812702e99194043b74e12ba",
"codesearch_mirror_revision_timestamp": 1616353876,
"root_solution_revision": "e8451687bb55c14bfd8df2f35d93e436f1b84cef",
"root_solution_revision_timestamp": 1616353791
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9017550590649453008"
},
{
"key": "scheduler_job_id",
"value": "infra/codesearch-gen-chromium-linux"
},
{
"key": "user_agent",
"value": "luci-scheduler"
},
{
"key": "parent_buildername",
"value": "codesearch-gen-chromium-initiator"
},
{
"key": "parent_buildnumber",
"value": "12587"
},
{
"key": "user_agent",
"value": "recipe"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[20:03:48.884] Scheduled build:
{
"id": "8852120528526244752",
"builder": {
"project": "infra",
"bucket": "codesearch",
"builder": "codesearch-gen-chromium-linux"
},
"number": 10248,
"createdBy": "project:infra",
"createTime": "2021-03-21T20:03:48.420510Z",
"updateTime": "2021-03-21T20:03:48.817686Z",
"status": "SCHEDULED",
"input": {
},
"canary": true
}
[20:03:48.884] Task URL: https://cr-buildbucket.appspot.com/build/8852120528526244752
[20:03:48.884] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:2:0) after 6m55s
[20:04:20.366] Received PubSub notification, asking Buildbucket for the build status
[20:04:20.405] Build status: STARTED
[20:10:43.902] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:2:0)
[20:10:43.902] Timer tick, asking Buildbucket for the build status
[20:10:43.980] Build 8852120528526244752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:43.980] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:4:0) after 1m0s
[20:11:43.996] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:4:0)
[20:11:43.996] Timer tick, asking Buildbucket for the build status
[20:11:44.177] Build 8852120528526244752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:44.177] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:5:0) after 1m0s
[20:12:44.192] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:5:0)
[20:12:44.192] Timer tick, asking Buildbucket for the build status
[20:12:44.253] Build 8852120528526244752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:44.253] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:6:0) after 1m0s
[20:13:44.326] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:6:0)
[20:13:44.326] Timer tick, asking Buildbucket for the build status
[20:13:44.390] Build 8852120528526244752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:44.390] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:7:0) after 1m0s
[20:14:44.438] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:7:0)
[20:14:44.438] Timer tick, asking Buildbucket for the build status
[20:14:44.560] Build 8852120528526244752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:44.560] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:8:0) after 1m0s
[20:15:44.737] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:8:0)
[20:15:44.737] Timer tick, asking Buildbucket for the build status
[20:15:44.814] Build 8852120528526244752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:44.814] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:9:0) after 1m0s
[20:16:44.832] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:9:0)
[20:16:44.860] Build status: STARTED
[20:16:44.860] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:10:0) after 1m47s
[20:18:31.926] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:10:0)
[20:18:31.926] Timer tick, asking Buildbucket for the build status
[20:18:32.365] Build 8852120528526244752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:32.365] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:11:0) after 1m0s
[20:19:32.382] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:11:0)
[20:19:32.382] Timer tick, asking Buildbucket for the build status
[20:19:32.475] Build 8852120528526244752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:32.475] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:12:0) after 1m0s
[20:20:32.590] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:12:0)
[20:20:32.615] Build status: STARTED
[20:20:32.615] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:13:0) after 6m1s
[20:26:33.633] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:13:0)
[20:26:33.659] Build status: STARTED
[20:26:33.659] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:14:0) after 4m29s
[20:31:02.679] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:14:0)
[20:31:02.706] Build status: STARTED
[20:31:02.706] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:15:0) after 9m54s
[20:40:56.723] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:15:0)
[20:40:56.752] Build status: STARTED
[20:40:56.752] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:16:0) after 9m5s
[20:50:01.775] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:16:0)
[20:50:01.775] Timer tick, asking Buildbucket for the build status
[20:50:01.928] Build 8852120528526244752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:01.928] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:17:0) after 1m0s
[20:51:01.893] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:17:0)
[20:51:01.921] Build status: STARTED
[20:51:01.921] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:18:0) after 5m42s
[20:56:43.961] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:18:0)
[20:56:43.961] Timer tick, asking Buildbucket for the build status
[20:56:44.057] Build 8852120528526244752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:44.057] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:19:0) after 1m0s
[20:57:44.175] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:19:0)
[20:57:44.175] Timer tick, asking Buildbucket for the build status
[20:57:44.252] Build 8852120528526244752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:44.252] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:20:0) after 1m0s
[20:58:44.509] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:20:0)
[20:58:44.532] Build status: STARTED
[20:58:44.532] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:21:0) after 4m24s
[21:03:08.685] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:21:0)
[21:03:08.717] Build status: STARTED
[21:03:08.717] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:22:0) after 5m4s
[21:08:12.785] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:22:0)
[21:08:12.812] Build status: STARTED
[21:08:12.812] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:23:0) after 5m32s
[21:13:44.831] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:23:0)
[21:13:44.858] Build status: STARTED
[21:13:44.858] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:24:0) after 2m55s
[21:16:40.230] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:24:0)
[21:16:40.230] Timer tick, asking Buildbucket for the build status
[21:16:40.314] Build 8852120528526244752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:40.314] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:25:0) after 1m0s
[21:17:40.331] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:25:0)
[21:17:40.331] Timer tick, asking Buildbucket for the build status
[21:17:40.447] Build 8852120528526244752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:40.447] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:26:0) after 1m0s
[21:18:40.462] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:26:0)
[21:18:40.462] Timer tick, asking Buildbucket for the build status
[21:18:40.602] Build 8852120528526244752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:40.603] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:27:0) after 1m0s
[21:19:40.636] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:27:0)
[21:19:40.663] Build status: STARTED
[21:19:40.663] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:28:0) after 4m38s
[21:24:18.687] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:28:0)
[21:24:18.713] Build status: STARTED
[21:24:18.713] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:29:0) after 2m35s
[21:26:53.792] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:29:0)
[21:26:53.792] Timer tick, asking Buildbucket for the build status
[21:26:53.874] Build 8852120528526244752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:53.874] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:30:0) after 1m0s
[21:27:54.014] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:30:0)
[21:27:54.040] Build status: STARTED
[21:27:54.040] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:31:0) after 7m49s
[21:35:43.058] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:31:0)
[21:35:43.058] Timer tick, asking Buildbucket for the build status
[21:35:43.138] Build 8852120528526244752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:43.138] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:32:0) after 1m0s
[21:36:43.157] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:32:0)
[21:36:43.157] Timer tick, asking Buildbucket for the build status
[21:36:43.233] Build 8852120528526244752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:43.233] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:33:0) after 1m0s
[21:37:43.255] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:33:0)
[21:37:43.255] Timer tick, asking Buildbucket for the build status
[21:37:43.499] Build 8852120528526244752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:43.499] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:34:0) after 1m0s
[21:38:43.517] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:34:0)
[21:38:43.540] Build status: STARTED
[21:38:43.540] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:35:0) after 4m34s
[21:43:17.582] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:35:0)
[21:43:17.582] Timer tick, asking Buildbucket for the build status
[21:43:17.658] Build 8852120528526244752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:17.658] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:36:0) after 1m0s
[21:44:17.677] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:36:0)
[21:44:17.711] Build status: STARTED
[21:44:17.711] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017550590649453008:37:0) after 5m20s
[21:46:16.599] Received PubSub notification, asking Buildbucket for the build status
[21:46:16.624] Build:
{
"id": "8852120528526244752",
"builder": {
"project": "infra",
"bucket": "codesearch",
"builder": "codesearch-gen-chromium-linux"
},
"number": 10248,
"createdBy": "project:infra",
"createTime": "2021-03-21T20:03:48.420510Z",
"startTime": "2021-03-21T20:04:18.734458Z",
"endTime": "2021-03-21T21:46:16.013927053Z",
"updateTime": "2021-03-21T21:46:16.014368062Z",
"status": "SUCCESS",
"input": {
},
"canary": true
}
[21:46:16.624] Invocation finished in 1h42m29.612783404s with status SUCCEEDED