[22:46:39.272] New invocation is queued and will start shortly
[22:46:40.345] Starting the invocation (attempt 1)
[22:46:40.379] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6b92db2597df8f5f32def45d5784beeed1433f4c"
[22:46:40.379] Popped gitiles commit info from properties and tags
[22:46:40.379] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:46:40.380] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:46:40.380] Buildbucket request:
{
"requestId": "9007393483471198080",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTFuchsia x64"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "6b92db2597df8f5f32def45d5784beeed1433f4c",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9007393483471198080"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTFuchsia x64"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[22:46:41.054] Scheduled build:
{
"id": "8841963421559844560",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTFuchsia x64"
},
"number": 1169,
"createdBy": "project:chromium",
"createTime": "2021-07-11T22:46:40.462205Z",
"updateTime": "2021-07-11T22:46:40.882853Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "6b92db2597df8f5f32def45d5784beeed1433f4c",
"ref": "refs/heads/main"
}
}
}
[22:46:41.054] Task URL: https://cr-buildbucket.appspot.com/build/8841963421559844560
[22:46:41.054] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:2:0) after 6m41s
[22:46:54.853] Received PubSub notification, asking Buildbucket for the build status
[22:46:54.876] Build status: STARTED
[22:53:22.413] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:2:0)
[22:53:22.442] Build status: STARTED
[22:53:22.442] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:4:0) after 7m49s
[23:01:11.466] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:4:0)
[23:01:11.497] Build status: STARTED
[23:01:11.497] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:5:0) after 9m11s
[23:10:22.855] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:5:0)
[23:10:22.885] Build status: STARTED
[23:10:22.885] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:6:0) after 7m31s
[23:17:53.946] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:6:0)
[23:17:53.976] Build status: STARTED
[23:17:53.976] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:7:0) after 6m30s
[23:24:23.991] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:7:0)
[23:24:24.016] Build status: STARTED
[23:24:24.016] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:8:0) after 7m2s
[23:31:26.033] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:8:0)
[23:31:26.061] Build status: STARTED
[23:31:26.061] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:9:0) after 6m9s
[23:37:35.077] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:9:0)
[23:37:35.120] Build status: STARTED
[23:37:35.120] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:10:0) after 4m1s
[23:41:36.233] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:10:0)
[23:41:36.262] Build status: STARTED
[23:41:36.262] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:11:0) after 2m4s
[23:43:40.344] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:11:0)
[23:43:40.374] Build status: STARTED
[23:43:40.374] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:12:0) after 9m41s
[23:53:21.392] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:12:0)
[23:53:21.423] Build status: STARTED
[23:53:21.423] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:13:0) after 2m51s
[23:56:12.822] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:13:0)
[23:56:12.852] Build status: STARTED
[23:56:12.852] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:14:0) after 7m0s
[00:03:12.874] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:14:0)
[00:03:12.902] Build status: STARTED
[00:03:12.902] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:15:0) after 8m27s
[00:11:40.120] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:15:0)
[00:11:40.146] Build status: STARTED
[00:11:40.146] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:16:0) after 7m18s
[00:18:58.175] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:16:0)
[00:18:58.206] Build status: STARTED
[00:18:58.206] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:17:0) after 3m13s
[00:22:11.254] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:17:0)
[00:22:11.304] Build status: STARTED
[00:22:11.304] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:18:0) after 8m2s
[00:30:13.334] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:18:0)
[00:30:13.367] Build status: STARTED
[00:30:13.367] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:19:0) after 2m21s
[00:32:34.755] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:19:0)
[00:32:34.995] Build status: STARTED
[00:32:34.995] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:20:0) after 9m7s
[00:41:42.023] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:20:0)
[00:41:42.059] Build status: STARTED
[00:41:42.059] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:21:0) after 1m19s
[00:43:01.118] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:21:0)
[00:43:01.157] Build status: STARTED
[00:43:01.157] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:22:0) after 7m22s
[00:50:23.310] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:22:0)
[00:50:23.339] Build status: STARTED
[00:50:23.339] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:23:0) after 6m31s
[00:56:54.445] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:23:0)
[00:56:54.484] Build status: STARTED
[00:56:54.484] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:24:0) after 9m25s
[01:06:19.502] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:24:0)
[01:06:19.543] Build status: STARTED
[01:06:19.543] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:25:0) after 6m24s
[01:12:43.579] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:25:0)
[01:12:43.612] Build status: STARTED
[01:12:43.612] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:26:0) after 6m10s
[01:18:53.704] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:26:0)
[01:18:53.732] Build status: STARTED
[01:18:53.732] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:27:0) after 2m25s
[01:21:19.039] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:27:0)
[01:21:19.070] Build status: STARTED
[01:21:19.070] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:28:0) after 3m8s
[01:24:27.173] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:28:0)
[01:24:27.204] Build status: STARTED
[01:24:27.204] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:29:0) after 7m49s
[01:32:16.427] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:29:0)
[01:32:16.461] Build status: STARTED
[01:32:16.461] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:30:0) after 8m8s
[01:40:24.745] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:30:0)
[01:40:24.775] Build status: STARTED
[01:40:24.775] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:31:0) after 1m16s
[01:41:41.044] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:31:0)
[01:41:41.133] Build status: STARTED
[01:41:41.133] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:32:0) after 4m19s
[01:46:00.380] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:32:0)
[01:46:00.416] Build status: STARTED
[01:46:00.416] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:33:0) after 9m50s
[01:55:50.438] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:33:0)
[01:55:50.475] Build status: STARTED
[01:55:50.475] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:34:0) after 4m0s
[01:59:50.496] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:34:0)
[01:59:50.526] Build status: STARTED
[01:59:50.526] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:35:0) after 8m56s
[02:08:46.650] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:35:0)
[02:08:46.702] Build status: STARTED
[02:08:46.702] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:36:0) after 4m30s
[02:13:16.720] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:36:0)
[02:13:16.747] Build status: STARTED
[02:13:16.747] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:37:0) after 6m7s
[02:19:23.773] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:37:0)
[02:19:23.805] Build status: STARTED
[02:19:23.805] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:38:0) after 6m35s
[02:25:58.962] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:38:0)
[02:25:58.993] Build status: STARTED
[02:25:58.993] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:39:0) after 3m21s
[02:29:20.124] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:39:0)
[02:29:20.163] Build status: STARTED
[02:29:20.163] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:40:0) after 6m17s
[02:35:37.203] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:40:0)
[02:35:37.250] Build status: STARTED
[02:35:37.250] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:41:0) after 7m40s
[02:43:17.615] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:41:0)
[02:43:17.643] Build status: STARTED
[02:43:17.643] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:42:0) after 4m9s
[02:47:26.663] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:42:0)
[02:47:26.694] Build status: STARTED
[02:47:26.694] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:43:0) after 5m59s
[02:53:25.715] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:43:0)
[02:53:25.844] Build status: STARTED
[02:53:25.844] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:44:0) after 3m14s
[02:56:39.861] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:44:0)
[02:56:40.096] Build status: STARTED
[02:56:40.096] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:45:0) after 5m14s
[03:01:54.215] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:45:0)
[03:01:54.246] Build status: STARTED
[03:01:54.246] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:46:0) after 7m44s
[03:09:38.370] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:46:0)
[03:09:38.557] Build status: STARTED
[03:09:38.557] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:47:0) after 1m2s
[03:10:40.757] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:47:0)
[03:10:40.795] Build status: STARTED
[03:10:40.795] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:48:0) after 6m14s
[03:16:54.824] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:48:0)
[03:16:54.855] Build status: STARTED
[03:16:54.855] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:49:0) after 1m29s
[03:18:23.877] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:49:0)
[03:18:23.907] Build status: STARTED
[03:18:23.907] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:50:0) after 8m30s
[03:26:54.913] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:50:0)
[03:26:54.954] Build status: STARTED
[03:26:54.954] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:51:0) after 1m17s
[03:28:11.980] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:51:0)
[03:28:12.012] Build status: STARTED
[03:28:12.012] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:52:0) after 7m12s
[03:35:24.186] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:52:0)
[03:35:24.216] Build status: STARTED
[03:35:24.216] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:53:0) after 3m15s
[03:38:39.738] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:53:0)
[03:38:39.773] Build status: STARTED
[03:38:39.773] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:54:0) after 6m14s
[03:44:54.068] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:54:0)
[03:44:54.096] Build status: STARTED
[03:44:54.096] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:55:0) after 5m12s
[03:50:06.218] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:55:0)
[03:50:06.305] Build status: STARTED
[03:50:06.305] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:56:0) after 7m11s
[03:57:17.468] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:56:0)
[03:57:17.563] Build status: STARTED
[03:57:17.563] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:57:0) after 1m45s
[03:59:02.642] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:57:0)
[03:59:03.121] Build status: STARTED
[03:59:03.121] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:58:0) after 7m17s
[04:06:20.241] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:58:0)
[04:06:20.283] Build status: STARTED
[04:06:20.283] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:59:0) after 3m2s
[04:09:22.308] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:59:0)
[04:09:22.341] Build status: STARTED
[04:09:22.341] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:60:0) after 2m5s
[04:11:27.412] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:60:0)
[04:11:27.442] Build status: STARTED
[04:11:27.442] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:61:0) after 5m32s
[04:16:59.476] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:61:0)
[04:16:59.504] Build status: STARTED
[04:16:59.504] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:62:0) after 8m48s
[04:25:47.537] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:62:0)
[04:25:47.570] Build status: STARTED
[04:25:47.570] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:63:0) after 4m1s
[04:29:48.856] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:63:0)
[04:29:48.893] Build status: STARTED
[04:29:48.893] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:64:0) after 2m6s
[04:31:54.918] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:64:0)
[04:31:54.951] Build status: STARTED
[04:31:54.952] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:65:0) after 5m53s
[04:37:47.979] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:65:0)
[04:37:48.072] Build status: STARTED
[04:37:48.072] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:9007393483471198080:66:0) after 8m55s
[04:45:19.420] Received PubSub notification, asking Buildbucket for the build status
[04:45:19.455] Build:
{
"id": "8841963421559844560",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTFuchsia x64"
},
"number": 1169,
"createdBy": "project:chromium",
"createTime": "2021-07-11T22:46:40.462205Z",
"startTime": "2021-07-11T22:46:53.914538Z",
"endTime": "2021-07-12T04:45:18.714428186Z",
"updateTime": "2021-07-12T04:45:18.714786953Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "6b92db2597df8f5f32def45d5784beeed1433f4c",
"ref": "refs/heads/main"
}
}
}
[04:45:19.455] Invocation finished in 5h58m40.198867266s with status SUCCEEDED