[11:18:04.169] New invocation is queued and will start shortly
[11:18:05.222] Starting the invocation (attempt 1)
[11:18:05.248] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b369e8328db9e625fe7825ee384c289746c951e8"
[11:18:05.248] Popped gitiles commit info from properties and tags
[11:18:05.248] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:18:05.249] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:18:05.249] Buildbucket request:
{
"requestId": "8924993566067694816",
"builder": {
"project": "chromium",
"bucket": "reclient",
"builder": "Windows Cross deterministic"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b369e8328db9e625fe7825ee384c289746c951e8",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8924993566067694816"
},
{
"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"
}
}
[11:18:05.939] Scheduled build:
{
"id": "8759563504215107089",
"builder": {
"project": "chromium",
"bucket": "reclient",
"builder": "Windows Cross deterministic"
},
"number": 1901,
"createdBy": "project:chromium",
"createTime": "2024-01-07T11:18:05.308918932Z",
"updateTime": "2024-01-07T11:18:05.308918932Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b369e8328db9e625fe7825ee384c289746c951e8",
"ref": "refs/heads/main"
}
},
"canary": true
}
[11:18:05.939] Task URL: https://cr-buildbucket.appspot.com/build/8759563504215107089
[11:18:05.939] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:2:0) after 6m17s
[11:18:06.739] Received PubSub notification, asking Buildbucket for the build status
[11:18:06.755] Build status: SCHEDULED
[11:18:07.186] Received PubSub notification, asking Buildbucket for the build status
[11:18:07.205] Build status: STARTED
[11:24:22.966] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:2:0)
[11:24:22.994] Build status: STARTED
[11:24:22.994] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:5:0) after 1m35s
[11:25:57.975] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:5:0)
[11:25:58.060] Build status: STARTED
[11:25:58.060] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:6:0) after 3m53s
[11:29:51.091] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:6:0)
[11:29:51.124] Build status: STARTED
[11:29:51.124] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:7:0) after 7m40s
[11:37:31.140] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:7:0)
[11:37:31.162] Build status: STARTED
[11:37:31.162] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:8:0) after 1m57s
[11:39:28.217] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:8:0)
[11:39:28.232] Build status: STARTED
[11:39:28.232] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:9:0) after 7m18s
[11:46:46.261] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:9:0)
[11:46:46.374] Build status: STARTED
[11:46:46.374] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:10:0) after 3m18s
[11:50:04.397] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:10:0)
[11:50:04.435] Build status: STARTED
[11:50:04.435] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:11:0) after 8m22s
[11:58:26.453] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:11:0)
[11:58:26.479] Build status: STARTED
[11:58:26.479] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:12:0) after 8m7s
[12:06:33.498] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:12:0)
[12:06:33.529] Build status: STARTED
[12:06:33.529] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:13:0) after 3m20s
[12:09:53.633] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:13:0)
[12:09:53.672] Build status: STARTED
[12:09:53.672] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:14:0) after 5m1s
[12:14:54.688] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:14:0)
[12:14:54.705] Build status: STARTED
[12:14:54.705] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:15:0) after 4m11s
[12:19:05.945] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:15:0)
[12:19:05.998] Build status: STARTED
[12:19:05.998] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:16:0) after 7m36s
[12:26:42.331] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:16:0)
[12:26:42.405] Build status: STARTED
[12:26:42.405] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:17:0) after 3m10s
[12:29:52.441] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:17:0)
[12:29:52.457] Build status: STARTED
[12:29:52.457] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:18:0) after 3m26s
[12:33:18.566] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:18:0)
[12:33:18.634] Build status: STARTED
[12:33:18.634] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:19:0) after 6m7s
[12:39:25.664] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:19:0)
[12:39:25.734] Build status: STARTED
[12:39:25.734] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:20:0) after 2m39s
[12:42:04.751] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:20:0)
[12:42:04.772] Build status: STARTED
[12:42:04.772] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:21:0) after 5m40s
[12:47:45.207] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:21:0)
[12:47:45.292] Build status: STARTED
[12:47:45.292] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:22:0) after 6m27s
[12:54:12.560] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:22:0)
[12:54:12.738] Build status: STARTED
[12:54:12.738] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:23:0) after 4m50s
[12:59:02.836] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:23:0)
[12:59:02.881] Build status: STARTED
[12:59:02.881] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:24:0) after 4m35s
[13:03:38.230] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:24:0)
[13:03:38.246] Build status: STARTED
[13:03:38.246] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:25:0) after 3m45s
[13:07:23.279] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:25:0)
[13:07:23.474] Build status: STARTED
[13:07:23.474] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:26:0) after 3m17s
[13:10:40.490] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:26:0)
[13:10:40.535] Build status: STARTED
[13:10:40.536] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:27:0) after 1m9s
[13:11:49.813] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:27:0)
[13:11:49.928] Build status: STARTED
[13:11:49.928] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:28:0) after 1m40s
[13:13:29.944] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:28:0)
[13:13:29.961] Build status: STARTED
[13:13:29.961] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:29:0) after 1m11s
[13:14:40.989] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:29:0)
[13:14:41.007] Build status: STARTED
[13:14:41.007] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:30:0) after 6m33s
[13:21:14.022] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:30:0)
[13:21:14.042] Build status: STARTED
[13:21:14.042] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:31:0) after 8m43s
[13:29:57.109] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:31:0)
[13:29:57.131] Build status: STARTED
[13:29:57.131] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:32:0) after 2m32s
[13:32:29.155] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:32:0)
[13:32:29.170] Build status: STARTED
[13:32:29.171] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:33:0) after 2m40s
[13:35:09.217] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:33:0)
[13:35:09.237] Build status: STARTED
[13:35:09.237] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:34:0) after 2m21s
[13:37:30.342] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:34:0)
[13:37:30.364] Build status: STARTED
[13:37:30.364] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:35:0) after 6m2s
[13:43:32.389] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:35:0)
[13:43:32.414] Build status: STARTED
[13:43:32.414] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:36:0) after 5m59s
[13:49:31.413] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:36:0)
[13:49:31.443] Build status: STARTED
[13:49:31.443] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:37:0) after 3m58s
[13:53:29.507] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:37:0)
[13:53:29.576] Build status: STARTED
[13:53:29.577] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:38:0) after 1m44s
[13:55:13.549] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:38:0)
[13:55:13.577] Build status: STARTED
[13:55:13.577] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:39:0) after 6m15s
[14:01:28.597] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:39:0)
[14:01:28.627] Build status: STARTED
[14:01:28.627] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:40:0) after 3m36s
[14:05:04.760] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:40:0)
[14:05:04.783] Build status: STARTED
[14:05:04.783] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:41:0) after 3m17s
[14:08:21.893] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:41:0)
[14:08:21.927] Build status: STARTED
[14:08:21.927] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:42:0) after 8m1s
[14:16:22.950] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:42:0)
[14:16:22.969] Build status: STARTED
[14:16:22.969] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:43:0) after 3m42s
[14:20:05.006] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:43:0)
[14:20:05.031] Build status: STARTED
[14:20:05.031] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:44:0) after 4m52s
[14:24:57.050] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:44:0)
[14:24:57.083] Build status: STARTED
[14:24:57.083] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:45:0) after 4m31s
[14:29:28.179] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:45:0)
[14:29:28.200] Build status: STARTED
[14:29:28.200] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:46:0) after 3m51s
[14:33:19.225] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:46:0)
[14:33:19.248] Build status: STARTED
[14:33:19.248] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:47:0) after 8m53s
[14:42:12.277] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:47:0)
[14:42:12.300] Build status: STARTED
[14:42:12.300] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:48:0) after 8m27s
[14:50:39.320] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:48:0)
[14:50:39.355] Build status: STARTED
[14:50:39.355] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:49:0) after 9m57s
[15:00:36.389] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:49:0)
[15:00:36.410] Build status: STARTED
[15:00:36.411] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:50:0) after 6m41s
[15:07:17.442] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:50:0)
[15:07:17.488] Build status: STARTED
[15:07:17.489] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:51:0) after 6m44s
[15:14:01.514] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:51:0)
[15:14:01.539] Build status: STARTED
[15:14:01.539] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:52:0) after 9m7s
[15:23:08.720] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:52:0)
[15:23:08.740] Build status: STARTED
[15:23:08.740] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:53:0) after 9m5s
[15:32:13.747] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:53:0)
[15:32:13.767] Build status: STARTED
[15:32:13.767] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:54:0) after 3m30s
[15:35:43.788] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:54:0)
[15:35:43.811] Build status: STARTED
[15:35:43.811] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:55:0) after 4m44s
[15:40:27.964] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:55:0)
[15:40:27.986] Build status: STARTED
[15:40:27.986] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:56:0) after 5m46s
[15:46:14.024] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:56:0)
[15:46:14.088] Build status: STARTED
[15:46:14.088] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:57:0) after 5m1s
[15:51:15.227] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:57:0)
[15:51:15.250] Build status: STARTED
[15:51:15.250] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:58:0) after 4m42s
[15:55:57.275] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:58:0)
[15:55:57.344] Build status: STARTED
[15:55:57.344] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:59:0) after 4m18s
[16:00:15.376] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:59:0)
[16:00:15.402] Build status: STARTED
[16:00:15.402] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:60:0) after 2m44s
[16:02:59.428] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:60:0)
[16:02:59.504] Build status: STARTED
[16:02:59.504] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:61:0) after 3m38s
[16:06:37.539] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:61:0)
[16:06:37.575] Build status: STARTED
[16:06:37.575] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:62:0) after 6m29s
[16:13:06.601] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:62:0)
[16:13:06.624] Build status: STARTED
[16:13:06.624] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:63:0) after 9m43s
[16:22:49.817] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:63:0)
[16:22:50.028] Build status: STARTED
[16:22:50.028] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:64:0) after 7m24s
[16:30:14.061] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:64:0)
[16:30:14.158] Build status: STARTED
[16:30:14.158] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:65:0) after 4m28s
[16:34:42.276] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:65:0)
[16:34:42.297] Build status: STARTED
[16:34:42.297] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:66:0) after 8m0s
[16:42:42.548] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:66:0)
[16:42:42.569] Build status: STARTED
[16:42:42.569] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:67:0) after 9m57s
[16:52:39.727] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:67:0)
[16:52:39.751] Build status: STARTED
[16:52:39.751] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:68:0) after 6m18s
[16:58:57.780] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:68:0)
[16:58:57.804] Build status: STARTED
[16:58:57.804] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:69:0) after 9m57s
[17:08:54.894] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:69:0)
[17:08:54.948] Build status: STARTED
[17:08:54.948] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:70:0) after 8m8s
[17:17:02.965] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:70:0)
[17:17:02.988] Build status: STARTED
[17:17:02.988] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:71:0) after 1m44s
[17:18:47.004] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:71:0)
[17:18:47.029] Build status: STARTED
[17:18:47.029] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:72:0) after 8m0s
[17:26:47.051] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:72:0)
[17:26:47.095] Build status: STARTED
[17:26:47.095] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:73:0) after 6m51s
[17:33:38.366] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:73:0)
[17:33:38.399] Build status: STARTED
[17:33:38.399] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:74:0) after 6m6s
[17:39:44.776] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:74:0)
[17:39:44.812] Build status: STARTED
[17:39:44.812] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:75:0) after 3m32s
[17:43:17.016] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:75:0)
[17:43:17.062] Build status: STARTED
[17:43:17.062] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:76:0) after 6m34s
[17:49:51.202] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:76:0)
[17:49:51.241] Build status: STARTED
[17:49:51.241] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:77:0) after 7m8s
[17:56:59.263] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:77:0)
[17:56:59.333] Build status: STARTED
[17:56:59.333] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:78:0) after 7m49s
[18:04:48.472] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:78:0)
[18:04:48.499] Build status: STARTED
[18:04:48.499] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:79:0) after 5m44s
[18:10:32.524] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:79:0)
[18:10:32.572] Build status: STARTED
[18:10:32.572] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:80:0) after 9m50s
[18:20:22.774] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:80:0)
[18:20:22.857] Build status: STARTED
[18:20:22.857] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:81:0) after 2m58s
[18:23:21.017] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:81:0)
[18:23:21.083] Build status: STARTED
[18:23:21.083] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:82:0) after 1m53s
[18:25:14.101] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:82:0)
[18:25:14.147] Build status: STARTED
[18:25:14.147] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:83:0) after 4m18s
[18:29:32.766] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:83:0)
[18:29:32.786] Build status: STARTED
[18:29:32.786] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:84:0) after 1m45s
[18:31:17.895] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:84:0)
[18:31:17.961] Build status: STARTED
[18:31:17.961] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:85:0) after 3m17s
[18:34:35.001] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:85:0)
[18:34:35.023] Build status: STARTED
[18:34:35.023] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:86:0) after 1m52s
[18:36:27.048] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:86:0)
[18:36:27.093] Build status: STARTED
[18:36:27.093] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:87:0) after 2m22s
[18:38:49.157] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:87:0)
[18:38:49.178] Build status: STARTED
[18:38:49.178] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:88:0) after 9m17s
[18:48:06.253] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:88:0)
[18:48:06.275] Build status: STARTED
[18:48:06.275] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:89:0) after 5m7s
[18:53:13.398] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:89:0)
[18:53:13.444] Build status: STARTED
[18:53:13.444] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:90:0) after 6m33s
[18:59:46.547] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:90:0)
[18:59:46.656] Build status: STARTED
[18:59:46.656] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:91:0) after 7m5s
[19:06:51.741] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:91:0)
[19:06:51.759] Build status: STARTED
[19:06:51.759] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:92:0) after 5m51s
[19:12:42.875] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:92:0)
[19:12:42.896] Build status: STARTED
[19:12:42.897] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:93:0) after 9m45s
[19:22:27.972] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:93:0)
[19:22:28.059] Build status: STARTED
[19:22:28.059] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:94:0) after 8m36s
[19:31:04.076] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:94:0)
[19:31:04.097] Build status: STARTED
[19:31:04.097] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:95:0) after 7m27s
[19:38:31.115] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:95:0)
[19:38:31.133] Build status: STARTED
[19:38:31.133] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:96:0) after 8m22s
[19:46:53.170] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:96:0)
[19:46:53.187] Build status: STARTED
[19:46:53.187] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:97:0) after 8m43s
[19:55:36.214] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:97:0)
[19:55:36.286] Build status: STARTED
[19:55:36.286] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:98:0) after 1m17s
[19:56:53.402] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:98:0)
[19:56:53.442] Build status: STARTED
[19:56:53.443] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:99:0) after 5m18s
[20:02:11.491] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:99:0)
[20:02:11.508] Build status: STARTED
[20:02:11.509] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:100:0) after 8m49s
[20:11:00.706] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:100:0)
[20:11:00.727] Build status: STARTED
[20:11:00.727] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:101:0) after 9m59s
[20:20:59.747] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:101:0)
[20:20:59.792] Build status: STARTED
[20:20:59.792] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:102:0) after 8m1s
[20:29:00.822] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:102:0)
[20:29:00.885] Build status: STARTED
[20:29:00.885] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:103:0) after 5m10s
[20:34:10.958] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:103:0)
[20:34:10.982] Build status: STARTED
[20:34:10.982] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:104:0) after 3m30s
[20:37:40.958] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:104:0)
[20:37:41.002] Build status: STARTED
[20:37:41.002] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:105:0) after 7m6s
[20:44:47.067] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:105:0)
[20:44:47.101] Build status: STARTED
[20:44:47.101] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:106:0) after 5m26s
[20:50:13.099] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:106:0)
[20:50:13.120] Build status: STARTED
[20:50:13.120] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:107:0) after 2m6s
[20:52:19.137] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:107:0)
[20:52:19.222] Build status: STARTED
[20:52:19.223] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:108:0) after 7m57s
[21:00:16.483] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:108:0)
[21:00:16.514] Build status: STARTED
[21:00:16.515] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:109:0) after 9m51s
[21:10:07.535] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:109:0)
[21:10:07.672] Build status: STARTED
[21:10:07.672] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:110:0) after 3m44s
[21:13:51.688] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:110:0)
[21:13:51.717] Build status: STARTED
[21:13:51.717] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:111:0) after 9m55s
[21:23:46.739] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:111:0)
[21:23:46.760] Build status: STARTED
[21:23:46.760] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:112:0) after 3m20s
[21:27:06.884] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:112:0)
[21:27:06.909] Build status: STARTED
[21:27:06.909] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:113:0) after 6m3s
[21:33:09.935] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:113:0)
[21:33:09.967] Build status: STARTED
[21:33:09.967] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:114:0) after 4m12s
[21:37:22.017] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:114:0)
[21:37:22.046] Build status: STARTED
[21:37:22.046] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:115:0) after 7m8s
[21:44:30.073] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:115:0)
[21:44:30.097] Build status: STARTED
[21:44:30.097] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:116:0) after 7m6s
[21:51:36.475] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:116:0)
[21:51:36.503] Build status: STARTED
[21:51:36.503] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:117:0) after 8m51s
[22:00:27.526] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:117:0)
[22:00:27.554] Build status: STARTED
[22:00:27.554] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:118:0) after 8m41s
[22:09:08.788] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:118:0)
[22:09:08.812] Build status: STARTED
[22:09:08.812] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:119:0) after 5m9s
[22:14:17.813] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:119:0)
[22:14:17.914] Build status: STARTED
[22:14:17.914] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:120:0) after 1m6s
[22:15:23.970] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:120:0)
[22:15:23.989] Build status: STARTED
[22:15:23.989] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:121:0) after 1m9s
[22:16:33.012] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:121:0)
[22:16:33.030] Build status: STARTED
[22:16:33.030] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:122:0) after 6m17s
[22:22:50.052] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:122:0)
[22:22:50.074] Build status: STARTED
[22:22:50.074] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:123:0) after 3m55s
[22:26:45.100] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:123:0)
[22:26:45.128] Build status: STARTED
[22:26:45.128] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:124:0) after 9m49s
[22:36:34.154] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:124:0)
[22:36:34.289] Build status: STARTED
[22:36:34.289] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:125:0) after 3m6s
[22:39:40.309] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:125:0)
[22:39:40.325] Build status: STARTED
[22:39:40.325] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:126:0) after 5m25s
[22:45:05.352] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:126:0)
[22:45:05.449] Build status: STARTED
[22:45:05.449] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:127:0) after 1m18s
[22:46:23.495] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:127:0)
[22:46:23.516] Build status: STARTED
[22:46:23.516] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:128:0) after 6m38s
[22:53:01.541] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:128:0)
[22:53:01.560] Build status: STARTED
[22:53:01.560] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:129:0) after 6m48s
[22:59:49.591] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:129:0)
[22:59:49.693] Build status: STARTED
[22:59:49.693] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:130:0) after 9m5s
[23:08:54.821] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:130:0)
[23:08:54.865] Build status: STARTED
[23:08:54.865] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:131:0) after 1m11s
[23:10:06.634] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:131:0)
[23:10:06.661] Build status: STARTED
[23:10:06.661] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:132:0) after 7m18s
[23:17:24.813] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:132:0)
[23:17:24.830] Build status: STARTED
[23:17:24.830] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:133:0) after 1m13s
[23:18:37.853] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:133:0)
[23:18:37.872] Build status: STARTED
[23:18:37.872] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:134:0) after 2m14s
[23:20:51.902] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:134:0)
[23:20:51.922] Build status: STARTED
[23:20:51.922] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:135:0) after 4m10s
[23:25:02.196] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:135:0)
[23:25:02.218] Build status: STARTED
[23:25:02.218] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:136:0) after 6m36s
[23:31:38.275] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:136:0)
[23:31:38.306] Build status: STARTED
[23:31:38.306] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:137:0) after 7m10s
[23:38:48.303] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:137:0)
[23:38:48.334] Build status: STARTED
[23:38:48.334] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:138:0) after 8m35s
[23:47:23.472] Handling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:138:0)
[23:47:23.489] Build status: STARTED
[23:47:23.489] Scheduling timer "check-buildbucket-build-status" (chromium/Windows Cross deterministic:8924993566067694816:139:0) after 4m24s
[23:50:04.623] Received PubSub notification, asking Buildbucket for the build status
[23:50:04.646] Build:
{
"id": "8759563504215107089",
"builder": {
"project": "chromium",
"bucket": "reclient",
"builder": "Windows Cross deterministic"
},
"number": 1901,
"createdBy": "project:chromium",
"createTime": "2024-01-07T11:18:05.308918932Z",
"startTime": "2024-01-07T11:18:06.738358Z",
"endTime": "2024-01-07T23:50:03.660732Z",
"updateTime": "2024-01-07T23:50:03.660732Z",
"status": "INFRA_FAILURE",
"statusDetails": {
"timeout": {
}
},
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b369e8328db9e625fe7825ee384c289746c951e8",
"ref": "refs/heads/main"
}
},
"canary": true
}
[23:50:04.646] Invocation finished in 12h32m0.487250225s with status FAILED