[19:41:42.067] New invocation is queued and will start shortly
[19:41:43.162] Starting the invocation (attempt 1)
[19:41:43.188] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/2c294f13317362e04e4fdf1c7a770b867e1d0359"
[19:41:43.188] Popped gitiles commit info from properties and tags
[19:41:43.188] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:41:43.188] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:41:43.189] Buildbucket request:
{
"requestId": "8910285171750374688",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "sdk-core-linux"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8910285171750374688",
"job": "fuchsia/sdk-core-linux-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "73f91135f4df07c7f189fe7b7739c030985b70fa"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@73f91135f4df07c7f189fe7b7739c030985b70fa",
"title": "73f91135f4df07c7f189fe7b7739c030985b70fa",
"url": "https://fuchsia.googlesource.com/integration/+/73f91135f4df07c7f189fe7b7739c030985b70fa"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "fa67458744cd5604556f01b82421a7b052e94f45"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@fa67458744cd5604556f01b82421a7b052e94f45",
"title": "fa67458744cd5604556f01b82421a7b052e94f45",
"url": "https://fuchsia.googlesource.com/integration/+/fa67458744cd5604556f01b82421a7b052e94f45"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "062e63c9449cc254389b04b2ad07332988a3e43f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@062e63c9449cc254389b04b2ad07332988a3e43f",
"title": "062e63c9449cc254389b04b2ad07332988a3e43f",
"url": "https://fuchsia.googlesource.com/integration/+/062e63c9449cc254389b04b2ad07332988a3e43f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "67c36f69a2f5983e58c82f880f33dc3a86f6bdec"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@67c36f69a2f5983e58c82f880f33dc3a86f6bdec",
"title": "67c36f69a2f5983e58c82f880f33dc3a86f6bdec",
"url": "https://fuchsia.googlesource.com/integration/+/67c36f69a2f5983e58c82f880f33dc3a86f6bdec"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "66681c392f4debc6e8878e008e7dd755b0306a72"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@66681c392f4debc6e8878e008e7dd755b0306a72",
"title": "66681c392f4debc6e8878e008e7dd755b0306a72",
"url": "https://fuchsia.googlesource.com/integration/+/66681c392f4debc6e8878e008e7dd755b0306a72"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2c294f13317362e04e4fdf1c7a770b867e1d0359"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2c294f13317362e04e4fdf1c7a770b867e1d0359",
"title": "2c294f13317362e04e4fdf1c7a770b867e1d0359",
"url": "https://fuchsia.googlesource.com/integration/+/2c294f13317362e04e4fdf1c7a770b867e1d0359"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "2c294f13317362e04e4fdf1c7a770b867e1d0359",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8910285171750374688"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/sdk-core-linux-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[19:41:43.846] Scheduled build:
{
"id": "8744855109849588337",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "sdk-core-linux"
},
"createdBy": "project:fuchsia",
"createTime": "2024-06-17T19:41:43.250797055Z",
"updateTime": "2024-06-17T19:41:43.250797055Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "2c294f13317362e04e4fdf1c7a770b867e1d0359",
"ref": "refs/heads/main"
}
}
}
[19:41:43.846] Task URL: https://cr-buildbucket.appspot.com/build/8744855109849588337
[19:41:43.846] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910285171750374688:2:0) after 7m35s
[19:41:44.913] Received PubSub notification, asking Buildbucket for the build status
[19:41:44.928] Build status: SCHEDULED
[19:41:48.132] Received PubSub notification, asking Buildbucket for the build status
[19:41:48.157] Build status: STARTED
[19:49:18.907] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910285171750374688:2:0)
[19:49:18.943] Build status: STARTED
[19:49:18.943] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910285171750374688:5:0) after 6m59s
[19:56:17.969] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910285171750374688:5:0)
[19:56:18.164] Build status: STARTED
[19:56:18.164] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910285171750374688:6:0) after 6m39s
[20:02:57.188] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910285171750374688:6:0)
[20:02:57.208] Build status: STARTED
[20:02:57.208] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910285171750374688:7:0) after 2m28s
[20:05:25.185] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910285171750374688:7:0)
[20:05:25.215] Build status: STARTED
[20:05:25.215] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910285171750374688:8:0) after 6m15s
[20:11:40.194] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910285171750374688:8:0)
[20:11:40.226] Build status: STARTED
[20:11:40.226] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910285171750374688:9:0) after 1m25s
[20:13:05.293] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910285171750374688:9:0)
[20:13:05.326] Build status: STARTED
[20:13:05.326] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910285171750374688:10:0) after 8m0s
[20:21:05.402] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910285171750374688:10:0)
[20:21:05.424] Build status: STARTED
[20:21:05.424] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910285171750374688:11:0) after 6m51s
[20:26:25.289] Received PubSub notification, asking Buildbucket for the build status
[20:26:25.315] Build:
{
"id": "8744855109849588337",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "sdk-core-linux"
},
"createdBy": "project:fuchsia",
"createTime": "2024-06-17T19:41:43.250797055Z",
"startTime": "2024-06-17T19:41:47.943256815Z",
"endTime": "2024-06-17T20:26:24.870188231Z",
"updateTime": "2024-06-17T20:26:24.870188231Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "2c294f13317362e04e4fdf1c7a770b867e1d0359",
"ref": "refs/heads/main"
}
}
}
[20:26:25.315] Invocation finished in 44m43.261536529s with status SUCCEEDED