[16:32:14.108] New invocation is queued and will start shortly
[16:32:15.256] Starting the invocation (attempt 1)
[16:32:15.290] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1bcbf565a9cf100e351358bba3f37662dacfb178"
[16:32:15.290] Popped gitiles commit info from properties and tags
[16:32:15.290] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:32:15.290] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:32:15.290] Buildbucket request:
{
"requestId": "8895982771226381648",
"builder": {
"project": "chromium-m130",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8895982771226381648",
"job": "chromium-m130/fuchsia-x64-cast-receiver-dbg",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c56a439d49f4a64c52ae5b3268da0bb23c53584c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@c56a439d49f4a64c52ae5b3268da0bb23c53584c",
"title": "c56a439d49f4a64c52ae5b3268da0bb23c53584c",
"url": "https://chromium.googlesource.com/chromium/src/+/c56a439d49f4a64c52ae5b3268da0bb23c53584c"
},
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "24e051396d989cc4e81ddc970d1b26bf6651e350"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@24e051396d989cc4e81ddc970d1b26bf6651e350",
"title": "24e051396d989cc4e81ddc970d1b26bf6651e350",
"url": "https://chromium.googlesource.com/chromium/src/+/24e051396d989cc4e81ddc970d1b26bf6651e350"
},
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "fe07cc8cc13515cb1e9636907f93b1a48ca42ede"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@fe07cc8cc13515cb1e9636907f93b1a48ca42ede",
"title": "fe07cc8cc13515cb1e9636907f93b1a48ca42ede",
"url": "https://chromium.googlesource.com/chromium/src/+/fe07cc8cc13515cb1e9636907f93b1a48ca42ede"
},
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "cc02bb5433641e75cd734b6c1d05a4c66c00aa90"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@cc02bb5433641e75cd734b6c1d05a4c66c00aa90",
"title": "cc02bb5433641e75cd734b6c1d05a4c66c00aa90",
"url": "https://chromium.googlesource.com/chromium/src/+/cc02bb5433641e75cd734b6c1d05a4c66c00aa90"
},
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0df42408a66ff2eaa9d1e0c561dadfdb3cefd100"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@0df42408a66ff2eaa9d1e0c561dadfdb3cefd100",
"title": "0df42408a66ff2eaa9d1e0c561dadfdb3cefd100",
"url": "https://chromium.googlesource.com/chromium/src/+/0df42408a66ff2eaa9d1e0c561dadfdb3cefd100"
},
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "cac5fce1643c6ffc4ab6fcadf8543ac8c6b9eb02"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@cac5fce1643c6ffc4ab6fcadf8543ac8c6b9eb02",
"title": "cac5fce1643c6ffc4ab6fcadf8543ac8c6b9eb02",
"url": "https://chromium.googlesource.com/chromium/src/+/cac5fce1643c6ffc4ab6fcadf8543ac8c6b9eb02"
},
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1bcbf565a9cf100e351358bba3f37662dacfb178"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@1bcbf565a9cf100e351358bba3f37662dacfb178",
"title": "1bcbf565a9cf100e351358bba3f37662dacfb178",
"url": "https://chromium.googlesource.com/chromium/src/+/1bcbf565a9cf100e351358bba3f37662dacfb178"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1bcbf565a9cf100e351358bba3f37662dacfb178",
"ref": "refs/branch-heads/6723"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8895982771226381648"
},
{
"key": "scheduler_job_id",
"value": "chromium-m130/fuchsia-x64-cast-receiver-dbg"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[16:32:15.934] Scheduled build:
{
"id": "8730552709242314993",
"builder": {
"project": "chromium-m130",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"number": 525,
"createdBy": "project:chromium-m130",
"createTime": "2024-11-22T16:32:15.372677529Z",
"updateTime": "2024-11-22T16:32:15.372677529Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1bcbf565a9cf100e351358bba3f37662dacfb178",
"ref": "refs/branch-heads/6723"
}
}
}
[16:32:15.934] Task URL: https://cr-buildbucket.appspot.com/build/8730552709242314993
[16:32:15.934] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:2:0) after 1m23s
[16:32:16.887] Received PubSub notification, asking Buildbucket for the build status
[16:32:16.907] Build status: SCHEDULED
[16:33:39.005] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:2:0)
[16:33:39.034] Build status: SCHEDULED
[16:33:39.034] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:4:0) after 5m16s
[16:38:55.285] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:4:0)
[16:38:55.315] Build status: SCHEDULED
[16:38:55.315] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:5:0) after 2m14s
[16:41:09.431] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:5:0)
[16:41:09.455] Build status: SCHEDULED
[16:41:09.455] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:6:0) after 2m53s
[16:44:02.471] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:6:0)
[16:44:02.526] Build status: SCHEDULED
[16:44:02.526] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:7:0) after 8m48s
[16:52:50.540] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:7:0)
[16:52:50.579] Build status: SCHEDULED
[16:52:50.579] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:8:0) after 1m12s
[16:54:02.606] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:8:0)
[16:54:02.630] Build status: SCHEDULED
[16:54:02.630] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:9:0) after 3m11s
[16:57:13.737] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:9:0)
[16:57:13.754] Build status: SCHEDULED
[16:57:13.754] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:10:0) after 3m47s
[17:01:00.936] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:10:0)
[17:01:00.957] Build status: SCHEDULED
[17:01:00.957] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:11:0) after 5m34s
[17:06:34.977] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:11:0)
[17:06:34.998] Build status: SCHEDULED
[17:06:34.998] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:12:0) after 7m1s
[17:13:36.095] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:12:0)
[17:13:36.113] Build status: SCHEDULED
[17:13:36.113] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:13:0) after 1m30s
[17:15:06.094] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:13:0)
[17:15:06.124] Build status: SCHEDULED
[17:15:06.124] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:14:0) after 4m16s
[17:19:22.179] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:14:0)
[17:19:22.200] Build status: SCHEDULED
[17:19:22.200] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:15:0) after 9m3s
[17:28:25.291] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:15:0)
[17:28:25.309] Build status: SCHEDULED
[17:28:25.309] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:16:0) after 8m23s
[17:36:48.324] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:16:0)
[17:36:48.347] Build status: SCHEDULED
[17:36:48.347] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:17:0) after 6m15s
[17:43:03.389] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:17:0)
[17:43:03.408] Build status: SCHEDULED
[17:43:03.409] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:18:0) after 7m42s
[17:50:45.452] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:18:0)
[17:50:45.476] Build status: SCHEDULED
[17:50:45.477] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:19:0) after 6m37s
[17:57:22.545] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:19:0)
[17:57:22.560] Build status: SCHEDULED
[17:57:22.560] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:20:0) after 9m55s
[18:05:12.160] Received PubSub notification, asking Buildbucket for the build status
[18:05:12.181] Build status: STARTED
[18:07:17.683] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:20:0)
[18:07:17.704] Build status: STARTED
[18:07:17.704] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:22:0) after 8m20s
[18:15:37.721] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:22:0)
[18:15:37.743] Build status: STARTED
[18:15:37.743] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:23:0) after 8m15s
[18:23:53.090] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:23:0)
[18:23:53.123] Build status: STARTED
[18:23:53.123] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:24:0) after 2m16s
[18:26:09.138] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:24:0)
[18:26:09.231] Build status: STARTED
[18:26:09.231] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:25:0) after 7m27s
[18:33:36.236] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:25:0)
[18:33:36.258] Build status: STARTED
[18:33:36.258] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:26:0) after 6m24s
[18:40:00.443] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:26:0)
[18:40:00.504] Build status: STARTED
[18:40:00.504] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:27:0) after 2m22s
[18:42:22.680] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:27:0)
[18:42:22.797] Build status: STARTED
[18:42:22.797] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:28:0) after 2m41s
[18:45:04.112] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:28:0)
[18:45:04.131] Build status: STARTED
[18:45:04.131] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:29:0) after 7m4s
[18:52:08.157] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:29:0)
[18:52:08.237] Build status: STARTED
[18:52:08.237] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:30:0) after 4m23s
[18:56:31.256] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:30:0)
[18:56:31.489] Build status: STARTED
[18:56:31.489] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:31:0) after 5m19s
[19:01:50.639] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:31:0)
[19:01:50.664] Build status: STARTED
[19:01:50.664] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:32:0) after 3m4s
[19:04:54.696] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:32:0)
[19:04:54.716] Build status: STARTED
[19:04:54.716] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:33:0) after 6m10s
[19:11:04.894] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:33:0)
[19:11:04.914] Build status: STARTED
[19:11:04.914] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:34:0) after 4m23s
[19:15:27.936] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:34:0)
[19:15:27.970] Build status: STARTED
[19:15:27.970] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:35:0) after 6m56s
[19:22:24.001] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:35:0)
[19:22:24.022] Build status: STARTED
[19:22:24.022] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:36:0) after 9m53s
[19:32:17.051] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:36:0)
[19:32:17.075] Build status: STARTED
[19:32:17.075] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:37:0) after 2m8s
[19:34:25.091] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:37:0)
[19:34:25.109] Build status: STARTED
[19:34:25.109] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:38:0) after 3m21s
[19:37:46.126] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:38:0)
[19:37:46.146] Build status: STARTED
[19:37:46.146] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:39:0) after 5m11s
[19:42:57.185] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:39:0)
[19:42:57.202] Build status: STARTED
[19:42:57.202] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8895982771226381648:40:0) after 4m53s
[19:45:54.380] Received PubSub notification, asking Buildbucket for the build status
[19:45:54.403] Build:
{
"id": "8730552709242314993",
"builder": {
"project": "chromium-m130",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"number": 525,
"createdBy": "project:chromium-m130",
"createTime": "2024-11-22T16:32:15.372677529Z",
"startTime": "2024-11-22T18:05:11.907355666Z",
"endTime": "2024-11-22T19:45:49.693479597Z",
"updateTime": "2024-11-22T19:45:49.693479597Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1bcbf565a9cf100e351358bba3f37662dacfb178",
"ref": "refs/branch-heads/6723"
}
}
}
[19:45:54.403] Invocation finished in 3h13m40.306196768s with status SUCCEEDED