[21:20:14.264] New invocation is queued and will start shortly
[21:20:15.467] Starting the invocation (attempt 1)
[21:20:15.505] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/497a292ef50fbb896b8cbc75afe8216605eaa96f"
[21:20:15.505] Popped gitiles commit info from properties and tags
[21:20:15.505] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:20:15.505] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:20:15.506] Buildbucket request:
{
"requestId": "8911819120796243072",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release-minfs"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8911819120796243072",
"job": "fuchsia/core.x64-release-minfs-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "20dddefee9b11eefcadcb1082c165ed3b0ce15d4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@20dddefee9b11eefcadcb1082c165ed3b0ce15d4",
"title": "20dddefee9b11eefcadcb1082c165ed3b0ce15d4",
"url": "https://fuchsia.googlesource.com/integration/+/20dddefee9b11eefcadcb1082c165ed3b0ce15d4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c5661c9aa386a3a6e07a68d9800d1fa4ea7ceb9e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c5661c9aa386a3a6e07a68d9800d1fa4ea7ceb9e",
"title": "c5661c9aa386a3a6e07a68d9800d1fa4ea7ceb9e",
"url": "https://fuchsia.googlesource.com/integration/+/c5661c9aa386a3a6e07a68d9800d1fa4ea7ceb9e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2e6304cf11079f7d39960e0863eadadf91aca97a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2e6304cf11079f7d39960e0863eadadf91aca97a",
"title": "2e6304cf11079f7d39960e0863eadadf91aca97a",
"url": "https://fuchsia.googlesource.com/integration/+/2e6304cf11079f7d39960e0863eadadf91aca97a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e7108125a3c14defdf18fa2cb0d697108a81f7bc"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e7108125a3c14defdf18fa2cb0d697108a81f7bc",
"title": "e7108125a3c14defdf18fa2cb0d697108a81f7bc",
"url": "https://fuchsia.googlesource.com/integration/+/e7108125a3c14defdf18fa2cb0d697108a81f7bc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a998fc569b4ead6ad1beb5c113f5f678a3ee9f3e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a998fc569b4ead6ad1beb5c113f5f678a3ee9f3e",
"title": "a998fc569b4ead6ad1beb5c113f5f678a3ee9f3e",
"url": "https://fuchsia.googlesource.com/integration/+/a998fc569b4ead6ad1beb5c113f5f678a3ee9f3e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "59bc1a6ca8d1c7db9732c1b033dcea6f18b080e7"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@59bc1a6ca8d1c7db9732c1b033dcea6f18b080e7",
"title": "59bc1a6ca8d1c7db9732c1b033dcea6f18b080e7",
"url": "https://fuchsia.googlesource.com/integration/+/59bc1a6ca8d1c7db9732c1b033dcea6f18b080e7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "28ef35648b5335e69ca61eba6653c3d813804c66"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@28ef35648b5335e69ca61eba6653c3d813804c66",
"title": "28ef35648b5335e69ca61eba6653c3d813804c66",
"url": "https://fuchsia.googlesource.com/integration/+/28ef35648b5335e69ca61eba6653c3d813804c66"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "696ffe17b11d1c25177858ce8130fdb982fb9501"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@696ffe17b11d1c25177858ce8130fdb982fb9501",
"title": "696ffe17b11d1c25177858ce8130fdb982fb9501",
"url": "https://fuchsia.googlesource.com/integration/+/696ffe17b11d1c25177858ce8130fdb982fb9501"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "232634535bc6141d74c9c6f17c56a183a25277ad"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@232634535bc6141d74c9c6f17c56a183a25277ad",
"title": "232634535bc6141d74c9c6f17c56a183a25277ad",
"url": "https://fuchsia.googlesource.com/integration/+/232634535bc6141d74c9c6f17c56a183a25277ad"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1e321598d6e028a5f0347eb4a5d09d134c5cde74"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1e321598d6e028a5f0347eb4a5d09d134c5cde74",
"title": "1e321598d6e028a5f0347eb4a5d09d134c5cde74",
"url": "https://fuchsia.googlesource.com/integration/+/1e321598d6e028a5f0347eb4a5d09d134c5cde74"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "43383056d066a00241286045f29e18ed21d2815c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@43383056d066a00241286045f29e18ed21d2815c",
"title": "43383056d066a00241286045f29e18ed21d2815c",
"url": "https://fuchsia.googlesource.com/integration/+/43383056d066a00241286045f29e18ed21d2815c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "82a00533fcb7e24c681f33d25828aec31e76a612"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@82a00533fcb7e24c681f33d25828aec31e76a612",
"title": "82a00533fcb7e24c681f33d25828aec31e76a612",
"url": "https://fuchsia.googlesource.com/integration/+/82a00533fcb7e24c681f33d25828aec31e76a612"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "edfa743d360bab98f388e4b41dd013f5d7844245"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@edfa743d360bab98f388e4b41dd013f5d7844245",
"title": "edfa743d360bab98f388e4b41dd013f5d7844245",
"url": "https://fuchsia.googlesource.com/integration/+/edfa743d360bab98f388e4b41dd013f5d7844245"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "99907092b0957d1810c287e15e20956668ba6fa8"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@99907092b0957d1810c287e15e20956668ba6fa8",
"title": "99907092b0957d1810c287e15e20956668ba6fa8",
"url": "https://fuchsia.googlesource.com/integration/+/99907092b0957d1810c287e15e20956668ba6fa8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "742e8c44b1cc1a3d3e8438e3e91091aed6216387"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@742e8c44b1cc1a3d3e8438e3e91091aed6216387",
"title": "742e8c44b1cc1a3d3e8438e3e91091aed6216387",
"url": "https://fuchsia.googlesource.com/integration/+/742e8c44b1cc1a3d3e8438e3e91091aed6216387"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "497a292ef50fbb896b8cbc75afe8216605eaa96f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@497a292ef50fbb896b8cbc75afe8216605eaa96f",
"title": "497a292ef50fbb896b8cbc75afe8216605eaa96f",
"url": "https://fuchsia.googlesource.com/integration/+/497a292ef50fbb896b8cbc75afe8216605eaa96f"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "497a292ef50fbb896b8cbc75afe8216605eaa96f",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8911819120796243072"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/core.x64-release-minfs-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[21:20:16.101] Scheduled build:
{
"id": "8746389058753392817",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release-minfs"
},
"createdBy": "project:fuchsia",
"createTime": "2024-05-31T21:20:15.578656173Z",
"updateTime": "2024-05-31T21:20:15.578656173Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "497a292ef50fbb896b8cbc75afe8216605eaa96f",
"ref": "refs/heads/main"
}
},
"canary": true
}
[21:20:16.101] Task URL: https://cr-buildbucket.appspot.com/build/8746389058753392817
[21:20:16.101] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-minfs-e5a0ec7f:8911819120796243072:2:0) after 3m11s
[21:20:17.038] Received PubSub notification, asking Buildbucket for the build status
[21:20:17.056] Build status: SCHEDULED
[21:22:38.951] Received PubSub notification, asking Buildbucket for the build status
[21:22:38.982] Build status: STARTED
[21:23:27.119] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-minfs-e5a0ec7f:8911819120796243072:2:0)
[21:23:27.140] Build status: STARTED
[21:23:27.140] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-minfs-e5a0ec7f:8911819120796243072:5:0) after 2m25s
[21:25:52.222] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-minfs-e5a0ec7f:8911819120796243072:5:0)
[21:25:52.243] Build status: STARTED
[21:25:52.243] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-minfs-e5a0ec7f:8911819120796243072:6:0) after 7m11s
[21:33:03.271] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-minfs-e5a0ec7f:8911819120796243072:6:0)
[21:33:03.290] Build status: STARTED
[21:33:03.290] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-minfs-e5a0ec7f:8911819120796243072:7:0) after 4m24s
[21:37:27.356] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-minfs-e5a0ec7f:8911819120796243072:7:0)
[21:37:27.438] Build status: STARTED
[21:37:27.438] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-minfs-e5a0ec7f:8911819120796243072:8:0) after 3m33s
[21:41:00.463] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-minfs-e5a0ec7f:8911819120796243072:8:0)
[21:41:00.477] Build status: STARTED
[21:41:00.477] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-minfs-e5a0ec7f:8911819120796243072:9:0) after 8m34s
[21:49:34.497] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-minfs-e5a0ec7f:8911819120796243072:9:0)
[21:49:34.514] Build status: STARTED
[21:49:34.514] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-minfs-e5a0ec7f:8911819120796243072:10:0) after 5m50s
[21:55:24.529] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-minfs-e5a0ec7f:8911819120796243072:10:0)
[21:55:24.560] Build status: STARTED
[21:55:24.560] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-minfs-e5a0ec7f:8911819120796243072:11:0) after 3m48s
[21:59:12.601] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-minfs-e5a0ec7f:8911819120796243072:11:0)
[21:59:12.617] Build status: STARTED
[21:59:12.617] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-minfs-e5a0ec7f:8911819120796243072:12:0) after 9m40s
[22:08:53.352] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-minfs-e5a0ec7f:8911819120796243072:12:0)
[22:08:53.376] Build status: STARTED
[22:08:53.376] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-minfs-e5a0ec7f:8911819120796243072:13:0) after 8m29s
[22:12:12.342] Received PubSub notification, asking Buildbucket for the build status
[22:12:12.413] Build:
{
"id": "8746389058753392817",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release-minfs"
},
"createdBy": "project:fuchsia",
"createTime": "2024-05-31T21:20:15.578656173Z",
"startTime": "2024-05-31T21:22:38.713281079Z",
"endTime": "2024-05-31T22:12:11.900230247Z",
"updateTime": "2024-05-31T22:12:11.900230247Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "497a292ef50fbb896b8cbc75afe8216605eaa96f",
"ref": "refs/heads/main"
}
},
"canary": true
}
[22:12:12.413] Invocation finished in 51m58.167102905s with status SUCCEEDED