[19:00:22.744] New invocation is queued and will start shortly
[19:00:24.027] Starting the invocation (attempt 1)
[19:00:24.052] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2aa74de7be233b5801a1a077ca2f8b45e1634fd6"
[19:00:24.052] Popped gitiles commit info from properties and tags
[19:00:24.052] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:00:24.052] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:00:24.052] Buildbucket request:
{
"requestId": "8889994051037324800",
"builder": {
"project": "chromium-m133",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8889994051037324800",
"job": "chromium-m133/fuchsia-x64-cast-receiver-dbg",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/6943",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "255d34135dac6e875e98c53154e12072d969a656"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6943@255d34135dac6e875e98c53154e12072d969a656",
"title": "255d34135dac6e875e98c53154e12072d969a656",
"url": "https://chromium.googlesource.com/chromium/src/+/255d34135dac6e875e98c53154e12072d969a656"
},
{
"gitiles": {
"ref": "refs/branch-heads/6943",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8d9136a10a355c593a4ba4452b5bfb9eb9fa12d6"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6943@8d9136a10a355c593a4ba4452b5bfb9eb9fa12d6",
"title": "8d9136a10a355c593a4ba4452b5bfb9eb9fa12d6",
"url": "https://chromium.googlesource.com/chromium/src/+/8d9136a10a355c593a4ba4452b5bfb9eb9fa12d6"
},
{
"gitiles": {
"ref": "refs/branch-heads/6943",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "76b754591a28aa67b86481cf1f5f53921d502f26"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6943@76b754591a28aa67b86481cf1f5f53921d502f26",
"title": "76b754591a28aa67b86481cf1f5f53921d502f26",
"url": "https://chromium.googlesource.com/chromium/src/+/76b754591a28aa67b86481cf1f5f53921d502f26"
},
{
"gitiles": {
"ref": "refs/branch-heads/6943",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "63a73814ced7f33572202508f5986f95dae2bddd"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6943@63a73814ced7f33572202508f5986f95dae2bddd",
"title": "63a73814ced7f33572202508f5986f95dae2bddd",
"url": "https://chromium.googlesource.com/chromium/src/+/63a73814ced7f33572202508f5986f95dae2bddd"
},
{
"gitiles": {
"ref": "refs/branch-heads/6943",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1c4c214d7fd4c70393e5f82bb6344b2cadd10899"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6943@1c4c214d7fd4c70393e5f82bb6344b2cadd10899",
"title": "1c4c214d7fd4c70393e5f82bb6344b2cadd10899",
"url": "https://chromium.googlesource.com/chromium/src/+/1c4c214d7fd4c70393e5f82bb6344b2cadd10899"
},
{
"gitiles": {
"ref": "refs/branch-heads/6943",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8ab99bacad9df27e7430eee7c62565fed63c32e6"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6943@8ab99bacad9df27e7430eee7c62565fed63c32e6",
"title": "8ab99bacad9df27e7430eee7c62565fed63c32e6",
"url": "https://chromium.googlesource.com/chromium/src/+/8ab99bacad9df27e7430eee7c62565fed63c32e6"
},
{
"gitiles": {
"ref": "refs/branch-heads/6943",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "14e6b028ebb577b122c842bbdd5051f874172de2"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6943@14e6b028ebb577b122c842bbdd5051f874172de2",
"title": "14e6b028ebb577b122c842bbdd5051f874172de2",
"url": "https://chromium.googlesource.com/chromium/src/+/14e6b028ebb577b122c842bbdd5051f874172de2"
},
{
"gitiles": {
"ref": "refs/branch-heads/6943",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "bdeebbc85db2d186f0ceecbeb91cdb8da2b6fb13"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6943@bdeebbc85db2d186f0ceecbeb91cdb8da2b6fb13",
"title": "bdeebbc85db2d186f0ceecbeb91cdb8da2b6fb13",
"url": "https://chromium.googlesource.com/chromium/src/+/bdeebbc85db2d186f0ceecbeb91cdb8da2b6fb13"
},
{
"gitiles": {
"ref": "refs/branch-heads/6943",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2aa74de7be233b5801a1a077ca2f8b45e1634fd6"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6943@2aa74de7be233b5801a1a077ca2f8b45e1634fd6",
"title": "2aa74de7be233b5801a1a077ca2f8b45e1634fd6",
"url": "https://chromium.googlesource.com/chromium/src/+/2aa74de7be233b5801a1a077ca2f8b45e1634fd6"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "2aa74de7be233b5801a1a077ca2f8b45e1634fd6",
"ref": "refs/branch-heads/6943"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8889994051037324800"
},
{
"key": "scheduler_job_id",
"value": "chromium-m133/fuchsia-x64-cast-receiver-dbg"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[19:00:24.746] Scheduled build:
{
"id": "8724563988945504225",
"builder": {
"project": "chromium-m133",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"number": 209,
"createdBy": "project:chromium-m133",
"createTime": "2025-01-27T19:00:24.107140908Z",
"updateTime": "2025-01-27T19:00:24.107140908Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "2aa74de7be233b5801a1a077ca2f8b45e1634fd6",
"ref": "refs/branch-heads/6943"
}
}
}
[19:00:24.746] Task URL: https://cr-buildbucket.appspot.com/build/8724563988945504225
[19:00:24.746] Scheduling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:2:0) after 3m50s
[19:00:25.661] Received PubSub notification, asking Buildbucket for the build status
[19:00:25.716] Build status: SCHEDULED
[19:04:14.735] Handling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:2:0)
[19:04:14.759] Build status: SCHEDULED
[19:04:14.759] Scheduling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:4:0) after 6m35s
[19:10:49.782] Handling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:4:0)
[19:10:49.844] Build status: SCHEDULED
[19:10:49.844] Scheduling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:5:0) after 2m26s
[19:13:15.900] Handling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:5:0)
[19:13:15.919] Build status: SCHEDULED
[19:13:15.919] Scheduling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:6:0) after 6m11s
[19:19:26.909] Handling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:6:0)
[19:19:26.930] Build status: SCHEDULED
[19:19:26.930] Scheduling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:7:0) after 6m55s
[19:26:21.953] Handling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:7:0)
[19:26:21.972] Build status: SCHEDULED
[19:26:21.972] Scheduling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:8:0) after 3m25s
[19:29:47.012] Handling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:8:0)
[19:29:47.032] Build status: SCHEDULED
[19:29:47.032] Scheduling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:9:0) after 8m26s
[19:33:20.543] Received PubSub notification, asking Buildbucket for the build status
[19:33:20.561] Build status: STARTED
[19:38:13.066] Handling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:9:0)
[19:38:13.103] Build status: STARTED
[19:38:13.103] Scheduling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:11:0) after 2m40s
[19:40:53.125] Handling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:11:0)
[19:40:53.163] Build status: STARTED
[19:40:53.163] Scheduling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:12:0) after 2m38s
[19:43:31.288] Handling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:12:0)
[19:43:31.307] Build status: STARTED
[19:43:31.307] Scheduling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:13:0) after 5m44s
[19:49:15.325] Handling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:13:0)
[19:49:15.343] Build status: STARTED
[19:49:15.343] Scheduling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:14:0) after 1m0s
[19:50:15.370] Handling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:14:0)
[19:50:15.413] Build status: STARTED
[19:50:15.414] Scheduling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:15:0) after 5m55s
[19:56:10.531] Handling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:15:0)
[19:56:10.602] Build status: STARTED
[19:56:10.602] Scheduling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:16:0) after 4m37s
[20:00:47.578] Handling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:16:0)
[20:00:47.619] Build status: STARTED
[20:00:47.619] Scheduling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:17:0) after 6m5s
[20:06:52.659] Handling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:17:0)
[20:06:52.716] Build status: STARTED
[20:06:52.716] Scheduling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:18:0) after 6m28s
[20:13:20.741] Handling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:18:0)
[20:13:20.760] Build status: STARTED
[20:13:20.760] Scheduling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:19:0) after 5m39s
[20:18:59.788] Handling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:19:0)
[20:18:59.808] Build status: STARTED
[20:18:59.808] Scheduling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:20:0) after 9m2s
[20:28:01.836] Handling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:20:0)
[20:28:01.894] Build status: STARTED
[20:28:01.894] Scheduling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:21:0) after 8m1s
[20:36:03.064] Handling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:21:0)
[20:36:03.080] Build status: STARTED
[20:36:03.080] Scheduling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:22:0) after 2m37s
[20:38:40.122] Handling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:22:0)
[20:38:40.140] Build status: STARTED
[20:38:40.140] Scheduling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:23:0) after 3m5s
[20:41:45.163] Handling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:23:0)
[20:41:45.180] Build status: STARTED
[20:41:45.180] Scheduling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:24:0) after 9m45s
[20:51:30.274] Handling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:24:0)
[20:51:30.307] Build status: STARTED
[20:51:30.307] Scheduling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:25:0) after 8m1s
[20:59:31.403] Handling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:25:0)
[20:59:31.446] Build status: STARTED
[20:59:31.446] Scheduling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:26:0) after 8m7s
[21:07:38.471] Handling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:26:0)
[21:07:38.486] Build status: STARTED
[21:07:38.486] Scheduling timer "check-buildbucket-build-status" (chromium-m133/fuchsia-x64-cast-receiver-dbg:8889994051037324800:27:0) after 3m58s
[21:08:08.114] Received PubSub notification, asking Buildbucket for the build status
[21:08:08.132] Build:
{
"id": "8724563988945504225",
"builder": {
"project": "chromium-m133",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"number": 209,
"createdBy": "project:chromium-m133",
"createTime": "2025-01-27T19:00:24.107140908Z",
"startTime": "2025-01-27T19:33:18.769856827Z",
"endTime": "2025-01-27T21:08:06.600630136Z",
"updateTime": "2025-01-27T21:08:06.600630136Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "2aa74de7be233b5801a1a077ca2f8b45e1634fd6",
"ref": "refs/branch-heads/6943"
}
}
}
[21:08:08.132] Invocation finished in 2h7m45.403104784s with status SUCCEEDED