[18:44:56.049] New invocation is queued and will start shortly
[18:44:57.139] Starting the invocation (attempt 1)
[18:44:57.167] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/851fd5d1b8b8fedc57570b5339947d879617a57d"
[18:44:57.167] Popped gitiles commit info from properties and tags
[18:44:57.167] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:44:57.167] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:44:57.168] Buildbucket request:
{
"requestId": "8913459637037431056",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "bringup.x64-debug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8913459637037431056",
"job": "fuchsia/bringup.x64-debug-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "307f9cbac7ee4b42e3f74cd56fb06c38bfbda249"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@307f9cbac7ee4b42e3f74cd56fb06c38bfbda249",
"title": "307f9cbac7ee4b42e3f74cd56fb06c38bfbda249",
"url": "https://fuchsia.googlesource.com/integration/+/307f9cbac7ee4b42e3f74cd56fb06c38bfbda249"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0cede14769d42b4bc283bc0ddb63683735044f79"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0cede14769d42b4bc283bc0ddb63683735044f79",
"title": "0cede14769d42b4bc283bc0ddb63683735044f79",
"url": "https://fuchsia.googlesource.com/integration/+/0cede14769d42b4bc283bc0ddb63683735044f79"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "12bfdb7c635e0c31a4731e317abe49f1635142f9"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@12bfdb7c635e0c31a4731e317abe49f1635142f9",
"title": "12bfdb7c635e0c31a4731e317abe49f1635142f9",
"url": "https://fuchsia.googlesource.com/integration/+/12bfdb7c635e0c31a4731e317abe49f1635142f9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "cb0969cf4e33cf5573f3f8f1105150c3db7958d8"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@cb0969cf4e33cf5573f3f8f1105150c3db7958d8",
"title": "cb0969cf4e33cf5573f3f8f1105150c3db7958d8",
"url": "https://fuchsia.googlesource.com/integration/+/cb0969cf4e33cf5573f3f8f1105150c3db7958d8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4f3165e6c960bedb8844ea112d8b3fd75aec9d76"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4f3165e6c960bedb8844ea112d8b3fd75aec9d76",
"title": "4f3165e6c960bedb8844ea112d8b3fd75aec9d76",
"url": "https://fuchsia.googlesource.com/integration/+/4f3165e6c960bedb8844ea112d8b3fd75aec9d76"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "14c11737223499fb9240ebe68dc1e96be9d4ea47"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@14c11737223499fb9240ebe68dc1e96be9d4ea47",
"title": "14c11737223499fb9240ebe68dc1e96be9d4ea47",
"url": "https://fuchsia.googlesource.com/integration/+/14c11737223499fb9240ebe68dc1e96be9d4ea47"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "151f50a37fc1b781d9ee8fa809365ee0691a822c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@151f50a37fc1b781d9ee8fa809365ee0691a822c",
"title": "151f50a37fc1b781d9ee8fa809365ee0691a822c",
"url": "https://fuchsia.googlesource.com/integration/+/151f50a37fc1b781d9ee8fa809365ee0691a822c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ad74ad844d1bada064408322a9ebde161839c250"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ad74ad844d1bada064408322a9ebde161839c250",
"title": "ad74ad844d1bada064408322a9ebde161839c250",
"url": "https://fuchsia.googlesource.com/integration/+/ad74ad844d1bada064408322a9ebde161839c250"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1533d5a993e4ee8e1bb27fe4979c39d9ece31750"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1533d5a993e4ee8e1bb27fe4979c39d9ece31750",
"title": "1533d5a993e4ee8e1bb27fe4979c39d9ece31750",
"url": "https://fuchsia.googlesource.com/integration/+/1533d5a993e4ee8e1bb27fe4979c39d9ece31750"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "50ea1410889e5e9b42506a6a06d60c7430601b40"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@50ea1410889e5e9b42506a6a06d60c7430601b40",
"title": "50ea1410889e5e9b42506a6a06d60c7430601b40",
"url": "https://fuchsia.googlesource.com/integration/+/50ea1410889e5e9b42506a6a06d60c7430601b40"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "736ce970bf7e915802dc6238922d0ef6cd6443cb"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@736ce970bf7e915802dc6238922d0ef6cd6443cb",
"title": "736ce970bf7e915802dc6238922d0ef6cd6443cb",
"url": "https://fuchsia.googlesource.com/integration/+/736ce970bf7e915802dc6238922d0ef6cd6443cb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e25de3607ad0618b26a53d3aa70822db5f9aa23c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e25de3607ad0618b26a53d3aa70822db5f9aa23c",
"title": "e25de3607ad0618b26a53d3aa70822db5f9aa23c",
"url": "https://fuchsia.googlesource.com/integration/+/e25de3607ad0618b26a53d3aa70822db5f9aa23c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a2ea7e4a99932ad08e5c5f4f2d864e796629072c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a2ea7e4a99932ad08e5c5f4f2d864e796629072c",
"title": "a2ea7e4a99932ad08e5c5f4f2d864e796629072c",
"url": "https://fuchsia.googlesource.com/integration/+/a2ea7e4a99932ad08e5c5f4f2d864e796629072c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b8bbb253dad129e42fad32dc787e18ef78cfcfd5"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b8bbb253dad129e42fad32dc787e18ef78cfcfd5",
"title": "b8bbb253dad129e42fad32dc787e18ef78cfcfd5",
"url": "https://fuchsia.googlesource.com/integration/+/b8bbb253dad129e42fad32dc787e18ef78cfcfd5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "616bc756c3be2f573901c28b8374c09a3e933e8c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@616bc756c3be2f573901c28b8374c09a3e933e8c",
"title": "616bc756c3be2f573901c28b8374c09a3e933e8c",
"url": "https://fuchsia.googlesource.com/integration/+/616bc756c3be2f573901c28b8374c09a3e933e8c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e891db4594111bc12473e6864ac50d9e981a9f91"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e891db4594111bc12473e6864ac50d9e981a9f91",
"title": "e891db4594111bc12473e6864ac50d9e981a9f91",
"url": "https://fuchsia.googlesource.com/integration/+/e891db4594111bc12473e6864ac50d9e981a9f91"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "851fd5d1b8b8fedc57570b5339947d879617a57d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@851fd5d1b8b8fedc57570b5339947d879617a57d",
"title": "851fd5d1b8b8fedc57570b5339947d879617a57d",
"url": "https://fuchsia.googlesource.com/integration/+/851fd5d1b8b8fedc57570b5339947d879617a57d"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "851fd5d1b8b8fedc57570b5339947d879617a57d",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8913459637037431056"
},
{
"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"
}
}
[18:44:57.668] Scheduled build:
{
"id": "8748029575150292769",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "bringup.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2024-05-13T18:44:57.224695246Z",
"updateTime": "2024-05-13T18:44:57.224695246Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "851fd5d1b8b8fedc57570b5339947d879617a57d",
"ref": "refs/heads/main"
}
}
}
[18:44:57.668] Task URL: https://cr-buildbucket.appspot.com/build/8748029575150292769
[18:44:57.668] Scheduling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8913459637037431056:2:0) after 5m5s
[18:45:15.694] Received PubSub notification, asking Buildbucket for the build status
[18:45:15.765] Build status: SCHEDULED
[18:46:29.328] Received PubSub notification, asking Buildbucket for the build status
[18:46:29.349] Build status: STARTED
[18:50:02.690] Handling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8913459637037431056:2:0)
[18:50:02.721] Build status: STARTED
[18:50:02.721] Scheduling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8913459637037431056:5:0) after 7m19s
[18:57:21.791] Handling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8913459637037431056:5:0)
[18:57:21.813] Build status: STARTED
[18:57:21.813] Scheduling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8913459637037431056:6:0) after 5m12s
[19:02:33.851] Handling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8913459637037431056:6:0)
[19:02:33.876] Build status: STARTED
[19:02:33.876] Scheduling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8913459637037431056:7:0) after 3m39s
[19:06:12.956] Handling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8913459637037431056:7:0)
[19:06:12.974] Build status: STARTED
[19:06:12.974] Scheduling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8913459637037431056:8:0) after 9m48s
[19:16:00.991] Handling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8913459637037431056:8:0)
[19:16:01.085] Build status: STARTED
[19:16:01.085] Scheduling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8913459637037431056:9:0) after 1m16s
[19:17:17.103] Handling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8913459637037431056:9:0)
[19:17:17.122] Build status: STARTED
[19:17:17.122] Scheduling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8913459637037431056:10:0) after 2m25s
[19:19:42.256] Handling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8913459637037431056:10:0)
[19:19:42.314] Build status: STARTED
[19:19:42.314] Scheduling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8913459637037431056:11:0) after 6m14s
[19:25:56.340] Handling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8913459637037431056:11:0)
[19:25:56.365] Build status: STARTED
[19:25:56.365] Scheduling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8913459637037431056:12:0) after 8m16s
[19:34:12.384] Handling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8913459637037431056:12:0)
[19:34:12.745] Build status: STARTED
[19:34:12.745] Scheduling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8913459637037431056:13:0) after 7m59s
[19:42:11.887] Handling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8913459637037431056:13:0)
[19:42:11.910] Build status: STARTED
[19:42:11.910] Scheduling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8913459637037431056:14:0) after 6m22s
[19:48:33.926] Handling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8913459637037431056:14:0)
[19:48:33.955] Build status: STARTED
[19:48:33.955] Scheduling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8913459637037431056:15:0) after 1m47s
[19:50:21.057] Handling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8913459637037431056:15:0)
[19:50:21.120] Build status: STARTED
[19:50:21.121] Scheduling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8913459637037431056:16:0) after 2m39s
[19:53:00.147] Handling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8913459637037431056:16:0)
[19:53:00.168] Build status: STARTED
[19:53:00.168] Scheduling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8913459637037431056:17:0) after 1m22s
[19:54:22.209] Handling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8913459637037431056:17:0)
[19:54:22.233] Build status: STARTED
[19:54:22.233] Scheduling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8913459637037431056:18:0) after 7m50s
[20:02:12.248] Handling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8913459637037431056:18:0)
[20:02:12.272] Build status: STARTED
[20:02:12.272] Scheduling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8913459637037431056:19:0) after 1m7s
[20:03:19.284] Handling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8913459637037431056:19:0)
[20:03:19.306] Build status: STARTED
[20:03:19.306] Scheduling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8913459637037431056:20:0) after 5m58s
[20:09:17.363] Handling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8913459637037431056:20:0)
[20:09:17.420] Build status: STARTED
[20:09:17.421] Scheduling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8913459637037431056:21:0) after 6m23s
[20:15:40.547] Handling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8913459637037431056:21:0)
[20:15:40.563] Build status: STARTED
[20:15:40.563] Scheduling timer "check-buildbucket-build-status" (fuchsia/bringup.x64-debug-e5a0ec7f:8913459637037431056:22:0) after 9m18s
[20:17:35.575] Received PubSub notification, asking Buildbucket for the build status
[20:17:35.594] Build:
{
"id": "8748029575150292769",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "bringup.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2024-05-13T18:44:57.224695246Z",
"startTime": "2024-05-13T18:46:28.826954289Z",
"endTime": "2024-05-13T20:17:34.270673449Z",
"updateTime": "2024-05-13T20:17:34.270673449Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "851fd5d1b8b8fedc57570b5339947d879617a57d",
"ref": "refs/heads/main"
}
}
}
[20:17:35.594] Invocation finished in 1h32m39.553089481s with status SUCCEEDED