[09:37:11.876] New invocation is queued and will start shortly
[09:37:11.876] Triggered by user:infra-codesearch@chops-service-accounts.iam.gserviceaccount.com
[09:37:12.969] Starting the invocation (attempt 1)
[09:37:13.004] Ignoring gitiles_ref tag without the buildset tag
[09:37:13.004] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:37:13.004] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:37:13.004] Buildbucket request:
{
"requestId": "8954443926442890704",
"builder": {
"project": "infra",
"bucket": "codesearch",
"builder": "codesearch-gen-chromium-win"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8954443926442890704",
"job": "infra/codesearch-gen-chromium-win",
"triggers": [
{
"buildbucket": {
"properties": {
"codesearch_mirror_revision": "e45562b86f3f45847d344c435dd503577c3a7833",
"codesearch_mirror_revision_timestamp": 1676519934,
"root_solution_revision": "64fd0ad00a439264090ec36c15ff3de29428d965",
"root_solution_revision_timestamp": 1676519669
},
"tags": [
"parent_buildername:codesearch-gen-chromium-initiator",
"parent_buildnumber:16765",
"user_agent:recipe"
]
},
"id": "dba863f0-7b91-4b42-97e3-b82b89c5333c",
"title": "codesearch-gen-chromium-initiator/16765"
},
{
"buildbucket": {
"properties": {
"codesearch_mirror_revision": "6cb91b8eda11b4cdc51107f6998ae280fc19966f",
"codesearch_mirror_revision_timestamp": 1676534384,
"root_solution_revision": "f20a7c7edfb3515fb5d555f5bfaef324d19bb800",
"root_solution_revision_timestamp": 1676533894
},
"tags": [
"parent_buildername:codesearch-gen-chromium-initiator",
"parent_buildnumber:16766",
"user_agent:recipe"
]
},
"id": "2738e8a2-89c6-4f83-9d99-2c19ad0caec6",
"title": "codesearch-gen-chromium-initiator/16766"
}
]
},
"codesearch_mirror_revision": "6cb91b8eda11b4cdc51107f6998ae280fc19966f",
"codesearch_mirror_revision_timestamp": 1676534384,
"root_solution_revision": "f20a7c7edfb3515fb5d555f5bfaef324d19bb800",
"root_solution_revision_timestamp": 1676533894
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8954443926442890704"
},
{
"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": "16766"
},
{
"key": "user_agent",
"value": "recipe"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[09:37:13.792] Scheduled build:
{
"id": "8789013864355991681",
"builder": {
"project": "infra",
"bucket": "codesearch",
"builder": "codesearch-gen-chromium-win"
},
"number": 11408,
"createdBy": "project:infra",
"createTime": "2023-02-16T09:37:13.233369241Z",
"updateTime": "2023-02-16T09:37:13.233369241Z",
"status": "SCHEDULED",
"input": {
}
}
[09:37:13.792] Task URL: https://cr-buildbucket.appspot.com/build/8789013864355991681
[09:37:13.792] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:2:0) after 1m26s
[09:37:44.948] Received PubSub notification, asking Buildbucket for the build status
[09:37:44.974] Build status: STARTED
[09:38:39.823] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:2:0)
[09:38:39.860] Build status: STARTED
[09:38:39.860] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:4:0) after 3m7s
[09:41:47.041] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:4:0)
[09:41:47.063] Build status: STARTED
[09:41:47.063] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:5:0) after 6m2s
[09:47:49.083] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:5:0)
[09:47:49.126] Build status: STARTED
[09:47:49.126] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:6:0) after 9m29s
[09:57:18.290] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:6:0)
[09:57:18.364] Build status: STARTED
[09:57:18.364] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:7:0) after 2m20s
[09:59:38.424] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:7:0)
[09:59:38.450] Build status: STARTED
[09:59:38.450] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:8:0) after 6m56s
[10:06:34.679] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:8:0)
[10:06:34.702] Build status: STARTED
[10:06:34.702] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:9:0) after 6m34s
[10:13:08.742] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:9:0)
[10:13:08.795] Build status: STARTED
[10:13:08.795] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:10:0) after 3m13s
[10:16:21.816] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:10:0)
[10:16:21.835] Build status: STARTED
[10:16:21.835] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:11:0) after 5m53s
[10:22:14.983] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:11:0)
[10:22:15.026] Build status: STARTED
[10:22:15.026] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:12:0) after 6m47s
[10:29:02.152] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:12:0)
[10:29:02.178] Build status: STARTED
[10:29:02.178] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:13:0) after 2m36s
[10:31:38.288] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:13:0)
[10:31:38.469] Build status: STARTED
[10:31:38.469] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:14:0) after 2m30s
[10:34:08.512] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:14:0)
[10:34:08.543] Build status: STARTED
[10:34:08.543] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:15:0) after 5m40s
[10:39:48.679] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:15:0)
[10:39:48.700] Build status: STARTED
[10:39:48.700] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:16:0) after 9m51s
[10:49:39.902] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:16:0)
[10:49:39.942] Build status: STARTED
[10:49:39.942] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:17:0) after 4m53s
[10:54:33.016] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:17:0)
[10:54:33.036] Build status: STARTED
[10:54:33.037] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:18:0) after 1m39s
[10:56:12.034] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:18:0)
[10:56:12.225] Build status: STARTED
[10:56:12.226] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:19:0) after 4m13s
[11:00:25.289] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:19:0)
[11:00:25.329] Build status: STARTED
[11:00:25.329] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:20:0) after 5m45s
[11:06:10.495] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:20:0)
[11:06:10.526] Build status: STARTED
[11:06:10.526] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:21:0) after 4m33s
[11:10:43.746] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:21:0)
[11:10:43.769] Build status: STARTED
[11:10:43.769] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:22:0) after 6m14s
[11:16:57.932] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:22:0)
[11:16:57.965] Build status: STARTED
[11:16:57.965] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:23:0) after 8m26s
[11:25:23.969] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:23:0)
[11:25:24.006] Build status: STARTED
[11:25:24.006] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:24:0) after 2m32s
[11:27:56.029] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:24:0)
[11:27:56.069] Build status: STARTED
[11:27:56.069] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:25:0) after 1m39s
[11:29:35.085] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:25:0)
[11:29:35.106] Build status: STARTED
[11:29:35.106] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:26:0) after 7m53s
[11:37:28.391] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:26:0)
[11:37:28.416] Build status: STARTED
[11:37:28.416] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:27:0) after 7m12s
[11:44:40.562] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:27:0)
[11:44:40.593] Build status: STARTED
[11:44:40.593] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:28:0) after 2m1s
[11:46:41.947] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:28:0)
[11:46:41.969] Build status: STARTED
[11:46:41.969] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:29:0) after 9m46s
[11:56:27.998] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:29:0)
[11:56:28.028] Build status: STARTED
[11:56:28.028] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:30:0) after 4m24s
[12:00:52.177] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:30:0)
[12:00:52.199] Build status: STARTED
[12:00:52.199] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:31:0) after 9m24s
[12:10:16.263] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:31:0)
[12:10:16.293] Build status: STARTED
[12:10:16.293] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:32:0) after 4m41s
[12:14:57.499] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:32:0)
[12:14:57.554] Build status: STARTED
[12:14:57.554] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:33:0) after 1m33s
[12:16:30.668] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:33:0)
[12:16:30.773] Build status: STARTED
[12:16:30.773] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:34:0) after 1m24s
[12:17:54.923] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:34:0)
[12:17:54.948] Build status: STARTED
[12:17:54.948] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:35:0) after 3m16s
[12:21:10.973] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:35:0)
[12:21:10.996] Build status: STARTED
[12:21:10.996] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:36:0) after 8m48s
[12:29:59.068] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:36:0)
[12:29:59.113] Build status: STARTED
[12:29:59.113] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:37:0) after 2m9s
[12:32:08.412] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:37:0)
[12:32:08.437] Build status: STARTED
[12:32:08.437] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:38:0) after 7m10s
[12:39:18.456] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:38:0)
[12:39:18.479] Build status: STARTED
[12:39:18.479] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:39:0) after 5m49s
[12:45:07.748] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:39:0)
[12:45:07.795] Build status: STARTED
[12:45:07.795] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:40:0) after 7m46s
[12:52:53.823] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:40:0)
[12:52:53.854] Build status: STARTED
[12:52:53.854] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:41:0) after 9m28s
[13:02:21.998] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:41:0)
[13:02:22.042] Build status: STARTED
[13:02:22.042] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:42:0) after 7m14s
[13:09:36.399] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:42:0)
[13:09:36.419] Build status: STARTED
[13:09:36.419] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:43:0) after 6m21s
[13:15:57.552] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:43:0)
[13:15:57.584] Build status: STARTED
[13:15:57.584] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:44:0) after 2m27s
[13:18:24.609] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:44:0)
[13:18:24.635] Build status: STARTED
[13:18:24.635] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:45:0) after 6m1s
[13:24:25.783] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:45:0)
[13:24:25.806] Build status: STARTED
[13:24:25.806] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:46:0) after 2m23s
[13:26:48.892] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:46:0)
[13:26:48.914] Build status: STARTED
[13:26:48.914] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:47:0) after 2m25s
[13:29:13.932] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:47:0)
[13:29:13.953] Build status: STARTED
[13:29:13.953] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:48:0) after 5m32s
[13:34:46.011] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:48:0)
[13:34:46.038] Build status: STARTED
[13:34:46.038] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:49:0) after 1m59s
[13:36:45.241] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:49:0)
[13:36:45.284] Build status: STARTED
[13:36:45.284] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8954443926442890704:50:0) after 8m47s
[13:37:11.707] Received PubSub notification, asking Buildbucket for the build status
[13:37:11.778] Build:
{
"id": "8789013864355991681",
"builder": {
"project": "infra",
"bucket": "codesearch",
"builder": "codesearch-gen-chromium-win"
},
"number": 11408,
"createdBy": "project:infra",
"createTime": "2023-02-16T09:37:13.233369241Z",
"startTime": "2023-02-16T09:37:43.961730Z",
"endTime": "2023-02-16T13:37:11.485291073Z",
"updateTime": "2023-02-16T13:37:11.485291073Z",
"status": "SUCCESS",
"input": {
}
}
[13:37:11.778] Invocation finished in 3h59m59.918836795s with status SUCCEEDED