[02:06:46.367] New invocation is queued and will start shortly
[02:06:47.564] Starting the invocation (attempt 1)
[02:06:47.612] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/82e99c07fbb78df44364fd74468cf2e2ef4c3161"
[02:06:47.612] Popped gitiles commit info from properties and tags
[02:06:47.612] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:06:47.613] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:06:47.613] Buildbucket request:
{
"requestId": "8920860790199206864",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release-ffx_subtools"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8920860790199206864",
"job": "fuchsia/core.x64-release-ffx_subtools-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ca42082e04a383bd22cdd614fcc1e79902963048"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ca42082e04a383bd22cdd614fcc1e79902963048",
"title": "ca42082e04a383bd22cdd614fcc1e79902963048",
"url": "https://fuchsia.googlesource.com/integration/+/ca42082e04a383bd22cdd614fcc1e79902963048"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "7252b4f1ab85bbfb754284498c484d810c5c51a8"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@7252b4f1ab85bbfb754284498c484d810c5c51a8",
"title": "7252b4f1ab85bbfb754284498c484d810c5c51a8",
"url": "https://fuchsia.googlesource.com/integration/+/7252b4f1ab85bbfb754284498c484d810c5c51a8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b7a38d7f2c202f037912672fbd1a2ea5b4a82592"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b7a38d7f2c202f037912672fbd1a2ea5b4a82592",
"title": "b7a38d7f2c202f037912672fbd1a2ea5b4a82592",
"url": "https://fuchsia.googlesource.com/integration/+/b7a38d7f2c202f037912672fbd1a2ea5b4a82592"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e6e60e09383934a3073db5627a414e06db5d1beb"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e6e60e09383934a3073db5627a414e06db5d1beb",
"title": "e6e60e09383934a3073db5627a414e06db5d1beb",
"url": "https://fuchsia.googlesource.com/integration/+/e6e60e09383934a3073db5627a414e06db5d1beb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9095c978ac243f5950e3c99b3af7911725333361"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9095c978ac243f5950e3c99b3af7911725333361",
"title": "9095c978ac243f5950e3c99b3af7911725333361",
"url": "https://fuchsia.googlesource.com/integration/+/9095c978ac243f5950e3c99b3af7911725333361"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c0d2efe4d3a2e0a2e90221051e190180de8f1989"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c0d2efe4d3a2e0a2e90221051e190180de8f1989",
"title": "c0d2efe4d3a2e0a2e90221051e190180de8f1989",
"url": "https://fuchsia.googlesource.com/integration/+/c0d2efe4d3a2e0a2e90221051e190180de8f1989"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9e802bbb9518b176eb7e4c7e4e78af37a3d4ef99"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9e802bbb9518b176eb7e4c7e4e78af37a3d4ef99",
"title": "9e802bbb9518b176eb7e4c7e4e78af37a3d4ef99",
"url": "https://fuchsia.googlesource.com/integration/+/9e802bbb9518b176eb7e4c7e4e78af37a3d4ef99"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "411f6be3962fb7ee01a437b45d64ae4ee42f377b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@411f6be3962fb7ee01a437b45d64ae4ee42f377b",
"title": "411f6be3962fb7ee01a437b45d64ae4ee42f377b",
"url": "https://fuchsia.googlesource.com/integration/+/411f6be3962fb7ee01a437b45d64ae4ee42f377b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "544b04e7c1a6f5eb8a0c888017bd1dfe1c76e6fb"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@544b04e7c1a6f5eb8a0c888017bd1dfe1c76e6fb",
"title": "544b04e7c1a6f5eb8a0c888017bd1dfe1c76e6fb",
"url": "https://fuchsia.googlesource.com/integration/+/544b04e7c1a6f5eb8a0c888017bd1dfe1c76e6fb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "63d5d133ccfdc95f51240795d5a18b68d7b84dc3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@63d5d133ccfdc95f51240795d5a18b68d7b84dc3",
"title": "63d5d133ccfdc95f51240795d5a18b68d7b84dc3",
"url": "https://fuchsia.googlesource.com/integration/+/63d5d133ccfdc95f51240795d5a18b68d7b84dc3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e864ffc9ada0fe38c6d287bccafd3f39091f6408"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e864ffc9ada0fe38c6d287bccafd3f39091f6408",
"title": "e864ffc9ada0fe38c6d287bccafd3f39091f6408",
"url": "https://fuchsia.googlesource.com/integration/+/e864ffc9ada0fe38c6d287bccafd3f39091f6408"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3fb9e4aec02b053cae19cbd56baaadce732e5bb4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3fb9e4aec02b053cae19cbd56baaadce732e5bb4",
"title": "3fb9e4aec02b053cae19cbd56baaadce732e5bb4",
"url": "https://fuchsia.googlesource.com/integration/+/3fb9e4aec02b053cae19cbd56baaadce732e5bb4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "08a1bf5767228a2bd7c1cfaa976bb4ae085200db"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@08a1bf5767228a2bd7c1cfaa976bb4ae085200db",
"title": "08a1bf5767228a2bd7c1cfaa976bb4ae085200db",
"url": "https://fuchsia.googlesource.com/integration/+/08a1bf5767228a2bd7c1cfaa976bb4ae085200db"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "04f931ddd12911669b8a0e830b7099634bec6244"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@04f931ddd12911669b8a0e830b7099634bec6244",
"title": "04f931ddd12911669b8a0e830b7099634bec6244",
"url": "https://fuchsia.googlesource.com/integration/+/04f931ddd12911669b8a0e830b7099634bec6244"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "82e99c07fbb78df44364fd74468cf2e2ef4c3161"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@82e99c07fbb78df44364fd74468cf2e2ef4c3161",
"title": "82e99c07fbb78df44364fd74468cf2e2ef4c3161",
"url": "https://fuchsia.googlesource.com/integration/+/82e99c07fbb78df44364fd74468cf2e2ef4c3161"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "82e99c07fbb78df44364fd74468cf2e2ef4c3161",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8920860790199206864"
},
{
"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"
}
}
[02:06:48.117] Scheduled build:
{
"id": "8755430728131417153",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release-ffx_subtools"
},
"createdBy": "project:fuchsia",
"createTime": "2024-02-22T02:06:47.714754179Z",
"updateTime": "2024-02-22T02:06:47.714754179Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "82e99c07fbb78df44364fd74468cf2e2ef4c3161",
"ref": "refs/heads/main"
}
}
}
[02:06:48.117] Task URL: https://cr-buildbucket.appspot.com/build/8755430728131417153
[02:06:48.117] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8920860790199206864:2:0) after 8m47s
[02:06:49.211] Received PubSub notification, asking Buildbucket for the build status
[02:06:49.233] Build status: SCHEDULED
[02:06:52.173] Received PubSub notification, asking Buildbucket for the build status
[02:06:52.201] Build status: STARTED
[02:15:35.142] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8920860790199206864:2:0)
[02:15:35.170] Build status: STARTED
[02:15:35.170] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8920860790199206864:5:0) after 8m5s
[02:23:40.195] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8920860790199206864:5:0)
[02:23:40.215] Build status: STARTED
[02:23:40.215] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8920860790199206864:6:0) after 1m0s
[02:24:40.235] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8920860790199206864:6:0)
[02:24:40.326] Build status: STARTED
[02:24:40.326] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8920860790199206864:7:0) after 6m53s
[02:31:33.425] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8920860790199206864:7:0)
[02:31:33.470] Build status: STARTED
[02:31:33.471] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8920860790199206864:8:0) after 5m28s
[02:37:01.911] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8920860790199206864:8:0)
[02:37:01.930] Build status: STARTED
[02:37:01.930] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8920860790199206864:9:0) after 3m18s
[02:40:19.990] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8920860790199206864:9:0)
[02:40:20.008] Build status: STARTED
[02:40:20.009] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8920860790199206864:10:0) after 5m14s
[02:45:34.123] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8920860790199206864:10:0)
[02:45:34.146] Build status: STARTED
[02:45:34.146] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8920860790199206864:11:0) after 3m54s
[02:49:28.173] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8920860790199206864:11:0)
[02:49:28.205] Build status: STARTED
[02:49:28.206] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8920860790199206864:12:0) after 8m6s
[02:57:34.368] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8920860790199206864:12:0)
[02:57:34.397] Build status: STARTED
[02:57:34.397] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8920860790199206864:13:0) after 4m44s
[03:02:18.428] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8920860790199206864:13:0)
[03:02:18.494] Build status: STARTED
[03:02:18.494] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8920860790199206864:14:0) after 2m43s
[03:05:01.631] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8920860790199206864:14:0)
[03:05:01.682] Build status: STARTED
[03:05:01.682] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8920860790199206864:15:0) after 3m17s
[03:08:19.018] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8920860790199206864:15:0)
[03:08:19.037] Build status: STARTED
[03:08:19.037] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8920860790199206864:16:0) after 7m49s
[03:16:08.064] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8920860790199206864:16:0)
[03:16:08.089] Build status: STARTED
[03:16:08.089] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8920860790199206864:17:0) after 8m12s
[03:24:20.181] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8920860790199206864:17:0)
[03:24:20.221] Build status: STARTED
[03:24:20.221] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8920860790199206864:18:0) after 7m40s
[03:30:34.110] Received PubSub notification, asking Buildbucket for the build status
[03:30:34.227] Build:
{
"id": "8755430728131417153",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release-ffx_subtools"
},
"createdBy": "project:fuchsia",
"createTime": "2024-02-22T02:06:47.714754179Z",
"startTime": "2024-02-22T02:06:51.430409Z",
"endTime": "2024-02-22T03:30:18.962063Z",
"updateTime": "2024-02-22T03:30:18.962063Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "82e99c07fbb78df44364fd74468cf2e2ef4c3161",
"ref": "refs/heads/main"
}
}
}
[03:30:34.227] Invocation finished in 1h23m47.867843153s with status SUCCEEDED