[18:36:42.353] New invocation is queued and will start shortly
[18:36:43.563] Starting the invocation (attempt 1)
[18:36:43.592] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/46bee721ac3beb24c6d83f306def2c0f0f1963cb"
[18:36:43.592] Popped gitiles commit info from properties and tags
[18:36:43.592] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:36:43.594] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:36:43.594] Buildbucket request:
{
"requestId": "8936834172052345440",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win10-rel-no-external-ip"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "46bee721ac3beb24c6d83f306def2c0f0f1963cb",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8936834172052345440"
},
{
"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"
}
}
[18:36:45.527] Scheduled build:
{
"id": "8771404110006716913",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win10-rel-no-external-ip"
},
"number": 2080,
"createdBy": "project:chromium",
"createTime": "2023-08-29T18:36:43.674249904Z",
"updateTime": "2023-08-29T18:36:43.674249904Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "46bee721ac3beb24c6d83f306def2c0f0f1963cb",
"ref": "refs/heads/main"
}
},
"canary": true
}
[18:36:45.527] Task URL: https://cr-buildbucket.appspot.com/build/8771404110006716913
[18:36:45.527] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:2:0) after 4m16s
[18:36:46.446] Received PubSub notification, asking Buildbucket for the build status
[18:36:46.468] Build status: SCHEDULED
[18:41:02.905] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:2:0)
[18:41:02.925] Build status: SCHEDULED
[18:41:02.925] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:4:0) after 9m40s
[18:46:52.395] Received PubSub notification, asking Buildbucket for the build status
[18:46:52.432] Build status: STARTED
[18:50:43.387] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:4:0)
[18:50:43.426] Build status: STARTED
[18:50:43.426] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:6:0) after 8m36s
[18:59:19.858] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:6:0)
[18:59:20.004] Build status: STARTED
[18:59:20.005] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:7:0) after 4m33s
[19:03:53.659] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:7:0)
[19:03:53.687] Build status: STARTED
[19:03:53.687] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:8:0) after 1m6s
[19:04:59.943] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:8:0)
[19:04:59.965] Build status: STARTED
[19:04:59.965] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:9:0) after 7m52s
[19:12:52.001] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:9:0)
[19:12:52.027] Build status: STARTED
[19:12:52.027] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:10:0) after 9m36s
[19:22:28.155] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:10:0)
[19:22:28.210] Build status: STARTED
[19:22:28.210] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:11:0) after 2m50s
[19:25:18.335] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:11:0)
[19:25:18.367] Build status: STARTED
[19:25:18.367] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:12:0) after 8m51s
[19:34:09.506] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:12:0)
[19:34:09.535] Build status: STARTED
[19:34:09.535] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:13:0) after 1m32s
[19:35:41.560] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:13:0)
[19:35:41.590] Build status: STARTED
[19:35:41.590] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:14:0) after 8m58s
[19:44:39.719] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:14:0)
[19:44:39.767] Build status: STARTED
[19:44:39.767] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:15:0) after 3m3s
[19:47:42.906] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:15:0)
[19:47:42.927] Build status: STARTED
[19:47:42.927] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:16:0) after 4m33s
[19:52:16.066] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:16:0)
[19:52:16.091] Build status: STARTED
[19:52:16.091] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:17:0) after 5m54s
[19:58:10.117] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:17:0)
[19:58:10.133] Build status: STARTED
[19:58:10.133] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:18:0) after 5m41s
[20:03:51.154] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:18:0)
[20:03:51.178] Build status: STARTED
[20:03:51.178] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:19:0) after 5m10s
[20:09:01.282] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:19:0)
[20:09:01.306] Build status: STARTED
[20:09:01.306] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:20:0) after 6m41s
[20:15:42.442] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:20:0)
[20:15:42.464] Build status: STARTED
[20:15:42.464] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:21:0) after 5m13s
[20:20:55.485] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:21:0)
[20:20:55.505] Build status: STARTED
[20:20:55.505] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:22:0) after 9m32s
[20:30:27.553] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:22:0)
[20:30:27.589] Build status: STARTED
[20:30:27.589] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:23:0) after 8m31s
[20:38:58.609] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:23:0)
[20:38:58.627] Build status: STARTED
[20:38:58.627] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:24:0) after 9m8s
[20:48:06.724] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:24:0)
[20:48:06.747] Build status: STARTED
[20:48:06.747] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:25:0) after 2m46s
[20:50:52.768] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:25:0)
[20:50:52.786] Build status: STARTED
[20:50:52.786] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:26:0) after 2m1s
[20:52:53.833] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:26:0)
[20:52:53.852] Build status: STARTED
[20:52:53.852] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:27:0) after 2m36s
[20:55:29.993] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:27:0)
[20:55:30.021] Build status: STARTED
[20:55:30.022] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:28:0) after 5m36s
[21:01:06.049] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:28:0)
[21:01:06.132] Build status: STARTED
[21:01:06.132] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:29:0) after 2m55s
[21:04:01.155] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:29:0)
[21:04:01.187] Build status: STARTED
[21:04:01.187] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:30:0) after 3m55s
[21:07:56.213] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:30:0)
[21:07:56.233] Build status: STARTED
[21:07:56.233] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:31:0) after 5m22s
[21:13:18.253] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:31:0)
[21:13:18.274] Build status: STARTED
[21:13:18.274] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:32:0) after 9m34s
[21:22:52.617] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:32:0)
[21:22:52.638] Build status: STARTED
[21:22:52.639] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:33:0) after 5m9s
[21:28:02.717] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:33:0)
[21:28:02.774] Build status: STARTED
[21:28:02.774] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:34:0) after 2m58s
[21:31:00.796] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:34:0)
[21:31:00.815] Build status: STARTED
[21:31:00.815] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:35:0) after 1m0s
[21:32:00.950] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:35:0)
[21:32:00.979] Build status: STARTED
[21:32:00.979] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:36:0) after 2m49s
[21:34:50.364] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:36:0)
[21:34:50.383] Build status: STARTED
[21:34:50.383] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:37:0) after 4m9s
[21:38:59.405] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:37:0)
[21:38:59.443] Build status: STARTED
[21:38:59.443] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:38:0) after 9m11s
[21:48:10.496] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:38:0)
[21:48:10.538] Build status: STARTED
[21:48:10.538] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:39:0) after 9m55s
[21:58:05.568] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:39:0)
[21:58:05.625] Build status: STARTED
[21:58:05.625] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:40:0) after 4m58s
[22:03:03.765] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:40:0)
[22:03:03.879] Build status: STARTED
[22:03:03.879] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:41:0) after 7m16s
[22:10:19.925] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:41:0)
[22:10:19.954] Build status: STARTED
[22:10:19.954] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:42:0) after 1m1s
[22:11:21.056] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:42:0)
[22:11:21.077] Build status: STARTED
[22:11:21.077] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:43:0) after 4m59s
[22:16:20.444] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:43:0)
[22:16:20.463] Build status: STARTED
[22:16:20.463] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:44:0) after 5m45s
[22:22:05.548] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:44:0)
[22:22:05.571] Build status: STARTED
[22:22:05.571] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:45:0) after 9m11s
[22:31:16.602] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:45:0)
[22:31:16.629] Build status: STARTED
[22:31:16.629] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:46:0) after 9m17s
[22:40:33.945] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:46:0)
[22:40:33.964] Build status: STARTED
[22:40:33.964] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:47:0) after 1m32s
[22:42:06.040] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:47:0)
[22:42:06.058] Build status: STARTED
[22:42:06.058] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:48:0) after 8m0s
[22:50:06.332] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:48:0)
[22:50:06.450] Build status: STARTED
[22:50:06.450] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:49:0) after 6m1s
[22:56:08.064] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:49:0)
[22:56:08.093] Build status: STARTED
[22:56:08.093] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:50:0) after 1m47s
[22:57:55.278] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:50:0)
[22:57:55.302] Build status: STARTED
[22:57:55.302] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:51:0) after 1m9s
[22:59:04.432] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:51:0)
[22:59:04.467] Build status: STARTED
[22:59:04.467] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:52:0) after 2m33s
[23:01:37.979] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:52:0)
[23:01:38.006] Build status: STARTED
[23:01:38.006] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:53:0) after 2m59s
[23:04:37.030] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:53:0)
[23:04:37.051] Build status: STARTED
[23:04:37.051] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:54:0) after 7m52s
[23:12:29.082] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:54:0)
[23:12:29.105] Build status: STARTED
[23:12:29.105] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:55:0) after 9m1s
[23:21:30.163] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:55:0)
[23:21:30.193] Build status: STARTED
[23:21:30.194] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:56:0) after 4m32s
[23:26:02.275] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:56:0)
[23:26:02.317] Build status: STARTED
[23:26:02.317] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:57:0) after 1m51s
[23:27:53.342] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:57:0)
[23:27:53.572] Build status: STARTED
[23:27:53.572] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:58:0) after 9m15s
[23:37:08.925] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:58:0)
[23:37:08.946] Build status: STARTED
[23:37:08.946] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:59:0) after 7m11s
[23:44:20.070] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:59:0)
[23:44:20.097] Build status: STARTED
[23:44:20.097] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:60:0) after 8m48s
[23:53:08.129] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:60:0)
[23:53:08.176] Build status: STARTED
[23:53:08.176] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:61:0) after 6m51s
[23:59:59.599] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:61:0)
[23:59:59.624] Build status: STARTED
[23:59:59.624] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:62:0) after 2m51s
[00:02:51.226] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:62:0)
[00:02:51.245] Build status: STARTED
[00:02:51.245] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:63:0) after 5m40s
[00:08:31.395] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:63:0)
[00:08:31.456] Build status: STARTED
[00:08:31.456] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:64:0) after 3m13s
[00:11:44.483] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:64:0)
[00:11:44.505] Build status: STARTED
[00:11:44.505] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:65:0) after 4m4s
[00:15:48.639] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:65:0)
[00:15:48.672] Build status: STARTED
[00:15:48.672] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:66:0) after 9m23s
[00:25:11.795] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:66:0)
[00:25:11.812] Build status: STARTED
[00:25:11.812] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:67:0) after 9m0s
[00:34:12.171] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:67:0)
[00:34:12.197] Build status: STARTED
[00:34:12.197] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:68:0) after 5m9s
[00:39:21.225] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:68:0)
[00:39:21.263] Build status: STARTED
[00:39:21.263] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:69:0) after 3m46s
[00:43:07.435] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:69:0)
[00:43:07.454] Build status: STARTED
[00:43:07.455] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:70:0) after 6m49s
[00:49:56.651] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:70:0)
[00:49:56.670] Build status: STARTED
[00:49:56.670] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:71:0) after 4m4s
[00:54:00.731] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:71:0)
[00:54:00.755] Build status: STARTED
[00:54:00.755] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:72:0) after 5m56s
[00:59:57.039] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:72:0)
[00:59:57.068] Build status: STARTED
[00:59:57.068] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:73:0) after 1m33s
[01:01:30.591] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:73:0)
[01:01:30.611] Build status: STARTED
[01:01:30.611] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:74:0) after 5m23s
[01:06:53.790] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:74:0)
[01:06:53.813] Build status: STARTED
[01:06:53.813] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:75:0) after 6m32s
[01:13:25.845] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:75:0)
[01:13:25.884] Build status: STARTED
[01:13:25.884] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:76:0) after 7m2s
[01:20:27.961] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:76:0)
[01:20:27.979] Build status: STARTED
[01:20:27.979] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:77:0) after 4m47s
[01:25:15.058] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:77:0)
[01:25:15.093] Build status: STARTED
[01:25:15.093] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:78:0) after 2m24s
[01:27:39.297] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:78:0)
[01:27:39.331] Build status: STARTED
[01:27:39.332] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:79:0) after 9m50s
[01:37:29.415] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:79:0)
[01:37:29.432] Build status: STARTED
[01:37:29.432] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:80:0) after 6m6s
[01:43:35.459] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:80:0)
[01:43:35.476] Build status: STARTED
[01:43:35.476] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:81:0) after 8m10s
[01:51:45.538] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:81:0)
[01:51:45.576] Build status: STARTED
[01:51:45.576] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:82:0) after 7m56s
[01:59:41.695] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:82:0)
[01:59:41.719] Build status: STARTED
[01:59:41.719] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:83:0) after 6m22s
[02:06:03.956] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:83:0)
[02:06:03.979] Build status: STARTED
[02:06:03.979] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:84:0) after 7m53s
[02:13:57.408] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:84:0)
[02:13:57.431] Build status: STARTED
[02:13:57.431] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:85:0) after 1m22s
[02:15:19.458] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:85:0)
[02:15:19.482] Build status: STARTED
[02:15:19.482] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:86:0) after 8m29s
[02:23:48.785] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:86:0)
[02:23:48.804] Build status: STARTED
[02:23:48.804] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:87:0) after 1m31s
[02:25:20.122] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:87:0)
[02:25:20.142] Build status: STARTED
[02:25:20.142] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:88:0) after 3m6s
[02:28:26.582] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:88:0)
[02:28:26.604] Build status: STARTED
[02:28:26.604] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:89:0) after 1m49s
[02:30:15.588] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:89:0)
[02:30:15.912] Build status: STARTED
[02:30:15.912] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8936834172052345440:90:0) after 8m9s
[02:33:40.393] Received PubSub notification, asking Buildbucket for the build status
[02:33:40.416] Build:
{
"id": "8771404110006716913",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win10-rel-no-external-ip"
},
"number": 2080,
"createdBy": "project:chromium",
"createTime": "2023-08-29T18:36:43.674249904Z",
"startTime": "2023-08-29T18:46:51.865954Z",
"endTime": "2023-08-30T02:33:40.078182483Z",
"updateTime": "2023-08-30T02:33:40.078182483Z",
"status": "INFRA_FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "46bee721ac3beb24c6d83f306def2c0f0f1963cb",
"ref": "refs/heads/main"
}
},
"canary": true
}
[02:33:40.416] Invocation finished in 7h56m58.077012806s with status FAILED