[21:55:12.984] New invocation is queued and will start shortly
[21:55:14.141] Starting the invocation (attempt 1)
[21:55:14.169] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/95a9c3002f68fd3b0cea08077c45aec4f3ab2699"
[21:55:14.169] Popped gitiles commit info from properties and tags
[21:55:14.169] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:55:14.169] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:55:14.169] Buildbucket request:
{
"requestId": "8923413331822718816",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release-ffx_subtools"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8923413331822718816",
"job": "fuchsia/core.x64-release-ffx_subtools-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d5ee198fe245973343a8fc9f44da45b54b26c5ea"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d5ee198fe245973343a8fc9f44da45b54b26c5ea",
"title": "d5ee198fe245973343a8fc9f44da45b54b26c5ea",
"url": "https://fuchsia.googlesource.com/integration/+/d5ee198fe245973343a8fc9f44da45b54b26c5ea"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "66c9864c9f47cbc5da4d7d8165f03ba9ded72f74"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@66c9864c9f47cbc5da4d7d8165f03ba9ded72f74",
"title": "66c9864c9f47cbc5da4d7d8165f03ba9ded72f74",
"url": "https://fuchsia.googlesource.com/integration/+/66c9864c9f47cbc5da4d7d8165f03ba9ded72f74"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "5e03432cb008418d8b0c6141177bc30099c1604b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@5e03432cb008418d8b0c6141177bc30099c1604b",
"title": "5e03432cb008418d8b0c6141177bc30099c1604b",
"url": "https://fuchsia.googlesource.com/integration/+/5e03432cb008418d8b0c6141177bc30099c1604b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4574e8939fff59e50a5f6e03af7b8c1fe81481e5"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4574e8939fff59e50a5f6e03af7b8c1fe81481e5",
"title": "4574e8939fff59e50a5f6e03af7b8c1fe81481e5",
"url": "https://fuchsia.googlesource.com/integration/+/4574e8939fff59e50a5f6e03af7b8c1fe81481e5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "edfaab576da3a31572041f47dfe6e2b99f09f27a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@edfaab576da3a31572041f47dfe6e2b99f09f27a",
"title": "edfaab576da3a31572041f47dfe6e2b99f09f27a",
"url": "https://fuchsia.googlesource.com/integration/+/edfaab576da3a31572041f47dfe6e2b99f09f27a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "83dddb0deb27c5f8d914f487d71928197d804d53"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@83dddb0deb27c5f8d914f487d71928197d804d53",
"title": "83dddb0deb27c5f8d914f487d71928197d804d53",
"url": "https://fuchsia.googlesource.com/integration/+/83dddb0deb27c5f8d914f487d71928197d804d53"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e5943547ba2af93c69f1c5c6e4717a1bc4809990"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e5943547ba2af93c69f1c5c6e4717a1bc4809990",
"title": "e5943547ba2af93c69f1c5c6e4717a1bc4809990",
"url": "https://fuchsia.googlesource.com/integration/+/e5943547ba2af93c69f1c5c6e4717a1bc4809990"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "7fe033b33cd766859d165c0f3f9c142bc6ca628f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@7fe033b33cd766859d165c0f3f9c142bc6ca628f",
"title": "7fe033b33cd766859d165c0f3f9c142bc6ca628f",
"url": "https://fuchsia.googlesource.com/integration/+/7fe033b33cd766859d165c0f3f9c142bc6ca628f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9c6f30010a9c49b86d02bbc9137c61f795241df3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9c6f30010a9c49b86d02bbc9137c61f795241df3",
"title": "9c6f30010a9c49b86d02bbc9137c61f795241df3",
"url": "https://fuchsia.googlesource.com/integration/+/9c6f30010a9c49b86d02bbc9137c61f795241df3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "557b94dd10b3b00716e8ac4fb3384e66ec11647a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@557b94dd10b3b00716e8ac4fb3384e66ec11647a",
"title": "557b94dd10b3b00716e8ac4fb3384e66ec11647a",
"url": "https://fuchsia.googlesource.com/integration/+/557b94dd10b3b00716e8ac4fb3384e66ec11647a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e9cce718a0fddf32be5e183330978bd2d75b1de2"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e9cce718a0fddf32be5e183330978bd2d75b1de2",
"title": "e9cce718a0fddf32be5e183330978bd2d75b1de2",
"url": "https://fuchsia.googlesource.com/integration/+/e9cce718a0fddf32be5e183330978bd2d75b1de2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "eb0c7be4664d77b41b80cffd05fef52e7d69c51d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@eb0c7be4664d77b41b80cffd05fef52e7d69c51d",
"title": "eb0c7be4664d77b41b80cffd05fef52e7d69c51d",
"url": "https://fuchsia.googlesource.com/integration/+/eb0c7be4664d77b41b80cffd05fef52e7d69c51d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "95a9c3002f68fd3b0cea08077c45aec4f3ab2699"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@95a9c3002f68fd3b0cea08077c45aec4f3ab2699",
"title": "95a9c3002f68fd3b0cea08077c45aec4f3ab2699",
"url": "https://fuchsia.googlesource.com/integration/+/95a9c3002f68fd3b0cea08077c45aec4f3ab2699"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "95a9c3002f68fd3b0cea08077c45aec4f3ab2699",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8923413331822718816"
},
{
"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:55:14.575] Scheduled build:
{
"id": "8757983269867844353",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release-ffx_subtools"
},
"createdBy": "project:fuchsia",
"createTime": "2024-01-24T21:55:14.219995719Z",
"updateTime": "2024-01-24T21:55:14.219995719Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "95a9c3002f68fd3b0cea08077c45aec4f3ab2699",
"ref": "refs/heads/main"
}
}
}
[21:55:14.575] Task URL: https://cr-buildbucket.appspot.com/build/8757983269867844353
[21:55:14.575] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923413331822718816:2:0) after 5m42s
[21:55:15.552] Received PubSub notification, asking Buildbucket for the build status
[21:55:15.569] Build status: STARTED
[21:55:16.618] Received PubSub notification, asking Buildbucket for the build status
[21:55:16.663] Build status: STARTED
[22:00:56.610] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923413331822718816:2:0)
[22:00:56.634] Build status: STARTED
[22:00:56.634] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923413331822718816:5:0) after 8m53s
[22:09:49.651] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923413331822718816:5:0)
[22:09:49.670] Build status: STARTED
[22:09:49.670] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923413331822718816:6:0) after 2m12s
[22:12:01.716] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923413331822718816:6:0)
[22:12:01.755] Build status: STARTED
[22:12:01.755] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923413331822718816:7:0) after 2m16s
[22:14:17.763] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923413331822718816:7:0)
[22:14:17.878] Build status: STARTED
[22:14:17.878] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923413331822718816:8:0) after 8m29s
[22:22:46.892] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923413331822718816:8:0)
[22:22:46.961] Build status: STARTED
[22:22:46.961] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923413331822718816:9:0) after 3m24s
[22:26:10.991] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923413331822718816:9:0)
[22:26:11.020] Build status: STARTED
[22:26:11.020] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923413331822718816:10:0) after 3m11s
[22:29:22.016] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923413331822718816:10:0)
[22:29:22.051] Build status: STARTED
[22:29:22.051] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923413331822718816:11:0) after 4m49s
[22:34:11.028] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923413331822718816:11:0)
[22:34:11.046] Build status: STARTED
[22:34:11.046] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923413331822718816:12:0) after 1m39s
[22:35:50.063] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923413331822718816:12:0)
[22:35:50.102] Build status: STARTED
[22:35:50.102] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923413331822718816:13:0) after 4m42s
[22:40:32.151] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923413331822718816:13:0)
[22:40:32.169] Build status: STARTED
[22:40:32.169] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923413331822718816:14:0) after 8m47s
[22:49:19.170] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923413331822718816:14:0)
[22:49:19.186] Build status: STARTED
[22:49:19.186] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923413331822718816:15:0) after 6m54s
[22:56:13.200] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923413331822718816:15:0)
[22:56:13.308] Build status: STARTED
[22:56:13.308] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923413331822718816:16:0) after 6m27s
[22:59:55.024] Received PubSub notification, asking Buildbucket for the build status
[22:59:55.037] Build:
{
"id": "8757983269867844353",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release-ffx_subtools"
},
"createdBy": "project:fuchsia",
"createTime": "2024-01-24T21:55:14.219995719Z",
"startTime": "2024-01-24T21:55:14.978937Z",
"endTime": "2024-01-24T22:59:54.289862Z",
"updateTime": "2024-01-24T22:59:54.289862Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "95a9c3002f68fd3b0cea08077c45aec4f3ab2699",
"ref": "refs/heads/main"
}
}
}
[22:59:55.037] Invocation finished in 1h4m42.066683267s with status SUCCEEDED