[14:52:51.062] New invocation is queued and will start shortly
[14:52:52.128] Starting the invocation (attempt 1)
[14:52:52.163] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ff7f5d2ebe23f07c5f8e1f3c973b3c6f517c72ce"
[14:52:52.163] Popped gitiles commit info from properties and tags
[14:52:52.163] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[14:52:52.163] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[14:52:52.164] Buildbucket request:
{
"requestId": "8897166784493794256",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-local-ssd-rel-dev"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ff7f5d2ebe23f07c5f8e1f3c973b3c6f517c72ce",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8897166784493794256"
},
{
"key": "scheduler_job_id",
"value": "chromium/win-local-ssd-rel-dev"
},
{
"key": "user_agent",
"value": "luci-scheduler-dev"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
}
}
[14:52:52.912] Scheduled build:
{
"id": "8731736722605927969",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-local-ssd-rel-dev"
},
"number": 19703,
"createdBy": "project:chromium",
"createTime": "2024-11-09T14:52:52.237079276Z",
"updateTime": "2024-11-09T14:52:52.237079276Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ff7f5d2ebe23f07c5f8e1f3c973b3c6f517c72ce",
"ref": "refs/heads/main"
}
},
"canary": true
}
[14:52:52.912] Task URL: https://cr-buildbucket-dev.appspot.com/build/8731736722605927969
[14:52:52.912] Scheduling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:2:0) after 7m55s
[14:52:57.809] Received PubSub notification, asking Buildbucket for the build status
[14:52:57.839] Build status: SCHEDULED
[14:53:04.645] Received PubSub notification, asking Buildbucket for the build status
[14:53:04.667] Build status: STARTED
[15:00:48.542] Handling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:2:0)
[15:00:48.567] Build status: STARTED
[15:00:48.567] Scheduling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:5:0) after 6m59s
[15:07:47.581] Handling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:5:0)
[15:07:47.622] Build status: STARTED
[15:07:47.622] Scheduling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:6:0) after 4m33s
[15:12:20.636] Handling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:6:0)
[15:12:20.661] Build status: STARTED
[15:12:20.661] Scheduling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:7:0) after 2m29s
[15:14:49.681] Handling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:7:0)
[15:14:49.714] Build status: STARTED
[15:14:49.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:8:0) after 9m18s
[15:24:07.973] Handling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:8:0)
[15:24:08.013] Build status: STARTED
[15:24:08.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:9:0) after 6m57s
[15:31:05.030] Handling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:9:0)
[15:31:05.053] Build status: STARTED
[15:31:05.053] Scheduling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:10:0) after 3m12s
[15:34:17.071] Handling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:10:0)
[15:34:17.131] Build status: STARTED
[15:34:17.131] Scheduling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:11:0) after 5m57s
[15:40:14.151] Handling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:11:0)
[15:40:14.179] Build status: STARTED
[15:40:14.179] Scheduling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:12:0) after 8m56s
[15:49:10.195] Handling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:12:0)
[15:49:10.255] Build status: STARTED
[15:49:10.255] Scheduling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:13:0) after 3m43s
[15:52:53.273] Handling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:13:0)
[15:52:53.300] Build status: STARTED
[15:52:53.300] Scheduling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:14:0) after 6m40s
[15:59:33.315] Handling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:14:0)
[15:59:33.349] Build status: STARTED
[15:59:33.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:15:0) after 3m32s
[16:03:05.372] Handling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:15:0)
[16:03:05.424] Build status: STARTED
[16:03:05.425] Scheduling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:16:0) after 1m58s
[16:05:03.444] Handling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:16:0)
[16:05:03.468] Build status: STARTED
[16:05:03.468] Scheduling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:17:0) after 3m31s
[16:08:34.488] Handling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:17:0)
[16:08:34.539] Build status: STARTED
[16:08:34.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:18:0) after 5m37s
[16:14:11.562] Handling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:18:0)
[16:14:11.646] Build status: STARTED
[16:14:11.646] Scheduling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:19:0) after 4m18s
[16:18:29.664] Handling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:19:0)
[16:18:29.695] Build status: STARTED
[16:18:29.695] Scheduling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:20:0) after 2m27s
[16:20:56.718] Handling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:20:0)
[16:20:56.749] Build status: STARTED
[16:20:56.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:21:0) after 2m3s
[16:22:59.776] Handling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:21:0)
[16:22:59.795] Build status: STARTED
[16:22:59.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:22:0) after 2m9s
[16:25:08.812] Handling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:22:0)
[16:25:08.832] Build status: STARTED
[16:25:08.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:23:0) after 3m33s
[16:28:41.848] Handling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:23:0)
[16:28:41.884] Build status: STARTED
[16:28:41.884] Scheduling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:24:0) after 9m18s
[16:37:59.904] Handling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:24:0)
[16:37:59.943] Build status: STARTED
[16:37:59.943] Scheduling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:25:0) after 5m8s
[16:43:07.958] Handling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:25:0)
[16:43:08.023] Build status: STARTED
[16:43:08.023] Scheduling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:26:0) after 7m4s
[16:50:12.102] Handling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:26:0)
[16:50:12.126] Build status: STARTED
[16:50:12.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:27:0) after 6m57s
[16:57:09.147] Handling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:27:0)
[16:57:09.197] Build status: STARTED
[16:57:09.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:28:0) after 4m42s
[17:01:51.212] Handling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:28:0)
[17:01:51.351] Build status: STARTED
[17:01:51.351] Scheduling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:29:0) after 4m15s
[17:06:06.368] Handling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:29:0)
[17:06:06.396] Build status: STARTED
[17:06:06.396] Scheduling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:30:0) after 1m46s
[17:07:52.412] Handling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:30:0)
[17:07:52.449] Build status: STARTED
[17:07:52.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:31:0) after 3m39s
[17:11:31.463] Handling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:31:0)
[17:11:31.493] Build status: STARTED
[17:11:31.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:32:0) after 6m2s
[17:17:33.507] Handling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:32:0)
[17:17:33.535] Build status: STARTED
[17:17:33.535] Scheduling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:33:0) after 8m22s
[17:25:55.551] Handling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:33:0)
[17:25:55.584] Build status: STARTED
[17:25:55.584] Scheduling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8897166784493794256:34:0) after 9m6s
[17:26:27.940] Received PubSub notification, asking Buildbucket for the build status
[17:26:28.911] Build:
{
"id": "8731736722605927969",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-local-ssd-rel-dev"
},
"number": 19703,
"createdBy": "project:chromium",
"createTime": "2024-11-09T14:52:52.237079276Z",
"startTime": "2024-11-09T14:53:01.534306639Z",
"endTime": "2024-11-09T17:26:27.465712078Z",
"updateTime": "2024-11-09T17:26:27.465712078Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ff7f5d2ebe23f07c5f8e1f3c973b3c6f517c72ce",
"ref": "refs/heads/main"
}
},
"canary": true
}
[17:26:28.911] Invocation finished in 2h33m37.858313872s with status FAILED