[22:15:30.451] New invocation is queued and will start shortly
[22:15:31.523] Starting the invocation (attempt 1)
[22:15:31.551] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1b3973ab05322da92c545f90d8b3fa17e9a3d2b9"
[22:15:31.552] Popped gitiles commit info from properties and tags
[22:15:31.552] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:15:31.552] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:15:31.552] Buildbucket request:
{
"requestId": "8927941903528563520",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-fyi-arm64-dbg"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1b3973ab05322da92c545f90d8b3fa17e9a3d2b9",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8927941903528563520"
},
{
"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"
}
}
[22:15:32.200] Scheduled build:
{
"id": "8762511841631420785",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-fyi-arm64-dbg"
},
"number": 7270,
"createdBy": "project:chromium",
"createTime": "2023-12-05T22:15:31.638550589Z",
"updateTime": "2023-12-05T22:15:31.638550589Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1b3973ab05322da92c545f90d8b3fa17e9a3d2b9",
"ref": "refs/heads/main"
}
}
}
[22:15:32.200] Task URL: https://cr-buildbucket.appspot.com/build/8762511841631420785
[22:15:32.200] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:2:0) after 2m57s
[22:15:33.176] Received PubSub notification, asking Buildbucket for the build status
[22:15:33.201] Build status: SCHEDULED
[22:15:33.604] Received PubSub notification, asking Buildbucket for the build status
[22:15:33.634] Build status: STARTED
[22:18:29.270] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:2:0)
[22:18:29.339] Build status: STARTED
[22:18:29.340] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:5:0) after 5m43s
[22:24:12.362] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:5:0)
[22:24:12.378] Build status: STARTED
[22:24:12.378] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:6:0) after 3m36s
[22:27:48.439] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:6:0)
[22:27:48.474] Build status: STARTED
[22:27:48.474] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:7:0) after 9m0s
[22:36:48.491] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:7:0)
[22:36:48.531] Build status: STARTED
[22:36:48.531] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:8:0) after 5m25s
[22:42:13.556] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:8:0)
[22:42:13.595] Build status: STARTED
[22:42:13.595] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:9:0) after 6m29s
[22:48:42.619] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:9:0)
[22:48:42.638] Build status: STARTED
[22:48:42.638] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:10:0) after 9m58s
[22:58:40.711] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:10:0)
[22:58:40.732] Build status: STARTED
[22:58:40.732] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:11:0) after 4m17s
[23:02:57.812] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:11:0)
[23:02:57.838] Build status: STARTED
[23:02:57.838] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:12:0) after 1m17s
[23:04:14.945] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:12:0)
[23:04:14.964] Build status: STARTED
[23:04:14.964] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:13:0) after 6m56s
[23:11:10.962] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:13:0)
[23:11:10.985] Build status: STARTED
[23:11:10.985] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:14:0) after 5m7s
[23:16:18.010] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:14:0)
[23:16:18.055] Build status: STARTED
[23:16:18.055] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:15:0) after 4m45s
[23:21:03.115] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:15:0)
[23:21:03.139] Build status: STARTED
[23:21:03.139] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:16:0) after 4m24s
[23:25:27.406] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:16:0)
[23:25:27.468] Build status: STARTED
[23:25:27.468] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:17:0) after 3m26s
[23:28:53.490] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:17:0)
[23:28:53.530] Build status: STARTED
[23:28:53.530] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:18:0) after 8m26s
[23:37:19.575] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:18:0)
[23:37:19.594] Build status: STARTED
[23:37:19.594] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:19:0) after 5m38s
[23:42:57.692] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:19:0)
[23:42:57.710] Build status: STARTED
[23:42:57.710] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:20:0) after 3m14s
[23:46:11.764] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:20:0)
[23:46:11.783] Build status: STARTED
[23:46:11.783] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:21:0) after 1m19s
[23:47:31.247] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:21:0)
[23:47:31.273] Build status: STARTED
[23:47:31.273] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:22:0) after 4m2s
[23:51:33.472] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:22:0)
[23:51:33.502] Build status: STARTED
[23:51:33.502] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:23:0) after 4m49s
[23:56:22.628] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:23:0)
[23:56:22.647] Build status: STARTED
[23:56:22.647] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:24:0) after 7m20s
[00:03:42.793] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:24:0)
[00:03:42.866] Build status: STARTED
[00:03:42.866] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:25:0) after 3m42s
[00:07:25.039] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:25:0)
[00:07:25.061] Build status: STARTED
[00:07:25.061] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:26:0) after 2m23s
[00:09:48.081] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:26:0)
[00:09:48.104] Build status: STARTED
[00:09:48.104] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:27:0) after 7m57s
[00:17:45.147] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:27:0)
[00:17:45.206] Build status: STARTED
[00:17:45.206] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:28:0) after 3m54s
[00:21:39.247] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:28:0)
[00:21:39.325] Build status: STARTED
[00:21:39.326] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:29:0) after 9m53s
[00:31:32.403] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:29:0)
[00:31:32.420] Build status: STARTED
[00:31:32.420] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:30:0) after 3m33s
[00:35:05.448] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:30:0)
[00:35:05.481] Build status: STARTED
[00:35:05.481] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:31:0) after 7m5s
[00:42:10.546] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:31:0)
[00:42:10.588] Build status: STARTED
[00:42:10.588] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:32:0) after 9m41s
[00:51:51.615] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:32:0)
[00:51:51.639] Build status: STARTED
[00:51:51.639] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:33:0) after 5m37s
[00:57:28.709] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:33:0)
[00:57:28.751] Build status: STARTED
[00:57:28.751] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:34:0) after 1m40s
[00:59:08.801] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:34:0)
[00:59:08.817] Build status: STARTED
[00:59:08.817] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:35:0) after 7m4s
[01:06:12.924] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:35:0)
[01:06:12.961] Build status: STARTED
[01:06:12.961] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:36:0) after 4m6s
[01:10:19.417] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:36:0)
[01:10:19.443] Build status: STARTED
[01:10:19.443] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:37:0) after 5m28s
[01:15:47.467] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:37:0)
[01:15:47.506] Build status: STARTED
[01:15:47.506] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:38:0) after 2m3s
[01:17:50.531] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:38:0)
[01:17:50.598] Build status: STARTED
[01:17:50.598] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:39:0) after 1m16s
[01:19:06.669] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:39:0)
[01:19:06.689] Build status: STARTED
[01:19:06.689] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:40:0) after 7m12s
[01:26:18.714] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:40:0)
[01:26:18.746] Build status: STARTED
[01:26:18.746] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:41:0) after 7m7s
[01:33:25.765] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:41:0)
[01:33:25.806] Build status: STARTED
[01:33:25.807] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:42:0) after 2m40s
[01:36:05.844] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:42:0)
[01:36:05.864] Build status: STARTED
[01:36:05.864] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:43:0) after 1m49s
[01:37:54.892] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:43:0)
[01:37:54.987] Build status: STARTED
[01:37:54.987] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:44:0) after 5m40s
[01:43:35.023] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:44:0)
[01:43:35.100] Build status: STARTED
[01:43:35.100] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:45:0) after 2m48s
[01:46:23.693] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:45:0)
[01:46:23.712] Build status: STARTED
[01:46:23.712] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:46:0) after 8m0s
[01:54:23.737] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:46:0)
[01:54:23.755] Build status: STARTED
[01:54:23.755] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:47:0) after 8m46s
[02:03:09.843] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:47:0)
[02:03:09.864] Build status: STARTED
[02:03:09.864] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:48:0) after 5m16s
[02:08:26.228] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:48:0)
[02:08:26.258] Build status: STARTED
[02:08:26.258] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:49:0) after 5m21s
[02:13:47.333] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:49:0)
[02:13:47.398] Build status: STARTED
[02:13:47.398] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:50:0) after 9m8s
[02:22:55.559] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:50:0)
[02:22:55.578] Build status: STARTED
[02:22:55.579] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:51:0) after 1m5s
[02:24:00.754] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:51:0)
[02:24:00.773] Build status: STARTED
[02:24:00.773] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:52:0) after 4m49s
[02:28:49.840] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:52:0)
[02:28:49.940] Build status: STARTED
[02:28:49.940] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:53:0) after 8m4s
[02:36:54.318] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:53:0)
[02:36:54.463] Build status: STARTED
[02:36:54.463] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:54:0) after 7m10s
[02:44:04.487] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:54:0)
[02:44:04.529] Build status: STARTED
[02:44:04.529] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:55:0) after 6m0s
[02:50:04.582] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:55:0)
[02:50:04.609] Build status: STARTED
[02:50:04.609] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:56:0) after 5m28s
[02:55:32.862] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:56:0)
[02:55:32.881] Build status: STARTED
[02:55:32.881] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:57:0) after 9m54s
[03:05:27.016] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:57:0)
[03:05:27.040] Build status: STARTED
[03:05:27.040] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:58:0) after 7m1s
[03:12:28.160] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:58:0)
[03:12:28.213] Build status: STARTED
[03:12:28.214] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8927941903528563520:59:0) after 4m13s
[03:16:03.957] Received PubSub notification, asking Buildbucket for the build status
[03:16:03.991] Build:
{
"id": "8762511841631420785",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-fyi-arm64-dbg"
},
"number": 7270,
"createdBy": "project:chromium",
"createTime": "2023-12-05T22:15:31.638550589Z",
"startTime": "2023-12-05T22:15:33.063700Z",
"endTime": "2023-12-06T03:16:01.362789Z",
"updateTime": "2023-12-06T03:16:01.362789Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1b3973ab05322da92c545f90d8b3fa17e9a3d2b9",
"ref": "refs/heads/main"
}
}
}
[03:16:03.991] Invocation finished in 5h0m33.545939407s with status SUCCEEDED