[02:16:11.183] New invocation is queued and will start shortly
[02:16:12.512] Starting the invocation (attempt 1)
[02:16:12.542] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/d30d87e3c2416ed41755e493b06977faf2790945"
[02:16:12.542] Popped gitiles commit info from properties and tags
[02:16:12.542] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:16:12.542] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:16:12.543] Buildbucket request:
{
"requestId": "8898573344213897472",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "sdk-core-mac"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8898573344213897472",
"job": "fuchsia/sdk-core-mac-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f94e91cd911a6ba710255ee2bb10df9da1b86037"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f94e91cd911a6ba710255ee2bb10df9da1b86037",
"title": "f94e91cd911a6ba710255ee2bb10df9da1b86037",
"url": "https://fuchsia.googlesource.com/integration/+/f94e91cd911a6ba710255ee2bb10df9da1b86037"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4168dd3b21913989164532ff3b6ad5bbf0877188"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4168dd3b21913989164532ff3b6ad5bbf0877188",
"title": "4168dd3b21913989164532ff3b6ad5bbf0877188",
"url": "https://fuchsia.googlesource.com/integration/+/4168dd3b21913989164532ff3b6ad5bbf0877188"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6bd77048b7d6ac002cb8fab7a4c0ae81d30aa035"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6bd77048b7d6ac002cb8fab7a4c0ae81d30aa035",
"title": "6bd77048b7d6ac002cb8fab7a4c0ae81d30aa035",
"url": "https://fuchsia.googlesource.com/integration/+/6bd77048b7d6ac002cb8fab7a4c0ae81d30aa035"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3720be0dc5d5057a1e12a281438aa5b8bf5f9790"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3720be0dc5d5057a1e12a281438aa5b8bf5f9790",
"title": "3720be0dc5d5057a1e12a281438aa5b8bf5f9790",
"url": "https://fuchsia.googlesource.com/integration/+/3720be0dc5d5057a1e12a281438aa5b8bf5f9790"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "5689b3c6449714a2c8d366ea83ae2a1869e407a1"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@5689b3c6449714a2c8d366ea83ae2a1869e407a1",
"title": "5689b3c6449714a2c8d366ea83ae2a1869e407a1",
"url": "https://fuchsia.googlesource.com/integration/+/5689b3c6449714a2c8d366ea83ae2a1869e407a1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2b45688b8d0c24828c6d8bf382329975422e4365"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2b45688b8d0c24828c6d8bf382329975422e4365",
"title": "2b45688b8d0c24828c6d8bf382329975422e4365",
"url": "https://fuchsia.googlesource.com/integration/+/2b45688b8d0c24828c6d8bf382329975422e4365"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "045a3af1c9e66b6767b0cca35ea59dd98a2b8950"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@045a3af1c9e66b6767b0cca35ea59dd98a2b8950",
"title": "045a3af1c9e66b6767b0cca35ea59dd98a2b8950",
"url": "https://fuchsia.googlesource.com/integration/+/045a3af1c9e66b6767b0cca35ea59dd98a2b8950"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ad6e6390271f416af91aedd99b9dff4e9090202f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ad6e6390271f416af91aedd99b9dff4e9090202f",
"title": "ad6e6390271f416af91aedd99b9dff4e9090202f",
"url": "https://fuchsia.googlesource.com/integration/+/ad6e6390271f416af91aedd99b9dff4e9090202f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "86e047a1e411fb40a050da8f61eb3933502d8cb7"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@86e047a1e411fb40a050da8f61eb3933502d8cb7",
"title": "86e047a1e411fb40a050da8f61eb3933502d8cb7",
"url": "https://fuchsia.googlesource.com/integration/+/86e047a1e411fb40a050da8f61eb3933502d8cb7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b5959984af9489760bcd4f3596928eaf5e5df2be"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b5959984af9489760bcd4f3596928eaf5e5df2be",
"title": "b5959984af9489760bcd4f3596928eaf5e5df2be",
"url": "https://fuchsia.googlesource.com/integration/+/b5959984af9489760bcd4f3596928eaf5e5df2be"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a037e66cb94ad8472d61073a739819d1632f2440"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a037e66cb94ad8472d61073a739819d1632f2440",
"title": "a037e66cb94ad8472d61073a739819d1632f2440",
"url": "https://fuchsia.googlesource.com/integration/+/a037e66cb94ad8472d61073a739819d1632f2440"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "06fc93a7406a8cd8567d5ae2b87dbcda68c6d095"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@06fc93a7406a8cd8567d5ae2b87dbcda68c6d095",
"title": "06fc93a7406a8cd8567d5ae2b87dbcda68c6d095",
"url": "https://fuchsia.googlesource.com/integration/+/06fc93a7406a8cd8567d5ae2b87dbcda68c6d095"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "101629c1c3071dfc195aa9a5b1e4a3019cc3a8ce"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@101629c1c3071dfc195aa9a5b1e4a3019cc3a8ce",
"title": "101629c1c3071dfc195aa9a5b1e4a3019cc3a8ce",
"url": "https://fuchsia.googlesource.com/integration/+/101629c1c3071dfc195aa9a5b1e4a3019cc3a8ce"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3c290adf633d0fdd1ab55a2eba0cf29679b2962d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3c290adf633d0fdd1ab55a2eba0cf29679b2962d",
"title": "3c290adf633d0fdd1ab55a2eba0cf29679b2962d",
"url": "https://fuchsia.googlesource.com/integration/+/3c290adf633d0fdd1ab55a2eba0cf29679b2962d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b2b3e5020612c85d729cb39ec08df485099db592"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b2b3e5020612c85d729cb39ec08df485099db592",
"title": "b2b3e5020612c85d729cb39ec08df485099db592",
"url": "https://fuchsia.googlesource.com/integration/+/b2b3e5020612c85d729cb39ec08df485099db592"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "039fa15c38748f406c59d270da82597d08e9166b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@039fa15c38748f406c59d270da82597d08e9166b",
"title": "039fa15c38748f406c59d270da82597d08e9166b",
"url": "https://fuchsia.googlesource.com/integration/+/039fa15c38748f406c59d270da82597d08e9166b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "46eaabae8f05ab39c756909ae859f0ff5bcc9392"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@46eaabae8f05ab39c756909ae859f0ff5bcc9392",
"title": "46eaabae8f05ab39c756909ae859f0ff5bcc9392",
"url": "https://fuchsia.googlesource.com/integration/+/46eaabae8f05ab39c756909ae859f0ff5bcc9392"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "147f28a9664a5ea915595e84386fbf1dbf9031bc"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@147f28a9664a5ea915595e84386fbf1dbf9031bc",
"title": "147f28a9664a5ea915595e84386fbf1dbf9031bc",
"url": "https://fuchsia.googlesource.com/integration/+/147f28a9664a5ea915595e84386fbf1dbf9031bc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "decf830f20bd2164c599c7fb8d61be5db77fbdd5"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@decf830f20bd2164c599c7fb8d61be5db77fbdd5",
"title": "decf830f20bd2164c599c7fb8d61be5db77fbdd5",
"url": "https://fuchsia.googlesource.com/integration/+/decf830f20bd2164c599c7fb8d61be5db77fbdd5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d30d87e3c2416ed41755e493b06977faf2790945"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d30d87e3c2416ed41755e493b06977faf2790945",
"title": "d30d87e3c2416ed41755e493b06977faf2790945",
"url": "https://fuchsia.googlesource.com/integration/+/d30d87e3c2416ed41755e493b06977faf2790945"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "d30d87e3c2416ed41755e493b06977faf2790945",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8898573344213897472"
},
{
"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"
}
}
[02:16:13.323] Scheduled build:
{
"id": "8733143282025271185",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "sdk-core-mac"
},
"createdBy": "project:fuchsia",
"createTime": "2024-10-25T02:16:12.644499244Z",
"updateTime": "2024-10-25T02:16:12.644499244Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "d30d87e3c2416ed41755e493b06977faf2790945",
"ref": "refs/heads/main"
}
}
}
[02:16:13.323] Task URL: https://cr-buildbucket.appspot.com/build/8733143282025271185
[02:16:13.323] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:2:0) after 4m36s
[02:16:14.175] Received PubSub notification, asking Buildbucket for the build status
[02:16:14.195] Build status: SCHEDULED
[02:16:19.527] Received PubSub notification, asking Buildbucket for the build status
[02:16:19.551] Build status: STARTED
[02:20:49.401] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:2:0)
[02:20:49.578] Build status: STARTED
[02:20:49.578] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:5:0) after 7m1s
[02:27:50.597] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:5:0)
[02:27:50.624] Build status: STARTED
[02:27:50.624] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:6:0) after 2m7s
[02:29:57.671] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:6:0)
[02:29:57.696] Build status: STARTED
[02:29:57.696] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:7:0) after 6m19s
[02:36:16.715] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:7:0)
[02:36:16.750] Build status: STARTED
[02:36:16.750] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:8:0) after 6m56s
[02:43:12.765] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:8:0)
[02:43:12.791] Build status: STARTED
[02:43:12.791] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:9:0) after 8m58s
[02:52:10.808] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:9:0)
[02:52:10.830] Build status: STARTED
[02:52:10.830] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:10:0) after 8m37s
[03:00:47.829] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:10:0)
[03:00:47.896] Build status: STARTED
[03:00:47.896] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:11:0) after 9m37s
[03:10:24.944] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:11:0)
[03:10:24.965] Build status: STARTED
[03:10:24.965] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:12:0) after 8m57s
[03:19:21.992] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:12:0)
[03:19:22.008] Build status: STARTED
[03:19:22.009] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:13:0) after 4m41s
[03:24:03.030] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:13:0)
[03:24:03.061] Build status: STARTED
[03:24:03.061] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:14:0) after 8m20s
[03:32:23.084] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:14:0)
[03:32:23.103] Build status: STARTED
[03:32:23.103] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:15:0) after 4m56s
[03:37:19.124] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:15:0)
[03:37:19.162] Build status: STARTED
[03:37:19.163] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:16:0) after 5m6s
[03:42:25.194] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:16:0)
[03:42:25.212] Build status: STARTED
[03:42:25.212] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:17:0) after 1m33s
[03:43:58.271] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:17:0)
[03:43:58.372] Build status: STARTED
[03:43:58.372] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:18:0) after 7m13s
[03:51:11.383] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:18:0)
[03:51:11.400] Build status: STARTED
[03:51:11.400] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:19:0) after 1m28s
[03:52:39.389] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:19:0)
[03:52:39.409] Build status: STARTED
[03:52:39.409] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:20:0) after 5m31s
[03:58:10.678] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:20:0)
[03:58:10.704] Build status: STARTED
[03:58:10.704] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:21:0) after 7m35s
[04:05:45.733] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:21:0)
[04:05:45.751] Build status: STARTED
[04:05:45.751] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:22:0) after 4m43s
[04:10:28.883] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:22:0)
[04:10:28.903] Build status: STARTED
[04:10:28.903] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:23:0) after 4m7s
[04:14:36.352] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:23:0)
[04:14:36.369] Build status: STARTED
[04:14:36.369] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:24:0) after 1m23s
[04:15:59.710] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:24:0)
[04:15:59.729] Build status: STARTED
[04:15:59.729] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:25:0) after 3m40s
[04:19:39.937] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:25:0)
[04:19:39.954] Build status: STARTED
[04:19:39.954] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:26:0) after 6m38s
[04:26:18.567] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:26:0)
[04:26:18.586] Build status: STARTED
[04:26:18.586] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:27:0) after 5m19s
[04:31:37.701] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:27:0)
[04:31:37.723] Build status: STARTED
[04:31:37.723] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:28:0) after 5m24s
[04:37:01.779] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:28:0)
[04:37:01.798] Build status: STARTED
[04:37:01.798] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-mac-e5a0ec7f:8898573344213897472:29:0) after 9m43s
[04:40:15.568] Received PubSub notification, asking Buildbucket for the build status
[04:40:15.584] Build:
{
"id": "8733143282025271185",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "sdk-core-mac"
},
"createdBy": "project:fuchsia",
"createTime": "2024-10-25T02:16:12.644499244Z",
"startTime": "2024-10-25T02:16:19.334875118Z",
"endTime": "2024-10-25T04:40:15.286098063Z",
"updateTime": "2024-10-25T04:40:15.286098063Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "d30d87e3c2416ed41755e493b06977faf2790945",
"ref": "refs/heads/main"
}
}
}
[04:40:15.584] Invocation finished in 2h24m4.411143005s with status SUCCEEDED