[14:51:54.836] New invocation is queued and will start shortly
[14:51:55.913] Starting the invocation (attempt 1)
[14:51:55.945] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/32fcb61e5713f0370a0771a9749ac6a65333fbb5"
[14:51:55.945] Popped gitiles commit info from properties and tags
[14:51:55.945] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:51:55.945] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:51:55.945] Buildbucket request:
{
"requestId": "8994196194521500016",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin(dll)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "32fcb61e5713f0370a0771a9749ac6a65333fbb5",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8994196194521500016"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTWin(dll)"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[14:51:56.466] Scheduled build:
{
"id": "8828766132588890305",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin(dll)"
},
"number": 9410,
"createdBy": "project:chromium",
"createTime": "2021-12-04T14:51:56.000687469Z",
"updateTime": "2021-12-04T14:51:56.000687469Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "32fcb61e5713f0370a0771a9749ac6a65333fbb5",
"ref": "refs/heads/main"
}
}
}
[14:51:56.466] Task URL: https://cr-buildbucket.appspot.com/build/8828766132588890305
[14:51:56.466] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:2:0) after 7m1s
[14:58:58.053] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:2:0)
[14:58:58.081] Build status: SCHEDULED
[14:58:58.081] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:3:0) after 8m38s
[15:07:36.101] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:3:0)
[15:07:36.136] Build status: SCHEDULED
[15:07:36.136] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:4:0) after 6m58s
[15:14:34.219] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:4:0)
[15:14:34.279] Build status: SCHEDULED
[15:14:34.279] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:5:0) after 2m41s
[15:17:15.296] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:5:0)
[15:17:15.328] Build status: SCHEDULED
[15:17:15.328] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:6:0) after 1m5s
[15:18:20.675] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:6:0)
[15:18:20.714] Build status: SCHEDULED
[15:18:20.714] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:7:0) after 6m21s
[15:24:42.315] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:7:0)
[15:24:42.368] Build status: SCHEDULED
[15:24:42.368] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:8:0) after 4m26s
[15:29:08.421] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:8:0)
[15:29:08.453] Build status: SCHEDULED
[15:29:08.453] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:9:0) after 2m22s
[15:31:30.472] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:9:0)
[15:31:30.502] Build status: SCHEDULED
[15:31:30.502] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:10:0) after 3m18s
[15:34:48.790] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:10:0)
[15:34:48.817] Build status: SCHEDULED
[15:34:48.817] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:11:0) after 9m42s
[15:44:30.938] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:11:0)
[15:44:30.994] Build status: SCHEDULED
[15:44:30.994] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:12:0) after 5m57s
[15:50:28.411] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:12:0)
[15:50:28.461] Build status: SCHEDULED
[15:50:28.461] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:13:0) after 3m38s
[15:54:07.533] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:13:0)
[15:54:07.564] Build status: SCHEDULED
[15:54:07.564] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:14:0) after 9m33s
[16:03:40.833] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:14:0)
[16:03:40.863] Build status: SCHEDULED
[16:03:40.863] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:15:0) after 5m30s
[16:09:11.067] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:15:0)
[16:09:11.099] Build status: SCHEDULED
[16:09:11.099] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:16:0) after 1m46s
[16:10:57.349] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:16:0)
[16:10:57.384] Build status: SCHEDULED
[16:10:57.384] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:17:0) after 3m3s
[16:14:00.717] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:17:0)
[16:14:00.758] Build status: SCHEDULED
[16:14:00.758] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:18:0) after 6m43s
[16:20:43.780] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:18:0)
[16:20:43.818] Build status: SCHEDULED
[16:20:43.818] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:19:0) after 8m50s
[16:29:34.040] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:19:0)
[16:29:34.076] Build status: SCHEDULED
[16:29:34.076] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:20:0) after 5m34s
[16:30:46.737] Received PubSub notification, asking Buildbucket for the build status
[16:30:46.763] Build status: STARTED
[16:35:08.208] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:20:0)
[16:35:08.238] Build status: STARTED
[16:35:08.238] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:22:0) after 9m21s
[16:44:29.264] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:22:0)
[16:44:29.295] Build status: STARTED
[16:44:29.295] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:23:0) after 4m34s
[16:49:03.314] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:23:0)
[16:49:03.371] Build status: STARTED
[16:49:03.371] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:24:0) after 4m54s
[16:53:58.377] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:24:0)
[16:53:58.410] Build status: STARTED
[16:53:58.410] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:25:0) after 4m6s
[16:58:04.642] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:25:0)
[16:58:04.682] Build status: STARTED
[16:58:04.682] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:26:0) after 5m1s
[17:03:05.685] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:26:0)
[17:03:05.727] Build status: STARTED
[17:03:05.727] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:27:0) after 8m36s
[17:11:41.986] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:27:0)
[17:11:42.021] Build status: STARTED
[17:11:42.021] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:28:0) after 2m35s
[17:14:17.141] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:28:0)
[17:14:17.203] Build status: STARTED
[17:14:17.203] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:29:0) after 6m18s
[17:20:35.195] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:29:0)
[17:20:37.837] Build status: STARTED
[17:20:37.837] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:30:0) after 7m38s
[17:28:16.119] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:30:0)
[17:28:16.158] Build status: STARTED
[17:28:16.158] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:31:0) after 4m40s
[17:32:56.292] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:31:0)
[17:32:56.324] Build status: STARTED
[17:32:56.324] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:32:0) after 2m27s
[17:35:23.498] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:32:0)
[17:35:23.547] Build status: STARTED
[17:35:23.547] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:33:0) after 4m26s
[17:39:49.691] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:33:0)
[17:39:49.730] Build status: STARTED
[17:39:49.730] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:34:0) after 5m6s
[17:44:56.141] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:34:0)
[17:44:56.181] Build status: STARTED
[17:44:56.181] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:35:0) after 5m59s
[17:50:55.745] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:35:0)
[17:50:55.775] Build status: STARTED
[17:50:55.775] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:36:0) after 8m21s
[17:59:17.010] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:36:0)
[17:59:17.034] Build status: STARTED
[17:59:17.034] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:37:0) after 6m46s
[18:06:03.066] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:37:0)
[18:06:03.101] Build status: STARTED
[18:06:03.101] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:38:0) after 2m53s
[18:08:56.460] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:38:0)
[18:08:56.504] Build status: STARTED
[18:08:56.504] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:39:0) after 1m9s
[18:10:05.904] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:39:0)
[18:10:05.932] Build status: STARTED
[18:10:05.932] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:40:0) after 5m54s
[18:16:00.436] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:40:0)
[18:16:00.465] Build status: STARTED
[18:16:00.465] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:41:0) after 9m23s
[18:25:23.485] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:41:0)
[18:25:23.520] Build status: STARTED
[18:25:23.520] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:42:0) after 4m59s
[18:30:22.596] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:42:0)
[18:30:22.627] Build status: STARTED
[18:30:22.627] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:43:0) after 6m40s
[18:37:02.795] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:43:0)
[18:37:02.823] Build status: STARTED
[18:37:02.823] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:44:0) after 6m26s
[18:43:28.897] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:44:0)
[18:43:28.926] Build status: STARTED
[18:43:28.926] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:45:0) after 2m27s
[18:45:56.078] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:45:0)
[18:45:56.123] Build status: STARTED
[18:45:56.123] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:46:0) after 8m55s
[18:54:51.313] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:46:0)
[18:54:51.350] Build status: STARTED
[18:54:51.350] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:47:0) after 1m45s
[18:56:36.743] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:47:0)
[18:56:36.792] Build status: STARTED
[18:56:36.792] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:48:0) after 5m59s
[19:02:35.817] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:48:0)
[19:02:35.851] Build status: STARTED
[19:02:35.851] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:49:0) after 7m27s
[19:10:03.017] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:49:0)
[19:10:03.064] Build status: STARTED
[19:10:03.064] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:50:0) after 6m3s
[19:16:06.087] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:50:0)
[19:16:06.118] Build status: STARTED
[19:16:06.118] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:51:0) after 6m57s
[19:23:03.140] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:51:0)
[19:23:03.184] Build status: STARTED
[19:23:03.184] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:52:0) after 9m1s
[19:32:04.263] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:52:0)
[19:32:04.351] Build status: STARTED
[19:32:04.351] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:53:0) after 2m53s
[19:34:57.371] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:53:0)
[19:34:57.406] Build status: STARTED
[19:34:57.406] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:54:0) after 7m57s
[19:42:54.985] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:54:0)
[19:42:55.011] Build status: STARTED
[19:42:55.011] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:55:0) after 8m38s
[19:51:33.033] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:55:0)
[19:51:33.060] Build status: STARTED
[19:51:33.060] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:56:0) after 4m41s
[19:56:14.204] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:56:0)
[19:56:14.230] Build status: STARTED
[19:56:14.230] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:57:0) after 2m42s
[19:58:56.258] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:57:0)
[19:58:56.293] Build status: STARTED
[19:58:56.293] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:58:0) after 5m22s
[20:04:18.368] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:58:0)
[20:04:18.400] Build status: STARTED
[20:04:18.400] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:59:0) after 5m37s
[20:09:55.443] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:59:0)
[20:09:55.474] Build status: STARTED
[20:09:55.474] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:60:0) after 3m48s
[20:13:43.493] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:60:0)
[20:13:43.522] Build status: STARTED
[20:13:43.522] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:61:0) after 7m45s
[20:21:28.543] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:61:0)
[20:21:28.573] Build status: STARTED
[20:21:28.573] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:62:0) after 1m59s
[20:23:27.605] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:62:0)
[20:23:27.632] Build status: STARTED
[20:23:27.632] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:63:0) after 6m18s
[20:29:45.649] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:63:0)
[20:29:45.679] Build status: STARTED
[20:29:45.679] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:64:0) after 9m41s
[20:39:26.703] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:64:0)
[20:39:26.731] Build status: STARTED
[20:39:26.731] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:65:0) after 2m24s
[20:41:50.837] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:65:0)
[20:41:50.865] Build status: STARTED
[20:41:50.865] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:66:0) after 9m18s
[20:51:08.886] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:66:0)
[20:51:08.919] Build status: STARTED
[20:51:08.919] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:67:0) after 4m9s
[20:55:18.076] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:67:0)
[20:55:18.107] Build status: STARTED
[20:55:18.107] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:68:0) after 7m33s
[21:02:51.630] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:68:0)
[21:02:51.661] Build status: STARTED
[21:02:51.661] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:69:0) after 1m54s
[21:04:45.898] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:69:0)
[21:04:45.926] Build status: STARTED
[21:04:45.926] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:70:0) after 6m54s
[21:11:40.218] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:70:0)
[21:11:40.246] Build status: STARTED
[21:11:40.246] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:71:0) after 7m45s
[21:19:25.883] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:71:0)
[21:19:25.914] Build status: STARTED
[21:19:25.914] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:72:0) after 3m45s
[21:23:11.020] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:72:0)
[21:23:11.051] Build status: STARTED
[21:23:11.051] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:73:0) after 5m43s
[21:28:54.202] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:73:0)
[21:28:54.280] Build status: STARTED
[21:28:54.280] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:74:0) after 1m31s
[21:30:25.504] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:74:0)
[21:30:25.533] Build status: STARTED
[21:30:25.533] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:75:0) after 5m59s
[21:36:24.678] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:75:0)
[21:36:24.705] Build status: STARTED
[21:36:24.705] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:76:0) after 7m53s
[21:44:17.724] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:76:0)
[21:44:17.751] Build status: STARTED
[21:44:17.751] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:77:0) after 2m22s
[21:46:39.788] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:77:0)
[21:46:39.815] Build status: STARTED
[21:46:39.815] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:78:0) after 5m7s
[21:51:47.592] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:78:0)
[21:51:47.620] Build status: STARTED
[21:51:47.620] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:79:0) after 6m59s
[21:58:46.642] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:79:0)
[21:58:46.669] Build status: STARTED
[21:58:46.669] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:80:0) after 8m5s
[22:06:52.002] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:80:0)
[22:06:52.030] Build status: STARTED
[22:06:52.030] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:81:0) after 7m26s
[22:14:18.056] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:81:0)
[22:14:18.091] Build status: STARTED
[22:14:18.091] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:82:0) after 2m42s
[22:17:00.327] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:82:0)
[22:17:00.352] Build status: STARTED
[22:17:00.352] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:83:0) after 8m42s
[22:25:42.563] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:83:0)
[22:25:42.593] Build status: STARTED
[22:25:42.593] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:84:0) after 4m9s
[22:29:51.614] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:84:0)
[22:29:51.663] Build status: STARTED
[22:29:51.663] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:85:0) after 6m31s
[22:36:23.095] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:85:0)
[22:36:23.127] Build status: STARTED
[22:36:23.127] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:86:0) after 9m7s
[22:45:30.353] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:86:0)
[22:45:30.392] Build status: STARTED
[22:45:30.392] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:87:0) after 1m1s
[22:46:32.125] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:87:0)
[22:46:32.158] Build status: STARTED
[22:46:32.158] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:88:0) after 5m4s
[22:51:36.570] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:88:0)
[22:51:36.599] Build status: STARTED
[22:51:36.599] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:89:0) after 1m22s
[22:52:58.760] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:89:0)
[22:52:58.802] Build status: STARTED
[22:52:58.802] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:90:0) after 3m45s
[22:56:43.823] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:90:0)
[22:56:43.851] Build status: STARTED
[22:56:43.851] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:91:0) after 8m58s
[23:05:41.873] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:91:0)
[23:05:41.901] Build status: STARTED
[23:05:41.901] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:92:0) after 1m35s
[23:07:17.467] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:92:0)
[23:07:17.507] Build status: STARTED
[23:07:17.507] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:93:0) after 7m25s
[23:14:42.781] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:93:0)
[23:14:42.805] Build status: STARTED
[23:14:42.805] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:94:0) after 8m4s
[23:22:46.830] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:94:0)
[23:22:46.884] Build status: STARTED
[23:22:46.884] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:95:0) after 7m26s
[23:30:13.899] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:95:0)
[23:30:13.927] Build status: STARTED
[23:30:13.927] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:96:0) after 9m45s
[23:39:59.139] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:96:0)
[23:39:59.165] Build status: STARTED
[23:39:59.165] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:97:0) after 4m47s
[23:44:46.548] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:97:0)
[23:44:46.577] Build status: STARTED
[23:44:46.577] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:98:0) after 1m33s
[23:46:19.857] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:98:0)
[23:46:19.886] Build status: STARTED
[23:46:19.886] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:99:0) after 9m17s
[23:55:36.911] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:99:0)
[23:55:37.005] Build status: STARTED
[23:55:37.005] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:100:0) after 5m10s
[00:00:47.534] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:100:0)
[00:00:47.570] Build status: STARTED
[00:00:47.570] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:101:0) after 8m47s
[00:09:34.604] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:101:0)
[00:09:34.635] Build status: STARTED
[00:09:34.635] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:102:0) after 8m9s
[00:17:44.576] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:102:0)
[00:17:44.604] Build status: STARTED
[00:17:44.604] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:103:0) after 1m20s
[00:19:04.650] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:103:0)
[00:19:04.681] Build status: STARTED
[00:19:04.681] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:104:0) after 1m22s
[00:20:26.821] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:104:0)
[00:20:26.856] Build status: STARTED
[00:20:26.856] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:105:0) after 9m25s
[00:29:51.881] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:105:0)
[00:29:51.907] Build status: STARTED
[00:29:51.907] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:106:0) after 5m48s
[00:35:40.068] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:106:0)
[00:35:40.098] Build status: STARTED
[00:35:40.098] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:107:0) after 2m36s
[00:38:18.936] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:107:0)
[00:38:19.034] Build status: STARTED
[00:38:19.034] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:108:0) after 4m51s
[00:43:10.059] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:108:0)
[00:43:10.087] Build status: STARTED
[00:43:10.087] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:109:0) after 4m55s
[00:48:05.366] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:109:0)
[00:48:05.398] Build status: STARTED
[00:48:05.398] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:110:0) after 9m17s
[00:57:22.418] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:110:0)
[00:57:22.445] Build status: STARTED
[00:57:22.445] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:111:0) after 7m7s
[01:04:29.432] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:111:0)
[01:04:29.463] Build status: STARTED
[01:04:29.463] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:112:0) after 7m45s
[01:12:14.495] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:112:0)
[01:12:14.544] Build status: STARTED
[01:12:14.544] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:113:0) after 1m42s
[01:13:56.566] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:113:0)
[01:13:56.594] Build status: STARTED
[01:13:56.594] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):8994196194521500016:114:0) after 8m45s
[01:20:58.397] Received PubSub notification, asking Buildbucket for the build status
[01:20:58.557] Build:
{
"id": "8828766132588890305",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin(dll)"
},
"number": 9410,
"createdBy": "project:chromium",
"createTime": "2021-12-04T14:51:56.000687469Z",
"startTime": "2021-12-04T16:30:46.044044Z",
"endTime": "2021-12-05T01:20:57.971682010Z",
"updateTime": "2021-12-05T01:20:57.971682010Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "32fcb61e5713f0370a0771a9749ac6a65333fbb5",
"ref": "refs/heads/main"
}
}
}
[01:20:58.557] Invocation finished in 10h29m3.782999283s with status FAILED