[23:54:32.488] New invocation is queued and will start shortly
[23:54:33.662] Starting the invocation (attempt 1)
[23:54:33.692] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/f675ed58fe7e9872b6d1e7ce959af163972dfee0"
[23:54:33.692] Popped gitiles commit info from properties and tags
[23:54:33.692] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:54:33.693] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:54:33.694] Buildbucket request:
{
"requestId": "8909363294738155968",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "sdk-core-linux"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8909363294738155968",
"job": "fuchsia/sdk-core-linux-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8ee3e772762e32ee493ac9d3c62a5cbadda40ca7"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8ee3e772762e32ee493ac9d3c62a5cbadda40ca7",
"title": "8ee3e772762e32ee493ac9d3c62a5cbadda40ca7",
"url": "https://fuchsia.googlesource.com/integration/+/8ee3e772762e32ee493ac9d3c62a5cbadda40ca7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "472e13d2705e989c561c6cbc2051973d9766f8e4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@472e13d2705e989c561c6cbc2051973d9766f8e4",
"title": "472e13d2705e989c561c6cbc2051973d9766f8e4",
"url": "https://fuchsia.googlesource.com/integration/+/472e13d2705e989c561c6cbc2051973d9766f8e4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "316a005b837b38a3548651ce47ebb97eb72b718e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@316a005b837b38a3548651ce47ebb97eb72b718e",
"title": "316a005b837b38a3548651ce47ebb97eb72b718e",
"url": "https://fuchsia.googlesource.com/integration/+/316a005b837b38a3548651ce47ebb97eb72b718e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3e7e0a688e563dac45a5f39cede29e855a405664"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3e7e0a688e563dac45a5f39cede29e855a405664",
"title": "3e7e0a688e563dac45a5f39cede29e855a405664",
"url": "https://fuchsia.googlesource.com/integration/+/3e7e0a688e563dac45a5f39cede29e855a405664"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "bfd1f3521adfa0a72782a41dea2475d4be41eaa1"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@bfd1f3521adfa0a72782a41dea2475d4be41eaa1",
"title": "bfd1f3521adfa0a72782a41dea2475d4be41eaa1",
"url": "https://fuchsia.googlesource.com/integration/+/bfd1f3521adfa0a72782a41dea2475d4be41eaa1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f5435e0aa8f37bb6802e988b38b3e8be09576d3d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f5435e0aa8f37bb6802e988b38b3e8be09576d3d",
"title": "f5435e0aa8f37bb6802e988b38b3e8be09576d3d",
"url": "https://fuchsia.googlesource.com/integration/+/f5435e0aa8f37bb6802e988b38b3e8be09576d3d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9353843f10ef5c5b19a54d04b5340d33f4d73015"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9353843f10ef5c5b19a54d04b5340d33f4d73015",
"title": "9353843f10ef5c5b19a54d04b5340d33f4d73015",
"url": "https://fuchsia.googlesource.com/integration/+/9353843f10ef5c5b19a54d04b5340d33f4d73015"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "441f0569fdfe21fed4010ed1ae44242485444e68"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@441f0569fdfe21fed4010ed1ae44242485444e68",
"title": "441f0569fdfe21fed4010ed1ae44242485444e68",
"url": "https://fuchsia.googlesource.com/integration/+/441f0569fdfe21fed4010ed1ae44242485444e68"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b8ceb8c90f956e60b2576d155a5731c817baab8b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b8ceb8c90f956e60b2576d155a5731c817baab8b",
"title": "b8ceb8c90f956e60b2576d155a5731c817baab8b",
"url": "https://fuchsia.googlesource.com/integration/+/b8ceb8c90f956e60b2576d155a5731c817baab8b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0b39e18f5a778fb693fb1b81329529f8a4b8c978"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0b39e18f5a778fb693fb1b81329529f8a4b8c978",
"title": "0b39e18f5a778fb693fb1b81329529f8a4b8c978",
"url": "https://fuchsia.googlesource.com/integration/+/0b39e18f5a778fb693fb1b81329529f8a4b8c978"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ed8974761c83622b5d2e344417699f5798ce570b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ed8974761c83622b5d2e344417699f5798ce570b",
"title": "ed8974761c83622b5d2e344417699f5798ce570b",
"url": "https://fuchsia.googlesource.com/integration/+/ed8974761c83622b5d2e344417699f5798ce570b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f675ed58fe7e9872b6d1e7ce959af163972dfee0"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f675ed58fe7e9872b6d1e7ce959af163972dfee0",
"title": "f675ed58fe7e9872b6d1e7ce959af163972dfee0",
"url": "https://fuchsia.googlesource.com/integration/+/f675ed58fe7e9872b6d1e7ce959af163972dfee0"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "f675ed58fe7e9872b6d1e7ce959af163972dfee0",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8909363294738155968"
},
{
"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"
}
}
[23:54:34.151] Scheduled build:
{
"id": "8743933232740852785",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "sdk-core-linux"
},
"createdBy": "project:fuchsia",
"createTime": "2024-06-27T23:54:33.771468922Z",
"updateTime": "2024-06-27T23:54:33.771468922Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "f675ed58fe7e9872b6d1e7ce959af163972dfee0",
"ref": "refs/heads/main"
}
}
}
[23:54:34.151] Task URL: https://cr-buildbucket.appspot.com/build/8743933232740852785
[23:54:34.151] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8909363294738155968:2:0) after 7m7s
[23:54:35.079] Received PubSub notification, asking Buildbucket for the build status
[23:54:35.108] Build status: SCHEDULED
[23:54:38.841] Received PubSub notification, asking Buildbucket for the build status
[23:54:38.858] Build status: STARTED
[00:01:41.185] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8909363294738155968:2:0)
[00:01:41.205] Build status: STARTED
[00:01:41.205] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8909363294738155968:5:0) after 4m9s
[00:05:50.964] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8909363294738155968:5:0)
[00:05:50.991] Build status: STARTED
[00:05:50.991] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8909363294738155968:6:0) after 6m43s
[00:12:34.005] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8909363294738155968:6:0)
[00:12:34.035] Build status: STARTED
[00:12:34.035] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8909363294738155968:7:0) after 5m45s
[00:18:19.089] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8909363294738155968:7:0)
[00:18:19.126] Build status: STARTED
[00:18:19.126] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8909363294738155968:8:0) after 1m16s
[00:19:35.250] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8909363294738155968:8:0)
[00:19:35.313] Build status: STARTED
[00:19:35.313] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8909363294738155968:9:0) after 2m3s
[00:21:38.376] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8909363294738155968:9:0)
[00:21:38.393] Build status: STARTED
[00:21:38.393] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8909363294738155968:10:0) after 8m2s
[00:29:40.409] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8909363294738155968:10:0)
[00:29:40.434] Build status: STARTED
[00:29:40.434] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8909363294738155968:11:0) after 2m4s
[00:31:44.450] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8909363294738155968:11:0)
[00:31:44.469] Build status: STARTED
[00:31:44.469] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8909363294738155968:12:0) after 2m59s
[00:34:43.452] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8909363294738155968:12:0)
[00:34:43.468] Build status: STARTED
[00:34:43.468] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8909363294738155968:13:0) after 5m52s
[00:40:35.485] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8909363294738155968:13:0)
[00:40:35.572] Build status: STARTED
[00:40:35.572] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8909363294738155968:14:0) after 8m23s
[00:44:01.794] Received PubSub notification, asking Buildbucket for the build status
[00:44:01.831] Build:
{
"id": "8743933232740852785",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "sdk-core-linux"
},
"createdBy": "project:fuchsia",
"createTime": "2024-06-27T23:54:33.771468922Z",
"startTime": "2024-06-27T23:54:38.635700527Z",
"endTime": "2024-06-28T00:44:01.301779261Z",
"updateTime": "2024-06-28T00:44:01.301779261Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "f675ed58fe7e9872b6d1e7ce959af163972dfee0",
"ref": "refs/heads/main"
}
}
}
[00:44:01.831] Invocation finished in 49m29.3488325s with status SUCCEEDED