[22:21:54.075] New invocation is queued and will start shortly
[22:21:55.163] Starting the invocation (attempt 1)
[22:21:55.204] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/50d9937081af44ff938dc88e6dd4bc60d32848ff"
[22:21:55.204] Popped gitiles commit info from properties and tags
[22:21:55.204] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[22:21:55.205] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[22:21:55.205] Buildbucket request:
{
"requestId": "8902393156753366272",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win11-rel-dev"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "50d9937081af44ff938dc88e6dd4bc60d32848ff",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8902393156753366272"
},
{
"key": "scheduler_job_id",
"value": "chromium/win11-rel-dev"
},
{
"key": "user_agent",
"value": "luci-scheduler-dev"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
}
}
[22:21:56.014] Scheduled build:
{
"id": "8736963094834339265",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win11-rel-dev"
},
"number": 8064,
"createdBy": "project:chromium",
"createTime": "2024-09-12T22:21:55.275321117Z",
"updateTime": "2024-09-12T22:21:55.275321117Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "50d9937081af44ff938dc88e6dd4bc60d32848ff",
"ref": "refs/heads/main"
}
}
}
[22:21:56.015] Task URL: https://cr-buildbucket-dev.appspot.com/build/8736963094834339265
[22:21:56.015] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:2:0) after 2m36s
[22:21:56.640] Received PubSub notification, asking Buildbucket for the build status
[22:21:56.666] Build status: SCHEDULED
[22:24:32.050] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:2:0)
[22:24:32.094] Build status: SCHEDULED
[22:24:32.094] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:4:0) after 1m7s
[22:25:35.666] Received PubSub notification, asking Buildbucket for the build status
[22:25:35.714] Build status: STARTED
[22:25:39.119] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:4:0)
[22:25:39.142] Build status: STARTED
[22:25:39.142] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:6:0) after 5m44s
[22:31:23.164] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:6:0)
[22:31:23.196] Build status: STARTED
[22:31:23.196] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:7:0) after 8m30s
[22:39:53.215] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:7:0)
[22:39:53.277] Build status: STARTED
[22:39:53.277] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:8:0) after 2m4s
[22:41:57.306] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:8:0)
[22:41:57.341] Build status: STARTED
[22:41:57.341] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:9:0) after 5m17s
[22:47:14.363] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:9:0)
[22:47:14.393] Build status: STARTED
[22:47:14.393] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:10:0) after 1m46s
[22:49:00.430] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:10:0)
[22:49:00.488] Build status: STARTED
[22:49:00.488] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:11:0) after 6m26s
[22:55:26.511] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:11:0)
[22:55:26.567] Build status: STARTED
[22:55:26.567] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:12:0) after 2m15s
[22:57:41.622] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:12:0)
[22:57:41.680] Build status: STARTED
[22:57:41.680] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:13:0) after 3m29s
[23:01:10.701] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:13:0)
[23:01:10.747] Build status: STARTED
[23:01:10.747] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:14:0) after 5m27s
[23:06:37.769] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:14:0)
[23:06:37.833] Build status: STARTED
[23:06:37.833] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:15:0) after 4m28s
[23:11:05.853] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:15:0)
[23:11:05.910] Build status: STARTED
[23:11:05.910] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:16:0) after 2m14s
[23:13:19.931] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:16:0)
[23:13:19.988] Build status: STARTED
[23:13:19.988] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:17:0) after 6m28s
[23:19:48.016] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:17:0)
[23:19:48.044] Build status: STARTED
[23:19:48.044] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:18:0) after 8m33s
[23:28:21.076] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:18:0)
[23:28:21.107] Build status: STARTED
[23:28:21.107] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:19:0) after 1m14s
[23:29:35.137] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:19:0)
[23:29:35.163] Build status: STARTED
[23:29:35.164] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:20:0) after 5m54s
[23:35:29.183] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:20:0)
[23:35:29.211] Build status: STARTED
[23:35:29.211] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:21:0) after 6m34s
[23:42:03.205] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:21:0)
[23:42:03.228] Build status: STARTED
[23:42:03.228] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:22:0) after 9m26s
[23:51:29.253] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:22:0)
[23:51:29.287] Build status: STARTED
[23:51:29.287] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:23:0) after 5m11s
[23:56:40.311] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:23:0)
[23:56:40.355] Build status: STARTED
[23:56:40.355] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:24:0) after 4m3s
[00:00:43.386] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:24:0)
[00:00:43.410] Build status: STARTED
[00:00:43.410] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:25:0) after 7m56s
[00:08:39.437] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:25:0)
[00:08:39.475] Build status: STARTED
[00:08:39.475] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:26:0) after 8m11s
[00:16:50.494] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:26:0)
[00:16:50.527] Build status: STARTED
[00:16:50.527] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:27:0) after 7m27s
[00:24:17.550] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:27:0)
[00:24:17.576] Build status: STARTED
[00:24:17.576] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:28:0) after 7m25s
[00:31:42.599] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:28:0)
[00:31:42.631] Build status: STARTED
[00:31:42.631] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:29:0) after 8m11s
[00:39:53.649] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:29:0)
[00:39:53.687] Build status: STARTED
[00:39:53.687] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:30:0) after 5m49s
[00:45:42.707] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:30:0)
[00:45:42.736] Build status: STARTED
[00:45:42.736] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:31:0) after 4m22s
[00:50:04.757] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:31:0)
[00:50:04.795] Build status: STARTED
[00:50:04.795] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:32:0) after 7m10s
[00:57:14.816] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:32:0)
[00:57:14.856] Build status: STARTED
[00:57:14.856] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:33:0) after 8m46s
[01:06:00.875] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:33:0)
[01:06:00.907] Build status: STARTED
[01:06:00.907] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:34:0) after 1m52s
[01:07:53.473] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:34:0)
[01:07:53.507] Build status: STARTED
[01:07:53.508] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:35:0) after 7m18s
[01:15:11.537] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:35:0)
[01:15:11.572] Build status: STARTED
[01:15:11.572] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:36:0) after 2m30s
[01:17:41.879] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:36:0)
[01:17:41.993] Build status: STARTED
[01:17:41.993] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:37:0) after 8m32s
[01:26:14.018] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:37:0)
[01:26:14.054] Build status: STARTED
[01:26:14.054] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8902393156753366272:38:0) after 5m19s
[01:26:28.823] Received PubSub notification, asking Buildbucket for the build status
[01:26:28.856] Build:
{
"id": "8736963094834339265",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win11-rel-dev"
},
"number": 8064,
"createdBy": "project:chromium",
"createTime": "2024-09-12T22:21:55.275321117Z",
"startTime": "2024-09-12T22:25:35.423858626Z",
"endTime": "2024-09-13T01:26:28.324330527Z",
"updateTime": "2024-09-13T01:26:28.324330527Z",
"status": "INFRA_FAILURE",
"statusDetails": {
"timeout": {
}
},
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "50d9937081af44ff938dc88e6dd4bc60d32848ff",
"ref": "refs/heads/main"
}
}
}
[01:26:28.856] Invocation finished in 3h4m34.795259458s with status FAILED