[21:04:42.570] New invocation is queued and will start shortly
[21:04:43.633] Starting the invocation (attempt 1)
[21:04:43.672] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/ec9515c3778ae5e819aee8c07bc93143d6a113b3"
[21:04:43.672] Popped gitiles commit info from properties and tags
[21:04:43.672] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:04:43.673] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:04:43.673] Buildbucket request:
{
"requestId": "8937912024060143344",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8937912024060143344",
"job": "fuchsia/core.x64-debug-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "5f56699077a07bf33919ee4791a3f08b9dd43784"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@5f56699077a07bf33919ee4791a3f08b9dd43784",
"title": "5f56699077a07bf33919ee4791a3f08b9dd43784",
"url": "https://fuchsia.googlesource.com/integration/+/5f56699077a07bf33919ee4791a3f08b9dd43784"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1340d5be1e21f5f267b18718178627169b1c3a93"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1340d5be1e21f5f267b18718178627169b1c3a93",
"title": "1340d5be1e21f5f267b18718178627169b1c3a93",
"url": "https://fuchsia.googlesource.com/integration/+/1340d5be1e21f5f267b18718178627169b1c3a93"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "75517ad44815acfd4491cc7cf4595491874966fd"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@75517ad44815acfd4491cc7cf4595491874966fd",
"title": "75517ad44815acfd4491cc7cf4595491874966fd",
"url": "https://fuchsia.googlesource.com/integration/+/75517ad44815acfd4491cc7cf4595491874966fd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "27c1a938ec9e71aea7e33eb23ba9b138a6499d3a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@27c1a938ec9e71aea7e33eb23ba9b138a6499d3a",
"title": "27c1a938ec9e71aea7e33eb23ba9b138a6499d3a",
"url": "https://fuchsia.googlesource.com/integration/+/27c1a938ec9e71aea7e33eb23ba9b138a6499d3a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2aa3c6326ddcff617d0751f56be1c84cad3c77a6"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2aa3c6326ddcff617d0751f56be1c84cad3c77a6",
"title": "2aa3c6326ddcff617d0751f56be1c84cad3c77a6",
"url": "https://fuchsia.googlesource.com/integration/+/2aa3c6326ddcff617d0751f56be1c84cad3c77a6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "282a116c3cdf86c06bef13bfa797e6ceca3ae9e2"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@282a116c3cdf86c06bef13bfa797e6ceca3ae9e2",
"title": "282a116c3cdf86c06bef13bfa797e6ceca3ae9e2",
"url": "https://fuchsia.googlesource.com/integration/+/282a116c3cdf86c06bef13bfa797e6ceca3ae9e2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b209fdcadddfddf1e7dd89bd16efea9372759f28"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b209fdcadddfddf1e7dd89bd16efea9372759f28",
"title": "b209fdcadddfddf1e7dd89bd16efea9372759f28",
"url": "https://fuchsia.googlesource.com/integration/+/b209fdcadddfddf1e7dd89bd16efea9372759f28"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e6db7db204dcba2046d94ae6179ea6076ada7aff"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e6db7db204dcba2046d94ae6179ea6076ada7aff",
"title": "e6db7db204dcba2046d94ae6179ea6076ada7aff",
"url": "https://fuchsia.googlesource.com/integration/+/e6db7db204dcba2046d94ae6179ea6076ada7aff"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a912efb87d60d19adbc96559023fd3de1033bfd8"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a912efb87d60d19adbc96559023fd3de1033bfd8",
"title": "a912efb87d60d19adbc96559023fd3de1033bfd8",
"url": "https://fuchsia.googlesource.com/integration/+/a912efb87d60d19adbc96559023fd3de1033bfd8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "84293127fa011f51696096ef727b8e6cf4d03ea6"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@84293127fa011f51696096ef727b8e6cf4d03ea6",
"title": "84293127fa011f51696096ef727b8e6cf4d03ea6",
"url": "https://fuchsia.googlesource.com/integration/+/84293127fa011f51696096ef727b8e6cf4d03ea6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "24211f40b6caf71d3fea39ece0ced128328caf4d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@24211f40b6caf71d3fea39ece0ced128328caf4d",
"title": "24211f40b6caf71d3fea39ece0ced128328caf4d",
"url": "https://fuchsia.googlesource.com/integration/+/24211f40b6caf71d3fea39ece0ced128328caf4d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "fed61ccfb837c7cf6c5e3b27193513c74305761a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@fed61ccfb837c7cf6c5e3b27193513c74305761a",
"title": "fed61ccfb837c7cf6c5e3b27193513c74305761a",
"url": "https://fuchsia.googlesource.com/integration/+/fed61ccfb837c7cf6c5e3b27193513c74305761a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ec9515c3778ae5e819aee8c07bc93143d6a113b3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ec9515c3778ae5e819aee8c07bc93143d6a113b3",
"title": "ec9515c3778ae5e819aee8c07bc93143d6a113b3",
"url": "https://fuchsia.googlesource.com/integration/+/ec9515c3778ae5e819aee8c07bc93143d6a113b3"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "ec9515c3778ae5e819aee8c07bc93143d6a113b3",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8937912024060143344"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/core.x64-debug-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[21:04:44.203] Scheduled build:
{
"id": "8772481962169657089",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-08-17T21:04:43.749967015Z",
"updateTime": "2023-08-17T21:04:43.749967015Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "ec9515c3778ae5e819aee8c07bc93143d6a113b3",
"ref": "refs/heads/main"
}
}
}
[21:04:44.203] Task URL: https://cr-buildbucket.appspot.com/build/8772481962169657089
[21:04:44.203] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937912024060143344:2:0) after 6m17s
[21:04:45.215] Received PubSub notification, asking Buildbucket for the build status
[21:04:45.269] Build status: SCHEDULED
[21:06:26.377] Received PubSub notification, asking Buildbucket for the build status
[21:06:26.393] Build status: STARTED
[21:11:01.335] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937912024060143344:2:0)
[21:11:01.356] Build status: STARTED
[21:11:01.356] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937912024060143344:5:0) after 1m26s
[21:12:27.639] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937912024060143344:5:0)
[21:12:27.658] Build status: STARTED
[21:12:27.658] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937912024060143344:6:0) after 9m21s
[21:21:48.683] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937912024060143344:6:0)
[21:21:48.721] Build status: STARTED
[21:21:48.721] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937912024060143344:7:0) after 1m13s
[21:23:01.855] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937912024060143344:7:0)
[21:23:01.883] Build status: STARTED
[21:23:01.883] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937912024060143344:8:0) after 4m28s
[21:27:30.274] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937912024060143344:8:0)
[21:27:30.299] Build status: STARTED
[21:27:30.299] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937912024060143344:9:0) after 8m8s
[21:35:38.315] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937912024060143344:9:0)
[21:35:38.335] Build status: STARTED
[21:35:38.335] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937912024060143344:10:0) after 1m10s
[21:36:48.437] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937912024060143344:10:0)
[21:36:48.453] Build status: STARTED
[21:36:48.453] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937912024060143344:11:0) after 6m38s
[21:43:26.495] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937912024060143344:11:0)
[21:43:26.519] Build status: STARTED
[21:43:26.519] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937912024060143344:12:0) after 4m3s
[21:47:29.677] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937912024060143344:12:0)
[21:47:29.737] Build status: STARTED
[21:47:29.737] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937912024060143344:13:0) after 5m21s
[21:52:50.986] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937912024060143344:13:0)
[21:52:51.009] Build status: STARTED
[21:52:51.009] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937912024060143344:14:0) after 1m53s
[21:54:44.168] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937912024060143344:14:0)
[21:54:44.205] Build status: STARTED
[21:54:44.205] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937912024060143344:15:0) after 3m4s
[21:57:48.319] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937912024060143344:15:0)
[21:57:48.339] Build status: STARTED
[21:57:48.339] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937912024060143344:16:0) after 8m33s
[22:06:21.375] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937912024060143344:16:0)
[22:06:21.405] Build status: STARTED
[22:06:21.405] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937912024060143344:17:0) after 7m28s
[22:13:49.678] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937912024060143344:17:0)
[22:13:49.703] Build status: STARTED
[22:13:49.703] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937912024060143344:18:0) after 9m35s
[22:18:27.466] Received PubSub notification, asking Buildbucket for the build status
[22:18:27.486] Build:
{
"id": "8772481962169657089",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-08-17T21:04:43.749967015Z",
"startTime": "2023-08-17T21:06:25.501897Z",
"endTime": "2023-08-17T22:18:27.116459611Z",
"updateTime": "2023-08-17T22:18:27.116459611Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "ec9515c3778ae5e819aee8c07bc93143d6a113b3",
"ref": "refs/heads/main"
}
}
}
[22:18:27.486] Invocation finished in 1h13m44.922652951s with status SUCCEEDED