[23:38:50.097] New invocation is queued and will start shortly
[23:38:51.141] Starting the invocation (attempt 1)
[23:38:51.170] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/cfa9c86a39a17a50c53ef1c053110f3eb296cb3a"
[23:38:51.170] Popped gitiles commit info from properties and tags
[23:38:51.170] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:38:51.170] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:38:51.170] Buildbucket request:
{
"requestId": "8924493976299833856",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "bringup.x64-debug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8924493976299833856",
"job": "fuchsia/bringup.x64-debug-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "641f9e2c61c4de5ae2aea895727d42f05433a599"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@641f9e2c61c4de5ae2aea895727d42f05433a599",
"title": "641f9e2c61c4de5ae2aea895727d42f05433a599",
"url": "https://fuchsia.googlesource.com/integration/+/641f9e2c61c4de5ae2aea895727d42f05433a599"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "27ec79a08f1e73efa828e746dc55158c6d8afad9"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@27ec79a08f1e73efa828e746dc55158c6d8afad9",
"title": "27ec79a08f1e73efa828e746dc55158c6d8afad9",
"url": "https://fuchsia.googlesource.com/integration/+/27ec79a08f1e73efa828e746dc55158c6d8afad9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "872337ddbe99e26c3f5a9bcba067ef4d9348ce14"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@872337ddbe99e26c3f5a9bcba067ef4d9348ce14",
"title": "872337ddbe99e26c3f5a9bcba067ef4d9348ce14",
"url": "https://fuchsia.googlesource.com/integration/+/872337ddbe99e26c3f5a9bcba067ef4d9348ce14"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "fa1ff677b167610db8a00accfff26b9eb1bfe363"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@fa1ff677b167610db8a00accfff26b9eb1bfe363",
"title": "fa1ff677b167610db8a00accfff26b9eb1bfe363",
"url": "https://fuchsia.googlesource.com/integration/+/fa1ff677b167610db8a00accfff26b9eb1bfe363"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b53f03cebf2b59d315bd8a4c301ccf869a3a66a0"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b53f03cebf2b59d315bd8a4c301ccf869a3a66a0",
"title": "b53f03cebf2b59d315bd8a4c301ccf869a3a66a0",
"url": "https://fuchsia.googlesource.com/integration/+/b53f03cebf2b59d315bd8a4c301ccf869a3a66a0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "cbd32532c14f0e340d054ab974395b398feee9bc"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@cbd32532c14f0e340d054ab974395b398feee9bc",
"title": "cbd32532c14f0e340d054ab974395b398feee9bc",
"url": "https://fuchsia.googlesource.com/integration/+/cbd32532c14f0e340d054ab974395b398feee9bc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "aab3e47682d9c271fab2a08a7f9a91dcef43c0a0"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@aab3e47682d9c271fab2a08a7f9a91dcef43c0a0",
"title": "aab3e47682d9c271fab2a08a7f9a91dcef43c0a0",
"url": "https://fuchsia.googlesource.com/integration/+/aab3e47682d9c271fab2a08a7f9a91dcef43c0a0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "cfa9c86a39a17a50c53ef1c053110f3eb296cb3a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@cfa9c86a39a17a50c53ef1c053110f3eb296cb3a",
"title": "cfa9c86a39a17a50c53ef1c053110f3eb296cb3a",
"url": "https://fuchsia.googlesource.com/integration/+/cfa9c86a39a17a50c53ef1c053110f3eb296cb3a"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "cfa9c86a39a17a50c53ef1c053110f3eb296cb3a",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8924493976299833856"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/bringup.x64-debug-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[23:38:51.725] Scheduled build:
{
"id": "8759063914465587041",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "bringup.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2024-01-12T23:38:51.221235450Z",
"updateTime": "2024-01-12T23:38:51.221235450Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "cfa9c86a39a17a50c53ef1c053110f3eb296cb3a",
"ref": "refs/heads/main"
}
}
}
[23:38:51.725] Task URL: https://cr-buildbucket.appspot.com/build/8759063914465587041
[23:38:51.725] Scheduling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8924493976299833856:2:0) after 8m40s
[23:38:52.695] Received PubSub notification, asking Buildbucket for the build status
[23:38:52.715] Build status: SCHEDULED
[23:38:55.358] Received PubSub notification, asking Buildbucket for the build status
[23:38:55.424] Build status: STARTED
[23:47:32.024] Handling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8924493976299833856:2:0)
[23:47:32.043] Build status: STARTED
[23:47:32.043] Scheduling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8924493976299833856:5:0) after 3m4s
[23:50:36.303] Handling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8924493976299833856:5:0)
[23:50:36.326] Build status: STARTED
[23:50:36.326] Scheduling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8924493976299833856:6:0) after 3m13s
[23:53:49.343] Handling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8924493976299833856:6:0)
[23:53:49.449] Build status: STARTED
[23:53:49.449] Scheduling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8924493976299833856:7:0) after 2m45s
[23:56:34.470] Handling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8924493976299833856:7:0)
[23:56:34.608] Build status: STARTED
[23:56:34.608] Scheduling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8924493976299833856:8:0) after 7m48s
[23:57:41.868] Received PubSub notification, asking Buildbucket for the build status
[23:57:41.896] Build:
{
"id": "8759063914465587041",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "bringup.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2024-01-12T23:38:51.221235450Z",
"startTime": "2024-01-12T23:38:52.078477Z",
"endTime": "2024-01-12T23:57:41.122755Z",
"updateTime": "2024-01-12T23:57:41.122755Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "cfa9c86a39a17a50c53ef1c053110f3eb296cb3a",
"ref": "refs/heads/main"
}
}
}
[23:57:41.896] Invocation finished in 18m51.808375037s with status SUCCEEDED