[03:25:47.109] New invocation is queued and will start shortly
[03:25:48.637] Starting the invocation (attempt 1)
[03:25:48.663] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/c531a5c325383b1b61e751a6fbec06b1fc23d9fe"
[03:25:48.663] Popped gitiles commit info from properties and tags
[03:25:48.663] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:25:48.663] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:25:48.663] Buildbucket request:
{
"requestId": "8891502402064571088",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "sdk-core-mac"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8891502402064571088",
"job": "fuchsia/sdk-core-mac-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0c0d6c4415ed54baee40780581cba37c26e50c99"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0c0d6c4415ed54baee40780581cba37c26e50c99",
"title": "0c0d6c4415ed54baee40780581cba37c26e50c99",
"url": "https://fuchsia.googlesource.com/integration/+/0c0d6c4415ed54baee40780581cba37c26e50c99"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1e180e65b1a96fe809bc9ea537bade46400379a8"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1e180e65b1a96fe809bc9ea537bade46400379a8",
"title": "1e180e65b1a96fe809bc9ea537bade46400379a8",
"url": "https://fuchsia.googlesource.com/integration/+/1e180e65b1a96fe809bc9ea537bade46400379a8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f034e648a6a31df76b255abbda0388cc556478d3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f034e648a6a31df76b255abbda0388cc556478d3",
"title": "f034e648a6a31df76b255abbda0388cc556478d3",
"url": "https://fuchsia.googlesource.com/integration/+/f034e648a6a31df76b255abbda0388cc556478d3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ecd9325400b117107ec738df70470cd65a0d17ae"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ecd9325400b117107ec738df70470cd65a0d17ae",
"title": "ecd9325400b117107ec738df70470cd65a0d17ae",
"url": "https://fuchsia.googlesource.com/integration/+/ecd9325400b117107ec738df70470cd65a0d17ae"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ff625c87b3ca7d2e511012f6e4d17271b9ea5230"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ff625c87b3ca7d2e511012f6e4d17271b9ea5230",
"title": "ff625c87b3ca7d2e511012f6e4d17271b9ea5230",
"url": "https://fuchsia.googlesource.com/integration/+/ff625c87b3ca7d2e511012f6e4d17271b9ea5230"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c63d3e731e1bf7c3119c786b0171db2fde2dc750"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c63d3e731e1bf7c3119c786b0171db2fde2dc750",
"title": "c63d3e731e1bf7c3119c786b0171db2fde2dc750",
"url": "https://fuchsia.googlesource.com/integration/+/c63d3e731e1bf7c3119c786b0171db2fde2dc750"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "5811a23699795bf6d45db695ea722ac32640de60"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@5811a23699795bf6d45db695ea722ac32640de60",
"title": "5811a23699795bf6d45db695ea722ac32640de60",
"url": "https://fuchsia.googlesource.com/integration/+/5811a23699795bf6d45db695ea722ac32640de60"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f86e9ec8bfd81a2a2a0a3a1c4d9736e716375f39"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f86e9ec8bfd81a2a2a0a3a1c4d9736e716375f39",
"title": "f86e9ec8bfd81a2a2a0a3a1c4d9736e716375f39",
"url": "https://fuchsia.googlesource.com/integration/+/f86e9ec8bfd81a2a2a0a3a1c4d9736e716375f39"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d0995bd0abd045a24f26825508aa1c81e9d51481"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d0995bd0abd045a24f26825508aa1c81e9d51481",
"title": "d0995bd0abd045a24f26825508aa1c81e9d51481",
"url": "https://fuchsia.googlesource.com/integration/+/d0995bd0abd045a24f26825508aa1c81e9d51481"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "24511f94909f6b62d7a01c84a4a2410f960a3262"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@24511f94909f6b62d7a01c84a4a2410f960a3262",
"title": "24511f94909f6b62d7a01c84a4a2410f960a3262",
"url": "https://fuchsia.googlesource.com/integration/+/24511f94909f6b62d7a01c84a4a2410f960a3262"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "cc949a212c35fc3b8be369f7670746cd58a87ab3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@cc949a212c35fc3b8be369f7670746cd58a87ab3",
"title": "cc949a212c35fc3b8be369f7670746cd58a87ab3",
"url": "https://fuchsia.googlesource.com/integration/+/cc949a212c35fc3b8be369f7670746cd58a87ab3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c39fa2b12062877a6d81c5f7d741a8f3e21a0075"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c39fa2b12062877a6d81c5f7d741a8f3e21a0075",
"title": "c39fa2b12062877a6d81c5f7d741a8f3e21a0075",
"url": "https://fuchsia.googlesource.com/integration/+/c39fa2b12062877a6d81c5f7d741a8f3e21a0075"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "455d5a068afd4caec2c4828d3e9c02d262c70ed2"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@455d5a068afd4caec2c4828d3e9c02d262c70ed2",
"title": "455d5a068afd4caec2c4828d3e9c02d262c70ed2",
"url": "https://fuchsia.googlesource.com/integration/+/455d5a068afd4caec2c4828d3e9c02d262c70ed2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c531a5c325383b1b61e751a6fbec06b1fc23d9fe"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c531a5c325383b1b61e751a6fbec06b1fc23d9fe",
"title": "c531a5c325383b1b61e751a6fbec06b1fc23d9fe",
"url": "https://fuchsia.googlesource.com/integration/+/c531a5c325383b1b61e751a6fbec06b1fc23d9fe"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "c531a5c325383b1b61e751a6fbec06b1fc23d9fe",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8891502402064571088"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/sdk-core-mac-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[03:25:49.052] Scheduled build:
{
"id": "8726072339702807889",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "sdk-core-mac"
},
"createdBy": "project:fuchsia",
"createTime": "2025-01-11T03:25:48.730611637Z",
"updateTime": "2025-01-11T03:25:48.730611637Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "c531a5c325383b1b61e751a6fbec06b1fc23d9fe",
"ref": "refs/heads/main"
}
},
"canary": true
}
[03:25:49.052] Task URL: https://cr-buildbucket.appspot.com/build/8726072339702807889
[03:25:49.052] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:2:0) after 5m15s
[03:25:50.053] Received PubSub notification, asking Buildbucket for the build status
[03:25:50.081] Build status: SCHEDULED
[03:25:55.544] Received PubSub notification, asking Buildbucket for the build status
[03:25:55.651] Build status: STARTED
[03:31:04.112] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:2:0)
[03:31:04.136] Build status: STARTED
[03:31:04.136] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:5:0) after 7m3s
[03:38:07.452] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:5:0)
[03:38:07.527] Build status: STARTED
[03:38:07.527] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:6:0) after 4m33s
[03:42:40.677] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:6:0)
[03:42:40.702] Build status: STARTED
[03:42:40.703] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:7:0) after 7m37s
[03:50:17.917] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:7:0)
[03:50:17.937] Build status: STARTED
[03:50:17.937] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:8:0) after 7m28s
[03:57:45.953] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:8:0)
[03:57:45.972] Build status: STARTED
[03:57:45.972] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:9:0) after 1m46s
[03:59:32.520] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:9:0)
[03:59:32.546] Build status: STARTED
[03:59:32.546] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:10:0) after 6m45s
[04:06:17.601] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:10:0)
[04:06:17.619] Build status: STARTED
[04:06:17.619] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:11:0) after 1m27s
[04:07:44.644] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:11:0)
[04:07:44.666] Build status: STARTED
[04:07:44.666] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:12:0) after 9m18s
[04:17:02.816] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:12:0)
[04:17:02.844] Build status: STARTED
[04:17:02.844] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:13:0) after 2m43s
[04:19:45.965] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:13:0)
[04:19:46.020] Build status: STARTED
[04:19:46.020] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:14:0) after 5m3s
[04:24:49.054] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:14:0)
[04:24:49.081] Build status: STARTED
[04:24:49.081] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:15:0) after 7m46s
[04:32:35.327] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:15:0)
[04:32:35.344] Build status: STARTED
[04:32:35.344] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:16:0) after 5m28s
[04:38:03.369] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:16:0)
[04:38:03.389] Build status: STARTED
[04:38:03.389] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:17:0) after 3m35s
[04:41:38.408] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:17:0)
[04:41:38.431] Build status: STARTED
[04:41:38.431] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:18:0) after 5m35s
[04:47:13.455] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:18:0)
[04:47:13.502] Build status: STARTED
[04:47:13.502] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:19:0) after 3m42s
[04:50:55.523] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:19:0)
[04:50:55.552] Build status: STARTED
[04:50:55.553] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:20:0) after 1m16s
[04:52:11.548] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:20:0)
[04:52:11.575] Build status: STARTED
[04:52:11.575] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:21:0) after 2m30s
[04:54:41.657] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:21:0)
[04:54:41.703] Build status: STARTED
[04:54:41.703] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:22:0) after 1m20s
[04:56:01.823] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:22:0)
[04:56:01.839] Build status: STARTED
[04:56:01.840] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:23:0) after 7m29s
[05:03:31.044] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:23:0)
[05:03:31.071] Build status: STARTED
[05:03:31.071] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:24:0) after 6m55s
[05:10:26.322] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:24:0)
[05:10:26.342] Build status: STARTED
[05:10:26.342] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:25:0) after 3m33s
[05:13:59.369] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:25:0)
[05:13:59.391] Build status: STARTED
[05:13:59.391] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:26:0) after 8m59s
[05:22:58.491] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:26:0)
[05:22:58.507] Build status: STARTED
[05:22:58.507] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:27:0) after 4m28s
[05:27:26.537] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:27:0)
[05:27:26.558] Build status: STARTED
[05:27:26.558] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:28:0) after 2m29s
[05:29:55.844] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:28:0)
[05:29:55.858] Build status: STARTED
[05:29:55.858] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:29:0) after 7m39s
[05:37:34.885] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:29:0)
[05:37:34.906] Build status: STARTED
[05:37:34.906] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8891502402064571088:30:0) after 7m39s
[05:44:21.724] Received PubSub notification, asking Buildbucket for the build status
[05:44:21.740] Build:
{
"id": "8726072339702807889",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "sdk-core-mac"
},
"createdBy": "project:fuchsia",
"createTime": "2025-01-11T03:25:48.730611637Z",
"startTime": "2025-01-11T03:25:55.220282494Z",
"endTime": "2025-01-11T05:44:21.471508355Z",
"updateTime": "2025-01-11T05:44:21.471508355Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "c531a5c325383b1b61e751a6fbec06b1fc23d9fe",
"ref": "refs/heads/main"
}
},
"canary": true
}
[05:44:21.741] Invocation finished in 2h18m34.646377217s with status SUCCEEDED