[00:17:43.570] New invocation is queued and will start shortly
[00:17:46.433] Starting the invocation (attempt 1)
[00:17:46.469] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/bab9bf0742763649d98ea7619ab81feadd243fcd"
[00:17:46.469] Popped gitiles commit info from properties and tags
[00:17:46.469] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:17:46.469] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:17:46.469] Buildbucket request:
{
"requestId": "8952123604231399712",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8952123604231399712",
"job": "fuchsia/firebase-docs-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "dbd753250c210a1aa24887039bc2771c0121a3f4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@dbd753250c210a1aa24887039bc2771c0121a3f4",
"title": "dbd753250c210a1aa24887039bc2771c0121a3f4",
"url": "https://fuchsia.googlesource.com/integration/+/dbd753250c210a1aa24887039bc2771c0121a3f4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "33856ff74f7345d91bec4a6142785aa739dbf6bb"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@33856ff74f7345d91bec4a6142785aa739dbf6bb",
"title": "33856ff74f7345d91bec4a6142785aa739dbf6bb",
"url": "https://fuchsia.googlesource.com/integration/+/33856ff74f7345d91bec4a6142785aa739dbf6bb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "056cc320b29627643379f1646b7ab127455b67f7"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@056cc320b29627643379f1646b7ab127455b67f7",
"title": "056cc320b29627643379f1646b7ab127455b67f7",
"url": "https://fuchsia.googlesource.com/integration/+/056cc320b29627643379f1646b7ab127455b67f7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3b505ad0467e5235ae1c8aa5d8fea77e6be856ba"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3b505ad0467e5235ae1c8aa5d8fea77e6be856ba",
"title": "3b505ad0467e5235ae1c8aa5d8fea77e6be856ba",
"url": "https://fuchsia.googlesource.com/integration/+/3b505ad0467e5235ae1c8aa5d8fea77e6be856ba"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "06716dd55476d1a6f9b6d4e5093ee77ab4f3cf99"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@06716dd55476d1a6f9b6d4e5093ee77ab4f3cf99",
"title": "06716dd55476d1a6f9b6d4e5093ee77ab4f3cf99",
"url": "https://fuchsia.googlesource.com/integration/+/06716dd55476d1a6f9b6d4e5093ee77ab4f3cf99"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3f1de6152ae3f5e1b2ba55ecb7068d77c7a2da0f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3f1de6152ae3f5e1b2ba55ecb7068d77c7a2da0f",
"title": "3f1de6152ae3f5e1b2ba55ecb7068d77c7a2da0f",
"url": "https://fuchsia.googlesource.com/integration/+/3f1de6152ae3f5e1b2ba55ecb7068d77c7a2da0f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1374c9bc52b4b8daa2a7503e212fc4d92f115b5f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1374c9bc52b4b8daa2a7503e212fc4d92f115b5f",
"title": "1374c9bc52b4b8daa2a7503e212fc4d92f115b5f",
"url": "https://fuchsia.googlesource.com/integration/+/1374c9bc52b4b8daa2a7503e212fc4d92f115b5f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9a3e63853375c403b91e1961780d425294731908"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9a3e63853375c403b91e1961780d425294731908",
"title": "9a3e63853375c403b91e1961780d425294731908",
"url": "https://fuchsia.googlesource.com/integration/+/9a3e63853375c403b91e1961780d425294731908"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "623fd83d4f8b8bc18c1cca06234cb9b0aa3d1ad9"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@623fd83d4f8b8bc18c1cca06234cb9b0aa3d1ad9",
"title": "623fd83d4f8b8bc18c1cca06234cb9b0aa3d1ad9",
"url": "https://fuchsia.googlesource.com/integration/+/623fd83d4f8b8bc18c1cca06234cb9b0aa3d1ad9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b85464d8a8c80550f29c4288116964bd8ac7f50e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b85464d8a8c80550f29c4288116964bd8ac7f50e",
"title": "b85464d8a8c80550f29c4288116964bd8ac7f50e",
"url": "https://fuchsia.googlesource.com/integration/+/b85464d8a8c80550f29c4288116964bd8ac7f50e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "22e11e1ab5283ff4300ffb38a015e2b9ed06ffe1"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@22e11e1ab5283ff4300ffb38a015e2b9ed06ffe1",
"title": "22e11e1ab5283ff4300ffb38a015e2b9ed06ffe1",
"url": "https://fuchsia.googlesource.com/integration/+/22e11e1ab5283ff4300ffb38a015e2b9ed06ffe1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "926eee68726400bc48789090c74bd6f52824176f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@926eee68726400bc48789090c74bd6f52824176f",
"title": "926eee68726400bc48789090c74bd6f52824176f",
"url": "https://fuchsia.googlesource.com/integration/+/926eee68726400bc48789090c74bd6f52824176f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "261739ddecd104100571363b0daa3487e64d8fbc"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@261739ddecd104100571363b0daa3487e64d8fbc",
"title": "261739ddecd104100571363b0daa3487e64d8fbc",
"url": "https://fuchsia.googlesource.com/integration/+/261739ddecd104100571363b0daa3487e64d8fbc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "871458ffc71230773353a43841dbee957cd81da9"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@871458ffc71230773353a43841dbee957cd81da9",
"title": "871458ffc71230773353a43841dbee957cd81da9",
"url": "https://fuchsia.googlesource.com/integration/+/871458ffc71230773353a43841dbee957cd81da9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "599d22d5df807b023917df496071e3ce06ac787e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@599d22d5df807b023917df496071e3ce06ac787e",
"title": "599d22d5df807b023917df496071e3ce06ac787e",
"url": "https://fuchsia.googlesource.com/integration/+/599d22d5df807b023917df496071e3ce06ac787e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8c1b6ea47e20321970b43b779d803cc003bd7227"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8c1b6ea47e20321970b43b779d803cc003bd7227",
"title": "8c1b6ea47e20321970b43b779d803cc003bd7227",
"url": "https://fuchsia.googlesource.com/integration/+/8c1b6ea47e20321970b43b779d803cc003bd7227"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "bab9bf0742763649d98ea7619ab81feadd243fcd"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@bab9bf0742763649d98ea7619ab81feadd243fcd",
"title": "bab9bf0742763649d98ea7619ab81feadd243fcd",
"url": "https://fuchsia.googlesource.com/integration/+/bab9bf0742763649d98ea7619ab81feadd243fcd"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "bab9bf0742763649d98ea7619ab81feadd243fcd",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8952123604231399712"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/firebase-docs-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[00:17:46.835] Scheduled build:
{
"id": "8786693540426911505",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2023-03-14T00:17:46.570435143Z",
"updateTime": "2023-03-14T00:17:46.570435143Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "bab9bf0742763649d98ea7619ab81feadd243fcd",
"ref": "refs/heads/main"
}
},
"canary": true
}
[00:17:46.835] Task URL: https://cr-buildbucket.appspot.com/build/8786693540426911505
[00:17:46.836] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:2:0) after 9m45s
[00:21:49.354] Received PubSub notification, asking Buildbucket for the build status
[00:21:49.438] Build status: STARTED
[00:27:32.086] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:2:0)
[00:27:32.151] Build status: STARTED
[00:27:32.151] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:4:0) after 3m46s
[00:31:18.398] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:4:0)
[00:31:18.430] Build status: STARTED
[00:31:18.430] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:5:0) after 3m54s
[00:35:12.452] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:5:0)
[00:35:12.474] Build status: STARTED
[00:35:12.474] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:6:0) after 4m57s
[00:40:09.546] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:6:0)
[00:40:09.569] Build status: STARTED
[00:40:09.569] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:7:0) after 9m34s
[00:49:43.593] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:7:0)
[00:49:43.663] Build status: STARTED
[00:49:43.663] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:8:0) after 9m50s
[00:59:33.686] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:8:0)
[00:59:33.723] Build status: STARTED
[00:59:33.723] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:9:0) after 7m0s
[01:06:33.868] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:9:0)
[01:06:33.895] Build status: STARTED
[01:06:33.895] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:10:0) after 7m17s
[01:13:50.912] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:10:0)
[01:13:50.943] Build status: STARTED
[01:13:50.943] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:11:0) after 2m19s
[01:16:09.962] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:11:0)
[01:16:10.065] Build status: STARTED
[01:16:10.065] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:12:0) after 6m20s
[01:22:30.094] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:12:0)
[01:22:30.120] Build status: STARTED
[01:22:30.120] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:13:0) after 1m33s
[01:24:03.246] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:13:0)
[01:24:03.271] Build status: STARTED
[01:24:03.271] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:14:0) after 9m15s
[01:33:18.298] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:14:0)
[01:33:18.320] Build status: STARTED
[01:33:18.320] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:15:0) after 1m54s
[01:35:12.369] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:15:0)
[01:35:12.425] Build status: STARTED
[01:35:12.425] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:16:0) after 3m56s
[01:39:08.772] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:16:0)
[01:39:09.065] Build status: STARTED
[01:39:09.065] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:17:0) after 3m34s
[01:42:43.086] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:17:0)
[01:42:43.107] Build status: STARTED
[01:42:43.107] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:18:0) after 5m1s
[01:47:44.175] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:18:0)
[01:47:44.207] Build status: STARTED
[01:47:44.207] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:19:0) after 3m58s
[01:51:42.329] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:19:0)
[01:51:42.351] Build status: STARTED
[01:51:42.351] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:20:0) after 6m27s
[01:58:09.571] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:20:0)
[01:58:09.599] Build status: STARTED
[01:58:09.599] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:21:0) after 2m47s
[02:00:56.790] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:21:0)
[02:00:56.813] Build status: STARTED
[02:00:56.814] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:22:0) after 1m47s
[02:02:43.862] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:22:0)
[02:02:43.952] Build status: STARTED
[02:02:43.952] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:23:0) after 8m18s
[02:11:02.226] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:23:0)
[02:11:02.315] Build status: STARTED
[02:11:02.315] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:24:0) after 9m10s
[02:20:12.528] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:24:0)
[02:20:12.550] Build status: STARTED
[02:20:12.550] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:25:0) after 7m14s
[02:27:26.730] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:25:0)
[02:27:26.750] Build status: STARTED
[02:27:26.750] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:26:0) after 2m35s
[02:30:01.789] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:26:0)
[02:30:01.821] Build status: STARTED
[02:30:01.821] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:27:0) after 8m20s
[02:38:21.816] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:27:0)
[02:38:21.837] Build status: STARTED
[02:38:21.837] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:28:0) after 3m36s
[02:41:57.971] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:28:0)
[02:41:57.994] Build status: STARTED
[02:41:57.994] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:29:0) after 9m33s
[02:51:31.088] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:29:0)
[02:51:31.111] Build status: STARTED
[02:51:31.111] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:30:0) after 9m31s
[03:01:02.140] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:30:0)
[03:01:02.217] Build status: STARTED
[03:01:02.217] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:31:0) after 7m38s
[03:08:40.314] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:31:0)
[03:08:40.346] Build status: STARTED
[03:08:40.346] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:32:0) after 6m20s
[03:15:00.513] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:32:0)
[03:15:00.535] Build status: STARTED
[03:15:00.535] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:33:0) after 3m21s
[03:18:21.562] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:33:0)
[03:18:21.596] Build status: STARTED
[03:18:21.596] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8952123604231399712:34:0) after 5m44s
[03:18:29.639] Received PubSub notification, asking Buildbucket for the build status
[03:18:29.674] Build:
{
"id": "8786693540426911505",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2023-03-14T00:17:46.570435143Z",
"startTime": "2023-03-14T00:21:48.591971Z",
"endTime": "2023-03-14T03:18:28.553419762Z",
"updateTime": "2023-03-14T03:18:28.553419762Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "bab9bf0742763649d98ea7619ab81feadd243fcd",
"ref": "refs/heads/main"
}
},
"canary": true
}
[03:18:29.674] Invocation finished in 3h0m46.116878171s with status SUCCEEDED