[04:02:04.397] New invocation is queued and will start shortly
[04:02:04.397] Triggered by user:infra-codesearch@chops-service-accounts.iam.gserviceaccount.com
[04:02:05.426] Starting the invocation (attempt 1)
[04:02:05.459] Ignoring gitiles_ref tag without the buildset tag
[04:02:05.459] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:02:05.459] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:02:05.459] Buildbucket request:
{
"requestId": "8921850102754506400",
"builder": {
"project": "infra",
"bucket": "codesearch",
"builder": "codesearch-gen-chromium-win"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8921850102754506400",
"job": "infra/codesearch-gen-chromium-win",
"triggers": [
{
"buildbucket": {
"properties": {
"codesearch_mirror_revision": "f80844bcafe2c182e5a604f8b9435098bb36a6ec",
"codesearch_mirror_revision_timestamp": 1707622815,
"root_solution_revision": "093840de363de848e1f1a93de5573257b6ccd04a",
"root_solution_revision_timestamp": 1707621794
},
"tags": [
"parent_buildername:codesearch-gen-chromium-initiator",
"parent_buildnumber:18926",
"user_agent:recipe"
]
},
"id": "9f285e6c-dc96-48a4-932f-3c50bdc404e1",
"title": "codesearch-gen-chromium-initiator/18926"
}
]
},
"codesearch_mirror_revision": "f80844bcafe2c182e5a604f8b9435098bb36a6ec",
"codesearch_mirror_revision_timestamp": 1707622815,
"root_solution_revision": "093840de363de848e1f1a93de5573257b6ccd04a",
"root_solution_revision_timestamp": 1707621794
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8921850102754506400"
},
{
"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": "18926"
},
{
"key": "user_agent",
"value": "recipe"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[04:02:06.260] Scheduled build:
{
"id": "8756420040889482369",
"builder": {
"project": "infra",
"bucket": "codesearch",
"builder": "codesearch-gen-chromium-win"
},
"number": 13170,
"createdBy": "project:infra",
"createTime": "2024-02-11T04:02:05.546089950Z",
"updateTime": "2024-02-11T04:02:05.546089950Z",
"status": "SCHEDULED",
"input": {
}
}
[04:02:06.260] Task URL: https://cr-buildbucket.appspot.com/build/8756420040889482369
[04:02:06.260] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:2:0) after 1m11s
[04:02:07.255] Received PubSub notification, asking Buildbucket for the build status
[04:02:07.336] Build status: SCHEDULED
[04:02:12.580] Received PubSub notification, asking Buildbucket for the build status
[04:02:12.604] Build status: STARTED
[04:03:17.435] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:2:0)
[04:03:17.500] Build status: STARTED
[04:03:17.500] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:5:0) after 4m31s
[04:07:48.519] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:5:0)
[04:07:48.556] Build status: STARTED
[04:07:48.556] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:6:0) after 4m11s
[04:11:59.590] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:6:0)
[04:11:59.639] Build status: STARTED
[04:11:59.639] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:7:0) after 6m15s
[04:18:14.655] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:7:0)
[04:18:14.687] Build status: STARTED
[04:18:14.687] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:8:0) after 1m44s
[04:19:58.868] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:8:0)
[04:19:58.887] Build status: STARTED
[04:19:58.887] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:9:0) after 8m31s
[04:28:29.994] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:9:0)
[04:28:30.015] Build status: STARTED
[04:28:30.015] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:10:0) after 5m13s
[04:33:43.034] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:10:0)
[04:33:43.066] Build status: STARTED
[04:33:43.066] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:11:0) after 7m21s
[04:41:04.137] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:11:0)
[04:41:04.157] Build status: STARTED
[04:41:04.157] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:12:0) after 2m28s
[04:43:32.170] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:12:0)
[04:43:32.403] Build status: STARTED
[04:43:32.403] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:13:0) after 3m5s
[04:46:37.787] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:13:0)
[04:46:37.809] Build status: STARTED
[04:46:37.809] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:14:0) after 8m26s
[04:55:03.828] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:14:0)
[04:55:03.861] Build status: STARTED
[04:55:03.861] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:15:0) after 4m36s
[04:59:40.012] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:15:0)
[04:59:40.054] Build status: STARTED
[04:59:40.054] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:16:0) after 3m35s
[05:03:15.071] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:16:0)
[05:03:15.111] Build status: STARTED
[05:03:15.111] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:17:0) after 9m37s
[05:12:52.199] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:17:0)
[05:12:52.216] Build status: STARTED
[05:12:52.216] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:18:0) after 9m4s
[05:21:56.248] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:18:0)
[05:21:56.284] Build status: STARTED
[05:21:56.284] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:19:0) after 2m12s
[05:24:08.311] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:19:0)
[05:24:08.337] Build status: STARTED
[05:24:08.337] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:20:0) after 4m16s
[05:28:24.356] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:20:0)
[05:28:24.378] Build status: STARTED
[05:28:24.378] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:21:0) after 5m8s
[05:33:32.422] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:21:0)
[05:33:32.444] Build status: STARTED
[05:33:32.444] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:22:0) after 2m19s
[05:35:51.691] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:22:0)
[05:35:51.711] Build status: STARTED
[05:35:51.711] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:23:0) after 9m18s
[05:45:09.744] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:23:0)
[05:45:09.768] Build status: STARTED
[05:45:09.768] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:24:0) after 6m28s
[05:51:37.801] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:24:0)
[05:51:37.837] Build status: STARTED
[05:51:37.837] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:25:0) after 4m38s
[05:56:15.851] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:25:0)
[05:56:15.885] Build status: STARTED
[05:56:15.885] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:26:0) after 7m4s
[06:03:19.991] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:26:0)
[06:03:20.014] Build status: STARTED
[06:03:20.014] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:27:0) after 4m26s
[06:07:46.088] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:27:0)
[06:07:46.170] Build status: STARTED
[06:07:46.170] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:28:0) after 1m2s
[06:08:48.187] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:28:0)
[06:08:48.235] Build status: STARTED
[06:08:48.235] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:29:0) after 1m23s
[06:10:11.257] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:29:0)
[06:10:11.293] Build status: STARTED
[06:10:11.293] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:30:0) after 6m42s
[06:16:53.280] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:30:0)
[06:16:53.305] Build status: STARTED
[06:16:53.305] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:31:0) after 4m38s
[06:21:31.380] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:31:0)
[06:21:31.408] Build status: STARTED
[06:21:31.408] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:32:0) after 1m0s
[06:22:31.489] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:32:0)
[06:22:31.521] Build status: STARTED
[06:22:31.521] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:33:0) after 7m17s
[06:29:48.538] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:33:0)
[06:29:48.606] Build status: STARTED
[06:29:48.606] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8921850102754506400:34:0) after 8m41s
[06:32:26.958] Received PubSub notification, asking Buildbucket for the build status
[06:32:27.095] Build:
{
"id": "8756420040889482369",
"builder": {
"project": "infra",
"bucket": "codesearch",
"builder": "codesearch-gen-chromium-win"
},
"number": 13170,
"createdBy": "project:infra",
"createTime": "2024-02-11T04:02:05.546089950Z",
"startTime": "2024-02-11T04:02:12.310314801Z",
"endTime": "2024-02-11T06:32:26.713564110Z",
"updateTime": "2024-02-11T06:32:26.713564110Z",
"status": "SUCCESS",
"input": {
}
}
[06:32:27.095] Invocation finished in 2h30m22.711237888s with status SUCCEEDED