[13:31:16.923] New invocation is queued and will start shortly
[13:31:18.240] Starting the invocation (attempt 1)
[13:31:18.275] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0bb5f6595e6cbceb3d4a3d7bb0b8ea6cf7a60c18"
[13:31:18.275] Popped gitiles commit info from properties and tags
[13:31:18.275] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:31:18.275] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:31:18.275] Buildbucket request:
{
"requestId": "8883854162312157024",
"builder": {
"project": "openscreen",
"bucket": "ci",
"builder": "chromium_linux_x64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8883854162312157024",
"job": "openscreen/chromium_linux_x64",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1f6c7aead178c1780568a335939b4961ab707417"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/heads/main@1f6c7aead178c1780568a335939b4961ab707417",
"title": "1f6c7aead178c1780568a335939b4961ab707417",
"url": "https://chromium.googlesource.com/chromium/src/+/1f6c7aead178c1780568a335939b4961ab707417"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a384281eebb617aa2eb52253a9caa247378b748e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/heads/main@a384281eebb617aa2eb52253a9caa247378b748e",
"title": "a384281eebb617aa2eb52253a9caa247378b748e",
"url": "https://chromium.googlesource.com/chromium/src/+/a384281eebb617aa2eb52253a9caa247378b748e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3fecab3834561f029e7b2eec39d475a6db33cd36"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/heads/main@3fecab3834561f029e7b2eec39d475a6db33cd36",
"title": "3fecab3834561f029e7b2eec39d475a6db33cd36",
"url": "https://chromium.googlesource.com/chromium/src/+/3fecab3834561f029e7b2eec39d475a6db33cd36"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "83d97b8e4094cec13fdcbe63100ca9937306ba8a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/heads/main@83d97b8e4094cec13fdcbe63100ca9937306ba8a",
"title": "83d97b8e4094cec13fdcbe63100ca9937306ba8a",
"url": "https://chromium.googlesource.com/chromium/src/+/83d97b8e4094cec13fdcbe63100ca9937306ba8a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "47dde81b2086030164be45e66e9db5c7827cc1d3"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/heads/main@47dde81b2086030164be45e66e9db5c7827cc1d3",
"title": "47dde81b2086030164be45e66e9db5c7827cc1d3",
"url": "https://chromium.googlesource.com/chromium/src/+/47dde81b2086030164be45e66e9db5c7827cc1d3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "56f1ca3b8ac1981356af657219fd338b7640ac43"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/heads/main@56f1ca3b8ac1981356af657219fd338b7640ac43",
"title": "56f1ca3b8ac1981356af657219fd338b7640ac43",
"url": "https://chromium.googlesource.com/chromium/src/+/56f1ca3b8ac1981356af657219fd338b7640ac43"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e36af747a0a307f29d649285b48d2ceb1dfebade"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/heads/main@e36af747a0a307f29d649285b48d2ceb1dfebade",
"title": "e36af747a0a307f29d649285b48d2ceb1dfebade",
"url": "https://chromium.googlesource.com/chromium/src/+/e36af747a0a307f29d649285b48d2ceb1dfebade"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4ec70d3649008ed193ca6d7c368e17c1971a9a8c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/heads/main@4ec70d3649008ed193ca6d7c368e17c1971a9a8c",
"title": "4ec70d3649008ed193ca6d7c368e17c1971a9a8c",
"url": "https://chromium.googlesource.com/chromium/src/+/4ec70d3649008ed193ca6d7c368e17c1971a9a8c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2e0611622850530e026ab0fcac42c9eeaa85790e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/heads/main@2e0611622850530e026ab0fcac42c9eeaa85790e",
"title": "2e0611622850530e026ab0fcac42c9eeaa85790e",
"url": "https://chromium.googlesource.com/chromium/src/+/2e0611622850530e026ab0fcac42c9eeaa85790e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "13c870c3b5baa669a374d9046e50ed6eb733c238"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/heads/main@13c870c3b5baa669a374d9046e50ed6eb733c238",
"title": "13c870c3b5baa669a374d9046e50ed6eb733c238",
"url": "https://chromium.googlesource.com/chromium/src/+/13c870c3b5baa669a374d9046e50ed6eb733c238"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "608fa621a47015b7e50e1efce6c8591ad0370fb6"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/heads/main@608fa621a47015b7e50e1efce6c8591ad0370fb6",
"title": "608fa621a47015b7e50e1efce6c8591ad0370fb6",
"url": "https://chromium.googlesource.com/chromium/src/+/608fa621a47015b7e50e1efce6c8591ad0370fb6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "746b678a61273e1001b15ff2f44a1eb075b88309"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/heads/main@746b678a61273e1001b15ff2f44a1eb075b88309",
"title": "746b678a61273e1001b15ff2f44a1eb075b88309",
"url": "https://chromium.googlesource.com/chromium/src/+/746b678a61273e1001b15ff2f44a1eb075b88309"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7fb0debab99e1b2ee3b689f33df77df706078442"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/heads/main@7fb0debab99e1b2ee3b689f33df77df706078442",
"title": "7fb0debab99e1b2ee3b689f33df77df706078442",
"url": "https://chromium.googlesource.com/chromium/src/+/7fb0debab99e1b2ee3b689f33df77df706078442"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "98c0e34e7151f34a118d41f56dd544cbd11d448b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/heads/main@98c0e34e7151f34a118d41f56dd544cbd11d448b",
"title": "98c0e34e7151f34a118d41f56dd544cbd11d448b",
"url": "https://chromium.googlesource.com/chromium/src/+/98c0e34e7151f34a118d41f56dd544cbd11d448b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7551c0855c1a3ae77c4626b6a3ae8d156e6492d1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/heads/main@7551c0855c1a3ae77c4626b6a3ae8d156e6492d1",
"title": "7551c0855c1a3ae77c4626b6a3ae8d156e6492d1",
"url": "https://chromium.googlesource.com/chromium/src/+/7551c0855c1a3ae77c4626b6a3ae8d156e6492d1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f96deed53f57f690f86d3d8e369141d69868d8cd"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/heads/main@f96deed53f57f690f86d3d8e369141d69868d8cd",
"title": "f96deed53f57f690f86d3d8e369141d69868d8cd",
"url": "https://chromium.googlesource.com/chromium/src/+/f96deed53f57f690f86d3d8e369141d69868d8cd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0bb5f6595e6cbceb3d4a3d7bb0b8ea6cf7a60c18"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/heads/main@0bb5f6595e6cbceb3d4a3d7bb0b8ea6cf7a60c18",
"title": "0bb5f6595e6cbceb3d4a3d7bb0b8ea6cf7a60c18",
"url": "https://chromium.googlesource.com/chromium/src/+/0bb5f6595e6cbceb3d4a3d7bb0b8ea6cf7a60c18"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "0bb5f6595e6cbceb3d4a3d7bb0b8ea6cf7a60c18",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8883854162312157024"
},
{
"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"
}
}
[13:31:18.717] Scheduled build:
{
"id": "8718424100185600305",
"builder": {
"project": "openscreen",
"bucket": "ci",
"builder": "chromium_linux_x64"
},
"createdBy": "project:openscreen",
"createTime": "2025-04-05T13:31:18.323091738Z",
"updateTime": "2025-04-05T13:31:18.323091738Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "0bb5f6595e6cbceb3d4a3d7bb0b8ea6cf7a60c18",
"ref": "refs/heads/main"
}
}
}
[13:31:18.717] Task URL: https://cr-buildbucket.appspot.com/build/8718424100185600305
[13:31:18.717] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:2:0) after 9m52s
[13:31:19.766] Received PubSub notification, asking Buildbucket for the build status
[13:31:19.794] Build status: SCHEDULED
[13:31:25.129] Received PubSub notification, asking Buildbucket for the build status
[13:31:25.158] Build status: STARTED
[13:41:10.942] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:2:0)
[13:41:10.976] Build status: STARTED
[13:41:10.976] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:5:0) after 6m8s
[13:47:19.042] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:5:0)
[13:47:19.078] Build status: STARTED
[13:47:19.078] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:6:0) after 1m30s
[13:48:49.100] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:6:0)
[13:48:49.138] Build status: STARTED
[13:48:49.138] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:7:0) after 5m52s
[13:54:41.272] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:7:0)
[13:54:41.307] Build status: STARTED
[13:54:41.307] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:8:0) after 6m13s
[14:00:55.519] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:8:0)
[14:00:55.552] Build status: STARTED
[14:00:55.552] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:9:0) after 4m21s
[14:05:16.755] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:9:0)
[14:05:16.782] Build status: STARTED
[14:05:16.782] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:10:0) after 3m28s
[14:08:44.870] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:10:0)
[14:08:44.948] Build status: STARTED
[14:08:44.948] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:11:0) after 7m7s
[14:15:51.981] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:11:0)
[14:15:51.999] Build status: STARTED
[14:15:51.999] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:12:0) after 7m35s
[14:23:26.974] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:12:0)
[14:23:27.090] Build status: STARTED
[14:23:27.090] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:13:0) after 5m35s
[14:29:02.261] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:13:0)
[14:29:02.286] Build status: STARTED
[14:29:02.286] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:14:0) after 7m12s
[14:36:14.405] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:14:0)
[14:36:14.442] Build status: STARTED
[14:36:14.442] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:15:0) after 1m37s
[14:37:51.462] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:15:0)
[14:37:51.488] Build status: STARTED
[14:37:51.488] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:16:0) after 1m23s
[14:39:14.624] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:16:0)
[14:39:14.654] Build status: STARTED
[14:39:14.654] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:17:0) after 2m41s
[14:41:55.873] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:17:0)
[14:41:55.902] Build status: STARTED
[14:41:55.902] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:18:0) after 7m12s
[14:49:08.013] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:18:0)
[14:49:08.030] Build status: STARTED
[14:49:08.030] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:19:0) after 6m17s
[14:55:25.046] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:19:0)
[14:55:25.068] Build status: STARTED
[14:55:25.068] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:20:0) after 1m13s
[14:56:38.088] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:20:0)
[14:56:38.104] Build status: STARTED
[14:56:38.104] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:21:0) after 3m33s
[15:00:11.194] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:21:0)
[15:00:11.212] Build status: STARTED
[15:00:11.212] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:22:0) after 3m6s
[15:03:17.427] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:22:0)
[15:03:17.452] Build status: STARTED
[15:03:17.452] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:23:0) after 7m13s
[15:10:30.612] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:23:0)
[15:10:30.629] Build status: STARTED
[15:10:30.629] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:24:0) after 7m33s
[15:18:03.751] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:24:0)
[15:18:03.795] Build status: STARTED
[15:18:03.795] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:25:0) after 7m3s
[15:25:06.825] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:25:0)
[15:25:06.845] Build status: STARTED
[15:25:06.845] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:26:0) after 9m47s
[15:34:53.966] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:26:0)
[15:34:54.032] Build status: STARTED
[15:34:54.032] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:27:0) after 7m31s
[15:42:25.063] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:27:0)
[15:42:25.089] Build status: STARTED
[15:42:25.089] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:28:0) after 3m7s
[15:45:32.120] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:28:0)
[15:45:32.135] Build status: STARTED
[15:45:32.135] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:29:0) after 4m48s
[15:50:20.158] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:29:0)
[15:50:20.178] Build status: STARTED
[15:50:20.178] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:30:0) after 9m56s
[16:00:16.299] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:30:0)
[16:00:16.314] Build status: STARTED
[16:00:16.314] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:31:0) after 2m27s
[16:02:43.333] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:31:0)
[16:02:43.362] Build status: STARTED
[16:02:43.362] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:32:0) after 8m40s
[16:11:23.439] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:32:0)
[16:11:23.481] Build status: STARTED
[16:11:23.481] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:33:0) after 5m13s
[16:16:36.487] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:33:0)
[16:16:36.518] Build status: STARTED
[16:16:36.518] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:34:0) after 5m42s
[16:22:18.537] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:34:0)
[16:22:18.562] Build status: STARTED
[16:22:18.562] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:35:0) after 1m22s
[16:23:40.586] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:35:0)
[16:23:40.656] Build status: STARTED
[16:23:40.656] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:36:0) after 2m30s
[16:26:10.774] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:36:0)
[16:26:10.803] Build status: STARTED
[16:26:10.803] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:37:0) after 4m39s
[16:30:49.827] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:37:0)
[16:30:49.846] Build status: STARTED
[16:30:49.846] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8883854162312157024:38:0) after 7m43s
[16:31:36.298] Received PubSub notification, asking Buildbucket for the build status
[16:31:36.323] Build:
{
"id": "8718424100185600305",
"builder": {
"project": "openscreen",
"bucket": "ci",
"builder": "chromium_linux_x64"
},
"createdBy": "project:openscreen",
"createTime": "2025-04-05T13:31:18.323091738Z",
"startTime": "2025-04-05T13:31:24.918494048Z",
"endTime": "2025-04-05T16:31:36.107943438Z",
"updateTime": "2025-04-05T16:31:36.107943438Z",
"status": "INFRA_FAILURE",
"statusDetails": {
"timeout": {
}
},
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "0bb5f6595e6cbceb3d4a3d7bb0b8ea6cf7a60c18",
"ref": "refs/heads/main"
}
}
}
[16:31:36.324] Invocation finished in 3h0m19.41148425s with status FAILED