[16:45:05.974] New invocation is queued and will start shortly
[16:45:07.085] Starting the invocation (attempt 1)
[16:45:07.117] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/a1f184c405c99b75b73b57873558b0d9eb48a17c"
[16:45:07.117] Popped gitiles commit info from properties and tags
[16:45:07.117] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:45:07.117] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:45:07.118] Buildbucket request:
{
"requestId": "8957678495999338192",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8957678495999338192",
"job": "fuchsia/firebase-docs-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e75221250142d13f340776cbaf486605db210b3e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e75221250142d13f340776cbaf486605db210b3e",
"title": "e75221250142d13f340776cbaf486605db210b3e",
"url": "https://fuchsia.googlesource.com/integration/+/e75221250142d13f340776cbaf486605db210b3e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3ca309bcee5c79872e17079fa7456297c343dfcf"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3ca309bcee5c79872e17079fa7456297c343dfcf",
"title": "3ca309bcee5c79872e17079fa7456297c343dfcf",
"url": "https://fuchsia.googlesource.com/integration/+/3ca309bcee5c79872e17079fa7456297c343dfcf"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2036164a6a795193b1d7250a5f470f8e3c71cc20"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2036164a6a795193b1d7250a5f470f8e3c71cc20",
"title": "2036164a6a795193b1d7250a5f470f8e3c71cc20",
"url": "https://fuchsia.googlesource.com/integration/+/2036164a6a795193b1d7250a5f470f8e3c71cc20"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "64c353e18e4b9f10ecb1f9b9035a2dafb022d093"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@64c353e18e4b9f10ecb1f9b9035a2dafb022d093",
"title": "64c353e18e4b9f10ecb1f9b9035a2dafb022d093",
"url": "https://fuchsia.googlesource.com/integration/+/64c353e18e4b9f10ecb1f9b9035a2dafb022d093"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "541545a70016e3c5c7f45786ab47b3d51fac0e18"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@541545a70016e3c5c7f45786ab47b3d51fac0e18",
"title": "541545a70016e3c5c7f45786ab47b3d51fac0e18",
"url": "https://fuchsia.googlesource.com/integration/+/541545a70016e3c5c7f45786ab47b3d51fac0e18"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "785cf62bca842f16383cef6b69923738cd584de1"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@785cf62bca842f16383cef6b69923738cd584de1",
"title": "785cf62bca842f16383cef6b69923738cd584de1",
"url": "https://fuchsia.googlesource.com/integration/+/785cf62bca842f16383cef6b69923738cd584de1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6f765e9587de4e4b6de10e4147de2f5265dde987"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6f765e9587de4e4b6de10e4147de2f5265dde987",
"title": "6f765e9587de4e4b6de10e4147de2f5265dde987",
"url": "https://fuchsia.googlesource.com/integration/+/6f765e9587de4e4b6de10e4147de2f5265dde987"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "bfbc036bd21d5c7b6fcd1b2b6007a9e3086f7200"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@bfbc036bd21d5c7b6fcd1b2b6007a9e3086f7200",
"title": "bfbc036bd21d5c7b6fcd1b2b6007a9e3086f7200",
"url": "https://fuchsia.googlesource.com/integration/+/bfbc036bd21d5c7b6fcd1b2b6007a9e3086f7200"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "153ec1a15f0fa81b49d6727e3acd17a0911d33d9"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@153ec1a15f0fa81b49d6727e3acd17a0911d33d9",
"title": "153ec1a15f0fa81b49d6727e3acd17a0911d33d9",
"url": "https://fuchsia.googlesource.com/integration/+/153ec1a15f0fa81b49d6727e3acd17a0911d33d9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0cabfe39a3db70a3f4b7b06ee95142fca1f351e8"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0cabfe39a3db70a3f4b7b06ee95142fca1f351e8",
"title": "0cabfe39a3db70a3f4b7b06ee95142fca1f351e8",
"url": "https://fuchsia.googlesource.com/integration/+/0cabfe39a3db70a3f4b7b06ee95142fca1f351e8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d15fb745685f9cb77b64651a30767ad7d5ca0a1c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d15fb745685f9cb77b64651a30767ad7d5ca0a1c",
"title": "d15fb745685f9cb77b64651a30767ad7d5ca0a1c",
"url": "https://fuchsia.googlesource.com/integration/+/d15fb745685f9cb77b64651a30767ad7d5ca0a1c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f070fb36ad917ed3048502813cf6c8f7df55b779"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f070fb36ad917ed3048502813cf6c8f7df55b779",
"title": "f070fb36ad917ed3048502813cf6c8f7df55b779",
"url": "https://fuchsia.googlesource.com/integration/+/f070fb36ad917ed3048502813cf6c8f7df55b779"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c62fd9523a073db27e9e65269cd0f19abec85bfd"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c62fd9523a073db27e9e65269cd0f19abec85bfd",
"title": "c62fd9523a073db27e9e65269cd0f19abec85bfd",
"url": "https://fuchsia.googlesource.com/integration/+/c62fd9523a073db27e9e65269cd0f19abec85bfd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f2309fa57fbc92f7c05f23d7a3dde639ba574426"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f2309fa57fbc92f7c05f23d7a3dde639ba574426",
"title": "f2309fa57fbc92f7c05f23d7a3dde639ba574426",
"url": "https://fuchsia.googlesource.com/integration/+/f2309fa57fbc92f7c05f23d7a3dde639ba574426"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a25fc95e0d43fcb7bd64d77bb151f3c17307a0a2"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a25fc95e0d43fcb7bd64d77bb151f3c17307a0a2",
"title": "a25fc95e0d43fcb7bd64d77bb151f3c17307a0a2",
"url": "https://fuchsia.googlesource.com/integration/+/a25fc95e0d43fcb7bd64d77bb151f3c17307a0a2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a1f184c405c99b75b73b57873558b0d9eb48a17c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a1f184c405c99b75b73b57873558b0d9eb48a17c",
"title": "a1f184c405c99b75b73b57873558b0d9eb48a17c",
"url": "https://fuchsia.googlesource.com/integration/+/a1f184c405c99b75b73b57873558b0d9eb48a17c"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "a1f184c405c99b75b73b57873558b0d9eb48a17c",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8957678495999338192"
},
{
"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:45:07.593] Scheduled build:
{
"id": "8792248433897015713",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2023-01-11T16:45:07.337674257Z",
"updateTime": "2023-01-11T16:45:07.337674257Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "a1f184c405c99b75b73b57873558b0d9eb48a17c",
"ref": "refs/heads/main"
}
}
}
[16:45:07.593] Task URL: https://cr-buildbucket.appspot.com/build/8792248433897015713
[16:45:07.593] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:2:0) after 5m42s
[16:45:49.236] Received PubSub notification, asking Buildbucket for the build status
[16:45:49.266] Build status: STARTED
[16:50:49.711] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:2:0)
[16:50:49.741] Build status: STARTED
[16:50:49.741] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:4:0) after 6m9s
[16:56:58.759] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:4:0)
[16:56:58.793] Build status: STARTED
[16:56:58.793] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:5:0) after 9m21s
[17:06:19.869] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:5:0)
[17:06:19.897] Build status: STARTED
[17:06:19.897] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:6:0) after 1m57s
[17:08:16.920] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:6:0)
[17:08:16.954] Build status: STARTED
[17:08:16.954] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:7:0) after 5m34s
[17:13:51.079] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:7:0)
[17:13:51.109] Build status: STARTED
[17:13:51.109] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:8:0) after 9m3s
[17:22:54.311] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:8:0)
[17:22:54.341] Build status: STARTED
[17:22:54.341] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:9:0) after 8m2s
[17:30:56.368] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:9:0)
[17:30:56.391] Build status: STARTED
[17:30:56.391] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:10:0) after 8m48s
[17:39:44.707] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:10:0)
[17:39:44.734] Build status: STARTED
[17:39:44.734] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:11:0) after 6m23s
[17:46:07.705] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:11:0)
[17:46:07.735] Build status: STARTED
[17:46:07.735] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:12:0) after 8m7s
[17:54:15.164] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:12:0)
[17:54:15.196] Build status: STARTED
[17:54:15.196] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:13:0) after 1m17s
[17:55:32.198] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:13:0)
[17:55:32.227] Build status: STARTED
[17:55:32.227] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:14:0) after 3m40s
[17:59:12.254] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:14:0)
[17:59:12.282] Build status: STARTED
[17:59:12.282] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:15:0) after 4m0s
[18:03:12.484] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:15:0)
[18:03:12.533] Build status: STARTED
[18:03:12.533] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:16:0) after 4m11s
[18:07:24.262] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:16:0)
[18:07:24.293] Build status: STARTED
[18:07:24.293] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:17:0) after 2m43s
[18:10:07.329] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:17:0)
[18:10:07.361] Build status: STARTED
[18:10:07.361] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:18:0) after 8m42s
[18:18:49.729] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:18:0)
[18:18:49.753] Build status: STARTED
[18:18:49.753] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:19:0) after 7m42s
[18:26:31.888] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:19:0)
[18:26:31.911] Build status: STARTED
[18:26:31.911] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:20:0) after 5m56s
[18:32:27.936] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:20:0)
[18:32:27.981] Build status: STARTED
[18:32:27.981] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:21:0) after 4m20s
[18:36:48.002] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:21:0)
[18:36:48.034] Build status: STARTED
[18:36:48.034] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:22:0) after 9m45s
[18:46:33.052] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:22:0)
[18:46:33.083] Build status: STARTED
[18:46:33.083] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:23:0) after 4m40s
[18:51:13.410] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:23:0)
[18:51:13.433] Build status: STARTED
[18:51:13.433] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:24:0) after 2m7s
[18:53:20.691] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:24:0)
[18:53:20.721] Build status: STARTED
[18:53:20.721] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:25:0) after 9m18s
[19:02:38.839] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:25:0)
[19:02:38.867] Build status: STARTED
[19:02:38.867] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:26:0) after 8m33s
[19:11:11.906] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:26:0)
[19:11:11.927] Build status: STARTED
[19:11:11.927] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:27:0) after 4m9s
[19:15:20.955] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:27:0)
[19:15:20.987] Build status: STARTED
[19:15:20.987] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:28:0) after 8m7s
[19:23:28.086] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:28:0)
[19:23:28.123] Build status: STARTED
[19:23:28.123] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:29:0) after 2m31s
[19:25:59.317] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:29:0)
[19:25:59.348] Build status: STARTED
[19:25:59.348] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:30:0) after 7m41s
[19:33:40.646] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:30:0)
[19:33:40.669] Build status: STARTED
[19:33:40.669] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:31:0) after 2m27s
[19:36:07.690] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:31:0)
[19:36:07.715] Build status: STARTED
[19:36:07.715] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:32:0) after 4m9s
[19:40:16.697] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:32:0)
[19:40:16.731] Build status: STARTED
[19:40:16.731] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957678495999338192:33:0) after 8m38s
[19:47:09.210] Received PubSub notification, asking Buildbucket for the build status
[19:47:09.245] Build:
{
"id": "8792248433897015713",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2023-01-11T16:45:07.337674257Z",
"startTime": "2023-01-11T16:45:48.316342Z",
"endTime": "2023-01-11T19:47:08.673772480Z",
"updateTime": "2023-01-11T19:47:08.673772480Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "a1f184c405c99b75b73b57873558b0d9eb48a17c",
"ref": "refs/heads/main"
}
}
}
[19:47:09.245] Invocation finished in 3h2m3.297316619s with status SUCCEEDED