[23:19:14.477] New invocation is queued and will start shortly
[23:19:15.655] Starting the invocation (attempt 1)
[23:19:15.701] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/788eaa94eb13fa7b78974f4a878aef2e0d91247b"
[23:19:15.701] Popped gitiles commit info from properties and tags
[23:19:15.701] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:19:15.702] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:19:15.702] Buildbucket request:
{
"requestId": "8919512375904764688",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-fyi-arm64-dbg"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "788eaa94eb13fa7b78974f4a878aef2e0d91247b",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8919512375904764688"
},
{
"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"
}
}
[23:19:16.672] Scheduled build:
{
"id": "8754082313855363185",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-fyi-arm64-dbg"
},
"number": 7796,
"createdBy": "project:chromium",
"createTime": "2024-03-07T23:19:15.777128691Z",
"updateTime": "2024-03-07T23:19:15.777128691Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "788eaa94eb13fa7b78974f4a878aef2e0d91247b",
"ref": "refs/heads/main"
}
}
}
[23:19:16.672] Task URL: https://cr-buildbucket.appspot.com/build/8754082313855363185
[23:19:16.672] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:2:0) after 2m31s
[23:19:17.551] Received PubSub notification, asking Buildbucket for the build status
[23:19:17.650] Build status: SCHEDULED
[23:19:19.948] Received PubSub notification, asking Buildbucket for the build status
[23:19:19.980] Build status: STARTED
[23:21:47.767] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:2:0)
[23:21:47.788] Build status: STARTED
[23:21:47.788] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:5:0) after 1m49s
[23:23:37.121] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:5:0)
[23:23:37.161] Build status: STARTED
[23:23:37.161] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:6:0) after 9m8s
[23:32:45.234] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:6:0)
[23:32:45.255] Build status: STARTED
[23:32:45.255] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:7:0) after 6m8s
[23:38:53.286] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:7:0)
[23:38:53.302] Build status: STARTED
[23:38:53.302] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:8:0) after 9m53s
[23:48:46.353] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:8:0)
[23:48:46.570] Build status: STARTED
[23:48:46.570] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:9:0) after 4m4s
[23:52:50.693] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:9:0)
[23:52:50.709] Build status: STARTED
[23:52:50.709] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:10:0) after 7m57s
[00:00:47.745] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:10:0)
[00:00:47.781] Build status: STARTED
[00:00:47.781] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:11:0) after 2m38s
[00:03:25.809] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:11:0)
[00:03:25.859] Build status: STARTED
[00:03:25.859] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:12:0) after 3m51s
[00:07:17.457] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:12:0)
[00:07:17.586] Build status: STARTED
[00:07:17.587] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:13:0) after 3m40s
[00:10:58.255] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:13:0)
[00:10:58.301] Build status: STARTED
[00:10:58.301] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:14:0) after 7m46s
[00:18:44.404] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:14:0)
[00:18:44.427] Build status: STARTED
[00:18:44.427] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:15:0) after 7m0s
[00:25:44.535] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:15:0)
[00:25:44.552] Build status: STARTED
[00:25:44.552] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:16:0) after 7m41s
[00:33:25.867] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:16:0)
[00:33:25.885] Build status: STARTED
[00:33:25.885] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:17:0) after 2m40s
[00:36:05.925] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:17:0)
[00:36:05.954] Build status: STARTED
[00:36:05.954] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:18:0) after 9m50s
[00:45:56.110] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:18:0)
[00:45:56.164] Build status: STARTED
[00:45:56.164] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:19:0) after 8m25s
[00:54:21.205] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:19:0)
[00:54:21.226] Build status: STARTED
[00:54:21.226] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:20:0) after 5m10s
[00:59:31.251] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:20:0)
[00:59:31.267] Build status: STARTED
[00:59:31.267] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:21:0) after 9m44s
[01:09:15.303] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:21:0)
[01:09:15.327] Build status: STARTED
[01:09:15.327] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:22:0) after 9m17s
[01:18:32.418] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:22:0)
[01:18:32.437] Build status: STARTED
[01:18:32.437] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:23:0) after 8m37s
[01:27:09.524] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:23:0)
[01:27:09.551] Build status: STARTED
[01:27:09.551] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:24:0) after 4m37s
[01:31:46.577] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:24:0)
[01:31:46.596] Build status: STARTED
[01:31:46.596] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:25:0) after 4m53s
[01:36:39.620] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:25:0)
[01:36:39.643] Build status: STARTED
[01:36:39.643] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:26:0) after 6m54s
[01:43:33.701] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:26:0)
[01:43:33.720] Build status: STARTED
[01:43:33.720] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:27:0) after 8m54s
[01:52:27.754] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:27:0)
[01:52:27.772] Build status: STARTED
[01:52:27.772] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:28:0) after 7m0s
[01:59:28.156] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:28:0)
[01:59:28.183] Build status: STARTED
[01:59:28.183] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:29:0) after 7m44s
[02:07:12.221] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:29:0)
[02:07:12.242] Build status: STARTED
[02:07:12.243] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:30:0) after 3m43s
[02:10:55.272] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:30:0)
[02:10:55.297] Build status: STARTED
[02:10:55.297] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:31:0) after 2m10s
[02:13:05.325] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:31:0)
[02:13:05.344] Build status: STARTED
[02:13:05.344] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:32:0) after 8m37s
[02:21:42.365] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:32:0)
[02:21:42.383] Build status: STARTED
[02:21:42.384] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:33:0) after 2m27s
[02:24:09.424] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:33:0)
[02:24:09.449] Build status: STARTED
[02:24:09.449] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:34:0) after 5m31s
[02:29:40.664] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:34:0)
[02:29:40.682] Build status: STARTED
[02:29:40.682] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:35:0) after 4m33s
[02:34:13.785] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:35:0)
[02:34:13.806] Build status: STARTED
[02:34:13.806] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:36:0) after 3m40s
[02:37:53.833] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:36:0)
[02:37:53.854] Build status: STARTED
[02:37:53.854] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:37:0) after 1m54s
[02:39:47.876] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:37:0)
[02:39:47.903] Build status: STARTED
[02:39:47.903] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:38:0) after 9m51s
[02:49:38.996] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:38:0)
[02:49:39.063] Build status: STARTED
[02:49:39.063] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:39:0) after 7m24s
[02:57:03.460] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:39:0)
[02:57:03.535] Build status: STARTED
[02:57:03.535] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:40:0) after 1m27s
[02:58:30.617] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:40:0)
[02:58:30.678] Build status: STARTED
[02:58:30.678] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:41:0) after 5m50s
[03:04:20.764] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:41:0)
[03:04:20.783] Build status: STARTED
[03:04:20.783] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:42:0) after 4m32s
[03:08:52.777] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:42:0)
[03:08:52.819] Build status: STARTED
[03:08:52.819] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:43:0) after 1m47s
[03:10:39.855] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:43:0)
[03:10:39.876] Build status: STARTED
[03:10:39.876] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:44:0) after 6m7s
[03:16:46.900] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:44:0)
[03:16:47.028] Build status: STARTED
[03:16:47.028] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:45:0) after 2m41s
[03:19:28.150] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:45:0)
[03:19:28.185] Build status: STARTED
[03:19:28.185] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:46:0) after 7m21s
[03:26:49.354] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:46:0)
[03:26:49.371] Build status: STARTED
[03:26:49.371] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:47:0) after 4m21s
[03:31:10.420] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:47:0)
[03:31:10.443] Build status: STARTED
[03:31:10.443] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:48:0) after 9m34s
[03:40:44.534] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:48:0)
[03:40:44.702] Build status: STARTED
[03:40:44.702] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8919512375904764688:49:0) after 4m22s
[03:42:37.000] Received PubSub notification, asking Buildbucket for the build status
[03:42:37.033] Build:
{
"id": "8754082313855363185",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-fyi-arm64-dbg"
},
"number": 7796,
"createdBy": "project:chromium",
"createTime": "2024-03-07T23:19:15.777128691Z",
"startTime": "2024-03-07T23:19:19.428581Z",
"endTime": "2024-03-08T03:42:35.613137Z",
"updateTime": "2024-03-08T03:42:35.613137Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "788eaa94eb13fa7b78974f4a878aef2e0d91247b",
"ref": "refs/heads/main"
}
}
}
[03:42:37.033] Invocation finished in 4h23m22.593694482s with status FAILED