[12:03:04.862] New invocation is queued and will start shortly
[12:03:06.156] Starting the invocation (attempt 1)
[12:03:06.188] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1b2c9f68f1f9efb2efd0538f1f710c522a4af3c2"
[12:03:06.188] Popped gitiles commit info from properties and tags
[12:03:06.188] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:03:06.190] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:03:06.190] Buildbucket request:
{
"requestId": "8903700447094568864",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-fyi-arm64-dbg"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1b2c9f68f1f9efb2efd0538f1f710c522a4af3c2",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8903700447094568864"
},
{
"key": "scheduler_job_id",
"value": "chromium/fuchsia-fyi-arm64-dbg"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[12:03:06.855] Scheduled build:
{
"id": "8738270384948656001",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-fyi-arm64-dbg"
},
"number": 9378,
"createdBy": "project:chromium",
"createTime": "2024-08-29T12:03:06.269418684Z",
"updateTime": "2024-08-29T12:03:06.269418684Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1b2c9f68f1f9efb2efd0538f1f710c522a4af3c2",
"ref": "refs/heads/main"
}
}
}
[12:03:06.855] Task URL: https://cr-buildbucket.appspot.com/build/8738270384948656001
[12:03:06.855] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:2:0) after 4m52s
[12:03:07.802] Received PubSub notification, asking Buildbucket for the build status
[12:03:07.824] Build status: SCHEDULED
[12:07:59.196] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:2:0)
[12:07:59.224] Build status: SCHEDULED
[12:07:59.224] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:4:0) after 6m3s
[12:14:02.246] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:4:0)
[12:14:02.281] Build status: SCHEDULED
[12:14:02.282] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:5:0) after 8m27s
[12:22:29.300] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:5:0)
[12:22:29.345] Build status: SCHEDULED
[12:22:29.345] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:6:0) after 2m43s
[12:25:12.365] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:6:0)
[12:25:12.385] Build status: SCHEDULED
[12:25:12.385] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:7:0) after 3m47s
[12:28:59.403] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:7:0)
[12:28:59.422] Build status: SCHEDULED
[12:28:59.422] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:8:0) after 8m20s
[12:37:19.558] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:8:0)
[12:37:19.578] Build status: SCHEDULED
[12:37:19.578] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:9:0) after 3m4s
[12:40:23.603] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:9:0)
[12:40:23.624] Build status: SCHEDULED
[12:40:23.624] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:10:0) after 1m37s
[12:42:00.691] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:10:0)
[12:42:00.710] Build status: SCHEDULED
[12:42:00.711] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:11:0) after 1m32s
[12:43:32.900] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:11:0)
[12:43:32.920] Build status: SCHEDULED
[12:43:32.920] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:12:0) after 7m15s
[12:50:47.941] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:12:0)
[12:50:47.961] Build status: SCHEDULED
[12:50:47.961] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:13:0) after 9m39s
[13:00:26.981] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:13:0)
[13:00:27.002] Build status: SCHEDULED
[13:00:27.002] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:14:0) after 3m54s
[13:04:21.104] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:14:0)
[13:04:21.127] Build status: SCHEDULED
[13:04:21.127] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:15:0) after 8m39s
[13:06:34.196] Received PubSub notification, asking Buildbucket for the build status
[13:06:34.214] Build status: STARTED
[13:13:00.196] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:15:0)
[13:13:00.213] Build status: STARTED
[13:13:00.213] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:17:0) after 4m56s
[13:17:56.281] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:17:0)
[13:17:56.325] Build status: STARTED
[13:17:56.325] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:18:0) after 6m40s
[13:24:36.341] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:18:0)
[13:24:36.359] Build status: STARTED
[13:24:36.359] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:19:0) after 2m17s
[13:26:53.476] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:19:0)
[13:26:53.496] Build status: STARTED
[13:26:53.496] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:20:0) after 2m30s
[13:29:23.515] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:20:0)
[13:29:23.549] Build status: STARTED
[13:29:23.549] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:21:0) after 7m6s
[13:36:29.574] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:21:0)
[13:36:29.611] Build status: STARTED
[13:36:29.611] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:22:0) after 5m6s
[13:41:35.631] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:22:0)
[13:41:35.647] Build status: STARTED
[13:41:35.647] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:23:0) after 5m26s
[13:47:01.697] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:23:0)
[13:47:01.741] Build status: STARTED
[13:47:01.741] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:24:0) after 9m57s
[13:56:58.771] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:24:0)
[13:56:58.790] Build status: STARTED
[13:56:58.790] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:25:0) after 2m36s
[13:59:34.762] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:25:0)
[13:59:34.782] Build status: STARTED
[13:59:34.782] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:26:0) after 4m29s
[14:04:03.800] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:26:0)
[14:04:03.824] Build status: STARTED
[14:04:03.824] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:27:0) after 2m38s
[14:06:41.879] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:27:0)
[14:06:41.897] Build status: STARTED
[14:06:41.897] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:28:0) after 1m16s
[14:07:57.996] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:28:0)
[14:07:58.012] Build status: STARTED
[14:07:58.012] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:29:0) after 9m46s
[14:17:44.052] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:29:0)
[14:17:44.068] Build status: STARTED
[14:17:44.068] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:30:0) after 7m23s
[14:25:07.298] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:30:0)
[14:25:07.349] Build status: STARTED
[14:25:07.349] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:31:0) after 1m44s
[14:26:51.407] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:31:0)
[14:26:51.427] Build status: STARTED
[14:26:51.427] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:32:0) after 1m2s
[14:27:53.496] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:32:0)
[14:27:53.521] Build status: STARTED
[14:27:53.521] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:33:0) after 3m4s
[14:30:57.503] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:33:0)
[14:30:57.552] Build status: STARTED
[14:30:57.552] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:34:0) after 8m27s
[14:39:24.878] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:34:0)
[14:39:24.927] Build status: STARTED
[14:39:24.927] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:35:0) after 9m57s
[14:49:21.950] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:35:0)
[14:49:21.967] Build status: STARTED
[14:49:21.967] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:36:0) after 6m54s
[14:56:16.036] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:36:0)
[14:56:16.056] Build status: STARTED
[14:56:16.056] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:37:0) after 1m20s
[14:57:36.188] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:37:0)
[14:57:36.211] Build status: STARTED
[14:57:36.211] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8903700447094568864:38:0) after 9m14s
[15:00:29.501] Received PubSub notification, asking Buildbucket for the build status
[15:00:29.629] Build:
{
"id": "8738270384948656001",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-fyi-arm64-dbg"
},
"number": 9378,
"createdBy": "project:chromium",
"createTime": "2024-08-29T12:03:06.269418684Z",
"startTime": "2024-08-29T13:06:33.940306302Z",
"endTime": "2024-08-29T15:00:28.696125610Z",
"updateTime": "2024-08-29T15:00:28.696125610Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1b2c9f68f1f9efb2efd0538f1f710c522a4af3c2",
"ref": "refs/heads/main"
}
}
}
[15:00:29.629] Invocation finished in 2h57m24.789513853s with status FAILED