[13:33:12.627] New invocation is queued and will start shortly
[13:33:13.697] Starting the invocation (attempt 1)
[13:33:13.723] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/38cf55e7234e5178ae6e1e7e53695083338d7e9d"
[13:33:13.723] Popped gitiles commit info from properties and tags
[13:33:13.723] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:33:13.723] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:33:13.723] Buildbucket request:
{
"requestId": "8896990601109617888",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win10-rel-no-external-ip"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "38cf55e7234e5178ae6e1e7e53695083338d7e9d",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8896990601109617888"
},
{
"key": "scheduler_job_id",
"value": "chromium/win10-rel-no-external-ip"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[13:33:14.925] Scheduled build:
{
"id": "8731560539244534529",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win10-rel-no-external-ip"
},
"number": 4010,
"createdBy": "project:chromium",
"createTime": "2024-11-11T13:33:13.784203065Z",
"updateTime": "2024-11-11T13:33:13.784203065Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "38cf55e7234e5178ae6e1e7e53695083338d7e9d",
"ref": "refs/heads/main"
}
},
"canary": true
}
[13:33:14.925] Task URL: https://cr-buildbucket.appspot.com/build/8731560539244534529
[13:33:14.925] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:2:0) after 1m52s
[13:33:16.098] Received PubSub notification, asking Buildbucket for the build status
[13:33:16.115] Build status: SCHEDULED
[13:34:17.960] Received PubSub notification, asking Buildbucket for the build status
[13:34:18.004] Build status: STARTED
[13:35:06.945] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:2:0)
[13:35:06.964] Build status: STARTED
[13:35:06.964] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:5:0) after 1m33s
[13:36:40.379] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:5:0)
[13:36:40.394] Build status: STARTED
[13:36:40.394] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:6:0) after 5m30s
[13:42:10.465] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:6:0)
[13:42:10.481] Build status: STARTED
[13:42:10.481] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:7:0) after 7m58s
[13:50:08.806] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:7:0)
[13:50:08.827] Build status: STARTED
[13:50:08.827] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:8:0) after 6m49s
[13:56:57.964] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:8:0)
[13:56:57.995] Build status: STARTED
[13:56:57.995] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:9:0) after 4m2s
[14:01:00.035] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:9:0)
[14:01:00.059] Build status: STARTED
[14:01:00.059] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:10:0) after 7m15s
[14:08:15.255] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:10:0)
[14:08:15.297] Build status: STARTED
[14:08:15.297] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:11:0) after 7m55s
[14:16:10.321] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:11:0)
[14:16:10.343] Build status: STARTED
[14:16:10.343] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:12:0) after 9m6s
[14:25:16.378] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:12:0)
[14:25:16.464] Build status: STARTED
[14:25:16.468] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:13:0) after 3m49s
[14:29:05.526] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:13:0)
[14:29:05.545] Build status: STARTED
[14:29:05.545] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:14:0) after 7m51s
[14:36:56.673] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:14:0)
[14:36:56.763] Build status: STARTED
[14:36:56.763] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:15:0) after 5m31s
[14:42:27.800] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:15:0)
[14:42:27.820] Build status: STARTED
[14:42:27.820] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:16:0) after 3m37s
[14:46:04.797] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:16:0)
[14:46:04.823] Build status: STARTED
[14:46:04.823] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:17:0) after 2m35s
[14:48:39.843] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:17:0)
[14:48:39.866] Build status: STARTED
[14:48:39.866] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:18:0) after 6m59s
[14:55:38.991] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:18:0)
[14:55:39.011] Build status: STARTED
[14:55:39.011] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:19:0) after 8m18s
[15:03:57.077] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:19:0)
[15:03:57.094] Build status: STARTED
[15:03:57.094] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:20:0) after 3m2s
[15:06:59.177] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:20:0)
[15:06:59.194] Build status: STARTED
[15:06:59.194] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:21:0) after 3m49s
[15:10:48.275] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:21:0)
[15:10:48.293] Build status: STARTED
[15:10:48.293] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:22:0) after 9m46s
[15:20:34.486] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:22:0)
[15:20:34.503] Build status: STARTED
[15:20:34.503] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:23:0) after 1m33s
[15:22:07.584] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:23:0)
[15:22:07.620] Build status: STARTED
[15:22:07.620] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:24:0) after 9m55s
[15:32:02.652] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:24:0)
[15:32:02.683] Build status: STARTED
[15:32:02.683] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:25:0) after 6m34s
[15:38:36.713] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:25:0)
[15:38:36.738] Build status: STARTED
[15:38:36.738] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:26:0) after 5m42s
[15:44:18.852] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:26:0)
[15:44:18.881] Build status: STARTED
[15:44:18.881] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:27:0) after 9m51s
[15:54:09.913] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:27:0)
[15:54:09.941] Build status: STARTED
[15:54:09.941] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:28:0) after 4m53s
[15:59:03.192] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:28:0)
[15:59:03.209] Build status: STARTED
[15:59:03.209] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:29:0) after 4m35s
[16:03:38.998] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:29:0)
[16:03:39.016] Build status: STARTED
[16:03:39.016] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:30:0) after 3m38s
[16:07:17.377] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:30:0)
[16:07:17.396] Build status: STARTED
[16:07:17.397] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:31:0) after 7m7s
[16:14:24.425] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:31:0)
[16:14:24.445] Build status: STARTED
[16:14:24.445] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:32:0) after 7m0s
[16:21:25.052] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:32:0)
[16:21:25.120] Build status: STARTED
[16:21:25.120] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:33:0) after 4m26s
[16:25:51.218] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:33:0)
[16:25:51.237] Build status: STARTED
[16:25:51.237] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:34:0) after 4m16s
[16:30:07.263] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:34:0)
[16:30:07.294] Build status: STARTED
[16:30:07.294] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:35:0) after 4m28s
[16:34:35.315] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:35:0)
[16:34:35.383] Build status: STARTED
[16:34:35.383] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:36:0) after 2m48s
[16:37:23.407] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:36:0)
[16:37:23.495] Build status: STARTED
[16:37:23.495] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:37:0) after 3m50s
[16:41:13.587] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:37:0)
[16:41:13.608] Build status: STARTED
[16:41:13.608] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:38:0) after 6m6s
[16:47:19.631] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:38:0)
[16:47:19.655] Build status: STARTED
[16:47:19.655] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:39:0) after 2m33s
[16:49:52.685] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:39:0)
[16:49:52.718] Build status: STARTED
[16:49:52.718] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:40:0) after 8m2s
[16:57:54.786] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:40:0)
[16:57:54.809] Build status: STARTED
[16:57:54.809] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:41:0) after 9m14s
[17:07:08.854] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:41:0)
[17:07:08.872] Build status: STARTED
[17:07:08.872] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:42:0) after 6m59s
[17:14:07.906] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:42:0)
[17:14:07.925] Build status: STARTED
[17:14:07.925] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:43:0) after 8m30s
[17:22:38.002] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:43:0)
[17:22:38.061] Build status: STARTED
[17:22:38.061] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:44:0) after 9m9s
[17:31:47.174] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:44:0)
[17:31:47.248] Build status: STARTED
[17:31:47.248] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:45:0) after 8m46s
[17:40:33.295] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:45:0)
[17:40:33.318] Build status: STARTED
[17:40:33.318] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:46:0) after 6m17s
[17:46:50.473] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:46:0)
[17:46:50.491] Build status: STARTED
[17:46:50.491] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:47:0) after 2m32s
[17:49:22.490] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:47:0)
[17:49:22.530] Build status: STARTED
[17:49:22.530] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:48:0) after 9m26s
[17:58:48.703] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:48:0)
[17:58:48.720] Build status: STARTED
[17:58:48.720] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:49:0) after 9m55s
[18:08:43.753] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:49:0)
[18:08:43.769] Build status: STARTED
[18:08:43.769] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:50:0) after 6m13s
[18:14:56.893] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:50:0)
[18:14:56.919] Build status: STARTED
[18:14:56.919] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:51:0) after 9m39s
[18:24:36.023] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:51:0)
[18:24:36.050] Build status: STARTED
[18:24:36.051] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:52:0) after 8m38s
[18:33:14.079] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:52:0)
[18:33:14.111] Build status: STARTED
[18:33:14.111] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:53:0) after 6m39s
[18:39:53.220] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:53:0)
[18:39:53.239] Build status: STARTED
[18:39:53.239] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:54:0) after 9m21s
[18:49:14.310] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:54:0)
[18:49:14.329] Build status: STARTED
[18:49:14.329] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:55:0) after 4m38s
[18:53:52.361] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:55:0)
[18:53:52.379] Build status: STARTED
[18:53:52.379] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:56:0) after 6m58s
[19:00:50.402] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:56:0)
[19:00:50.431] Build status: STARTED
[19:00:50.431] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:57:0) after 9m46s
[19:10:36.590] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:57:0)
[19:10:36.620] Build status: STARTED
[19:10:36.620] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:58:0) after 6m34s
[19:17:10.695] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:58:0)
[19:17:10.720] Build status: STARTED
[19:17:10.721] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:59:0) after 6m0s
[19:23:10.852] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:59:0)
[19:23:10.875] Build status: STARTED
[19:23:10.875] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:60:0) after 5m26s
[19:28:36.898] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:60:0)
[19:28:36.914] Build status: STARTED
[19:28:36.914] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:61:0) after 2m43s
[19:31:19.956] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:61:0)
[19:31:19.977] Build status: STARTED
[19:31:19.977] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:62:0) after 7m45s
[19:39:05.171] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:62:0)
[19:39:05.268] Build status: STARTED
[19:39:05.268] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:63:0) after 2m37s
[19:41:42.293] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:63:0)
[19:41:42.309] Build status: STARTED
[19:41:42.309] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:64:0) after 5m11s
[19:46:53.346] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:64:0)
[19:46:53.368] Build status: STARTED
[19:46:53.368] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:65:0) after 6m27s
[19:53:20.388] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:65:0)
[19:53:20.408] Build status: STARTED
[19:53:20.408] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:66:0) after 6m59s
[20:00:19.435] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:66:0)
[20:00:19.461] Build status: STARTED
[20:00:19.461] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:67:0) after 6m52s
[20:07:12.163] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:67:0)
[20:07:12.207] Build status: STARTED
[20:07:12.207] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:68:0) after 6m8s
[20:13:20.334] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:68:0)
[20:13:20.351] Build status: STARTED
[20:13:20.351] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:69:0) after 1m10s
[20:14:30.372] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:69:0)
[20:14:30.392] Build status: STARTED
[20:14:30.392] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:70:0) after 2m10s
[20:16:40.414] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:70:0)
[20:16:40.434] Build status: STARTED
[20:16:40.434] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:71:0) after 1m57s
[20:18:37.459] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:71:0)
[20:18:37.480] Build status: STARTED
[20:18:37.480] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:72:0) after 5m17s
[20:23:54.501] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:72:0)
[20:23:54.561] Build status: STARTED
[20:23:54.561] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:73:0) after 3m27s
[20:27:21.580] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:73:0)
[20:27:21.631] Build status: STARTED
[20:27:21.631] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:74:0) after 7m0s
[20:34:21.653] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:74:0)
[20:34:21.673] Build status: STARTED
[20:34:21.673] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:75:0) after 3m47s
[20:38:08.701] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:75:0)
[20:38:08.753] Build status: STARTED
[20:38:08.753] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:76:0) after 8m16s
[20:46:24.780] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:76:0)
[20:46:24.797] Build status: STARTED
[20:46:24.797] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:77:0) after 5m0s
[20:51:24.868] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:77:0)
[20:51:24.893] Build status: STARTED
[20:51:24.893] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:78:0) after 2m9s
[20:53:33.918] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:78:0)
[20:53:33.935] Build status: STARTED
[20:53:33.935] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:79:0) after 2m56s
[20:56:29.971] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:79:0)
[20:56:29.988] Build status: STARTED
[20:56:29.988] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:80:0) after 2m49s
[20:59:18.982] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:80:0)
[20:59:19.000] Build status: STARTED
[20:59:19.000] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:81:0) after 1m19s
[21:00:37.998] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:81:0)
[21:00:38.023] Build status: STARTED
[21:00:38.023] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:82:0) after 6m37s
[21:07:15.044] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:82:0)
[21:07:15.075] Build status: STARTED
[21:07:15.075] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:83:0) after 6m11s
[21:13:26.090] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:83:0)
[21:13:26.110] Build status: STARTED
[21:13:26.110] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:84:0) after 3m22s
[21:16:48.477] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:84:0)
[21:16:48.498] Build status: STARTED
[21:16:48.498] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:85:0) after 5m58s
[21:22:46.522] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:85:0)
[21:22:46.547] Build status: STARTED
[21:22:46.547] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:86:0) after 8m11s
[21:30:57.701] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:86:0)
[21:30:57.717] Build status: STARTED
[21:30:57.717] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:87:0) after 3m29s
[21:34:26.893] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:87:0)
[21:34:26.917] Build status: STARTED
[21:34:26.917] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:88:0) after 8m43s
[21:43:10.116] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:88:0)
[21:43:10.143] Build status: STARTED
[21:43:10.143] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:89:0) after 9m46s
[21:52:56.212] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:89:0)
[21:52:56.240] Build status: STARTED
[21:52:56.240] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:90:0) after 5m51s
[21:58:47.313] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:90:0)
[21:58:47.334] Build status: STARTED
[21:58:47.334] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:91:0) after 2m50s
[22:01:37.548] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:91:0)
[22:01:37.567] Build status: STARTED
[22:01:37.567] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:92:0) after 7m44s
[22:09:21.591] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:92:0)
[22:09:21.616] Build status: STARTED
[22:09:21.616] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:93:0) after 2m35s
[22:11:56.643] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:93:0)
[22:11:56.667] Build status: STARTED
[22:11:56.667] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:94:0) after 3m23s
[22:15:19.653] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:94:0)
[22:15:19.672] Build status: STARTED
[22:15:19.672] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:95:0) after 5m25s
[22:20:44.726] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:95:0)
[22:20:44.766] Build status: STARTED
[22:20:44.766] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:96:0) after 9m6s
[22:29:50.789] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:96:0)
[22:29:50.804] Build status: STARTED
[22:29:50.804] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:97:0) after 8m59s
[22:38:49.789] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:97:0)
[22:38:49.805] Build status: STARTED
[22:38:49.806] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:98:0) after 3m46s
[22:42:35.877] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:98:0)
[22:42:35.982] Build status: STARTED
[22:42:35.982] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:99:0) after 3m34s
[22:46:10.051] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:99:0)
[22:46:10.070] Build status: STARTED
[22:46:10.070] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:100:0) after 5m46s
[22:51:56.092] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:100:0)
[22:51:56.137] Build status: STARTED
[22:51:56.137] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:101:0) after 4m53s
[22:56:49.162] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:101:0)
[22:56:49.241] Build status: STARTED
[22:56:49.241] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:102:0) after 8m58s
[23:05:47.293] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:102:0)
[23:05:47.312] Build status: STARTED
[23:05:47.312] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:103:0) after 7m13s
[23:13:00.392] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:103:0)
[23:13:00.416] Build status: STARTED
[23:13:00.416] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:104:0) after 2m11s
[23:15:11.444] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:104:0)
[23:15:11.471] Build status: STARTED
[23:15:11.472] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:105:0) after 9m10s
[23:24:21.477] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:105:0)
[23:24:21.493] Build status: STARTED
[23:24:21.493] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:106:0) after 5m0s
[23:29:21.565] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:106:0)
[23:29:21.586] Build status: STARTED
[23:29:21.586] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:107:0) after 1m32s
[23:30:53.608] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:107:0)
[23:30:53.633] Build status: STARTED
[23:30:53.633] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:108:0) after 9m44s
[23:40:37.660] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:108:0)
[23:40:37.694] Build status: STARTED
[23:40:37.694] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:109:0) after 6m12s
[23:46:49.738] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:109:0)
[23:46:49.761] Build status: STARTED
[23:46:49.761] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:110:0) after 8m26s
[23:55:15.840] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:110:0)
[23:55:15.862] Build status: STARTED
[23:55:15.862] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:111:0) after 8m27s
[00:03:42.928] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:111:0)
[00:03:43.006] Build status: STARTED
[00:03:43.006] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:112:0) after 5m36s
[00:09:19.103] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:112:0)
[00:09:19.121] Build status: STARTED
[00:09:19.121] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:113:0) after 8m7s
[00:17:26.143] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:113:0)
[00:17:26.163] Build status: STARTED
[00:17:26.163] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:114:0) after 1m46s
[00:19:12.267] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:114:0)
[00:19:12.297] Build status: STARTED
[00:19:12.297] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:115:0) after 4m44s
[00:23:56.321] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:115:0)
[00:23:56.338] Build status: STARTED
[00:23:56.338] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:116:0) after 3m22s
[00:27:18.315] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:116:0)
[00:27:18.337] Build status: STARTED
[00:27:18.337] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:117:0) after 2m27s
[00:29:45.324] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:117:0)
[00:29:45.344] Build status: STARTED
[00:29:45.344] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:118:0) after 9m43s
[00:39:28.374] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:118:0)
[00:39:28.394] Build status: STARTED
[00:39:28.394] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:119:0) after 7m2s
[00:46:30.377] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:119:0)
[00:46:30.402] Build status: STARTED
[00:46:30.402] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:120:0) after 8m13s
[00:54:43.563] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:120:0)
[00:54:43.583] Build status: STARTED
[00:54:43.583] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:121:0) after 7m42s
[01:02:25.609] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:121:0)
[01:02:25.699] Build status: STARTED
[01:02:25.699] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:122:0) after 7m11s
[01:09:36.755] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:122:0)
[01:09:36.774] Build status: STARTED
[01:09:36.774] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:123:0) after 9m43s
[01:19:19.807] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:123:0)
[01:19:19.858] Build status: STARTED
[01:19:19.858] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:124:0) after 6m27s
[01:25:46.951] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:124:0)
[01:25:47.018] Build status: STARTED
[01:25:47.019] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:125:0) after 9m31s
[01:35:18.044] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:125:0)
[01:35:18.086] Build status: STARTED
[01:35:18.086] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:126:0) after 6m17s
[01:41:35.158] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:126:0)
[01:41:35.187] Build status: STARTED
[01:41:35.188] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:127:0) after 5m40s
[01:47:15.205] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:127:0)
[01:47:15.226] Build status: STARTED
[01:47:15.226] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:128:0) after 9m2s
[01:56:17.362] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:128:0)
[01:56:17.400] Build status: STARTED
[01:56:17.401] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:129:0) after 2m14s
[01:58:31.506] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:129:0)
[01:58:31.537] Build status: STARTED
[01:58:31.537] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:130:0) after 3m58s
[02:02:29.607] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:130:0)
[02:02:29.623] Build status: STARTED
[02:02:29.623] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:131:0) after 6m7s
[02:08:36.647] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:131:0)
[02:08:36.665] Build status: STARTED
[02:08:36.666] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:132:0) after 6m37s
[02:15:13.712] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:132:0)
[02:15:13.775] Build status: STARTED
[02:15:13.775] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:133:0) after 3m53s
[02:19:06.849] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:133:0)
[02:19:06.870] Build status: STARTED
[02:19:06.870] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:134:0) after 6m24s
[02:25:31.092] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:134:0)
[02:25:31.108] Build status: STARTED
[02:25:31.109] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:135:0) after 5m12s
[02:30:43.248] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:135:0)
[02:30:43.319] Build status: STARTED
[02:30:43.320] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:136:0) after 2m9s
[02:32:52.342] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:136:0)
[02:32:52.415] Build status: STARTED
[02:32:52.415] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:137:0) after 8m6s
[02:40:58.524] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:137:0)
[02:40:58.542] Build status: STARTED
[02:40:58.543] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:138:0) after 1m46s
[02:42:44.569] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:138:0)
[02:42:44.585] Build status: STARTED
[02:42:44.585] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:139:0) after 1m49s
[02:44:33.607] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:139:0)
[02:44:33.728] Build status: STARTED
[02:44:33.728] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:140:0) after 2m14s
[02:46:47.802] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:140:0)
[02:46:47.823] Build status: STARTED
[02:46:47.823] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:141:0) after 9m5s
[02:55:53.464] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:141:0)
[02:55:53.484] Build status: STARTED
[02:55:53.484] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:142:0) after 5m23s
[03:01:16.530] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:142:0)
[03:01:16.559] Build status: STARTED
[03:01:16.559] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:143:0) after 6m27s
[03:07:43.537] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:143:0)
[03:07:43.556] Build status: STARTED
[03:07:43.556] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:144:0) after 2m48s
[03:10:31.577] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:144:0)
[03:10:31.595] Build status: STARTED
[03:10:31.595] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:145:0) after 1m0s
[03:11:31.621] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:145:0)
[03:11:31.643] Build status: STARTED
[03:11:31.644] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:146:0) after 1m12s
[03:12:43.670] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:146:0)
[03:12:43.695] Build status: STARTED
[03:12:43.695] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:147:0) after 3m43s
[03:16:27.206] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:147:0)
[03:16:27.228] Build status: STARTED
[03:16:27.228] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:148:0) after 9m3s
[03:25:30.328] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:148:0)
[03:25:30.347] Build status: STARTED
[03:25:30.347] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:149:0) after 4m10s
[03:29:40.377] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:149:0)
[03:29:40.394] Build status: STARTED
[03:29:40.394] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:150:0) after 4m35s
[03:34:15.624] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:150:0)
[03:34:15.700] Build status: STARTED
[03:34:15.700] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:151:0) after 5m21s
[03:39:36.731] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:151:0)
[03:39:36.747] Build status: STARTED
[03:39:36.747] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:152:0) after 4m12s
[03:43:48.985] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:152:0)
[03:43:48.999] Build status: STARTED
[03:43:48.999] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:153:0) after 6m7s
[03:49:56.167] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:153:0)
[03:49:56.187] Build status: STARTED
[03:49:56.187] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:154:0) after 3m41s
[03:53:37.179] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:154:0)
[03:53:37.196] Build status: STARTED
[03:53:37.196] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:155:0) after 6m42s
[04:00:19.221] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:155:0)
[04:00:19.242] Build status: STARTED
[04:00:19.242] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8896990601109617888:156:0) after 3m39s
[04:01:51.600] Received PubSub notification, asking Buildbucket for the build status
[04:01:51.618] Build:
{
"id": "8731560539244534529",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win10-rel-no-external-ip"
},
"number": 4010,
"createdBy": "project:chromium",
"createTime": "2024-11-11T13:33:13.784203065Z",
"startTime": "2024-11-11T13:34:15.840340852Z",
"endTime": "2024-11-12T04:01:51.072784510Z",
"updateTime": "2024-11-12T04:01:51.072784510Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "38cf55e7234e5178ae6e1e7e53695083338d7e9d",
"ref": "refs/heads/main"
}
},
"canary": true
}
[04:01:51.618] Invocation finished in 14h28m38.997103326s with status FAILED