[18:50:40.400] New invocation is queued and will start shortly
[18:50:43.158] Starting the invocation (attempt 1)
[18:50:43.188] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/ee0888aa7c45e1d14b4c47c3652a8e2bddb70f7e"
[18:50:43.188] Popped gitiles commit info from properties and tags
[18:50:43.188] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:50:43.188] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:50:43.189] Buildbucket request:
{
"requestId": "8935927323629074128",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8935927323629074128",
"job": "fuchsia/core.x64-debug-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d1875b64f4fa0758e57e1ff035b8c4691c82986f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d1875b64f4fa0758e57e1ff035b8c4691c82986f",
"title": "d1875b64f4fa0758e57e1ff035b8c4691c82986f",
"url": "https://fuchsia.googlesource.com/integration/+/d1875b64f4fa0758e57e1ff035b8c4691c82986f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "56de33dc1540e4970bc7a0e0a46d4358f28d4357"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@56de33dc1540e4970bc7a0e0a46d4358f28d4357",
"title": "56de33dc1540e4970bc7a0e0a46d4358f28d4357",
"url": "https://fuchsia.googlesource.com/integration/+/56de33dc1540e4970bc7a0e0a46d4358f28d4357"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d07f4d8f6cf886ff4fb53600f47d3b5762b29961"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d07f4d8f6cf886ff4fb53600f47d3b5762b29961",
"title": "d07f4d8f6cf886ff4fb53600f47d3b5762b29961",
"url": "https://fuchsia.googlesource.com/integration/+/d07f4d8f6cf886ff4fb53600f47d3b5762b29961"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d2246e96a499d17029e023cda81197702728ab4f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d2246e96a499d17029e023cda81197702728ab4f",
"title": "d2246e96a499d17029e023cda81197702728ab4f",
"url": "https://fuchsia.googlesource.com/integration/+/d2246e96a499d17029e023cda81197702728ab4f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "effb737caa788507e42cd4a8792b03f7cd0df283"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@effb737caa788507e42cd4a8792b03f7cd0df283",
"title": "effb737caa788507e42cd4a8792b03f7cd0df283",
"url": "https://fuchsia.googlesource.com/integration/+/effb737caa788507e42cd4a8792b03f7cd0df283"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a7b49b3be014db3d64ad287845e7e2c69b50c7f9"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a7b49b3be014db3d64ad287845e7e2c69b50c7f9",
"title": "a7b49b3be014db3d64ad287845e7e2c69b50c7f9",
"url": "https://fuchsia.googlesource.com/integration/+/a7b49b3be014db3d64ad287845e7e2c69b50c7f9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8701cd38bb433ba743440f699d8be1fa24df335b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8701cd38bb433ba743440f699d8be1fa24df335b",
"title": "8701cd38bb433ba743440f699d8be1fa24df335b",
"url": "https://fuchsia.googlesource.com/integration/+/8701cd38bb433ba743440f699d8be1fa24df335b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "20600c886bc935700e9f4e9a82d9ae83be451ef4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@20600c886bc935700e9f4e9a82d9ae83be451ef4",
"title": "20600c886bc935700e9f4e9a82d9ae83be451ef4",
"url": "https://fuchsia.googlesource.com/integration/+/20600c886bc935700e9f4e9a82d9ae83be451ef4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1813c4bd500ee930c3f482fdc1b3cfb09951a3fe"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1813c4bd500ee930c3f482fdc1b3cfb09951a3fe",
"title": "1813c4bd500ee930c3f482fdc1b3cfb09951a3fe",
"url": "https://fuchsia.googlesource.com/integration/+/1813c4bd500ee930c3f482fdc1b3cfb09951a3fe"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "90c63ebede0907e5043f052f8d2a03164f3d1bd2"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@90c63ebede0907e5043f052f8d2a03164f3d1bd2",
"title": "90c63ebede0907e5043f052f8d2a03164f3d1bd2",
"url": "https://fuchsia.googlesource.com/integration/+/90c63ebede0907e5043f052f8d2a03164f3d1bd2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ff19f0c815ee5ddc888935c88e7282dfb8d91be7"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ff19f0c815ee5ddc888935c88e7282dfb8d91be7",
"title": "ff19f0c815ee5ddc888935c88e7282dfb8d91be7",
"url": "https://fuchsia.googlesource.com/integration/+/ff19f0c815ee5ddc888935c88e7282dfb8d91be7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2f5b41157202d8a88110ab53bd5cdbeafb004697"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2f5b41157202d8a88110ab53bd5cdbeafb004697",
"title": "2f5b41157202d8a88110ab53bd5cdbeafb004697",
"url": "https://fuchsia.googlesource.com/integration/+/2f5b41157202d8a88110ab53bd5cdbeafb004697"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "81cbc0fab33ec268b76eabe6e3700003716d0283"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@81cbc0fab33ec268b76eabe6e3700003716d0283",
"title": "81cbc0fab33ec268b76eabe6e3700003716d0283",
"url": "https://fuchsia.googlesource.com/integration/+/81cbc0fab33ec268b76eabe6e3700003716d0283"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6fb99cf85efd65233be34db632a21a2464e9458a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6fb99cf85efd65233be34db632a21a2464e9458a",
"title": "6fb99cf85efd65233be34db632a21a2464e9458a",
"url": "https://fuchsia.googlesource.com/integration/+/6fb99cf85efd65233be34db632a21a2464e9458a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a12035ef6753f6bd267cbc8804c919756186716f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a12035ef6753f6bd267cbc8804c919756186716f",
"title": "a12035ef6753f6bd267cbc8804c919756186716f",
"url": "https://fuchsia.googlesource.com/integration/+/a12035ef6753f6bd267cbc8804c919756186716f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d8bb048ef475639bf3863afad287ddbab3359ae4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d8bb048ef475639bf3863afad287ddbab3359ae4",
"title": "d8bb048ef475639bf3863afad287ddbab3359ae4",
"url": "https://fuchsia.googlesource.com/integration/+/d8bb048ef475639bf3863afad287ddbab3359ae4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2c7c32b175f2482fa9575db57f3d2098c8849290"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2c7c32b175f2482fa9575db57f3d2098c8849290",
"title": "2c7c32b175f2482fa9575db57f3d2098c8849290",
"url": "https://fuchsia.googlesource.com/integration/+/2c7c32b175f2482fa9575db57f3d2098c8849290"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "be54bd20056cab74fa880735e5bf29d6a6840985"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@be54bd20056cab74fa880735e5bf29d6a6840985",
"title": "be54bd20056cab74fa880735e5bf29d6a6840985",
"url": "https://fuchsia.googlesource.com/integration/+/be54bd20056cab74fa880735e5bf29d6a6840985"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "aba3c3bebe0120321b365f25cb620275b66a13db"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@aba3c3bebe0120321b365f25cb620275b66a13db",
"title": "aba3c3bebe0120321b365f25cb620275b66a13db",
"url": "https://fuchsia.googlesource.com/integration/+/aba3c3bebe0120321b365f25cb620275b66a13db"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "44a40f1e034dd9fe09ca476f9a77059850f1b1c5"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@44a40f1e034dd9fe09ca476f9a77059850f1b1c5",
"title": "44a40f1e034dd9fe09ca476f9a77059850f1b1c5",
"url": "https://fuchsia.googlesource.com/integration/+/44a40f1e034dd9fe09ca476f9a77059850f1b1c5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f95015d19d23c34fe3764c9cb5f2b9d2af770dc6"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f95015d19d23c34fe3764c9cb5f2b9d2af770dc6",
"title": "f95015d19d23c34fe3764c9cb5f2b9d2af770dc6",
"url": "https://fuchsia.googlesource.com/integration/+/f95015d19d23c34fe3764c9cb5f2b9d2af770dc6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e43692acacb43964ad72d545c62aaa21e3cc42a3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e43692acacb43964ad72d545c62aaa21e3cc42a3",
"title": "e43692acacb43964ad72d545c62aaa21e3cc42a3",
"url": "https://fuchsia.googlesource.com/integration/+/e43692acacb43964ad72d545c62aaa21e3cc42a3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ae93ead7c7de643eb7acb88d0462bdd81b5002b0"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ae93ead7c7de643eb7acb88d0462bdd81b5002b0",
"title": "ae93ead7c7de643eb7acb88d0462bdd81b5002b0",
"url": "https://fuchsia.googlesource.com/integration/+/ae93ead7c7de643eb7acb88d0462bdd81b5002b0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "bf094cfcdf0dff08c71a898201ae19918f8bef4d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@bf094cfcdf0dff08c71a898201ae19918f8bef4d",
"title": "bf094cfcdf0dff08c71a898201ae19918f8bef4d",
"url": "https://fuchsia.googlesource.com/integration/+/bf094cfcdf0dff08c71a898201ae19918f8bef4d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c80157cd01ecc1e1a41a10c97f28eb35f3d248b9"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c80157cd01ecc1e1a41a10c97f28eb35f3d248b9",
"title": "c80157cd01ecc1e1a41a10c97f28eb35f3d248b9",
"url": "https://fuchsia.googlesource.com/integration/+/c80157cd01ecc1e1a41a10c97f28eb35f3d248b9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "49252e468c503ec79b10059153c95532a3974a19"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@49252e468c503ec79b10059153c95532a3974a19",
"title": "49252e468c503ec79b10059153c95532a3974a19",
"url": "https://fuchsia.googlesource.com/integration/+/49252e468c503ec79b10059153c95532a3974a19"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "5ab7c6fc7baa4cb6aebd488d3b38924601b8b420"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@5ab7c6fc7baa4cb6aebd488d3b38924601b8b420",
"title": "5ab7c6fc7baa4cb6aebd488d3b38924601b8b420",
"url": "https://fuchsia.googlesource.com/integration/+/5ab7c6fc7baa4cb6aebd488d3b38924601b8b420"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "64466f89963988936f4786b7cd0db17cf49f5f96"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@64466f89963988936f4786b7cd0db17cf49f5f96",
"title": "64466f89963988936f4786b7cd0db17cf49f5f96",
"url": "https://fuchsia.googlesource.com/integration/+/64466f89963988936f4786b7cd0db17cf49f5f96"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ee0888aa7c45e1d14b4c47c3652a8e2bddb70f7e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ee0888aa7c45e1d14b4c47c3652a8e2bddb70f7e",
"title": "ee0888aa7c45e1d14b4c47c3652a8e2bddb70f7e",
"url": "https://fuchsia.googlesource.com/integration/+/ee0888aa7c45e1d14b4c47c3652a8e2bddb70f7e"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "ee0888aa7c45e1d14b4c47c3652a8e2bddb70f7e",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8935927323629074128"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/core.x64-debug-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[18:50:43.774] Scheduled build:
{
"id": "8770497259981141457",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-09-08T18:50:43.252885837Z",
"updateTime": "2023-09-08T18:50:43.252885837Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "ee0888aa7c45e1d14b4c47c3652a8e2bddb70f7e",
"ref": "refs/heads/main"
}
},
"canary": true
}
[18:50:43.774] Task URL: https://cr-buildbucket.appspot.com/build/8770497259981141457
[18:50:43.774] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935927323629074128:2:0) after 3m44s
[18:50:44.731] Received PubSub notification, asking Buildbucket for the build status
[18:50:44.748] Build status: SCHEDULED
[18:51:20.604] Received PubSub notification, asking Buildbucket for the build status
[18:51:20.626] Build status: STARTED
[18:54:27.837] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935927323629074128:2:0)
[18:54:27.873] Build status: STARTED
[18:54:27.873] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935927323629074128:5:0) after 8m4s
[19:02:31.913] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935927323629074128:5:0)
[19:02:31.934] Build status: STARTED
[19:02:31.934] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935927323629074128:6:0) after 9m57s
[19:12:29.323] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935927323629074128:6:0)
[19:12:29.346] Build status: STARTED
[19:12:29.346] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935927323629074128:7:0) after 2m59s
[19:15:28.380] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935927323629074128:7:0)
[19:15:28.398] Build status: STARTED
[19:15:28.398] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935927323629074128:8:0) after 7m8s
[19:22:36.572] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935927323629074128:8:0)
[19:22:36.593] Build status: STARTED
[19:22:36.594] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935927323629074128:9:0) after 5m41s
[19:28:17.631] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935927323629074128:9:0)
[19:28:17.716] Build status: STARTED
[19:28:17.716] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935927323629074128:10:0) after 7m5s
[19:35:22.850] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935927323629074128:10:0)
[19:35:22.963] Build status: STARTED
[19:35:22.963] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935927323629074128:11:0) after 2m33s
[19:37:56.028] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935927323629074128:11:0)
[19:37:56.052] Build status: STARTED
[19:37:56.052] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935927323629074128:12:0) after 8m31s
[19:46:27.201] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935927323629074128:12:0)
[19:46:27.222] Build status: STARTED
[19:46:27.222] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935927323629074128:13:0) after 3m6s
[19:49:33.323] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935927323629074128:13:0)
[19:49:33.342] Build status: STARTED
[19:49:33.342] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935927323629074128:14:0) after 7m16s
[19:56:50.127] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935927323629074128:14:0)
[19:56:50.147] Build status: STARTED
[19:56:50.147] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935927323629074128:15:0) after 9m37s
[20:00:15.054] Received PubSub notification, asking Buildbucket for the build status
[20:00:15.078] Build:
{
"id": "8770497259981141457",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-09-08T18:50:43.252885837Z",
"startTime": "2023-09-08T18:51:20.004211Z",
"endTime": "2023-09-08T20:00:14.358239501Z",
"updateTime": "2023-09-08T20:00:14.358239501Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "ee0888aa7c45e1d14b4c47c3652a8e2bddb70f7e",
"ref": "refs/heads/main"
}
},
"canary": true
}
[20:00:15.079] Invocation finished in 1h9m34.688513616s with status SUCCEEDED