[22:44:14.602] New invocation is queued and will start shortly
[22:44:15.714] Starting the invocation (attempt 1)
[22:44:15.741] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/90ee4d4798d5145966f799dcc7e1d80fea93acab"
[22:44:15.741] Popped gitiles commit info from properties and tags
[22:44:15.741] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:44:15.741] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:44:15.742] Buildbucket request:
{
"requestId": "8938811731601930688",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-fyi-arm64-dbg"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "90ee4d4798d5145966f799dcc7e1d80fea93acab",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8938811731601930688"
},
{
"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:44:16.563] Scheduled build:
{
"id": "8773381669679957441",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-fyi-arm64-dbg"
},
"number": 6578,
"createdBy": "project:chromium",
"createTime": "2023-08-07T22:44:15.804456922Z",
"updateTime": "2023-08-07T22:44:15.804456922Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "90ee4d4798d5145966f799dcc7e1d80fea93acab",
"ref": "refs/heads/main"
}
}
}
[22:44:16.563] Task URL: https://cr-buildbucket.appspot.com/build/8773381669679957441
[22:44:16.563] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:2:0) after 2m10s
[22:44:17.608] Received PubSub notification, asking Buildbucket for the build status
[22:44:17.677] Build status: SCHEDULED
[22:46:26.585] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:2:0)
[22:46:26.611] Build status: SCHEDULED
[22:46:26.611] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:4:0) after 8m46s
[22:46:49.604] Received PubSub notification, asking Buildbucket for the build status
[22:46:49.721] Build status: STARTED
[22:55:12.628] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:4:0)
[22:55:12.661] Build status: STARTED
[22:55:12.661] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:6:0) after 6m3s
[23:01:15.671] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:6:0)
[23:01:15.696] Build status: STARTED
[23:01:15.696] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:7:0) after 8m19s
[23:09:34.852] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:7:0)
[23:09:34.874] Build status: STARTED
[23:09:34.874] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:8:0) after 7m34s
[23:17:08.931] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:8:0)
[23:17:08.947] Build status: STARTED
[23:17:08.947] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:9:0) after 2m30s
[23:19:38.965] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:9:0)
[23:19:38.988] Build status: STARTED
[23:19:38.988] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:10:0) after 4m50s
[23:24:28.994] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:10:0)
[23:24:29.014] Build status: STARTED
[23:24:29.014] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:11:0) after 9m45s
[23:34:14.044] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:11:0)
[23:34:14.091] Build status: STARTED
[23:34:14.091] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:12:0) after 3m25s
[23:37:39.176] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:12:0)
[23:37:39.225] Build status: STARTED
[23:37:39.225] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:13:0) after 9m32s
[23:47:11.261] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:13:0)
[23:47:11.286] Build status: STARTED
[23:47:11.286] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:14:0) after 2m12s
[23:49:23.304] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:14:0)
[23:49:23.354] Build status: STARTED
[23:49:23.354] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:15:0) after 4m24s
[23:53:47.406] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:15:0)
[23:53:47.555] Build status: STARTED
[23:53:47.555] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:16:0) after 4m27s
[23:58:14.573] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:16:0)
[23:58:14.593] Build status: STARTED
[23:58:14.593] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:17:0) after 2m59s
[00:01:13.744] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:17:0)
[00:01:13.769] Build status: STARTED
[00:01:13.769] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:18:0) after 7m56s
[00:09:10.607] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:18:0)
[00:09:10.629] Build status: STARTED
[00:09:10.629] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:19:0) after 7m1s
[00:16:12.027] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:19:0)
[00:16:12.054] Build status: STARTED
[00:16:12.054] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:20:0) after 9m19s
[00:25:31.080] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:20:0)
[00:25:31.098] Build status: STARTED
[00:25:31.098] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:21:0) after 6m36s
[00:32:07.776] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:21:0)
[00:32:07.820] Build status: STARTED
[00:32:07.820] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:22:0) after 2m43s
[00:34:51.020] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:22:0)
[00:34:51.041] Build status: STARTED
[00:34:51.041] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:23:0) after 6m54s
[00:41:45.763] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:23:0)
[00:41:45.784] Build status: STARTED
[00:41:45.785] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:24:0) after 5m50s
[00:47:35.994] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:24:0)
[00:47:36.042] Build status: STARTED
[00:47:36.042] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:25:0) after 3m18s
[00:50:54.173] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:25:0)
[00:50:54.192] Build status: STARTED
[00:50:54.192] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:26:0) after 4m31s
[00:55:25.328] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:26:0)
[00:55:25.467] Build status: STARTED
[00:55:25.467] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:27:0) after 5m40s
[01:01:05.491] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:27:0)
[01:01:05.519] Build status: STARTED
[01:01:05.519] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:28:0) after 1m15s
[01:02:20.551] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:28:0)
[01:02:20.572] Build status: STARTED
[01:02:20.572] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:29:0) after 3m6s
[01:05:26.605] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:29:0)
[01:05:26.626] Build status: STARTED
[01:05:26.626] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:30:0) after 1m46s
[01:07:12.676] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:30:0)
[01:07:12.732] Build status: STARTED
[01:07:12.732] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:31:0) after 6m45s
[01:13:57.814] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:31:0)
[01:13:57.836] Build status: STARTED
[01:13:57.836] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:32:0) after 6m21s
[01:20:19.124] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:32:0)
[01:20:19.144] Build status: STARTED
[01:20:19.144] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:33:0) after 8m24s
[01:28:43.629] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:33:0)
[01:28:43.651] Build status: STARTED
[01:28:43.651] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:34:0) after 8m4s
[01:36:47.809] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:34:0)
[01:36:47.837] Build status: STARTED
[01:36:47.837] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:35:0) after 2m31s
[01:39:18.941] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:35:0)
[01:39:18.957] Build status: STARTED
[01:39:18.957] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:36:0) after 9m26s
[01:48:45.159] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:36:0)
[01:48:45.258] Build status: STARTED
[01:48:45.258] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:37:0) after 7m27s
[01:56:12.415] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:37:0)
[01:56:12.436] Build status: STARTED
[01:56:12.436] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:38:0) after 2m13s
[01:58:25.560] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:38:0)
[01:58:25.578] Build status: STARTED
[01:58:25.578] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:39:0) after 4m19s
[02:02:44.725] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:39:0)
[02:02:44.742] Build status: STARTED
[02:02:44.742] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:40:0) after 9m55s
[02:12:40.052] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:40:0)
[02:12:40.075] Build status: STARTED
[02:12:40.075] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:41:0) after 1m36s
[02:14:16.122] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:41:0)
[02:14:16.158] Build status: STARTED
[02:14:16.159] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:42:0) after 8m48s
[02:23:04.543] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:42:0)
[02:23:04.568] Build status: STARTED
[02:23:04.568] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:43:0) after 1m0s
[02:24:04.734] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:43:0)
[02:24:04.767] Build status: STARTED
[02:24:04.767] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:44:0) after 1m31s
[02:25:35.991] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:44:0)
[02:25:36.034] Build status: STARTED
[02:25:36.034] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:45:0) after 1m8s
[02:26:44.261] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:45:0)
[02:26:44.288] Build status: STARTED
[02:26:44.288] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:46:0) after 6m42s
[02:33:26.595] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:46:0)
[02:33:26.614] Build status: STARTED
[02:33:26.614] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:47:0) after 4m16s
[02:37:42.775] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:47:0)
[02:37:42.796] Build status: STARTED
[02:37:42.796] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:48:0) after 3m6s
[02:40:48.978] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:48:0)
[02:40:48.995] Build status: STARTED
[02:40:48.995] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:49:0) after 9m56s
[02:50:45.038] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:49:0)
[02:50:45.053] Build status: STARTED
[02:50:45.053] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:50:0) after 5m57s
[02:56:42.391] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:50:0)
[02:56:42.410] Build status: STARTED
[02:56:42.410] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8938811731601930688:51:0) after 9m20s
[02:57:52.226] Received PubSub notification, asking Buildbucket for the build status
[02:57:52.245] Build:
{
"id": "8773381669679957441",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-fyi-arm64-dbg"
},
"number": 6578,
"createdBy": "project:chromium",
"createTime": "2023-08-07T22:44:15.804456922Z",
"startTime": "2023-08-07T22:46:48.559551Z",
"endTime": "2023-08-08T02:57:51.934823830Z",
"updateTime": "2023-08-08T02:57:51.934823830Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "90ee4d4798d5145966f799dcc7e1d80fea93acab",
"ref": "refs/heads/main"
}
}
}
[02:57:52.246] Invocation finished in 4h13m37.657880393s with status SUCCEEDED