[12:10:10.549] New invocation is queued and will start shortly
[12:10:11.732] Starting the invocation (attempt 1)
[12:10:11.768] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a9a7d99a209a784b1ceced90743faa153f48d7d8"
[12:10:11.768] Popped gitiles commit info from properties and tags
[12:10:11.768] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:10:11.769] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:10:11.769] Buildbucket request:
{
"requestId": "8930335508836276768",
"builder": {
"project": "chromium",
"bucket": "reclient",
"builder": "Windows Cross deterministic"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a9a7d99a209a784b1ceced90743faa153f48d7d8",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8930335508836276768"
},
{
"key": "scheduler_job_id",
"value": "chromium/Windows Cross deterministic"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[12:10:12.490] Scheduled build:
{
"id": "8764905446698618785",
"builder": {
"project": "chromium",
"bucket": "reclient",
"builder": "Windows Cross deterministic"
},
"number": 1784,
"createdBy": "project:chromium",
"createTime": "2023-11-09T12:10:11.962924179Z",
"updateTime": "2023-11-09T12:10:11.962924179Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a9a7d99a209a784b1ceced90743faa153f48d7d8",
"ref": "refs/heads/main"
}
},
"canary": true
}
[12:10:12.490] Task URL: https://cr-buildbucket.appspot.com/build/8764905446698618785
[12:10:12.490] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:2:0) after 1m27s
[12:10:13.525] Received PubSub notification, asking Buildbucket for the build status
[12:10:13.553] Build status: SCHEDULED
[12:10:14.890] Received PubSub notification, asking Buildbucket for the build status
[12:10:14.910] Build status: STARTED
[12:11:39.759] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:2:0)
[12:11:39.778] Build status: STARTED
[12:11:39.778] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:5:0) after 4m17s
[12:15:56.808] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:5:0)
[12:15:56.829] Build status: STARTED
[12:15:56.829] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:6:0) after 4m58s
[12:20:54.934] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:6:0)
[12:20:54.960] Build status: STARTED
[12:20:54.960] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:7:0) after 6m12s
[12:27:07.150] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:7:0)
[12:27:07.169] Build status: STARTED
[12:27:07.169] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:8:0) after 1m37s
[12:28:44.293] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:8:0)
[12:28:44.320] Build status: STARTED
[12:28:44.320] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:9:0) after 9m58s
[12:38:42.342] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:9:0)
[12:38:42.383] Build status: STARTED
[12:38:42.383] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:10:0) after 2m40s
[12:41:22.483] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:10:0)
[12:41:22.534] Build status: STARTED
[12:41:22.534] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:11:0) after 4m10s
[12:45:33.105] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:11:0)
[12:45:33.134] Build status: STARTED
[12:45:33.134] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:12:0) after 1m2s
[12:46:35.165] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:12:0)
[12:46:35.201] Build status: STARTED
[12:46:35.201] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:13:0) after 1m32s
[12:48:07.236] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:13:0)
[12:48:07.257] Build status: STARTED
[12:48:07.257] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:14:0) after 6m18s
[12:54:25.455] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:14:0)
[12:54:25.534] Build status: STARTED
[12:54:25.534] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:15:0) after 2m16s
[12:56:41.690] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:15:0)
[12:56:41.726] Build status: STARTED
[12:56:41.726] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:16:0) after 8m29s
[13:05:11.115] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:16:0)
[13:05:11.157] Build status: STARTED
[13:05:11.157] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:17:0) after 9m28s
[13:14:39.186] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:17:0)
[13:14:39.217] Build status: STARTED
[13:14:39.217] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:18:0) after 4m53s
[13:19:32.234] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:18:0)
[13:19:32.280] Build status: STARTED
[13:19:32.280] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:19:0) after 4m24s
[13:23:56.339] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:19:0)
[13:23:56.358] Build status: STARTED
[13:23:56.359] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:20:0) after 9m8s
[13:33:04.438] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:20:0)
[13:33:04.582] Build status: STARTED
[13:33:04.582] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:21:0) after 9m54s
[13:42:58.717] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:21:0)
[13:42:58.736] Build status: STARTED
[13:42:58.736] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:22:0) after 7m27s
[13:50:25.860] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:22:0)
[13:50:25.880] Build status: STARTED
[13:50:25.881] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:23:0) after 6m29s
[13:56:54.916] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:23:0)
[13:56:54.944] Build status: STARTED
[13:56:54.944] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:24:0) after 2m26s
[13:59:21.129] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:24:0)
[13:59:21.161] Build status: STARTED
[13:59:21.161] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:25:0) after 8m8s
[14:07:29.261] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:25:0)
[14:07:29.281] Build status: STARTED
[14:07:29.281] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:26:0) after 4m45s
[14:12:14.307] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:26:0)
[14:12:14.407] Build status: STARTED
[14:12:14.407] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:27:0) after 8m36s
[14:20:50.432] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:27:0)
[14:20:50.456] Build status: STARTED
[14:20:50.456] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:28:0) after 8m59s
[14:29:49.590] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:28:0)
[14:29:49.629] Build status: STARTED
[14:29:49.629] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:29:0) after 9m45s
[14:39:34.722] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:29:0)
[14:39:34.748] Build status: STARTED
[14:39:34.748] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:30:0) after 8m39s
[14:48:13.893] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:30:0)
[14:48:13.911] Build status: STARTED
[14:48:13.911] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:31:0) after 9m4s
[14:57:17.940] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:31:0)
[14:57:17.966] Build status: STARTED
[14:57:17.966] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:32:0) after 9m58s
[15:07:16.138] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:32:0)
[15:07:16.190] Build status: STARTED
[15:07:16.190] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:33:0) after 1m45s
[15:09:01.335] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:33:0)
[15:09:01.359] Build status: STARTED
[15:09:01.359] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:34:0) after 1m43s
[15:10:44.878] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:34:0)
[15:10:44.982] Build status: STARTED
[15:10:44.982] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:35:0) after 5m14s
[15:15:59.317] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:35:0)
[15:15:59.338] Build status: STARTED
[15:15:59.338] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:36:0) after 6m2s
[15:22:01.387] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:36:0)
[15:22:01.411] Build status: STARTED
[15:22:01.411] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:37:0) after 4m32s
[15:26:33.568] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:37:0)
[15:26:33.608] Build status: STARTED
[15:26:33.608] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:38:0) after 8m30s
[15:35:04.372] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:38:0)
[15:35:04.458] Build status: STARTED
[15:35:04.458] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:39:0) after 4m38s
[15:39:42.686] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:39:0)
[15:39:42.718] Build status: STARTED
[15:39:42.718] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:40:0) after 4m49s
[15:44:32.029] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:40:0)
[15:44:32.085] Build status: STARTED
[15:44:32.085] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:41:0) after 9m11s
[15:53:43.152] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:41:0)
[15:53:43.187] Build status: STARTED
[15:53:43.187] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:42:0) after 8m46s
[16:02:29.310] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:42:0)
[16:02:29.341] Build status: STARTED
[16:02:29.341] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:43:0) after 7m29s
[16:09:58.449] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:43:0)
[16:09:58.476] Build status: STARTED
[16:09:58.476] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:44:0) after 7m45s
[16:17:43.603] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:44:0)
[16:17:43.624] Build status: STARTED
[16:17:43.624] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:45:0) after 4m37s
[16:22:21.313] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:45:0)
[16:22:21.359] Build status: STARTED
[16:22:21.359] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:46:0) after 1m11s
[16:23:32.476] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:46:0)
[16:23:32.518] Build status: STARTED
[16:23:32.518] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:47:0) after 6m47s
[16:30:19.552] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:47:0)
[16:30:19.580] Build status: STARTED
[16:30:19.580] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:48:0) after 1m59s
[16:32:18.623] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:48:0)
[16:32:18.664] Build status: STARTED
[16:32:18.664] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:49:0) after 1m7s
[16:33:25.693] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:49:0)
[16:33:25.723] Build status: STARTED
[16:33:25.723] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:50:0) after 9m21s
[16:42:46.739] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:50:0)
[16:42:46.804] Build status: STARTED
[16:42:46.804] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:51:0) after 4m52s
[16:47:38.897] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:51:0)
[16:47:38.925] Build status: STARTED
[16:47:38.925] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:52:0) after 5m37s
[16:53:15.951] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:52:0)
[16:53:15.976] Build status: STARTED
[16:53:15.976] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:53:0) after 7m41s
[17:00:57.294] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:53:0)
[17:00:57.342] Build status: STARTED
[17:00:57.342] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:54:0) after 3m2s
[17:03:59.720] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:54:0)
[17:03:59.779] Build status: STARTED
[17:03:59.779] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:55:0) after 6m14s
[17:10:13.803] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:55:0)
[17:10:13.830] Build status: STARTED
[17:10:13.830] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:56:0) after 2m0s
[17:12:14.034] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:56:0)
[17:12:14.056] Build status: STARTED
[17:12:14.056] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:57:0) after 9m29s
[17:21:43.165] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:57:0)
[17:21:43.189] Build status: STARTED
[17:21:43.189] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:58:0) after 6m45s
[17:28:28.744] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:58:0)
[17:28:28.768] Build status: STARTED
[17:28:28.768] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:59:0) after 3m39s
[17:32:07.819] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:59:0)
[17:32:07.847] Build status: STARTED
[17:32:07.847] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:60:0) after 8m7s
[17:40:14.895] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:60:0)
[17:40:14.945] Build status: STARTED
[17:40:14.945] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:61:0) after 5m54s
[17:46:09.125] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:61:0)
[17:46:09.148] Build status: STARTED
[17:46:09.148] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:62:0) after 4m28s
[17:50:37.173] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:62:0)
[17:50:37.193] Build status: STARTED
[17:50:37.193] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:63:0) after 6m26s
[17:57:03.248] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:63:0)
[17:57:03.270] Build status: STARTED
[17:57:03.270] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:64:0) after 6m43s
[18:03:46.360] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:64:0)
[18:03:46.385] Build status: STARTED
[18:03:46.385] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:65:0) after 1m49s
[18:05:35.410] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:65:0)
[18:05:35.427] Build status: STARTED
[18:05:35.427] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:66:0) after 8m26s
[18:14:01.565] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:66:0)
[18:14:01.625] Build status: STARTED
[18:14:01.625] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:67:0) after 1m41s
[18:15:42.615] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:67:0)
[18:15:42.649] Build status: STARTED
[18:15:42.649] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:68:0) after 6m54s
[18:22:36.683] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:68:0)
[18:22:36.757] Build status: STARTED
[18:22:36.757] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:69:0) after 2m7s
[18:24:43.780] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:69:0)
[18:24:43.800] Build status: STARTED
[18:24:43.800] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:70:0) after 6m26s
[18:31:09.831] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:70:0)
[18:31:09.978] Build status: STARTED
[18:31:09.978] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:71:0) after 1m59s
[18:33:09.104] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:71:0)
[18:33:09.123] Build status: STARTED
[18:33:09.123] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:72:0) after 2m31s
[18:35:40.125] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:72:0)
[18:35:40.148] Build status: STARTED
[18:35:40.148] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:73:0) after 9m25s
[18:45:05.167] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:73:0)
[18:45:05.239] Build status: STARTED
[18:45:05.239] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:74:0) after 1m27s
[18:46:32.237] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:74:0)
[18:46:32.258] Build status: STARTED
[18:46:32.258] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:75:0) after 1m5s
[18:47:37.281] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:75:0)
[18:47:37.314] Build status: STARTED
[18:47:37.314] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:76:0) after 1m47s
[18:49:24.391] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:76:0)
[18:49:24.411] Build status: STARTED
[18:49:24.411] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:77:0) after 8m11s
[18:57:35.629] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:77:0)
[18:57:35.657] Build status: STARTED
[18:57:35.657] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:78:0) after 1m44s
[18:59:19.797] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:78:0)
[18:59:19.823] Build status: STARTED
[18:59:19.823] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:79:0) after 3m3s
[19:02:22.848] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:79:0)
[19:02:22.880] Build status: STARTED
[19:02:22.880] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:80:0) after 3m32s
[19:05:54.943] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:80:0)
[19:05:54.995] Build status: STARTED
[19:05:54.995] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:81:0) after 6m25s
[19:12:20.047] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:81:0)
[19:12:20.081] Build status: STARTED
[19:12:20.081] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:82:0) after 9m59s
[19:22:19.109] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:82:0)
[19:22:19.135] Build status: STARTED
[19:22:19.135] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:83:0) after 4m59s
[19:27:18.184] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:83:0)
[19:27:18.228] Build status: STARTED
[19:27:18.228] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:84:0) after 6m51s
[19:34:09.291] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:84:0)
[19:34:09.314] Build status: STARTED
[19:34:09.314] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:85:0) after 1m16s
[19:35:25.423] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:85:0)
[19:35:25.455] Build status: STARTED
[19:35:25.455] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:86:0) after 9m45s
[19:45:10.477] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:86:0)
[19:45:10.550] Build status: STARTED
[19:45:10.550] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:87:0) after 3m2s
[19:48:12.632] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:87:0)
[19:48:12.652] Build status: STARTED
[19:48:12.653] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:88:0) after 4m44s
[19:52:56.728] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:88:0)
[19:52:56.758] Build status: STARTED
[19:52:56.758] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:89:0) after 2m11s
[19:55:07.790] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:89:0)
[19:55:07.820] Build status: STARTED
[19:55:07.820] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:90:0) after 2m41s
[19:57:48.895] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:90:0)
[19:57:48.915] Build status: STARTED
[19:57:48.915] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:91:0) after 4m47s
[20:02:35.986] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:91:0)
[20:02:36.008] Build status: STARTED
[20:02:36.008] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:92:0) after 3m28s
[20:06:04.041] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:92:0)
[20:06:04.069] Build status: STARTED
[20:06:04.069] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:93:0) after 6m7s
[20:12:11.136] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:93:0)
[20:12:11.203] Build status: STARTED
[20:12:11.203] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:94:0) after 3m2s
[20:15:13.294] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:94:0)
[20:15:13.335] Build status: STARTED
[20:15:13.335] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:95:0) after 8m24s
[20:23:37.456] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:95:0)
[20:23:37.492] Build status: STARTED
[20:23:37.492] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:96:0) after 6m35s
[20:30:12.521] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:96:0)
[20:30:12.634] Build status: STARTED
[20:30:12.634] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:97:0) after 1m18s
[20:31:30.719] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:97:0)
[20:31:30.740] Build status: STARTED
[20:31:30.740] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:98:0) after 9m31s
[20:41:01.947] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:98:0)
[20:41:02.116] Build status: STARTED
[20:41:02.116] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:99:0) after 7m44s
[20:48:46.139] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:99:0)
[20:48:46.164] Build status: STARTED
[20:48:46.164] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:100:0) after 8m39s
[20:57:25.190] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:100:0)
[20:57:25.225] Build status: STARTED
[20:57:25.225] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:101:0) after 4m36s
[21:02:01.405] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:101:0)
[21:02:01.437] Build status: STARTED
[21:02:01.437] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:102:0) after 6m42s
[21:08:43.487] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:102:0)
[21:08:43.551] Build status: STARTED
[21:08:43.551] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:103:0) after 7m28s
[21:16:11.600] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:103:0)
[21:16:11.628] Build status: STARTED
[21:16:11.628] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:104:0) after 6m11s
[21:22:22.658] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:104:0)
[21:22:22.689] Build status: STARTED
[21:22:22.690] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:105:0) after 3m43s
[21:26:05.785] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:105:0)
[21:26:05.930] Build status: STARTED
[21:26:05.930] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:106:0) after 5m5s
[21:31:11.117] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:106:0)
[21:31:11.153] Build status: STARTED
[21:31:11.153] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:107:0) after 9m53s
[21:41:04.179] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:107:0)
[21:41:04.206] Build status: STARTED
[21:41:04.206] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:108:0) after 9m35s
[21:50:39.624] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:108:0)
[21:50:39.696] Build status: STARTED
[21:50:39.696] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:109:0) after 8m55s
[21:59:34.997] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:109:0)
[21:59:35.025] Build status: STARTED
[21:59:35.025] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:110:0) after 2m30s
[22:02:05.477] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:110:0)
[22:02:05.506] Build status: STARTED
[22:02:05.506] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:111:0) after 5m22s
[22:07:27.655] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:111:0)
[22:07:27.684] Build status: STARTED
[22:07:27.685] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:112:0) after 4m0s
[22:11:27.738] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:112:0)
[22:11:27.791] Build status: STARTED
[22:11:27.791] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:113:0) after 7m49s
[22:19:17.176] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:113:0)
[22:19:17.200] Build status: STARTED
[22:19:17.200] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:114:0) after 6m44s
[22:26:01.297] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:114:0)
[22:26:01.338] Build status: STARTED
[22:26:01.338] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:115:0) after 7m46s
[22:33:47.483] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:115:0)
[22:33:47.508] Build status: STARTED
[22:33:47.508] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:116:0) after 1m2s
[22:34:49.665] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:116:0)
[22:34:49.692] Build status: STARTED
[22:34:49.692] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:117:0) after 1m29s
[22:36:18.723] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:117:0)
[22:36:18.748] Build status: STARTED
[22:36:18.749] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:118:0) after 6m22s
[22:42:40.794] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:118:0)
[22:42:40.825] Build status: STARTED
[22:42:40.825] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:119:0) after 1m16s
[22:43:56.872] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:119:0)
[22:43:57.015] Build status: STARTED
[22:43:57.015] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:120:0) after 7m40s
[22:51:37.040] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:120:0)
[22:51:37.079] Build status: STARTED
[22:51:37.079] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:121:0) after 7m29s
[22:59:06.503] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:121:0)
[22:59:06.554] Build status: STARTED
[22:59:06.554] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:122:0) after 8m46s
[23:07:52.619] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:122:0)
[23:07:52.689] Build status: STARTED
[23:07:52.689] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:123:0) after 1m3s
[23:08:55.767] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:123:0)
[23:08:55.791] Build status: STARTED
[23:08:55.791] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:124:0) after 5m29s
[23:14:24.812] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:124:0)
[23:14:24.838] Build status: STARTED
[23:14:24.838] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:125:0) after 4m20s
[23:18:45.121] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:125:0)
[23:18:45.154] Build status: STARTED
[23:18:45.154] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:126:0) after 1m50s
[23:20:35.324] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:126:0)
[23:20:35.380] Build status: STARTED
[23:20:35.380] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:127:0) after 2m59s
[23:23:34.526] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:127:0)
[23:23:34.566] Build status: STARTED
[23:23:34.566] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:128:0) after 9m26s
[23:33:01.419] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:128:0)
[23:33:01.462] Build status: STARTED
[23:33:01.462] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:129:0) after 5m7s
[23:38:08.533] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:129:0)
[23:38:08.558] Build status: STARTED
[23:38:08.558] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:130:0) after 7m47s
[23:45:55.866] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:130:0)
[23:45:55.885] Build status: STARTED
[23:45:55.885] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:131:0) after 7m32s
[23:53:27.920] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:131:0)
[23:53:27.948] Build status: STARTED
[23:53:27.948] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:132:0) after 8m48s
[00:02:16.238] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:132:0)
[00:02:16.263] Build status: STARTED
[00:02:16.263] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:133:0) after 3m3s
[00:05:19.539] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:133:0)
[00:05:19.627] Build status: STARTED
[00:05:19.627] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:134:0) after 2m23s
[00:07:42.732] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:134:0)
[00:07:42.799] Build status: STARTED
[00:07:42.800] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:135:0) after 5m35s
[00:13:17.865] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:135:0)
[00:13:17.896] Build status: STARTED
[00:13:17.896] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:136:0) after 5m11s
[00:18:30.480] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:136:0)
[00:18:30.522] Build status: STARTED
[00:18:30.522] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:137:0) after 5m42s
[00:24:12.845] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:137:0)
[00:24:12.870] Build status: STARTED
[00:24:12.870] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:138:0) after 9m55s
[00:34:08.275] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:138:0)
[00:34:08.295] Build status: STARTED
[00:34:08.295] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:139:0) after 7m49s
[00:41:57.601] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:139:0)
[00:41:57.621] Build status: STARTED
[00:41:57.621] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8930335508836276768:140:0) after 9m59s
[00:42:59.134] Received PubSub notification, asking Buildbucket for the build status
[00:42:59.170] Build:
{
"id": "8764905446698618785",
"builder": {
"project": "chromium",
"bucket": "reclient",
"builder": "Windows Cross deterministic"
},
"number": 1784,
"createdBy": "project:chromium",
"createTime": "2023-11-09T12:10:11.962924179Z",
"startTime": "2023-11-09T12:10:14.004055Z",
"endTime": "2023-11-10T00:42:58.458893Z",
"updateTime": "2023-11-10T00:42:58.458893Z",
"status": "INFRA_FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a9a7d99a209a784b1ceced90743faa153f48d7d8",
"ref": "refs/heads/main"
}
},
"canary": true
}
[00:42:59.170] Invocation finished in 12h32m48.629500358s with status FAILED