[19:47:03.422] New invocation is queued and will start shortly
[19:47:04.862] Starting the invocation (attempt 1)
[19:47:04.900] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/78eea27c1f72927bed1a711e6854c512c3010e33"
[19:47:04.900] Popped gitiles commit info from properties and tags
[19:47:04.900] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:47:04.900] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:47:04.900] Buildbucket request:
{
"requestId": "8883739923627623376",
"builder": {
"project": "openscreen",
"bucket": "ci",
"builder": "chromium_linux_x64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8883739923627623376",
"job": "openscreen/chromium_linux_x64",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "254bd6662178c4af7f5ae5f949bd2a08a0944152"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/heads/main@254bd6662178c4af7f5ae5f949bd2a08a0944152",
"title": "254bd6662178c4af7f5ae5f949bd2a08a0944152",
"url": "https://chromium.googlesource.com/chromium/src/+/254bd6662178c4af7f5ae5f949bd2a08a0944152"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0275e929ee149fe0e1d87412ddea58364caedc2c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/heads/main@0275e929ee149fe0e1d87412ddea58364caedc2c",
"title": "0275e929ee149fe0e1d87412ddea58364caedc2c",
"url": "https://chromium.googlesource.com/chromium/src/+/0275e929ee149fe0e1d87412ddea58364caedc2c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a14e3ab97c43d3208690c8a4cf2fcfa1fc62f74e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/heads/main@a14e3ab97c43d3208690c8a4cf2fcfa1fc62f74e",
"title": "a14e3ab97c43d3208690c8a4cf2fcfa1fc62f74e",
"url": "https://chromium.googlesource.com/chromium/src/+/a14e3ab97c43d3208690c8a4cf2fcfa1fc62f74e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8088bfac0dfc37b0d5097ca9a298802c85baf656"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/heads/main@8088bfac0dfc37b0d5097ca9a298802c85baf656",
"title": "8088bfac0dfc37b0d5097ca9a298802c85baf656",
"url": "https://chromium.googlesource.com/chromium/src/+/8088bfac0dfc37b0d5097ca9a298802c85baf656"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0479afefb6ed927261d45b1334e19d977ad6751e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/heads/main@0479afefb6ed927261d45b1334e19d977ad6751e",
"title": "0479afefb6ed927261d45b1334e19d977ad6751e",
"url": "https://chromium.googlesource.com/chromium/src/+/0479afefb6ed927261d45b1334e19d977ad6751e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "82acbe8af81a04d641397c0cbe76811c56c1b52d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/heads/main@82acbe8af81a04d641397c0cbe76811c56c1b52d",
"title": "82acbe8af81a04d641397c0cbe76811c56c1b52d",
"url": "https://chromium.googlesource.com/chromium/src/+/82acbe8af81a04d641397c0cbe76811c56c1b52d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7b93a0dad35993e60de13882dc13d98a81cee273"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/heads/main@7b93a0dad35993e60de13882dc13d98a81cee273",
"title": "7b93a0dad35993e60de13882dc13d98a81cee273",
"url": "https://chromium.googlesource.com/chromium/src/+/7b93a0dad35993e60de13882dc13d98a81cee273"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2aa61ccbdf6ee6484ac928c5dfaa6f1129460842"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/heads/main@2aa61ccbdf6ee6484ac928c5dfaa6f1129460842",
"title": "2aa61ccbdf6ee6484ac928c5dfaa6f1129460842",
"url": "https://chromium.googlesource.com/chromium/src/+/2aa61ccbdf6ee6484ac928c5dfaa6f1129460842"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4a8bae0743394d4bbb439089676d100f21694f20"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/heads/main@4a8bae0743394d4bbb439089676d100f21694f20",
"title": "4a8bae0743394d4bbb439089676d100f21694f20",
"url": "https://chromium.googlesource.com/chromium/src/+/4a8bae0743394d4bbb439089676d100f21694f20"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9f1981b6bb2e9c49edd8372c665ff007e7ce8423"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/heads/main@9f1981b6bb2e9c49edd8372c665ff007e7ce8423",
"title": "9f1981b6bb2e9c49edd8372c665ff007e7ce8423",
"url": "https://chromium.googlesource.com/chromium/src/+/9f1981b6bb2e9c49edd8372c665ff007e7ce8423"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e8f5127c6a552993e543a316a9f4c2723220359f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/heads/main@e8f5127c6a552993e543a316a9f4c2723220359f",
"title": "e8f5127c6a552993e543a316a9f4c2723220359f",
"url": "https://chromium.googlesource.com/chromium/src/+/e8f5127c6a552993e543a316a9f4c2723220359f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "cf45d29ba222e80208fdf59f36c2898bdd0b107a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/heads/main@cf45d29ba222e80208fdf59f36c2898bdd0b107a",
"title": "cf45d29ba222e80208fdf59f36c2898bdd0b107a",
"url": "https://chromium.googlesource.com/chromium/src/+/cf45d29ba222e80208fdf59f36c2898bdd0b107a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "78eea27c1f72927bed1a711e6854c512c3010e33"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/heads/main@78eea27c1f72927bed1a711e6854c512c3010e33",
"title": "78eea27c1f72927bed1a711e6854c512c3010e33",
"url": "https://chromium.googlesource.com/chromium/src/+/78eea27c1f72927bed1a711e6854c512c3010e33"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "78eea27c1f72927bed1a711e6854c512c3010e33",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8883739923627623376"
},
{
"key": "scheduler_job_id",
"value": "openscreen/chromium_linux_x64"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[19:47:05.325] Scheduled build:
{
"id": "8718309861365733953",
"builder": {
"project": "openscreen",
"bucket": "ci",
"builder": "chromium_linux_x64"
},
"createdBy": "project:openscreen",
"createTime": "2025-04-06T19:47:04.952751064Z",
"updateTime": "2025-04-06T19:47:04.952751064Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "78eea27c1f72927bed1a711e6854c512c3010e33",
"ref": "refs/heads/main"
}
}
}
[19:47:05.325] Task URL: https://cr-buildbucket.appspot.com/build/8718309861365733953
[19:47:05.325] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:2:0) after 6m21s
[19:47:06.285] Received PubSub notification, asking Buildbucket for the build status
[19:47:06.329] Build status: SCHEDULED
[19:48:17.383] Received PubSub notification, asking Buildbucket for the build status
[19:48:17.451] Build status: STARTED
[19:53:26.344] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:2:0)
[19:53:26.368] Build status: STARTED
[19:53:26.368] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:5:0) after 6m56s
[20:00:22.515] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:5:0)
[20:00:22.536] Build status: STARTED
[20:00:22.536] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:6:0) after 4m9s
[20:04:31.558] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:6:0)
[20:04:31.610] Build status: STARTED
[20:04:31.610] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:7:0) after 3m17s
[20:07:48.647] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:7:0)
[20:07:48.680] Build status: STARTED
[20:07:48.680] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:8:0) after 3m19s
[20:11:07.743] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:8:0)
[20:11:07.781] Build status: STARTED
[20:11:07.781] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:9:0) after 5m57s
[20:17:04.904] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:9:0)
[20:17:04.937] Build status: STARTED
[20:17:04.937] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:10:0) after 3m19s
[20:20:24.367] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:10:0)
[20:20:24.391] Build status: STARTED
[20:20:24.391] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:11:0) after 7m37s
[20:28:01.410] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:11:0)
[20:28:01.427] Build status: STARTED
[20:28:01.427] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:12:0) after 1m48s
[20:29:49.451] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:12:0)
[20:29:49.490] Build status: STARTED
[20:29:49.490] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:13:0) after 3m4s
[20:32:53.505] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:13:0)
[20:32:53.524] Build status: STARTED
[20:32:53.524] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:14:0) after 8m16s
[20:41:09.586] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:14:0)
[20:41:09.932] Build status: STARTED
[20:41:09.932] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:15:0) after 5m35s
[20:46:44.943] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:15:0)
[20:46:44.973] Build status: STARTED
[20:46:44.973] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:16:0) after 4m3s
[20:50:47.990] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:16:0)
[20:50:48.059] Build status: STARTED
[20:50:48.059] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:17:0) after 8m42s
[20:59:30.299] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:17:0)
[20:59:30.326] Build status: STARTED
[20:59:30.326] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:18:0) after 8m10s
[21:07:40.431] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:18:0)
[21:07:40.461] Build status: STARTED
[21:07:40.461] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:19:0) after 4m11s
[21:11:51.484] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:19:0)
[21:11:51.515] Build status: STARTED
[21:11:51.515] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:20:0) after 4m25s
[21:16:16.716] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:20:0)
[21:16:16.811] Build status: STARTED
[21:16:16.811] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:21:0) after 8m9s
[21:24:25.933] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:21:0)
[21:24:25.978] Build status: STARTED
[21:24:25.978] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:22:0) after 5m30s
[21:29:56.006] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:22:0)
[21:29:56.088] Build status: STARTED
[21:29:56.088] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:23:0) after 1m40s
[21:31:36.279] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:23:0)
[21:31:36.307] Build status: STARTED
[21:31:36.307] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:24:0) after 4m22s
[21:35:58.327] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:24:0)
[21:35:58.353] Build status: STARTED
[21:35:58.353] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:25:0) after 2m41s
[21:38:39.369] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:25:0)
[21:38:39.402] Build status: STARTED
[21:38:39.402] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:26:0) after 9m35s
[21:48:14.503] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:26:0)
[21:48:14.590] Build status: STARTED
[21:48:14.590] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:27:0) after 5m0s
[21:53:14.812] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:27:0)
[21:53:14.838] Build status: STARTED
[21:53:14.838] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:28:0) after 2m11s
[21:55:25.958] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:28:0)
[21:55:25.978] Build status: STARTED
[21:55:25.978] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:29:0) after 9m57s
[22:05:23.207] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:29:0)
[22:05:23.229] Build status: STARTED
[22:05:23.229] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:30:0) after 7m20s
[22:12:43.256] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:30:0)
[22:12:43.283] Build status: STARTED
[22:12:43.283] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:31:0) after 7m36s
[22:20:19.262] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:31:0)
[22:20:19.283] Build status: STARTED
[22:20:19.283] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:32:0) after 5m25s
[22:25:44.307] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:32:0)
[22:25:44.327] Build status: STARTED
[22:25:44.327] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:33:0) after 2m16s
[22:28:00.382] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:33:0)
[22:28:00.405] Build status: STARTED
[22:28:00.405] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:34:0) after 5m48s
[22:33:48.532] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:34:0)
[22:33:48.570] Build status: STARTED
[22:33:48.570] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:35:0) after 2m35s
[22:36:23.584] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:35:0)
[22:36:23.608] Build status: STARTED
[22:36:23.608] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:36:0) after 1m55s
[22:38:18.631] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:36:0)
[22:38:18.667] Build status: STARTED
[22:38:18.667] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:37:0) after 8m49s
[22:47:07.872] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:37:0)
[22:47:07.891] Build status: STARTED
[22:47:07.891] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:38:0) after 1m8s
[22:48:15.913] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:38:0)
[22:48:15.940] Build status: STARTED
[22:48:15.941] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883739923627623376:39:0) after 9m55s
[22:48:57.798] Received PubSub notification, asking Buildbucket for the build status
[22:48:57.879] Build:
{
"id": "8718309861365733953",
"builder": {
"project": "openscreen",
"bucket": "ci",
"builder": "chromium_linux_x64"
},
"createdBy": "project:openscreen",
"createTime": "2025-04-06T19:47:04.952751064Z",
"startTime": "2025-04-06T19:48:17.159605748Z",
"endTime": "2025-04-06T22:48:57.606365281Z",
"updateTime": "2025-04-06T22:48:57.606365281Z",
"status": "INFRA_FAILURE",
"statusDetails": {
"timeout": {
}
},
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "78eea27c1f72927bed1a711e6854c512c3010e33",
"ref": "refs/heads/main"
}
}
}
[22:48:57.879] Invocation finished in 3h1m54.467731917s with status FAILED