[16:54:52.186] New invocation is queued and will start shortly
[16:54:53.367] Starting the invocation (attempt 1)
[16:54:53.394] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/b51d74d50c00843f4f8d3c7b75f683b8510860a3"
[16:54:53.394] Popped gitiles commit info from properties and tags
[16:54:53.394] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:54:53.394] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:54:53.394] Buildbucket request:
{
"requestId": "8939105503186239792",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8939105503186239792",
"job": "fuchsia/firebase-docs-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "72a36959f68ce7ac2c7a3338cdb1220c56644e53"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@72a36959f68ce7ac2c7a3338cdb1220c56644e53",
"title": "72a36959f68ce7ac2c7a3338cdb1220c56644e53",
"url": "https://fuchsia.googlesource.com/integration/+/72a36959f68ce7ac2c7a3338cdb1220c56644e53"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8f329c3687a3da29e0d6aadb2b2e55b3957188ba"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8f329c3687a3da29e0d6aadb2b2e55b3957188ba",
"title": "8f329c3687a3da29e0d6aadb2b2e55b3957188ba",
"url": "https://fuchsia.googlesource.com/integration/+/8f329c3687a3da29e0d6aadb2b2e55b3957188ba"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8250a7047dce5a5f39140aed38ecf24f942b17ce"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8250a7047dce5a5f39140aed38ecf24f942b17ce",
"title": "8250a7047dce5a5f39140aed38ecf24f942b17ce",
"url": "https://fuchsia.googlesource.com/integration/+/8250a7047dce5a5f39140aed38ecf24f942b17ce"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f99893266202a25116c73fb8b5db853db1ad51e1"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f99893266202a25116c73fb8b5db853db1ad51e1",
"title": "f99893266202a25116c73fb8b5db853db1ad51e1",
"url": "https://fuchsia.googlesource.com/integration/+/f99893266202a25116c73fb8b5db853db1ad51e1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "79c8c61963a9211f37f37ca1af1ac0d203588a72"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@79c8c61963a9211f37f37ca1af1ac0d203588a72",
"title": "79c8c61963a9211f37f37ca1af1ac0d203588a72",
"url": "https://fuchsia.googlesource.com/integration/+/79c8c61963a9211f37f37ca1af1ac0d203588a72"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2ed80038b485be1455c5386098c9ac53065cf5a2"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2ed80038b485be1455c5386098c9ac53065cf5a2",
"title": "2ed80038b485be1455c5386098c9ac53065cf5a2",
"url": "https://fuchsia.googlesource.com/integration/+/2ed80038b485be1455c5386098c9ac53065cf5a2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "82949673d685cf7b9a5ba6bc3ba44870022c7c53"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@82949673d685cf7b9a5ba6bc3ba44870022c7c53",
"title": "82949673d685cf7b9a5ba6bc3ba44870022c7c53",
"url": "https://fuchsia.googlesource.com/integration/+/82949673d685cf7b9a5ba6bc3ba44870022c7c53"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "faf8224256f4c29ca7d55206601fee1701911ca5"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@faf8224256f4c29ca7d55206601fee1701911ca5",
"title": "faf8224256f4c29ca7d55206601fee1701911ca5",
"url": "https://fuchsia.googlesource.com/integration/+/faf8224256f4c29ca7d55206601fee1701911ca5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "56ae1b4d1b3d6b9ef3a9cde0a9c8083a8e3f54fc"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@56ae1b4d1b3d6b9ef3a9cde0a9c8083a8e3f54fc",
"title": "56ae1b4d1b3d6b9ef3a9cde0a9c8083a8e3f54fc",
"url": "https://fuchsia.googlesource.com/integration/+/56ae1b4d1b3d6b9ef3a9cde0a9c8083a8e3f54fc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b51d74d50c00843f4f8d3c7b75f683b8510860a3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b51d74d50c00843f4f8d3c7b75f683b8510860a3",
"title": "b51d74d50c00843f4f8d3c7b75f683b8510860a3",
"url": "https://fuchsia.googlesource.com/integration/+/b51d74d50c00843f4f8d3c7b75f683b8510860a3"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "b51d74d50c00843f4f8d3c7b75f683b8510860a3",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8939105503186239792"
},
{
"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"
}
}
[16:54:53.855] Scheduled build:
{
"id": "8773675441184080305",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2023-08-04T16:54:53.466446173Z",
"updateTime": "2023-08-04T16:54:53.466446173Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "b51d74d50c00843f4f8d3c7b75f683b8510860a3",
"ref": "refs/heads/main"
}
}
}
[16:54:53.855] Task URL: https://cr-buildbucket.appspot.com/build/8773675441184080305
[16:54:53.855] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939105503186239792:2:0) after 5m27s
[16:54:54.890] Received PubSub notification, asking Buildbucket for the build status
[16:54:54.913] Build status: SCHEDULED
[16:55:23.329] Received PubSub notification, asking Buildbucket for the build status
[16:55:23.349] Build status: STARTED
[17:00:21.042] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939105503186239792:2:0)
[17:00:21.078] Build status: STARTED
[17:00:21.078] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939105503186239792:5:0) after 9m33s
[17:09:54.279] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939105503186239792:5:0)
[17:09:54.297] Build status: STARTED
[17:09:54.297] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939105503186239792:6:0) after 1m28s
[17:11:22.473] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939105503186239792:6:0)
[17:11:22.563] Build status: STARTED
[17:11:22.563] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939105503186239792:7:0) after 9m44s
[17:21:06.771] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939105503186239792:7:0)
[17:21:06.800] Build status: STARTED
[17:21:06.800] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939105503186239792:8:0) after 3m56s
[17:25:02.973] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939105503186239792:8:0)
[17:25:02.999] Build status: STARTED
[17:25:02.999] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939105503186239792:9:0) after 3m49s
[17:28:52.302] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939105503186239792:9:0)
[17:28:52.322] Build status: STARTED
[17:28:52.322] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939105503186239792:10:0) after 2m51s
[17:31:43.434] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939105503186239792:10:0)
[17:31:43.626] Build status: STARTED
[17:31:43.626] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939105503186239792:11:0) after 5m25s
[17:37:09.273] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939105503186239792:11:0)
[17:37:09.290] Build status: STARTED
[17:37:09.290] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939105503186239792:12:0) after 5m7s
[17:42:16.306] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939105503186239792:12:0)
[17:42:16.329] Build status: STARTED
[17:42:16.329] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939105503186239792:13:0) after 9m12s
[17:51:28.838] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939105503186239792:13:0)
[17:51:28.857] Build status: STARTED
[17:51:28.857] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939105503186239792:14:0) after 7m13s
[17:58:41.874] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939105503186239792:14:0)
[17:58:41.967] Build status: STARTED
[17:58:41.967] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939105503186239792:15:0) after 8m22s
[18:07:03.986] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939105503186239792:15:0)
[18:07:04.015] Build status: STARTED
[18:07:04.015] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939105503186239792:16:0) after 7m2s
[18:14:06.661] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939105503186239792:16:0)
[18:14:06.699] Build status: STARTED
[18:14:06.699] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939105503186239792:17:0) after 5m44s
[18:19:51.043] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939105503186239792:17:0)
[18:19:51.059] Build status: STARTED
[18:19:51.059] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939105503186239792:18:0) after 9m47s
[18:29:38.079] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939105503186239792:18:0)
[18:29:38.176] Build status: STARTED
[18:29:38.176] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939105503186239792:19:0) after 5m46s
[18:35:24.237] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939105503186239792:19:0)
[18:35:24.256] Build status: STARTED
[18:35:24.256] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939105503186239792:20:0) after 4m29s
[18:39:53.364] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939105503186239792:20:0)
[18:39:53.384] Build status: STARTED
[18:39:53.384] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939105503186239792:21:0) after 3m43s
[18:43:36.389] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939105503186239792:21:0)
[18:43:36.409] Build status: STARTED
[18:43:36.409] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939105503186239792:22:0) after 2m16s
[18:45:52.493] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939105503186239792:22:0)
[18:45:52.510] Build status: STARTED
[18:45:52.510] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939105503186239792:23:0) after 6m45s
[18:50:10.765] Received PubSub notification, asking Buildbucket for the build status
[18:50:10.794] Build:
{
"id": "8773675441184080305",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2023-08-04T16:54:53.466446173Z",
"startTime": "2023-08-04T16:55:23.019386310Z",
"endTime": "2023-08-04T18:50:09.969924002Z",
"updateTime": "2023-08-04T18:50:09.969924002Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "b51d74d50c00843f4f8d3c7b75f683b8510860a3",
"ref": "refs/heads/main"
}
}
}
[18:50:10.794] Invocation finished in 1h55m18.621397619s with status SUCCEEDED