[04:13:42.685] New invocation is queued and will start shortly
[04:13:43.787] Starting the invocation (attempt 1)
[04:13:43.816] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/46a47f2020147d656a2e362a0b566079479a79ef"
[04:13:43.816] Popped gitiles commit info from properties and tags
[04:13:43.816] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:13:43.816] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:13:43.817] Buildbucket request:
{
"requestId": "8896844607810667760",
"builder": {
"project": "chromium-m131",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8896844607810667760",
"job": "chromium-m131/fuchsia-x64-cast-receiver-dbg",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3b4eeaa614feaa56eca5eb4220c458db36e676fb"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@3b4eeaa614feaa56eca5eb4220c458db36e676fb",
"title": "3b4eeaa614feaa56eca5eb4220c458db36e676fb",
"url": "https://chromium.googlesource.com/chromium/src/+/3b4eeaa614feaa56eca5eb4220c458db36e676fb"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "46a47f2020147d656a2e362a0b566079479a79ef"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@46a47f2020147d656a2e362a0b566079479a79ef",
"title": "46a47f2020147d656a2e362a0b566079479a79ef",
"url": "https://chromium.googlesource.com/chromium/src/+/46a47f2020147d656a2e362a0b566079479a79ef"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "46a47f2020147d656a2e362a0b566079479a79ef",
"ref": "refs/branch-heads/6778"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8896844607810667760"
},
{
"key": "scheduler_job_id",
"value": "chromium-m131/fuchsia-x64-cast-receiver-dbg"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[04:13:44.344] Scheduled build:
{
"id": "8731414545910077729",
"builder": {
"project": "chromium-m131",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"number": 243,
"createdBy": "project:chromium-m131",
"createTime": "2024-11-13T04:13:43.877516912Z",
"updateTime": "2024-11-13T04:13:43.877516912Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "46a47f2020147d656a2e362a0b566079479a79ef",
"ref": "refs/branch-heads/6778"
}
}
}
[04:13:44.344] Task URL: https://cr-buildbucket.appspot.com/build/8731414545910077729
[04:13:44.344] Scheduling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:2:0) after 6m58s
[04:13:45.286] Received PubSub notification, asking Buildbucket for the build status
[04:13:45.322] Build status: SCHEDULED
[04:20:42.368] Handling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:2:0)
[04:20:42.398] Build status: SCHEDULED
[04:20:42.398] Scheduling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:4:0) after 7m9s
[04:27:51.412] Handling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:4:0)
[04:27:51.536] Build status: SCHEDULED
[04:27:51.536] Scheduling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:5:0) after 1m24s
[04:29:15.622] Handling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:5:0)
[04:29:15.641] Build status: SCHEDULED
[04:29:15.641] Scheduling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:6:0) after 8m18s
[04:37:33.675] Handling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:6:0)
[04:37:33.840] Build status: SCHEDULED
[04:37:33.840] Scheduling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:7:0) after 4m38s
[04:42:11.818] Handling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:7:0)
[04:42:11.847] Build status: SCHEDULED
[04:42:11.847] Scheduling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:8:0) after 5m11s
[04:47:22.963] Handling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:8:0)
[04:47:22.986] Build status: SCHEDULED
[04:47:22.986] Scheduling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:9:0) after 3m20s
[04:50:43.001] Handling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:9:0)
[04:50:43.103] Build status: SCHEDULED
[04:50:43.103] Scheduling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:10:0) after 5m18s
[04:56:01.216] Handling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:10:0)
[04:56:01.232] Build status: SCHEDULED
[04:56:01.232] Scheduling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:11:0) after 2m35s
[04:58:36.643] Handling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:11:0)
[04:58:36.683] Build status: SCHEDULED
[04:58:36.683] Scheduling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:12:0) after 4m25s
[05:03:01.697] Handling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:12:0)
[05:03:01.721] Build status: SCHEDULED
[05:03:01.721] Scheduling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:13:0) after 5m6s
[05:08:07.738] Handling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:13:0)
[05:08:07.764] Build status: SCHEDULED
[05:08:07.764] Scheduling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:14:0) after 6m51s
[05:14:58.810] Handling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:14:0)
[05:14:58.838] Build status: SCHEDULED
[05:14:58.839] Scheduling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:15:0) after 5m47s
[05:20:45.931] Handling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:15:0)
[05:20:45.952] Build status: SCHEDULED
[05:20:45.952] Scheduling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:16:0) after 4m6s
[05:24:51.972] Handling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:16:0)
[05:24:52.030] Build status: SCHEDULED
[05:24:52.030] Scheduling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:17:0) after 7m7s
[05:31:59.047] Handling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:17:0)
[05:31:59.068] Build status: SCHEDULED
[05:31:59.068] Scheduling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:18:0) after 6m13s
[05:38:12.276] Handling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:18:0)
[05:38:12.302] Build status: SCHEDULED
[05:38:12.302] Scheduling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:19:0) after 4m20s
[05:42:32.330] Handling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:19:0)
[05:42:32.363] Build status: SCHEDULED
[05:42:32.363] Scheduling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:20:0) after 4m35s
[05:47:07.481] Handling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:20:0)
[05:47:07.510] Build status: SCHEDULED
[05:47:07.510] Scheduling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:21:0) after 3m54s
[05:51:01.589] Handling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:21:0)
[05:51:01.609] Build status: SCHEDULED
[05:51:01.609] Scheduling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:22:0) after 6m2s
[05:57:03.859] Handling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:22:0)
[05:57:03.881] Build status: SCHEDULED
[05:57:03.881] Scheduling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:23:0) after 5m58s
[05:59:21.609] Received PubSub notification, asking Buildbucket for the build status
[05:59:21.644] Build status: STARTED
[06:03:01.890] Handling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:23:0)
[06:03:01.908] Build status: STARTED
[06:03:01.908] Scheduling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:25:0) after 4m0s
[06:07:02.230] Handling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:25:0)
[06:07:02.253] Build status: STARTED
[06:07:02.253] Scheduling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:26:0) after 3m33s
[06:10:35.357] Handling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:26:0)
[06:10:35.375] Build status: STARTED
[06:10:35.375] Scheduling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:27:0) after 1m39s
[06:12:14.467] Handling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:27:0)
[06:12:14.483] Build status: STARTED
[06:12:14.483] Scheduling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:28:0) after 4m51s
[06:17:05.579] Handling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:28:0)
[06:17:05.625] Build status: STARTED
[06:17:05.625] Scheduling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:29:0) after 1m54s
[06:18:59.777] Handling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:29:0)
[06:18:59.805] Build status: STARTED
[06:18:59.805] Scheduling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:30:0) after 8m40s
[06:27:39.918] Handling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:30:0)
[06:27:39.947] Build status: STARTED
[06:27:39.947] Scheduling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:31:0) after 6m36s
[06:34:15.960] Handling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:31:0)
[06:34:15.996] Build status: STARTED
[06:34:15.996] Scheduling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:32:0) after 3m56s
[06:38:12.017] Handling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:32:0)
[06:38:12.065] Build status: STARTED
[06:38:12.065] Scheduling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:33:0) after 7m26s
[06:45:38.079] Handling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:33:0)
[06:45:38.095] Build status: STARTED
[06:45:38.095] Scheduling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:34:0) after 1m47s
[06:47:25.123] Handling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:34:0)
[06:47:25.154] Build status: STARTED
[06:47:25.154] Scheduling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:35:0) after 6m4s
[06:53:29.431] Handling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:35:0)
[06:53:29.448] Build status: STARTED
[06:53:29.448] Scheduling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:36:0) after 4m48s
[06:58:17.463] Handling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:36:0)
[06:58:17.495] Build status: STARTED
[06:58:17.495] Scheduling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:37:0) after 4m47s
[07:03:04.832] Handling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:37:0)
[07:03:04.877] Build status: STARTED
[07:03:04.877] Scheduling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:38:0) after 5m21s
[07:08:26.030] Handling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:38:0)
[07:08:26.066] Build status: STARTED
[07:08:26.067] Scheduling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:39:0) after 3m55s
[07:12:21.085] Handling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:39:0)
[07:12:21.134] Build status: STARTED
[07:12:21.134] Scheduling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:40:0) after 7m12s
[07:19:33.154] Handling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:40:0)
[07:19:33.175] Build status: STARTED
[07:19:33.175] Scheduling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:41:0) after 9m29s
[07:29:02.541] Handling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:41:0)
[07:29:02.610] Build status: STARTED
[07:29:02.610] Scheduling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:42:0) after 9m17s
[07:38:19.671] Handling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:42:0)
[07:38:19.697] Build status: STARTED
[07:38:19.697] Scheduling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:43:0) after 9m25s
[07:47:44.723] Handling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:43:0)
[07:47:44.756] Build status: STARTED
[07:47:44.756] Scheduling timer "check-buildbucket-build-status" (chromium-m131/fuchsia-x64-cast-receiver-dbg:8896844607810667760:44:0) after 6m5s
[07:48:38.596] Received PubSub notification, asking Buildbucket for the build status
[07:48:38.615] Build:
{
"id": "8731414545910077729",
"builder": {
"project": "chromium-m131",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"number": 243,
"createdBy": "project:chromium-m131",
"createTime": "2024-11-13T04:13:43.877516912Z",
"startTime": "2024-11-13T05:59:18.932880649Z",
"endTime": "2024-11-13T07:48:38.141666821Z",
"updateTime": "2024-11-13T07:48:38.141666821Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "46a47f2020147d656a2e362a0b566079479a79ef",
"ref": "refs/branch-heads/6778"
}
}
}
[07:48:38.615] Invocation finished in 3h34m55.934025621s with status SUCCEEDED