[08:57:24.875] New invocation is queued and will start shortly
[08:57:24.875] Triggered by user:infra-codesearch@chops-service-accounts.iam.gserviceaccount.com
[08:57:25.940] Starting the invocation (attempt 1)
[08:57:25.966] Ignoring gitiles_ref tag without the buildset tag
[08:57:25.966] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:57:25.966] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:57:25.966] Buildbucket request:
{
"requestId": "8934243305883377456",
"builder": {
"project": "infra",
"bucket": "codesearch",
"builder": "codesearch-gen-chromium-win"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8934243305883377456",
"job": "infra/codesearch-gen-chromium-win",
"triggers": [
{
"buildbucket": {
"properties": {
"codesearch_mirror_revision": "3996b4b60105a22761107d7c36a4d22c034cc2c0",
"codesearch_mirror_revision_timestamp": 1695786487,
"root_solution_revision": "d556b7cb3b0fe04084900cf5bc0ed6d9e8c21fae",
"root_solution_revision_timestamp": 1695778839
},
"tags": [
"parent_buildername:codesearch-gen-chromium-initiator",
"parent_buildnumber:18103",
"user_agent:recipe"
]
},
"id": "71d2c727-01c2-421c-9f95-586edc3d8fb5",
"title": "codesearch-gen-chromium-initiator/18103"
},
{
"buildbucket": {
"properties": {
"codesearch_mirror_revision": "37bdb5ba237b36e920e674d8d6a3ef57688715b9",
"codesearch_mirror_revision_timestamp": 1695798263,
"root_solution_revision": "5b13b06306d7dc9365ba28cc547a27a6bf1a0460",
"root_solution_revision_timestamp": 1695798111
},
"tags": [
"parent_buildername:codesearch-gen-chromium-initiator",
"parent_buildnumber:18104",
"user_agent:recipe"
]
},
"id": "e59b40c8-82a4-4a21-8022-d5f8d51eae3d",
"title": "codesearch-gen-chromium-initiator/18104"
}
]
},
"codesearch_mirror_revision": "37bdb5ba237b36e920e674d8d6a3ef57688715b9",
"codesearch_mirror_revision_timestamp": 1695798263,
"root_solution_revision": "5b13b06306d7dc9365ba28cc547a27a6bf1a0460",
"root_solution_revision_timestamp": 1695798111
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8934243305883377456"
},
{
"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": "18104"
},
{
"key": "user_agent",
"value": "recipe"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[08:57:26.497] Scheduled build:
{
"id": "8768813244015623265",
"builder": {
"project": "infra",
"bucket": "codesearch",
"builder": "codesearch-gen-chromium-win"
},
"number": 12515,
"createdBy": "project:infra",
"createTime": "2023-09-27T08:57:26.027166269Z",
"updateTime": "2023-09-27T08:57:26.027166269Z",
"status": "SCHEDULED",
"input": {
}
}
[08:57:26.497] Task URL: https://cr-buildbucket.appspot.com/build/8768813244015623265
[08:57:26.497] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:2:0) after 6m16s
[08:57:27.506] Received PubSub notification, asking Buildbucket for the build status
[08:57:27.523] Build status: SCHEDULED
[08:57:57.836] Received PubSub notification, asking Buildbucket for the build status
[08:57:57.862] Build status: STARTED
[09:03:42.519] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:2:0)
[09:03:42.944] Build status: STARTED
[09:03:42.944] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:5:0) after 1m53s
[09:05:35.966] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:5:0)
[09:05:35.998] Build status: STARTED
[09:05:35.998] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:6:0) after 9m30s
[09:15:06.112] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:6:0)
[09:15:06.142] Build status: STARTED
[09:15:06.142] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:7:0) after 5m47s
[09:20:53.159] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:7:0)
[09:20:53.178] Build status: STARTED
[09:20:53.178] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:8:0) after 9m30s
[09:30:23.194] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:8:0)
[09:30:23.232] Build status: STARTED
[09:30:23.232] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:9:0) after 1m25s
[09:31:48.245] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:9:0)
[09:31:48.272] Build status: STARTED
[09:31:48.272] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:10:0) after 2m44s
[09:34:32.388] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:10:0)
[09:34:32.406] Build status: STARTED
[09:34:32.406] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:11:0) after 4m35s
[09:39:07.419] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:11:0)
[09:39:07.439] Build status: STARTED
[09:39:07.439] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:12:0) after 8m11s
[09:47:18.535] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:12:0)
[09:47:18.568] Build status: STARTED
[09:47:18.568] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:13:0) after 6m15s
[09:53:33.585] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:13:0)
[09:53:33.603] Build status: STARTED
[09:53:33.603] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:14:0) after 7m34s
[10:01:08.049] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:14:0)
[10:01:08.082] Build status: STARTED
[10:01:08.082] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:15:0) after 7m12s
[10:08:20.137] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:15:0)
[10:08:20.164] Build status: STARTED
[10:08:20.164] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:16:0) after 7m50s
[10:16:10.234] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:16:0)
[10:16:10.273] Build status: STARTED
[10:16:10.273] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:17:0) after 5m49s
[10:21:59.415] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:17:0)
[10:21:59.437] Build status: STARTED
[10:21:59.437] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:18:0) after 6m30s
[10:28:29.426] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:18:0)
[10:28:29.494] Build status: STARTED
[10:28:29.494] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:19:0) after 4m11s
[10:32:40.512] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:19:0)
[10:32:40.554] Build status: STARTED
[10:32:40.554] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:20:0) after 5m26s
[10:38:06.773] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:20:0)
[10:38:06.806] Build status: STARTED
[10:38:06.806] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:21:0) after 2m58s
[10:41:04.975] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:21:0)
[10:41:04.994] Build status: STARTED
[10:41:04.994] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:22:0) after 9m36s
[10:50:41.005] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:22:0)
[10:50:41.026] Build status: STARTED
[10:50:41.026] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:23:0) after 8m36s
[10:59:17.027] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:23:0)
[10:59:17.121] Build status: STARTED
[10:59:17.121] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:24:0) after 1m51s
[11:01:08.134] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:24:0)
[11:01:08.163] Build status: STARTED
[11:01:08.163] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:25:0) after 1m52s
[11:03:00.300] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:25:0)
[11:03:00.335] Build status: STARTED
[11:03:00.335] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:26:0) after 6m29s
[11:09:29.454] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:26:0)
[11:09:29.472] Build status: STARTED
[11:09:29.472] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:27:0) after 8m8s
[11:17:37.519] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:27:0)
[11:17:37.556] Build status: STARTED
[11:17:37.556] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:28:0) after 7m49s
[11:25:26.625] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:28:0)
[11:25:26.641] Build status: STARTED
[11:25:26.641] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:29:0) after 9m17s
[11:34:43.658] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:29:0)
[11:34:43.707] Build status: STARTED
[11:34:43.707] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:30:0) after 5m47s
[11:40:30.938] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:30:0)
[11:40:30.961] Build status: STARTED
[11:40:30.961] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:31:0) after 5m7s
[11:45:45.805] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:31:0)
[11:45:45.824] Build status: STARTED
[11:45:45.824] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:32:0) after 5m40s
[11:51:25.925] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:32:0)
[11:51:25.948] Build status: STARTED
[11:51:25.948] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:33:0) after 4m18s
[11:55:43.985] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:33:0)
[11:55:44.209] Build status: STARTED
[11:55:44.209] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:34:0) after 9m25s
[12:05:09.246] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:34:0)
[12:05:09.313] Build status: STARTED
[12:05:09.313] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:35:0) after 6m42s
[12:11:51.371] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:35:0)
[12:11:51.402] Build status: STARTED
[12:11:51.402] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:36:0) after 1m54s
[12:13:45.486] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:36:0)
[12:13:45.505] Build status: STARTED
[12:13:45.505] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:37:0) after 8m54s
[12:22:39.532] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:37:0)
[12:22:39.569] Build status: STARTED
[12:22:39.569] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:38:0) after 2m51s
[12:25:30.588] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:38:0)
[12:25:30.608] Build status: STARTED
[12:25:30.608] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:39:0) after 8m40s
[12:34:10.768] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:39:0)
[12:34:10.789] Build status: STARTED
[12:34:10.789] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:40:0) after 6m58s
[12:41:08.932] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:40:0)
[12:41:08.959] Build status: STARTED
[12:41:08.959] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:41:0) after 9m54s
[12:51:03.141] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:41:0)
[12:51:03.173] Build status: STARTED
[12:51:03.174] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:42:0) after 3m15s
[12:54:18.565] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:42:0)
[12:54:18.583] Build status: STARTED
[12:54:18.583] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:43:0) after 1m22s
[12:55:41.141] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:43:0)
[12:55:41.163] Build status: STARTED
[12:55:41.163] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:44:0) after 7m34s
[13:03:15.815] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:44:0)
[13:03:15.842] Build status: STARTED
[13:03:15.842] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:45:0) after 1m3s
[13:04:18.945] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:45:0)
[13:04:18.981] Build status: STARTED
[13:04:18.981] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:46:0) after 3m25s
[13:07:43.999] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:46:0)
[13:07:44.028] Build status: STARTED
[13:07:44.028] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:47:0) after 6m44s
[13:14:28.045] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:47:0)
[13:14:28.068] Build status: STARTED
[13:14:28.068] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:48:0) after 9m31s
[13:23:59.148] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:48:0)
[13:23:59.173] Build status: STARTED
[13:23:59.173] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:49:0) after 4m26s
[13:28:25.356] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:49:0)
[13:28:25.855] Build status: STARTED
[13:28:25.855] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:50:0) after 9m43s
[13:38:09.007] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:50:0)
[13:38:09.124] Build status: STARTED
[13:38:09.124] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:51:0) after 3m2s
[13:41:11.447] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:51:0)
[13:41:11.520] Build status: STARTED
[13:41:11.520] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:52:0) after 3m33s
[13:44:44.617] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:52:0)
[13:44:44.656] Build status: STARTED
[13:44:44.656] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:53:0) after 5m38s
[13:50:22.768] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:53:0)
[13:50:22.801] Build status: STARTED
[13:50:22.801] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:54:0) after 6m59s
[13:57:21.818] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:54:0)
[13:57:21.845] Build status: STARTED
[13:57:21.846] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:55:0) after 5m38s
[14:02:59.869] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:55:0)
[14:02:59.904] Build status: STARTED
[14:02:59.904] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:56:0) after 2m18s
[14:05:18.024] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:56:0)
[14:05:18.062] Build status: STARTED
[14:05:18.062] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:57:0) after 9m31s
[14:14:49.260] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:57:0)
[14:14:49.299] Build status: STARTED
[14:14:49.299] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:58:0) after 2m47s
[14:17:36.350] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:58:0)
[14:17:36.504] Build status: STARTED
[14:17:36.504] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8934243305883377456:59:0) after 5m5s
[14:22:09.823] Received PubSub notification, asking Buildbucket for the build status
[14:22:09.855] Build:
{
"id": "8768813244015623265",
"builder": {
"project": "infra",
"bucket": "codesearch",
"builder": "codesearch-gen-chromium-win"
},
"number": 12515,
"createdBy": "project:infra",
"createTime": "2023-09-27T08:57:26.027166269Z",
"startTime": "2023-09-27T08:57:57.142086Z",
"endTime": "2023-09-27T14:22:09.409363059Z",
"updateTime": "2023-09-27T14:22:09.409363059Z",
"status": "SUCCESS",
"input": {
}
}
[14:22:09.855] Invocation finished in 5h24m44.993010306s with status SUCCEEDED