[00:14:26.324] New invocation is queued and will start shortly
[00:14:26.324] Triggered by user:infra-codesearch@chops-service-accounts.iam.gserviceaccount.com
[00:14:27.499] Starting the invocation (attempt 1)
[00:14:27.560] Ignoring gitiles_ref tag without the buildset tag
[00:14:27.560] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:14:27.560] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:14:27.561] Buildbucket request:
{
"requestId": "8963810819724502992",
"builder": {
"project": "infra",
"bucket": "codesearch",
"builder": "codesearch-gen-chromium-win"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8963810819724502992",
"job": "infra/codesearch-gen-chromium-win",
"triggers": [
{
"buildbucket": {
"properties": {
"codesearch_mirror_revision": "eab608ec11da09f577c1773b018356e2e5174ad8",
"codesearch_mirror_revision_timestamp": 1667591962,
"root_solution_revision": "35d75852f5d920bad48ee10570836e8425d91351",
"root_solution_revision_timestamp": 1667591416
},
"tags": [
"parent_buildername:codesearch-gen-chromium-initiator",
"parent_buildnumber:16145",
"user_agent:recipe"
]
},
"id": "cbf45474-f20b-4c3b-a561-e7bb9f51c1b0",
"title": "codesearch-gen-chromium-initiator/16145"
},
{
"buildbucket": {
"properties": {
"codesearch_mirror_revision": "ce3127456c5584a1230d6f5141caa9278f4076d4",
"codesearch_mirror_revision_timestamp": 1667605301,
"root_solution_revision": "f53b6e76fef649e910228ce4439d9d496f563dfa",
"root_solution_revision_timestamp": 1667604848
},
"tags": [
"parent_buildername:codesearch-gen-chromium-initiator",
"parent_buildnumber:16146",
"user_agent:recipe"
]
},
"id": "d3ace80a-75be-4369-a2c9-4b70f1afa6ed",
"title": "codesearch-gen-chromium-initiator/16146"
}
]
},
"codesearch_mirror_revision": "ce3127456c5584a1230d6f5141caa9278f4076d4",
"codesearch_mirror_revision_timestamp": 1667605301,
"root_solution_revision": "f53b6e76fef649e910228ce4439d9d496f563dfa",
"root_solution_revision_timestamp": 1667604848
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8963810819724502992"
},
{
"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": "16146"
},
{
"key": "user_agent",
"value": "recipe"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[00:14:27.966] Scheduled build:
{
"id": "8798380757674657889",
"builder": {
"project": "infra",
"bucket": "codesearch",
"builder": "codesearch-gen-chromium-win"
},
"number": 10832,
"createdBy": "project:infra",
"createTime": "2022-11-05T00:14:27.650190804Z",
"updateTime": "2022-11-05T00:14:27.650190804Z",
"status": "SCHEDULED",
"input": {
}
}
[00:14:27.966] Task URL: https://cr-buildbucket.appspot.com/build/8798380757674657889
[00:14:27.966] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:2:0) after 6m57s
[00:14:58.193] Received PubSub notification, asking Buildbucket for the build status
[00:14:58.220] Build status: STARTED
[00:21:25.049] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:2:0)
[00:21:25.086] Build status: STARTED
[00:21:25.086] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:4:0) after 1m24s
[00:22:49.089] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:4:0)
[00:22:49.128] Build status: STARTED
[00:22:49.129] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:5:0) after 1m33s
[00:24:22.150] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:5:0)
[00:24:22.174] Build status: STARTED
[00:24:22.174] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:6:0) after 8m3s
[00:32:25.424] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:6:0)
[00:32:25.469] Build status: STARTED
[00:32:25.469] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:7:0) after 2m8s
[00:34:33.591] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:7:0)
[00:34:33.618] Build status: STARTED
[00:34:33.618] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:8:0) after 1m18s
[00:35:51.750] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:8:0)
[00:35:51.790] Build status: STARTED
[00:35:51.790] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:9:0) after 2m22s
[00:38:13.816] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:9:0)
[00:38:13.847] Build status: STARTED
[00:38:13.847] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:10:0) after 6m14s
[00:44:27.988] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:10:0)
[00:44:28.014] Build status: STARTED
[00:44:28.014] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:11:0) after 9m45s
[00:54:13.154] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:11:0)
[00:54:13.180] Build status: STARTED
[00:54:13.180] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:12:0) after 7m31s
[01:01:44.338] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:12:0)
[01:01:44.373] Build status: STARTED
[01:01:44.373] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:13:0) after 9m7s
[01:10:51.396] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:13:0)
[01:10:51.427] Build status: STARTED
[01:10:51.427] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:14:0) after 7m10s
[01:18:02.093] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:14:0)
[01:18:02.209] Build status: STARTED
[01:18:02.209] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:15:0) after 6m53s
[01:24:55.355] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:15:0)
[01:24:55.391] Build status: STARTED
[01:24:55.391] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:16:0) after 9m32s
[01:34:27.439] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:16:0)
[01:34:27.464] Build status: STARTED
[01:34:27.464] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:17:0) after 7m24s
[01:41:51.805] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:17:0)
[01:41:51.854] Build status: STARTED
[01:41:51.854] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:18:0) after 2m54s
[01:44:46.088] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:18:0)
[01:44:46.111] Build status: STARTED
[01:44:46.111] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:19:0) after 9m12s
[01:53:58.252] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:19:0)
[01:53:58.287] Build status: STARTED
[01:53:58.287] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:20:0) after 5m49s
[01:59:47.426] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:20:0)
[01:59:47.450] Build status: STARTED
[01:59:47.450] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:21:0) after 2m58s
[02:02:45.572] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:21:0)
[02:02:45.597] Build status: STARTED
[02:02:45.597] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:22:0) after 8m32s
[02:11:17.677] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:22:0)
[02:11:17.747] Build status: STARTED
[02:11:17.748] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:23:0) after 5m46s
[02:17:03.744] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:23:0)
[02:17:03.771] Build status: STARTED
[02:17:03.771] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:24:0) after 3m27s
[02:20:30.800] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:24:0)
[02:20:30.871] Build status: STARTED
[02:20:30.871] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:25:0) after 1m31s
[02:22:02.123] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:25:0)
[02:22:02.161] Build status: STARTED
[02:22:02.161] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:26:0) after 5m44s
[02:27:46.196] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:26:0)
[02:27:46.230] Build status: STARTED
[02:27:46.230] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:27:0) after 5m9s
[02:32:55.358] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:27:0)
[02:32:55.399] Build status: STARTED
[02:32:55.399] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:28:0) after 3m37s
[02:36:32.514] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:28:0)
[02:36:32.586] Build status: STARTED
[02:36:32.586] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:29:0) after 8m24s
[02:44:56.606] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:29:0)
[02:44:56.641] Build status: STARTED
[02:44:56.641] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:30:0) after 2m4s
[02:47:00.957] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:30:0)
[02:47:00.994] Build status: STARTED
[02:47:00.994] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:31:0) after 6m6s
[02:53:07.022] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:31:0)
[02:53:07.049] Build status: STARTED
[02:53:07.049] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:32:0) after 5m22s
[02:58:29.079] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:32:0)
[02:58:29.113] Build status: STARTED
[02:58:29.113] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:33:0) after 1m30s
[02:59:59.147] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:33:0)
[02:59:59.210] Build status: STARTED
[02:59:59.210] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:34:0) after 1m48s
[03:01:47.277] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:34:0)
[03:01:47.310] Build status: STARTED
[03:01:47.310] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:35:0) after 5m16s
[03:07:03.372] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:35:0)
[03:07:03.395] Build status: STARTED
[03:07:03.395] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:36:0) after 6m15s
[03:13:18.406] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:36:0)
[03:13:18.436] Build status: STARTED
[03:13:18.436] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:37:0) after 9m14s
[03:22:32.640] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:37:0)
[03:22:32.684] Build status: STARTED
[03:22:32.684] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:38:0) after 5m9s
[03:27:41.914] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:38:0)
[03:27:41.939] Build status: STARTED
[03:27:41.939] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:39:0) after 7m3s
[03:34:45.143] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:39:0)
[03:34:45.168] Build status: STARTED
[03:34:45.168] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:40:0) after 2m18s
[03:37:03.514] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:40:0)
[03:37:03.538] Build status: STARTED
[03:37:03.538] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:41:0) after 8m1s
[03:45:04.703] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:41:0)
[03:45:04.730] Build status: STARTED
[03:45:04.730] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:42:0) after 6m35s
[03:51:39.855] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:42:0)
[03:51:39.935] Build status: STARTED
[03:51:39.935] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:43:0) after 3m4s
[03:54:44.004] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:43:0)
[03:54:44.028] Build status: STARTED
[03:54:44.028] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:44:0) after 4m8s
[03:58:52.091] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:44:0)
[03:58:52.116] Build status: STARTED
[03:58:52.116] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:45:0) after 5m38s
[04:04:30.143] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:45:0)
[04:04:30.170] Build status: STARTED
[04:04:30.170] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:46:0) after 5m23s
[04:09:53.278] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:46:0)
[04:09:53.301] Build status: STARTED
[04:09:53.301] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:47:0) after 4m52s
[04:14:45.330] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:47:0)
[04:14:45.357] Build status: STARTED
[04:14:45.357] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:48:0) after 3m29s
[04:18:14.461] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:48:0)
[04:18:14.489] Build status: STARTED
[04:18:14.489] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:49:0) after 2m45s
[04:20:59.509] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:49:0)
[04:20:59.533] Build status: STARTED
[04:20:59.534] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:50:0) after 3m53s
[04:24:52.570] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:50:0)
[04:24:52.595] Build status: STARTED
[04:24:52.595] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:51:0) after 2m18s
[04:27:10.640] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:51:0)
[04:27:10.680] Build status: STARTED
[04:27:10.680] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8963810819724502992:52:0) after 9m43s
[04:34:55.106] Received PubSub notification, asking Buildbucket for the build status
[04:34:55.146] Build:
{
"id": "8798380757674657889",
"builder": {
"project": "infra",
"bucket": "codesearch",
"builder": "codesearch-gen-chromium-win"
},
"number": 10832,
"createdBy": "project:infra",
"createTime": "2022-11-05T00:14:27.650190804Z",
"startTime": "2022-11-05T00:14:57.519824Z",
"endTime": "2022-11-05T04:34:54.903009637Z",
"updateTime": "2022-11-05T04:34:54.903009637Z",
"status": "SUCCESS",
"input": {
}
}
[04:34:55.146] Invocation finished in 4h20m28.833870432s with status SUCCEEDED