[21:14:12.988] New invocation is queued and will start shortly
[21:14:14.063] Starting the invocation (attempt 1)
[21:14:14.086] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/742e8c44b1cc1a3d3e8438e3e91091aed6216387"
[21:14:14.086] Popped gitiles commit info from properties and tags
[21:14:14.086] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:14:14.087] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:14:14.087] Buildbucket request:
{
"requestId": "8911819499613230032",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release-ffx_subtools"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8911819499613230032",
"job": "fuchsia/core.x64-release-ffx_subtools-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"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "742e8c44b1cc1a3d3e8438e3e91091aed6216387",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8911819499613230032"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/core.x64-release-ffx_subtools-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[21:14:14.601] Scheduled build:
{
"id": "8746389437698218913",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release-ffx_subtools"
},
"createdBy": "project:fuchsia",
"createTime": "2024-05-31T21:14:14.188313146Z",
"updateTime": "2024-05-31T21:14:14.188313146Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "742e8c44b1cc1a3d3e8438e3e91091aed6216387",
"ref": "refs/heads/main"
}
}
}
[21:14:14.601] Task URL: https://cr-buildbucket.appspot.com/build/8746389437698218913
[21:14:14.601] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911819499613230032:2:0) after 7m54s
[21:14:15.635] Received PubSub notification, asking Buildbucket for the build status
[21:14:15.650] Build status: SCHEDULED
[21:15:15.397] Received PubSub notification, asking Buildbucket for the build status
[21:15:15.414] Build status: STARTED
[21:22:08.700] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911819499613230032:2:0)
[21:22:08.734] Build status: STARTED
[21:22:08.734] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911819499613230032:5:0) after 4m13s
[21:26:21.790] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911819499613230032:5:0)
[21:26:21.812] Build status: STARTED
[21:26:21.812] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911819499613230032:6:0) after 6m4s
[21:32:25.837] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911819499613230032:6:0)
[21:32:25.855] Build status: STARTED
[21:32:25.855] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911819499613230032:7:0) after 6m52s
[21:39:17.993] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911819499613230032:7:0)
[21:39:18.252] Build status: STARTED
[21:39:18.252] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911819499613230032:8:0) after 8m26s
[21:47:44.277] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911819499613230032:8:0)
[21:47:44.369] Build status: STARTED
[21:47:44.369] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911819499613230032:9:0) after 8m26s
[21:56:10.394] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911819499613230032:9:0)
[21:56:10.412] Build status: STARTED
[21:56:10.412] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911819499613230032:10:0) after 6m48s
[22:02:58.430] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911819499613230032:10:0)
[22:02:58.447] Build status: STARTED
[22:02:58.447] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911819499613230032:11:0) after 4m34s
[22:07:32.475] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911819499613230032:11:0)
[22:07:32.502] Build status: STARTED
[22:07:32.502] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911819499613230032:12:0) after 4m26s
[22:11:58.568] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911819499613230032:12:0)
[22:11:58.588] Build status: STARTED
[22:11:58.588] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911819499613230032:13:0) after 5m44s
[22:17:42.588] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911819499613230032:13:0)
[22:17:42.613] Build status: STARTED
[22:17:42.613] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911819499613230032:14:0) after 1m37s
[22:19:19.609] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911819499613230032:14:0)
[22:19:19.626] Build status: STARTED
[22:19:19.626] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911819499613230032:15:0) after 4m32s
[22:23:51.666] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911819499613230032:15:0)
[22:23:51.762] Build status: STARTED
[22:23:51.762] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911819499613230032:16:0) after 7m44s
[22:31:36.058] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911819499613230032:16:0)
[22:31:36.124] Build status: STARTED
[22:31:36.124] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911819499613230032:17:0) after 6m19s
[22:37:55.150] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911819499613230032:17:0)
[22:37:55.167] Build status: STARTED
[22:37:55.167] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911819499613230032:18:0) after 9m25s
[22:38:53.448] Received PubSub notification, asking Buildbucket for the build status
[22:38:53.492] Build:
{
"id": "8746389437698218913",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release-ffx_subtools"
},
"createdBy": "project:fuchsia",
"createTime": "2024-05-31T21:14:14.188313146Z",
"startTime": "2024-05-31T21:15:14.739368174Z",
"endTime": "2024-05-31T22:38:52.926714880Z",
"updateTime": "2024-05-31T22:38:52.926714880Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "742e8c44b1cc1a3d3e8438e3e91091aed6216387",
"ref": "refs/heads/main"
}
}
}
[22:38:53.492] Invocation finished in 1h24m40.514588582s with status SUCCEEDED