[17:39:04.860] New invocation is queued and will start shortly
[17:39:04.860] Triggered by user:infra-codesearch@chops-service-accounts.iam.gserviceaccount.com
[17:39:06.222] Starting the invocation (attempt 1)
[17:39:06.249] Ignoring gitiles_ref tag without the buildset tag
[17:39:06.249] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:39:06.249] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:39:06.251] Buildbucket request:
{
"requestId": "8937200185360941680",
"builder": {
"project": "infra",
"bucket": "codesearch",
"builder": "codesearch-gen-chromium-win"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8937200185360941680",
"job": "infra/codesearch-gen-chromium-win",
"triggers": [
{
"buildbucket": {
"properties": {
"codesearch_mirror_revision": "f89bcf2be273a9a0c74dff5764e9f4a84dca23e3",
"codesearch_mirror_revision_timestamp": 1692959031,
"root_solution_revision": "e8eea040218f35100127fdb30377253be5176351",
"root_solution_revision_timestamp": 1692958750
},
"tags": [
"parent_buildername:codesearch-gen-chromium-initiator",
"parent_buildnumber:17907",
"user_agent:recipe"
]
},
"id": "880fef2f-0b38-427f-9bd2-ad54d09455e8",
"title": "codesearch-gen-chromium-initiator/17907"
},
{
"buildbucket": {
"properties": {
"codesearch_mirror_revision": "32a1bb477f7e506c52989a5dd5433567b2d1094e",
"codesearch_mirror_revision_timestamp": 1692975385,
"root_solution_revision": "2f7c7b7264d8c5df06a486aba1960e8dd528557e",
"root_solution_revision_timestamp": 1692975270
},
"tags": [
"parent_buildername:codesearch-gen-chromium-initiator",
"parent_buildnumber:17908",
"user_agent:recipe"
]
},
"id": "75ba752a-2301-421d-a257-54c4a40cf1e7",
"title": "codesearch-gen-chromium-initiator/17908"
}
]
},
"codesearch_mirror_revision": "32a1bb477f7e506c52989a5dd5433567b2d1094e",
"codesearch_mirror_revision_timestamp": 1692975385,
"root_solution_revision": "2f7c7b7264d8c5df06a486aba1960e8dd528557e",
"root_solution_revision_timestamp": 1692975270
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8937200185360941680"
},
{
"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": "17908"
},
{
"key": "user_agent",
"value": "recipe"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[17:39:06.856] Scheduled build:
{
"id": "8771770123182724993",
"builder": {
"project": "infra",
"bucket": "codesearch",
"builder": "codesearch-gen-chromium-win"
},
"number": 12367,
"createdBy": "project:infra",
"createTime": "2023-08-25T17:39:06.308145395Z",
"updateTime": "2023-08-25T17:39:06.308145395Z",
"status": "SCHEDULED",
"input": {
}
}
[17:39:06.856] Task URL: https://cr-buildbucket.appspot.com/build/8771770123182724993
[17:39:06.856] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:2:0) after 7m43s
[17:39:07.839] Received PubSub notification, asking Buildbucket for the build status
[17:39:07.876] Build status: SCHEDULED
[17:39:30.328] Received PubSub notification, asking Buildbucket for the build status
[17:39:30.350] Build status: STARTED
[17:46:50.098] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:2:0)
[17:46:50.119] Build status: STARTED
[17:46:50.119] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:5:0) after 4m58s
[17:51:48.442] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:5:0)
[17:51:48.481] Build status: STARTED
[17:51:48.481] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:6:0) after 6m18s
[17:58:06.734] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:6:0)
[17:58:06.756] Build status: STARTED
[17:58:06.756] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:7:0) after 3m54s
[18:02:00.914] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:7:0)
[18:02:00.933] Build status: STARTED
[18:02:00.933] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:8:0) after 1m13s
[18:03:14.583] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:8:0)
[18:03:14.662] Build status: STARTED
[18:03:14.662] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:9:0) after 2m24s
[18:05:38.690] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:9:0)
[18:05:38.740] Build status: STARTED
[18:05:38.740] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:10:0) after 4m44s
[18:10:22.756] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:10:0)
[18:10:22.784] Build status: STARTED
[18:10:22.784] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:11:0) after 3m18s
[18:13:40.902] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:11:0)
[18:13:40.929] Build status: STARTED
[18:13:40.929] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:12:0) after 2m47s
[18:16:27.944] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:12:0)
[18:16:27.963] Build status: STARTED
[18:16:27.963] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:13:0) after 1m21s
[18:17:48.976] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:13:0)
[18:17:49.237] Build status: STARTED
[18:17:49.237] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:14:0) after 8m6s
[18:25:55.567] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:14:0)
[18:25:55.614] Build status: STARTED
[18:25:55.614] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:15:0) after 1m20s
[18:27:15.682] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:15:0)
[18:27:15.938] Build status: STARTED
[18:27:15.938] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:16:0) after 9m32s
[18:36:47.998] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:16:0)
[18:36:48.026] Build status: STARTED
[18:36:48.026] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:17:0) after 9m11s
[18:45:59.063] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:17:0)
[18:45:59.126] Build status: STARTED
[18:45:59.126] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:18:0) after 1m9s
[18:47:08.146] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:18:0)
[18:47:08.250] Build status: STARTED
[18:47:08.250] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:19:0) after 9m24s
[18:56:32.296] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:19:0)
[18:56:32.323] Build status: STARTED
[18:56:32.323] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:20:0) after 5m40s
[19:02:12.410] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:20:0)
[19:02:12.433] Build status: STARTED
[19:02:12.433] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:21:0) after 3m37s
[19:05:49.449] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:21:0)
[19:05:49.479] Build status: STARTED
[19:05:49.479] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:22:0) after 3m27s
[19:09:16.495] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:22:0)
[19:09:16.512] Build status: STARTED
[19:09:16.512] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:23:0) after 6m30s
[19:15:46.575] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:23:0)
[19:15:46.593] Build status: STARTED
[19:15:46.593] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:24:0) after 9m33s
[19:25:19.616] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:24:0)
[19:25:19.649] Build status: STARTED
[19:25:19.649] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:25:0) after 5m41s
[19:31:00.674] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:25:0)
[19:31:00.712] Build status: STARTED
[19:31:00.713] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:26:0) after 4m26s
[19:35:26.761] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:26:0)
[19:35:26.822] Build status: STARTED
[19:35:26.822] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:27:0) after 9m35s
[19:45:01.948] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:27:0)
[19:45:01.979] Build status: STARTED
[19:45:01.979] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:28:0) after 6m30s
[19:51:32.185] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:28:0)
[19:51:32.287] Build status: STARTED
[19:51:32.287] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:29:0) after 3m6s
[19:54:38.539] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:29:0)
[19:54:38.558] Build status: STARTED
[19:54:38.558] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:30:0) after 9m9s
[20:03:48.009] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:30:0)
[20:03:48.040] Build status: STARTED
[20:03:48.040] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:31:0) after 4m7s
[20:07:55.061] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:31:0)
[20:07:55.095] Build status: STARTED
[20:07:55.095] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:32:0) after 6m37s
[20:14:32.488] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:32:0)
[20:14:32.506] Build status: STARTED
[20:14:32.506] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:33:0) after 5m29s
[20:20:01.550] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:33:0)
[20:20:01.592] Build status: STARTED
[20:20:01.592] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:34:0) after 3m38s
[20:23:39.609] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:34:0)
[20:23:39.638] Build status: STARTED
[20:23:39.638] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:35:0) after 9m51s
[20:33:31.088] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:35:0)
[20:33:31.106] Build status: STARTED
[20:33:31.106] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:36:0) after 5m40s
[20:39:11.459] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:36:0)
[20:39:11.483] Build status: STARTED
[20:39:11.483] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:37:0) after 1m35s
[20:40:47.548] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:37:0)
[20:40:47.650] Build status: STARTED
[20:40:47.650] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:38:0) after 7m47s
[20:48:34.729] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:38:0)
[20:48:34.747] Build status: STARTED
[20:48:34.747] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:39:0) after 8m8s
[20:56:42.962] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:39:0)
[20:56:42.993] Build status: STARTED
[20:56:42.993] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:40:0) after 8m22s
[21:05:05.141] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:40:0)
[21:05:05.176] Build status: STARTED
[21:05:05.176] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:41:0) after 4m30s
[21:09:35.194] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:41:0)
[21:09:35.214] Build status: STARTED
[21:09:35.214] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:42:0) after 5m4s
[21:14:39.637] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:42:0)
[21:14:39.663] Build status: STARTED
[21:14:39.663] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:43:0) after 4m24s
[21:19:03.695] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:43:0)
[21:19:03.720] Build status: STARTED
[21:19:03.720] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:44:0) after 1m18s
[21:20:22.039] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:44:0)
[21:20:22.083] Build status: STARTED
[21:20:22.083] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:45:0) after 1m41s
[21:22:03.182] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:45:0)
[21:22:03.232] Build status: STARTED
[21:22:03.232] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:46:0) after 6m20s
[21:28:23.453] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:46:0)
[21:28:23.481] Build status: STARTED
[21:28:23.481] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:47:0) after 1m22s
[21:29:45.535] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:47:0)
[21:29:45.553] Build status: STARTED
[21:29:45.553] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:48:0) after 5m56s
[21:35:41.649] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:48:0)
[21:35:41.668] Build status: STARTED
[21:35:41.668] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:49:0) after 9m18s
[21:44:59.846] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:49:0)
[21:44:59.868] Build status: STARTED
[21:44:59.868] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:50:0) after 6m59s
[21:51:59.043] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:50:0)
[21:51:59.081] Build status: STARTED
[21:51:59.081] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:51:0) after 1m45s
[21:53:44.205] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:51:0)
[21:53:44.223] Build status: STARTED
[21:53:44.223] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:52:0) after 5m45s
[21:59:29.272] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:52:0)
[21:59:29.301] Build status: STARTED
[21:59:29.301] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:53:0) after 1m38s
[22:01:07.427] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:53:0)
[22:01:07.463] Build status: STARTED
[22:01:07.463] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:54:0) after 7m49s
[22:08:56.497] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:54:0)
[22:08:56.520] Build status: STARTED
[22:08:56.520] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:55:0) after 8m53s
[22:17:49.647] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:55:0)
[22:17:49.683] Build status: STARTED
[22:17:49.683] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:56:0) after 8m24s
[22:26:13.713] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:56:0)
[22:26:13.759] Build status: STARTED
[22:26:13.759] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:57:0) after 9m54s
[22:36:07.802] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:57:0)
[22:36:07.832] Build status: STARTED
[22:36:07.832] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:58:0) after 8m13s
[22:44:20.917] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:58:0)
[22:44:20.937] Build status: STARTED
[22:44:20.937] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:59:0) after 2m53s
[22:47:13.961] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:59:0)
[22:47:14.060] Build status: STARTED
[22:47:14.060] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:60:0) after 1m41s
[22:48:55.081] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:60:0)
[22:48:55.100] Build status: STARTED
[22:48:55.100] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8937200185360941680:61:0) after 8m18s
[22:56:43.239] Received PubSub notification, asking Buildbucket for the build status
[22:56:43.268] Build:
{
"id": "8771770123182724993",
"builder": {
"project": "infra",
"bucket": "codesearch",
"builder": "codesearch-gen-chromium-win"
},
"number": 12367,
"createdBy": "project:infra",
"createTime": "2023-08-25T17:39:06.308145395Z",
"startTime": "2023-08-25T17:39:29.812125Z",
"endTime": "2023-08-25T22:56:40.225069872Z",
"updateTime": "2023-08-25T22:56:40.225069872Z",
"status": "SUCCESS",
"input": {
}
}
[22:56:43.268] Invocation finished in 5h17m38.420429771s with status SUCCEEDED