[02:29:47.066] New invocation is queued and will start shortly
[02:29:48.142] Starting the invocation (attempt 1)
[02:29:48.173] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/06e664a5429c7d5e00107364428dbbbaf430addd"
[02:29:48.173] Popped gitiles commit info from properties and tags
[02:29:48.173] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[02:29:48.173] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[02:29:48.173] Buildbucket request:
{
"requestId": "8874020710802132272",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler-dev.appspot.com",
"invocation": "8874020710802132272",
"job": "infra/infra-continuous-win10-64",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "8a3ca7b5e11dadbdf6cfbd87319abc74d5da4ba6"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@8a3ca7b5e11dadbdf6cfbd87319abc74d5da4ba6",
"title": "8a3ca7b5e11dadbdf6cfbd87319abc74d5da4ba6",
"url": "https://chromium.googlesource.com/infra/infra/+/8a3ca7b5e11dadbdf6cfbd87319abc74d5da4ba6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "d50da23f01fedf513222ad12bc909b9ad76a566f"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@d50da23f01fedf513222ad12bc909b9ad76a566f",
"title": "d50da23f01fedf513222ad12bc909b9ad76a566f",
"url": "https://chromium.googlesource.com/infra/infra/+/d50da23f01fedf513222ad12bc909b9ad76a566f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "8064f4caaeabac31df455d5a834d31c1943b95f0"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@8064f4caaeabac31df455d5a834d31c1943b95f0",
"title": "8064f4caaeabac31df455d5a834d31c1943b95f0",
"url": "https://chromium.googlesource.com/infra/infra/+/8064f4caaeabac31df455d5a834d31c1943b95f0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "776e915d3adcab3d02b26baa0e12493d92432a91"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@776e915d3adcab3d02b26baa0e12493d92432a91",
"title": "776e915d3adcab3d02b26baa0e12493d92432a91",
"url": "https://chromium.googlesource.com/infra/infra/+/776e915d3adcab3d02b26baa0e12493d92432a91"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "06e664a5429c7d5e00107364428dbbbaf430addd"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@06e664a5429c7d5e00107364428dbbbaf430addd",
"title": "06e664a5429c7d5e00107364428dbbbaf430addd",
"url": "https://chromium.googlesource.com/infra/infra/+/06e664a5429c7d5e00107364428dbbbaf430addd"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "06e664a5429c7d5e00107364428dbbbaf430addd",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8874020710802132272"
},
{
"key": "scheduler_job_id",
"value": "infra/infra-continuous-win10-64"
},
{
"key": "user_agent",
"value": "luci-scheduler-dev"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
}
}
[02:29:48.820] Scheduled build:
{
"id": "8708590648918783809",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"number": 34859,
"createdBy": "project:infra",
"createTime": "2025-07-23T02:29:48.238392488Z",
"updateTime": "2025-07-23T02:29:48.238392488Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "06e664a5429c7d5e00107364428dbbbaf430addd",
"ref": "refs/heads/main"
}
},
"canary": true
}
[02:29:48.820] Task URL: https://cr-buildbucket-dev.appspot.com/build/8708590648918783809
[02:29:48.820] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8874020710802132272:2:0) after 2m43s
[02:29:49.850] Received PubSub notification, asking Buildbucket for the build status
[02:29:49.878] Build status: SCHEDULED
[02:32:31.836] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8874020710802132272:2:0)
[02:32:31.874] Build status: SCHEDULED
[02:32:31.874] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8874020710802132272:4:0) after 1m4s
[02:33:35.892] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8874020710802132272:4:0)
[02:33:35.950] Build status: SCHEDULED
[02:33:35.950] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8874020710802132272:5:0) after 2m7s
[02:35:42.973] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8874020710802132272:5:0)
[02:35:43.003] Build status: SCHEDULED
[02:35:43.003] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8874020710802132272:6:0) after 7m5s
[02:42:48.019] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8874020710802132272:6:0)
[02:42:48.039] Build status: SCHEDULED
[02:42:48.039] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8874020710802132272:7:0) after 4m32s
[02:47:20.055] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8874020710802132272:7:0)
[02:47:20.073] Build status: SCHEDULED
[02:47:20.073] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8874020710802132272:8:0) after 6m9s
[02:53:29.091] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8874020710802132272:8:0)
[02:53:29.213] Build status: SCHEDULED
[02:53:29.213] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8874020710802132272:9:0) after 6m31s
[03:00:00.227] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8874020710802132272:9:0)
[03:00:00.263] Build status: SCHEDULED
[03:00:00.263] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8874020710802132272:10:0) after 7m59s
[03:07:59.289] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8874020710802132272:10:0)
[03:07:59.339] Build status: SCHEDULED
[03:07:59.339] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8874020710802132272:11:0) after 1m34s
[03:09:33.362] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8874020710802132272:11:0)
[03:09:33.392] Build status: SCHEDULED
[03:09:33.392] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8874020710802132272:12:0) after 5m51s
[03:15:24.418] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8874020710802132272:12:0)
[03:15:24.439] Build status: SCHEDULED
[03:15:24.439] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8874020710802132272:13:0) after 6m31s
[03:21:55.459] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8874020710802132272:13:0)
[03:21:55.498] Build status: SCHEDULED
[03:21:55.498] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8874020710802132272:14:0) after 6m35s
[03:28:21.651] Received PubSub notification, asking Buildbucket for the build status
[03:28:21.686] Build status: STARTED
[03:28:30.515] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8874020710802132272:14:0)
[03:28:30.550] Build status: STARTED
[03:28:30.550] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8874020710802132272:16:0) after 1m6s
[03:29:36.567] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8874020710802132272:16:0)
[03:29:36.610] Build status: STARTED
[03:29:36.610] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8874020710802132272:17:0) after 4m10s
[03:33:46.630] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8874020710802132272:17:0)
[03:33:46.661] Build status: STARTED
[03:33:46.661] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8874020710802132272:18:0) after 3m33s
[03:37:19.680] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8874020710802132272:18:0)
[03:37:19.709] Build status: STARTED
[03:37:19.709] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8874020710802132272:19:0) after 4m44s
[03:42:03.725] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8874020710802132272:19:0)
[03:42:03.757] Build status: STARTED
[03:42:03.757] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8874020710802132272:20:0) after 1m39s
[03:43:42.771] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8874020710802132272:20:0)
[03:43:42.796] Build status: STARTED
[03:43:42.796] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8874020710802132272:21:0) after 7m49s
[03:51:31.813] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8874020710802132272:21:0)
[03:51:31.832] Build status: STARTED
[03:51:31.832] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8874020710802132272:22:0) after 6m10s
[03:57:41.848] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8874020710802132272:22:0)
[03:57:41.872] Build status: STARTED
[03:57:41.872] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8874020710802132272:23:0) after 1m53s
[03:59:34.889] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8874020710802132272:23:0)
[03:59:34.916] Build:
{
"id": "8708590648918783809",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"number": 34859,
"createdBy": "project:infra",
"createTime": "2025-07-23T02:29:48.238392488Z",
"startTime": "2025-07-23T03:28:20.777688300Z",
"endTime": "2025-07-23T03:59:31.393883348Z",
"updateTime": "2025-07-23T03:59:31.393883348Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "06e664a5429c7d5e00107364428dbbbaf430addd",
"ref": "refs/heads/main"
}
},
"canary": true
}
[03:59:34.916] Invocation finished in 1h29m47.858409323s with status FAILED