[20:03:29.590] New invocation is queued and will start shortly
[20:03:29.590] Triggered by user:infra-codesearch@chops-service-accounts.iam.gserviceaccount.com
[20:03:31.244] Starting the invocation (attempt 1)
[20:03:31.286] Ignoring gitiles_ref tag without the buildset tag
[20:03:31.286] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:03:31.286] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:03:31.286] Buildbucket request:
{
"requestId": "9013383148488170000",
"builder": {
"project": "infra",
"bucket": "codesearch",
"builder": "codesearch-gen-chromium-win"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"triggers": [
{
"buildbucket": {
"properties": {
"codesearch_mirror_revision": "f95d0eb0ad8e7671f2a8171ff96cad06973423cb",
"codesearch_mirror_revision_timestamp": 1620251670,
"root_solution_revision": "2fb972225a62171d9b02a34a270901833b4d7d94",
"root_solution_revision_timestamp": 1620251565
},
"tags": [
"parent_buildername:codesearch-gen-chromium-initiator",
"parent_buildnumber:12863",
"user_agent:recipe"
]
},
"id": "468b9fcd-d231-4095-bbc8-24c2388b28ad",
"title": "codesearch-gen-chromium-initiator/12863"
}
]
},
"codesearch_mirror_revision": "f95d0eb0ad8e7671f2a8171ff96cad06973423cb",
"codesearch_mirror_revision_timestamp": 1620251670,
"root_solution_revision": "2fb972225a62171d9b02a34a270901833b4d7d94",
"root_solution_revision_timestamp": 1620251565
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9013383148488170000"
},
{
"key": "scheduler_job_id",
"value": "infra/codesearch-gen-chromium-win"
},
{
"key": "user_agent",
"value": "luci-scheduler"
},
{
"key": "parent_buildername",
"value": "codesearch-gen-chromium-initiator"
},
{
"key": "parent_buildnumber",
"value": "12863"
},
{
"key": "user_agent",
"value": "recipe"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[20:03:31.843] Scheduled build:
{
"id": "8847953085856725840",
"builder": {
"project": "infra",
"bucket": "codesearch",
"builder": "codesearch-gen-chromium-win"
},
"number": 7582,
"createdBy": "project:infra",
"createTime": "2021-05-06T20:03:31.459276Z",
"updateTime": "2021-05-06T20:03:31.770710Z",
"status": "SCHEDULED",
"input": {
}
}
[20:03:31.843] Task URL: https://cr-buildbucket.appspot.com/build/8847953085856725840
[20:03:31.843] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:2:0) after 8m15s
[20:04:19.778] Received PubSub notification, asking Buildbucket for the build status
[20:04:19.804] Build status: STARTED
[20:11:46.972] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:2:0)
[20:11:46.996] Build status: STARTED
[20:11:46.996] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:4:0) after 9m13s
[20:21:00.143] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:4:0)
[20:21:00.169] Build status: STARTED
[20:21:00.169] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:5:0) after 6m57s
[20:27:57.353] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:5:0)
[20:27:57.385] Build status: STARTED
[20:27:57.385] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:6:0) after 5m33s
[20:33:30.533] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:6:0)
[20:33:30.582] Build status: STARTED
[20:33:30.582] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:7:0) after 1m5s
[20:34:36.055] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:7:0)
[20:34:36.084] Build status: STARTED
[20:34:36.084] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:8:0) after 8m29s
[20:43:05.446] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:8:0)
[20:43:05.473] Build status: STARTED
[20:43:05.473] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:9:0) after 5m55s
[20:49:00.448] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:9:0)
[20:49:00.482] Build status: STARTED
[20:49:00.483] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:10:0) after 6m34s
[20:55:34.561] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:10:0)
[20:55:34.599] Build status: STARTED
[20:55:34.599] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:11:0) after 5m24s
[21:00:58.780] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:11:0)
[21:00:58.896] Build status: STARTED
[21:00:58.896] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:12:0) after 8m34s
[21:09:32.986] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:12:0)
[21:09:33.011] Build status: STARTED
[21:09:33.011] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:13:0) after 6m1s
[21:15:34.312] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:13:0)
[21:15:34.342] Build status: STARTED
[21:15:34.342] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:14:0) after 9m0s
[21:24:34.369] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:14:0)
[21:24:34.401] Build status: STARTED
[21:24:34.401] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:15:0) after 3m42s
[21:28:16.425] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:15:0)
[21:28:16.452] Build status: STARTED
[21:28:16.452] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:16:0) after 5m1s
[21:33:17.470] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:16:0)
[21:33:17.528] Build status: STARTED
[21:33:17.528] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:17:0) after 4m43s
[21:38:00.551] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:17:0)
[21:38:00.578] Build status: STARTED
[21:38:00.578] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:18:0) after 7m1s
[21:45:01.696] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:18:0)
[21:45:01.738] Build status: STARTED
[21:45:01.738] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:19:0) after 6m39s
[21:51:40.779] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:19:0)
[21:51:40.810] Build status: STARTED
[21:51:40.810] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:20:0) after 7m46s
[21:59:27.039] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:20:0)
[21:59:27.069] Build status: STARTED
[21:59:27.069] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:21:0) after 1m25s
[22:00:52.429] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:21:0)
[22:00:52.462] Build status: STARTED
[22:00:52.462] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:22:0) after 7m33s
[22:08:25.477] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:22:0)
[22:08:25.517] Build status: STARTED
[22:08:25.517] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:23:0) after 4m27s
[22:12:52.631] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:23:0)
[22:12:52.662] Build status: STARTED
[22:12:52.662] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:24:0) after 8m6s
[22:20:58.780] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:24:0)
[22:20:58.867] Build status: STARTED
[22:20:58.867] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:25:0) after 2m28s
[22:23:26.884] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:25:0)
[22:23:26.939] Build status: STARTED
[22:23:26.939] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:26:0) after 4m54s
[22:28:20.910] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:26:0)
[22:28:21.007] Build status: STARTED
[22:28:21.007] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:27:0) after 3m9s
[22:31:30.357] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:27:0)
[22:31:30.390] Build status: STARTED
[22:31:30.390] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:28:0) after 5m18s
[22:36:48.433] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:28:0)
[22:36:48.462] Build status: STARTED
[22:36:48.462] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:29:0) after 5m40s
[22:42:28.506] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:29:0)
[22:42:28.548] Build status: STARTED
[22:42:28.548] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:30:0) after 8m45s
[22:51:13.677] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:30:0)
[22:51:13.708] Build status: STARTED
[22:51:13.708] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:31:0) after 1m14s
[22:52:27.903] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:31:0)
[22:52:27.929] Build status: STARTED
[22:52:27.930] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:32:0) after 4m17s
[22:56:45.418] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:32:0)
[22:56:45.445] Build status: STARTED
[22:56:45.445] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:33:0) after 2m9s
[22:58:54.558] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:33:0)
[22:58:54.585] Build status: STARTED
[22:58:54.585] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:34:0) after 4m4s
[23:02:59.061] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:34:0)
[23:02:59.087] Build status: STARTED
[23:02:59.087] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:35:0) after 1m29s
[23:04:28.166] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:35:0)
[23:04:28.195] Build status: STARTED
[23:04:28.195] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:36:0) after 9m24s
[23:13:52.334] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:36:0)
[23:13:52.470] Build status: STARTED
[23:13:52.470] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:37:0) after 5m14s
[23:19:06.645] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:37:0)
[23:19:06.758] Build status: STARTED
[23:19:06.758] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:38:0) after 9m12s
[23:28:18.740] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:38:0)
[23:28:18.770] Build status: STARTED
[23:28:18.770] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:39:0) after 2m37s
[23:30:55.792] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:39:0)
[23:30:55.821] Build status: STARTED
[23:30:55.821] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:40:0) after 7m11s
[23:38:07.009] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:40:0)
[23:38:07.039] Build status: STARTED
[23:38:07.039] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9013383148488170000:41:0) after 7m58s
[23:41:41.266] Received PubSub notification, asking Buildbucket for the build status
[23:41:41.297] Build:
{
"id": "8847953085856725840",
"builder": {
"project": "infra",
"bucket": "codesearch",
"builder": "codesearch-gen-chromium-win"
},
"number": 7582,
"createdBy": "project:infra",
"createTime": "2021-05-06T20:03:31.459276Z",
"startTime": "2021-05-06T20:04:18.504092Z",
"endTime": "2021-05-06T23:41:40.824208271Z",
"updateTime": "2021-05-06T23:41:40.824533518Z",
"status": "SUCCESS",
"input": {
}
}
[23:41:41.297] Invocation finished in 3h38m11.731583858s with status SUCCEEDED