[13:36:48.721] New invocation is queued and will start shortly
[13:36:49.753] Starting the invocation (attempt 1)
[13:36:49.776] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6af45f6c36bbceabd1f37bfd0406b8978f5d9c4f"
[13:36:49.776] Popped gitiles commit info from properties and tags
[13:36:49.776] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:36:49.776] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:36:49.776] Buildbucket request:
{
"requestId": "8870354866397977968",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-fyi-arm64-dbg"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "6af45f6c36bbceabd1f37bfd0406b8978f5d9c4f",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8870354866397977968"
},
{
"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"
}
}
[13:36:50.369] Scheduled build:
{
"id": "8704924804573444897",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-fyi-arm64-dbg"
},
"number": 12806,
"createdBy": "project:chromium",
"createTime": "2025-09-01T13:36:49.838860622Z",
"updateTime": "2025-09-01T13:36:49.838860622Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "6af45f6c36bbceabd1f37bfd0406b8978f5d9c4f",
"ref": "refs/heads/main"
}
}
}
[13:36:50.369] Task URL: https://cr-buildbucket.appspot.com/build/8704924804573444897
[13:36:50.369] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:2:0) after 1m18s
[13:36:51.423] Received PubSub notification, asking Buildbucket for the build status
[13:36:51.446] Build status: SCHEDULED
[13:38:08.353] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:2:0)
[13:38:08.368] Build status: SCHEDULED
[13:38:08.368] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:4:0) after 8m51s
[13:46:59.379] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:4:0)
[13:46:59.418] Build status: SCHEDULED
[13:46:59.418] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:5:0) after 2m47s
[13:49:46.470] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:5:0)
[13:49:46.484] Build status: SCHEDULED
[13:49:46.484] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:6:0) after 3m4s
[13:52:50.549] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:6:0)
[13:52:50.573] Build status: SCHEDULED
[13:52:50.573] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:7:0) after 6m35s
[13:59:25.642] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:7:0)
[13:59:25.658] Build status: SCHEDULED
[13:59:25.658] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:8:0) after 4m29s
[14:03:54.673] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:8:0)
[14:03:54.694] Build status: SCHEDULED
[14:03:54.694] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:9:0) after 1m50s
[14:05:44.711] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:9:0)
[14:05:44.741] Build status: SCHEDULED
[14:05:44.741] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:10:0) after 1m36s
[14:07:20.813] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:10:0)
[14:07:20.828] Build status: SCHEDULED
[14:07:20.828] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:11:0) after 8m18s
[14:15:39.110] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:11:0)
[14:15:39.134] Build status: SCHEDULED
[14:15:39.134] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:12:0) after 9m34s
[14:25:13.199] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:12:0)
[14:25:13.214] Build status: SCHEDULED
[14:25:13.214] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:13:0) after 2m5s
[14:26:23.184] Received PubSub notification, asking Buildbucket for the build status
[14:26:23.205] Build status: STARTED
[14:27:18.229] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:13:0)
[14:27:18.263] Build status: STARTED
[14:27:18.263] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:15:0) after 6m32s
[14:33:50.359] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:15:0)
[14:33:50.375] Build status: STARTED
[14:33:50.375] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:16:0) after 2m5s
[14:35:55.393] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:16:0)
[14:35:55.413] Build status: STARTED
[14:35:55.413] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:17:0) after 3m1s
[14:38:56.484] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:17:0)
[14:38:56.501] Build status: STARTED
[14:38:56.501] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:18:0) after 7m3s
[14:45:59.536] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:18:0)
[14:45:59.552] Build status: STARTED
[14:45:59.552] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:19:0) after 9m14s
[14:55:13.704] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:19:0)
[14:55:13.723] Build status: STARTED
[14:55:13.723] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:20:0) after 4m25s
[14:59:38.739] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:20:0)
[14:59:38.764] Build status: STARTED
[14:59:38.764] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:21:0) after 5m3s
[15:04:41.835] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:21:0)
[15:04:41.854] Build status: STARTED
[15:04:41.854] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:22:0) after 6m51s
[15:11:32.887] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:22:0)
[15:11:32.914] Build status: STARTED
[15:11:32.914] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:23:0) after 4m11s
[15:15:43.961] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:23:0)
[15:15:43.988] Build status: STARTED
[15:15:43.988] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:24:0) after 6m57s
[15:22:41.044] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:24:0)
[15:22:41.065] Build status: STARTED
[15:22:41.065] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:25:0) after 6m53s
[15:29:34.152] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:25:0)
[15:29:34.174] Build status: STARTED
[15:29:34.175] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:26:0) after 4m43s
[15:34:17.169] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:26:0)
[15:34:17.183] Build status: STARTED
[15:34:17.183] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:27:0) after 1m44s
[15:36:01.234] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:27:0)
[15:36:01.255] Build status: STARTED
[15:36:01.256] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:28:0) after 1m14s
[15:37:15.324] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:28:0)
[15:37:15.340] Build status: STARTED
[15:37:15.340] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:29:0) after 6m50s
[15:44:05.371] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:29:0)
[15:44:05.397] Build status: STARTED
[15:44:05.397] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:30:0) after 3m8s
[15:47:13.417] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:30:0)
[15:47:13.440] Build status: STARTED
[15:47:13.440] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:31:0) after 2m43s
[15:49:56.460] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:31:0)
[15:49:56.481] Build status: STARTED
[15:49:56.481] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:32:0) after 6m28s
[15:56:24.564] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:32:0)
[15:56:24.582] Build status: STARTED
[15:56:24.582] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:33:0) after 4m12s
[16:00:36.661] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:33:0)
[16:00:36.679] Build status: STARTED
[16:00:36.679] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:34:0) after 5m50s
[16:06:26.695] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:34:0)
[16:06:26.709] Build status: STARTED
[16:06:26.709] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:35:0) after 6m26s
[16:12:52.753] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:35:0)
[16:12:52.770] Build status: STARTED
[16:12:52.770] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:36:0) after 9m46s
[16:22:38.788] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:36:0)
[16:22:38.815] Build status: STARTED
[16:22:38.815] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8870354866397977968:37:0) after 2m25s
[16:24:24.175] Received PubSub notification, asking Buildbucket for the build status
[16:24:24.190] Build:
{
"id": "8704924804573444897",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-fyi-arm64-dbg"
},
"number": 12806,
"createdBy": "project:chromium",
"createTime": "2025-09-01T13:36:49.838860622Z",
"startTime": "2025-09-01T14:26:23.016958199Z",
"endTime": "2025-09-01T16:24:23.615236776Z",
"updateTime": "2025-09-01T16:24:23.615236776Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "6af45f6c36bbceabd1f37bfd0406b8978f5d9c4f",
"ref": "refs/heads/main"
}
}
}
[16:24:24.190] Invocation finished in 2h47m35.475166418s with status SUCCEEDED