[09:49:56.251] New invocation is queued and will start shortly
[09:49:57.323] Starting the invocation (attempt 1)
[09:49:57.361] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/414c0469d949875e481d44e115e02123da581f2f"
[09:49:57.361] Popped gitiles commit info from properties and tags
[09:49:57.361] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:49:57.361] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:49:57.362] Buildbucket request:
{
"requestId": "8957432824818462880",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8957432824818462880",
"job": "fuchsia/firebase-docs-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "333127b490a6e1251e958fbbcb75f452bb38b451"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@333127b490a6e1251e958fbbcb75f452bb38b451",
"title": "333127b490a6e1251e958fbbcb75f452bb38b451",
"url": "https://fuchsia.googlesource.com/integration/+/333127b490a6e1251e958fbbcb75f452bb38b451"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "312971e4e999fe9442cb342108a3e70494674c50"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@312971e4e999fe9442cb342108a3e70494674c50",
"title": "312971e4e999fe9442cb342108a3e70494674c50",
"url": "https://fuchsia.googlesource.com/integration/+/312971e4e999fe9442cb342108a3e70494674c50"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "330d206b99af53f7ba717db112e6bbd136fc7133"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@330d206b99af53f7ba717db112e6bbd136fc7133",
"title": "330d206b99af53f7ba717db112e6bbd136fc7133",
"url": "https://fuchsia.googlesource.com/integration/+/330d206b99af53f7ba717db112e6bbd136fc7133"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "645ef50c3688c8c1596f3631ffbe5ad745340e4a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@645ef50c3688c8c1596f3631ffbe5ad745340e4a",
"title": "645ef50c3688c8c1596f3631ffbe5ad745340e4a",
"url": "https://fuchsia.googlesource.com/integration/+/645ef50c3688c8c1596f3631ffbe5ad745340e4a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "414c0469d949875e481d44e115e02123da581f2f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@414c0469d949875e481d44e115e02123da581f2f",
"title": "414c0469d949875e481d44e115e02123da581f2f",
"url": "https://fuchsia.googlesource.com/integration/+/414c0469d949875e481d44e115e02123da581f2f"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "414c0469d949875e481d44e115e02123da581f2f",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8957432824818462880"
},
{
"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"
}
}
[09:49:57.736] Scheduled build:
{
"id": "8792002762902121649",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2023-01-14T09:49:57.455594892Z",
"updateTime": "2023-01-14T09:49:57.455594892Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "414c0469d949875e481d44e115e02123da581f2f",
"ref": "refs/heads/main"
}
}
}
[09:49:57.736] Task URL: https://cr-buildbucket.appspot.com/build/8792002762902121649
[09:49:57.736] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:2:0) after 6m46s
[09:52:10.296] Received PubSub notification, asking Buildbucket for the build status
[09:52:10.320] Build status: STARTED
[09:56:43.784] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:2:0)
[09:56:43.807] Build status: STARTED
[09:56:43.807] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:4:0) after 8m16s
[10:04:59.833] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:4:0)
[10:04:59.854] Build status: STARTED
[10:04:59.854] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:5:0) after 1m19s
[10:06:18.870] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:5:0)
[10:06:18.891] Build status: STARTED
[10:06:18.891] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:6:0) after 9m55s
[10:16:13.909] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:6:0)
[10:16:13.931] Build status: STARTED
[10:16:13.931] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:7:0) after 3m7s
[10:19:21.054] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:7:0)
[10:19:21.074] Build status: STARTED
[10:19:21.074] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:8:0) after 8m23s
[10:27:44.197] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:8:0)
[10:27:44.230] Build status: STARTED
[10:27:44.230] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:9:0) after 2m23s
[10:30:07.274] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:9:0)
[10:30:07.298] Build status: STARTED
[10:30:07.298] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:10:0) after 1m55s
[10:32:02.299] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:10:0)
[10:32:02.325] Build status: STARTED
[10:32:02.325] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:11:0) after 1m27s
[10:33:29.383] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:11:0)
[10:33:29.406] Build status: STARTED
[10:33:29.406] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:12:0) after 4m57s
[10:38:26.421] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:12:0)
[10:38:26.443] Build status: STARTED
[10:38:26.443] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:13:0) after 3m47s
[10:42:13.454] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:13:0)
[10:42:13.495] Build status: STARTED
[10:42:13.495] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:14:0) after 3m52s
[10:46:05.624] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:14:0)
[10:46:05.644] Build status: STARTED
[10:46:05.644] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:15:0) after 8m59s
[10:55:04.648] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:15:0)
[10:55:04.674] Build status: STARTED
[10:55:04.674] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:16:0) after 7m24s
[11:02:28.696] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:16:0)
[11:02:28.724] Build status: STARTED
[11:02:28.724] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:17:0) after 1m10s
[11:03:38.749] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:17:0)
[11:03:38.773] Build status: STARTED
[11:03:38.773] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:18:0) after 9m13s
[11:12:51.793] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:18:0)
[11:12:51.818] Build status: STARTED
[11:12:51.818] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:19:0) after 5m9s
[11:18:00.864] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:19:0)
[11:18:01.126] Build status: STARTED
[11:18:01.126] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:20:0) after 7m33s
[11:25:34.154] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:20:0)
[11:25:34.178] Build status: STARTED
[11:25:34.178] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:21:0) after 9m52s
[11:35:26.406] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:21:0)
[11:35:26.441] Build status: STARTED
[11:35:26.441] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:22:0) after 4m55s
[11:40:21.633] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:22:0)
[11:40:21.672] Build status: STARTED
[11:40:21.672] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:23:0) after 8m2s
[11:48:23.826] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:23:0)
[11:48:23.845] Build status: STARTED
[11:48:23.845] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:24:0) after 9m3s
[11:57:26.904] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:24:0)
[11:57:26.929] Build status: STARTED
[11:57:26.929] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:25:0) after 8m16s
[12:05:42.955] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:25:0)
[12:05:42.976] Build status: STARTED
[12:05:42.976] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:26:0) after 4m46s
[12:10:29.093] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:26:0)
[12:10:29.114] Build status: STARTED
[12:10:29.114] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:27:0) after 1m59s
[12:12:28.140] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:27:0)
[12:12:28.175] Build status: STARTED
[12:12:28.175] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:28:0) after 1m3s
[12:13:31.332] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:28:0)
[12:13:31.355] Build status: STARTED
[12:13:31.355] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:29:0) after 6m33s
[12:20:04.456] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:29:0)
[12:20:04.524] Build status: STARTED
[12:20:04.524] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:30:0) after 7m24s
[12:27:28.585] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:30:0)
[12:27:28.605] Build status: STARTED
[12:27:28.605] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:31:0) after 3m33s
[12:31:01.633] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:31:0)
[12:31:01.652] Build status: STARTED
[12:31:01.652] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:32:0) after 3m9s
[12:34:10.670] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:32:0)
[12:34:10.691] Build status: STARTED
[12:34:10.691] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:33:0) after 8m37s
[12:42:47.720] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:33:0)
[12:42:47.750] Build status: STARTED
[12:42:47.750] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:34:0) after 6m42s
[12:49:29.768] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:34:0)
[12:49:29.800] Build status: STARTED
[12:49:29.800] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8957432824818462880:35:0) after 7m0s
[12:54:08.064] Received PubSub notification, asking Buildbucket for the build status
[12:54:08.090] Build:
{
"id": "8792002762902121649",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2023-01-14T09:49:57.455594892Z",
"startTime": "2023-01-14T09:52:09.514407Z",
"endTime": "2023-01-14T12:54:07.532144375Z",
"updateTime": "2023-01-14T12:54:07.532144375Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "414c0469d949875e481d44e115e02123da581f2f",
"ref": "refs/heads/main"
}
}
}
[12:54:08.090] Invocation finished in 3h4m11.8467211s with status SUCCEEDED