[12:23:06.984] New invocation is queued and will start shortly
[12:23:08.195] Starting the invocation (attempt 1)
[12:23:08.225] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b82473ce03d1872a9a98deb5d740f7b9667e38c9"
[12:23:08.225] Popped gitiles commit info from properties and tags
[12:23:08.225] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:23:08.225] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:23:08.225] Buildbucket request:
{
"requestId": "8937491855036432000",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b82473ce03d1872a9a98deb5d740f7b9667e38c9",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8937491855036432000"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTWin64"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[12:23:08.866] Scheduled build:
{
"id": "8772061793003628481",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64"
},
"number": 4523,
"createdBy": "project:chromium",
"createTime": "2023-08-22T12:23:08.291766986Z",
"updateTime": "2023-08-22T12:23:08.291766986Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b82473ce03d1872a9a98deb5d740f7b9667e38c9",
"ref": "refs/heads/main"
}
},
"canary": true
}
[12:23:08.866] Task URL: https://cr-buildbucket.appspot.com/build/8772061793003628481
[12:23:08.866] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:2:0) after 2m18s
[12:23:09.898] Received PubSub notification, asking Buildbucket for the build status
[12:23:09.931] Build status: SCHEDULED
[12:23:15.487] Received PubSub notification, asking Buildbucket for the build status
[12:23:15.516] Build status: STARTED
[12:25:27.074] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:2:0)
[12:25:27.095] Build status: STARTED
[12:25:27.095] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:5:0) after 4m14s
[12:29:41.367] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:5:0)
[12:29:41.388] Build status: STARTED
[12:29:41.389] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:6:0) after 1m7s
[12:30:48.863] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:6:0)
[12:30:48.917] Build status: STARTED
[12:30:48.917] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:7:0) after 8m33s
[12:39:21.937] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:7:0)
[12:39:21.952] Build status: STARTED
[12:39:21.952] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:8:0) after 2m49s
[12:42:11.389] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:8:0)
[12:42:11.459] Build status: STARTED
[12:42:11.459] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:9:0) after 2m8s
[12:44:19.582] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:9:0)
[12:44:19.751] Build status: STARTED
[12:44:19.751] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:10:0) after 5m20s
[12:49:39.763] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:10:0)
[12:49:39.782] Build status: STARTED
[12:49:39.782] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:11:0) after 7m26s
[12:57:05.904] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:11:0)
[12:57:05.919] Build status: STARTED
[12:57:05.919] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:12:0) after 6m35s
[13:03:40.934] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:12:0)
[13:03:40.960] Build status: STARTED
[13:03:40.960] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:13:0) after 4m22s
[13:08:03.041] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:13:0)
[13:08:03.107] Build status: STARTED
[13:08:03.107] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:14:0) after 2m2s
[13:10:05.242] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:14:0)
[13:10:05.274] Build status: STARTED
[13:10:05.274] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:15:0) after 1m31s
[13:11:36.364] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:15:0)
[13:11:36.387] Build status: STARTED
[13:11:36.387] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:16:0) after 5m32s
[13:17:08.550] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:16:0)
[13:17:08.587] Build status: STARTED
[13:17:08.587] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:17:0) after 3m14s
[13:20:22.813] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:17:0)
[13:20:22.841] Build status: STARTED
[13:20:22.841] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:18:0) after 5m33s
[13:25:55.881] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:18:0)
[13:25:55.896] Build status: STARTED
[13:25:55.896] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:19:0) after 2m25s
[13:28:20.888] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:19:0)
[13:28:20.965] Build status: STARTED
[13:28:20.965] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:20:0) after 4m33s
[13:32:54.230] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:20:0)
[13:32:54.256] Build status: STARTED
[13:32:54.256] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:21:0) after 8m54s
[13:41:48.410] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:21:0)
[13:41:48.429] Build status: STARTED
[13:41:48.429] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:22:0) after 8m59s
[13:50:47.521] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:22:0)
[13:50:47.544] Build status: STARTED
[13:50:47.544] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:23:0) after 8m54s
[13:59:41.584] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:23:0)
[13:59:41.774] Build status: STARTED
[13:59:41.774] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:24:0) after 7m11s
[14:06:53.087] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:24:0)
[14:06:53.112] Build status: STARTED
[14:06:53.112] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:25:0) after 6m37s
[14:13:30.303] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:25:0)
[14:13:30.320] Build status: STARTED
[14:13:30.320] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:26:0) after 8m2s
[14:21:32.433] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:26:0)
[14:21:32.465] Build status: STARTED
[14:21:32.465] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:27:0) after 5m34s
[14:27:06.591] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:27:0)
[14:27:06.629] Build status: STARTED
[14:27:06.629] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:28:0) after 8m12s
[14:35:19.809] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:28:0)
[14:35:19.828] Build status: STARTED
[14:35:19.828] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:29:0) after 9m37s
[14:44:57.095] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:29:0)
[14:44:57.368] Build status: STARTED
[14:44:57.368] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:30:0) after 2m18s
[14:47:15.457] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:30:0)
[14:47:15.484] Build status: STARTED
[14:47:15.484] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:31:0) after 3m59s
[14:51:14.508] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:31:0)
[14:51:14.529] Build status: STARTED
[14:51:14.529] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:32:0) after 7m25s
[14:58:39.590] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:32:0)
[14:58:39.617] Build status: STARTED
[14:58:39.617] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:33:0) after 6m1s
[15:04:40.675] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:33:0)
[15:04:40.744] Build status: STARTED
[15:04:40.745] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:34:0) after 9m2s
[15:13:43.193] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:34:0)
[15:13:43.217] Build status: STARTED
[15:13:43.217] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:35:0) after 3m16s
[15:16:59.881] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:35:0)
[15:16:59.901] Build status: STARTED
[15:16:59.901] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:36:0) after 1m8s
[15:18:08.074] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:36:0)
[15:18:08.094] Build status: STARTED
[15:18:08.094] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:37:0) after 7m52s
[15:26:00.209] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:37:0)
[15:26:00.272] Build status: STARTED
[15:26:00.272] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:38:0) after 1m42s
[15:27:42.659] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:38:0)
[15:27:42.685] Build status: STARTED
[15:27:42.685] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:39:0) after 4m20s
[15:32:02.707] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:39:0)
[15:32:02.795] Build status: STARTED
[15:32:02.795] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:40:0) after 8m0s
[15:40:02.848] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:40:0)
[15:40:02.876] Build status: STARTED
[15:40:02.876] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:41:0) after 4m35s
[15:44:38.084] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:41:0)
[15:44:38.104] Build status: STARTED
[15:44:38.104] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:42:0) after 3m52s
[15:48:30.154] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:42:0)
[15:48:30.178] Build status: STARTED
[15:48:30.178] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:43:0) after 1m56s
[15:50:26.233] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:43:0)
[15:50:26.251] Build status: STARTED
[15:50:26.251] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:44:0) after 2m52s
[15:53:18.340] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:44:0)
[15:53:18.416] Build status: STARTED
[15:53:18.416] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:45:0) after 7m22s
[16:00:40.464] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:45:0)
[16:00:40.484] Build status: STARTED
[16:00:40.485] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:46:0) after 7m33s
[16:08:13.681] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:46:0)
[16:08:13.712] Build status: STARTED
[16:08:13.712] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:47:0) after 2m41s
[16:10:54.872] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:47:0)
[16:10:54.906] Build status: STARTED
[16:10:54.906] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:48:0) after 1m56s
[16:12:51.046] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:48:0)
[16:12:51.066] Build status: STARTED
[16:12:51.066] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:49:0) after 9m13s
[16:22:04.357] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:49:0)
[16:22:04.390] Build status: STARTED
[16:22:04.390] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:50:0) after 9m21s
[16:31:25.467] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:50:0)
[16:31:25.510] Build status: STARTED
[16:31:25.510] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:51:0) after 4m58s
[16:36:23.531] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:51:0)
[16:36:23.578] Build status: STARTED
[16:36:23.578] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:52:0) after 9m37s
[16:46:00.634] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:52:0)
[16:46:00.655] Build status: STARTED
[16:46:00.655] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:53:0) after 2m39s
[16:48:39.683] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:53:0)
[16:48:39.702] Build status: STARTED
[16:48:39.702] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:54:0) after 6m19s
[16:54:58.730] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:54:0)
[16:54:58.755] Build status: STARTED
[16:54:58.755] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:55:0) after 1m50s
[16:56:49.055] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:55:0)
[16:56:49.076] Build status: STARTED
[16:56:49.076] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:56:0) after 2m52s
[16:59:41.106] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:56:0)
[16:59:41.127] Build status: STARTED
[16:59:41.127] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:57:0) after 2m10s
[17:01:51.131] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:57:0)
[17:01:51.156] Build status: STARTED
[17:01:51.156] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:58:0) after 7m13s
[17:09:04.183] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:58:0)
[17:09:04.211] Build status: STARTED
[17:09:04.211] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:59:0) after 5m1s
[17:14:05.273] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:59:0)
[17:14:05.361] Build status: STARTED
[17:14:05.361] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:60:0) after 7m47s
[17:21:52.362] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:60:0)
[17:21:52.384] Build status: STARTED
[17:21:52.384] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:61:0) after 6m30s
[17:28:22.452] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:61:0)
[17:28:22.479] Build status: STARTED
[17:28:22.479] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:62:0) after 7m40s
[17:36:02.542] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:62:0)
[17:36:02.578] Build status: STARTED
[17:36:02.578] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:63:0) after 2m19s
[17:38:21.613] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:63:0)
[17:38:21.636] Build status: STARTED
[17:38:21.636] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:64:0) after 3m10s
[17:41:31.711] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:64:0)
[17:41:31.729] Build status: STARTED
[17:41:31.729] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:65:0) after 9m20s
[17:50:51.756] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:65:0)
[17:50:51.796] Build status: STARTED
[17:50:51.796] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:66:0) after 1m31s
[17:52:22.820] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:66:0)
[17:52:22.840] Build status: STARTED
[17:52:22.840] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:67:0) after 5m9s
[17:57:31.913] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:67:0)
[17:57:31.941] Build status: STARTED
[17:57:31.941] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:68:0) after 4m8s
[18:01:39.974] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:68:0)
[18:01:40.020] Build status: STARTED
[18:01:40.020] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:69:0) after 4m37s
[18:06:17.050] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:69:0)
[18:06:17.148] Build status: STARTED
[18:06:17.148] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:70:0) after 7m4s
[18:13:21.428] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:70:0)
[18:13:21.456] Build status: STARTED
[18:13:21.456] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:71:0) after 6m7s
[18:19:28.433] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:71:0)
[18:19:28.453] Build status: STARTED
[18:19:28.453] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:72:0) after 9m43s
[18:29:11.477] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:72:0)
[18:29:11.497] Build status: STARTED
[18:29:11.497] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:73:0) after 9m29s
[18:38:40.518] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:73:0)
[18:38:40.542] Build status: STARTED
[18:38:40.542] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:74:0) after 7m32s
[18:46:12.569] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:74:0)
[18:46:12.598] Build status: STARTED
[18:46:12.598] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:75:0) after 4m24s
[18:50:36.588] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:75:0)
[18:50:36.608] Build status: STARTED
[18:50:36.608] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:76:0) after 7m54s
[18:58:30.639] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:76:0)
[18:58:30.661] Build status: STARTED
[18:58:30.661] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:77:0) after 8m25s
[19:06:55.710] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:77:0)
[19:06:55.728] Build status: STARTED
[19:06:55.728] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:78:0) after 4m52s
[19:11:47.763] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:78:0)
[19:11:47.783] Build status: STARTED
[19:11:47.783] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:79:0) after 7m32s
[19:19:19.817] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:79:0)
[19:19:19.867] Build status: STARTED
[19:19:19.867] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:80:0) after 6m29s
[19:25:49.013] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:80:0)
[19:25:49.038] Build status: STARTED
[19:25:49.038] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:81:0) after 6m19s
[19:32:08.100] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:81:0)
[19:32:08.145] Build status: STARTED
[19:32:08.145] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:82:0) after 5m30s
[19:37:38.180] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:82:0)
[19:37:38.206] Build status: STARTED
[19:37:38.206] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:83:0) after 3m35s
[19:41:13.384] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:83:0)
[19:41:13.440] Build status: STARTED
[19:41:13.440] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:84:0) after 9m44s
[19:50:57.647] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:84:0)
[19:50:57.672] Build status: STARTED
[19:50:57.672] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:85:0) after 9m27s
[20:00:24.781] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:85:0)
[20:00:24.810] Build status: STARTED
[20:00:24.810] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:86:0) after 4m10s
[20:04:34.916] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:86:0)
[20:04:34.933] Build status: STARTED
[20:04:34.933] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:87:0) after 1m18s
[20:05:53.617] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:87:0)
[20:05:53.636] Build status: STARTED
[20:05:53.636] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:88:0) after 2m29s
[20:08:23.221] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:88:0)
[20:08:23.269] Build status: STARTED
[20:08:23.269] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:89:0) after 4m35s
[20:12:58.328] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:89:0)
[20:12:58.347] Build status: STARTED
[20:12:58.347] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:90:0) after 5m30s
[20:18:28.646] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:90:0)
[20:18:28.665] Build status: STARTED
[20:18:28.665] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:91:0) after 3m19s
[20:21:48.532] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:91:0)
[20:21:48.621] Build status: STARTED
[20:21:48.621] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:92:0) after 3m34s
[20:25:22.629] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:92:0)
[20:25:22.648] Build status: STARTED
[20:25:22.648] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:93:0) after 7m9s
[20:32:31.704] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:93:0)
[20:32:31.733] Build status: STARTED
[20:32:31.733] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:94:0) after 9m5s
[20:41:36.762] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:94:0)
[20:41:36.785] Build status: STARTED
[20:41:36.785] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:95:0) after 9m2s
[20:50:39.369] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:95:0)
[20:50:39.395] Build status: STARTED
[20:50:39.398] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:96:0) after 8m17s
[20:58:56.488] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:96:0)
[20:58:56.513] Build status: STARTED
[20:58:56.513] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:97:0) after 2m55s
[21:01:51.547] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:97:0)
[21:01:51.579] Build status: STARTED
[21:01:51.579] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:98:0) after 2m10s
[21:04:01.620] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:98:0)
[21:04:01.658] Build status: STARTED
[21:04:01.658] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:99:0) after 8m8s
[21:12:09.795] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:99:0)
[21:12:09.830] Build status: STARTED
[21:12:09.830] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:100:0) after 1m30s
[21:13:39.855] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:100:0)
[21:13:39.875] Build status: STARTED
[21:13:39.875] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:101:0) after 6m21s
[21:20:01.216] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:101:0)
[21:20:01.239] Build status: STARTED
[21:20:01.239] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:102:0) after 2m24s
[21:22:25.351] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:102:0)
[21:22:25.389] Build status: STARTED
[21:22:25.389] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:103:0) after 7m36s
[21:30:01.595] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:103:0)
[21:30:01.614] Build status: STARTED
[21:30:01.614] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:104:0) after 3m13s
[21:33:14.645] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:104:0)
[21:33:14.663] Build status: STARTED
[21:33:14.663] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:105:0) after 7m58s
[21:41:12.811] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:105:0)
[21:41:12.842] Build status: STARTED
[21:41:12.842] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:106:0) after 6m18s
[21:47:30.834] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:106:0)
[21:47:30.859] Build status: STARTED
[21:47:30.859] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:107:0) after 7m55s
[21:55:25.945] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:107:0)
[21:55:25.964] Build status: STARTED
[21:55:25.964] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:108:0) after 3m56s
[21:59:22.037] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:108:0)
[21:59:22.080] Build status: STARTED
[21:59:22.080] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:109:0) after 2m58s
[22:02:20.704] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:109:0)
[22:02:20.726] Build status: STARTED
[22:02:20.726] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:110:0) after 9m4s
[22:11:24.807] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:110:0)
[22:11:24.833] Build status: STARTED
[22:11:24.833] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:111:0) after 2m23s
[22:13:48.134] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:111:0)
[22:13:48.162] Build status: STARTED
[22:13:48.162] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:112:0) after 9m7s
[22:22:55.202] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:112:0)
[22:22:55.246] Build status: STARTED
[22:22:55.246] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:113:0) after 1m2s
[22:23:57.280] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:113:0)
[22:23:57.299] Build status: STARTED
[22:23:57.299] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:114:0) after 8m5s
[22:32:02.587] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:114:0)
[22:32:02.625] Build status: STARTED
[22:32:02.626] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:115:0) after 1m30s
[22:33:33.186] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:115:0)
[22:33:33.221] Build status: STARTED
[22:33:33.221] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:116:0) after 2m47s
[22:36:20.325] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:116:0)
[22:36:20.359] Build status: STARTED
[22:36:20.359] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:117:0) after 4m34s
[22:40:54.391] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:117:0)
[22:40:54.417] Build status: STARTED
[22:40:54.417] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:118:0) after 1m6s
[22:42:00.681] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:118:0)
[22:42:00.699] Build status: STARTED
[22:42:00.699] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:119:0) after 7m33s
[22:49:33.722] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:119:0)
[22:49:33.799] Build status: STARTED
[22:49:33.799] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:120:0) after 5m8s
[22:54:41.824] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:120:0)
[22:54:41.849] Build status: STARTED
[22:54:41.849] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:121:0) after 8m48s
[23:03:30.103] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:121:0)
[23:03:30.125] Build status: STARTED
[23:03:30.126] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:122:0) after 7m50s
[23:11:20.330] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:122:0)
[23:11:20.375] Build status: STARTED
[23:11:20.375] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:123:0) after 2m33s
[23:13:53.996] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:123:0)
[23:13:54.020] Build status: STARTED
[23:13:54.020] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:124:0) after 8m27s
[23:22:21.481] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:124:0)
[23:22:21.500] Build status: STARTED
[23:22:21.500] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:125:0) after 9m21s
[23:31:42.715] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:125:0)
[23:31:42.784] Build status: STARTED
[23:31:42.784] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:126:0) after 5m22s
[23:37:05.043] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:126:0)
[23:37:05.063] Build status: STARTED
[23:37:05.063] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:127:0) after 1m48s
[23:38:53.142] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:127:0)
[23:38:53.161] Build status: STARTED
[23:38:53.161] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:128:0) after 5m49s
[23:44:42.290] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:128:0)
[23:44:42.312] Build status: STARTED
[23:44:42.312] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:129:0) after 6m59s
[23:51:42.189] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:129:0)
[23:51:42.210] Build status: STARTED
[23:51:42.210] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:130:0) after 6m25s
[23:58:07.587] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:130:0)
[23:58:07.606] Build status: STARTED
[23:58:07.606] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:131:0) after 5m41s
[00:03:48.654] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:131:0)
[00:03:48.685] Build status: STARTED
[00:03:48.685] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:132:0) after 5m56s
[00:09:44.977] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:132:0)
[00:09:44.997] Build status: STARTED
[00:09:44.997] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:133:0) after 3m58s
[00:13:43.046] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:133:0)
[00:13:43.084] Build status: STARTED
[00:13:43.084] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:134:0) after 3m59s
[00:17:42.233] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:134:0)
[00:17:42.282] Build status: STARTED
[00:17:42.282] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:135:0) after 5m34s
[00:23:16.308] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:135:0)
[00:23:16.329] Build status: STARTED
[00:23:16.329] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:136:0) after 9m54s
[00:33:10.407] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:136:0)
[00:33:10.422] Build status: STARTED
[00:33:10.422] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8937491855036432000:137:0) after 9m54s
[00:42:28.020] Received PubSub notification, asking Buildbucket for the build status
[00:42:28.109] Build:
{
"id": "8772061793003628481",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64"
},
"number": 4523,
"createdBy": "project:chromium",
"createTime": "2023-08-22T12:23:08.291766986Z",
"startTime": "2023-08-22T12:23:14.694038Z",
"endTime": "2023-08-23T00:42:27.604238678Z",
"updateTime": "2023-08-23T00:42:27.604238678Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b82473ce03d1872a9a98deb5d740f7b9667e38c9",
"ref": "refs/heads/main"
}
},
"canary": true
}
[00:42:28.110] Invocation finished in 12h19m21.140224267s with status SUCCEEDED