[15:08:07.238] New invocation is queued and will start shortly
[15:08:08.353] Starting the invocation (attempt 1)
[15:08:08.379] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/77e9e9759d959a1dfb8d54a29af9edbf30b5bb01"
[15:08:08.379] Popped gitiles commit info from properties and tags
[15:08:08.379] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:08:08.379] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:08:08.380] Buildbucket request:
{
"requestId": "8939112219261575440",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8939112219261575440",
"job": "fuchsia/firebase-docs-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e4e894180c2eb5e85903abfa58419b2e14f101a9"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e4e894180c2eb5e85903abfa58419b2e14f101a9",
"title": "e4e894180c2eb5e85903abfa58419b2e14f101a9",
"url": "https://fuchsia.googlesource.com/integration/+/e4e894180c2eb5e85903abfa58419b2e14f101a9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "60be1fdb0d7ca7ae8f92cb24871b2a55cc573bb0"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@60be1fdb0d7ca7ae8f92cb24871b2a55cc573bb0",
"title": "60be1fdb0d7ca7ae8f92cb24871b2a55cc573bb0",
"url": "https://fuchsia.googlesource.com/integration/+/60be1fdb0d7ca7ae8f92cb24871b2a55cc573bb0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "737e0c9f17ad51cfb1f8c7d1eb1fdea1a093f986"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@737e0c9f17ad51cfb1f8c7d1eb1fdea1a093f986",
"title": "737e0c9f17ad51cfb1f8c7d1eb1fdea1a093f986",
"url": "https://fuchsia.googlesource.com/integration/+/737e0c9f17ad51cfb1f8c7d1eb1fdea1a093f986"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "bbe5c7374305385fd31dbb6530bc4757b7cfb89c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@bbe5c7374305385fd31dbb6530bc4757b7cfb89c",
"title": "bbe5c7374305385fd31dbb6530bc4757b7cfb89c",
"url": "https://fuchsia.googlesource.com/integration/+/bbe5c7374305385fd31dbb6530bc4757b7cfb89c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "89e97a8a93528ef7cc3669738193ab7309385229"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@89e97a8a93528ef7cc3669738193ab7309385229",
"title": "89e97a8a93528ef7cc3669738193ab7309385229",
"url": "https://fuchsia.googlesource.com/integration/+/89e97a8a93528ef7cc3669738193ab7309385229"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e8fc798ab091b1e2c4b31f19efeb75ad8dd24cff"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e8fc798ab091b1e2c4b31f19efeb75ad8dd24cff",
"title": "e8fc798ab091b1e2c4b31f19efeb75ad8dd24cff",
"url": "https://fuchsia.googlesource.com/integration/+/e8fc798ab091b1e2c4b31f19efeb75ad8dd24cff"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c00a3188c37a73d29caeedd5f663658d3419b691"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c00a3188c37a73d29caeedd5f663658d3419b691",
"title": "c00a3188c37a73d29caeedd5f663658d3419b691",
"url": "https://fuchsia.googlesource.com/integration/+/c00a3188c37a73d29caeedd5f663658d3419b691"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "77e9e9759d959a1dfb8d54a29af9edbf30b5bb01"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@77e9e9759d959a1dfb8d54a29af9edbf30b5bb01",
"title": "77e9e9759d959a1dfb8d54a29af9edbf30b5bb01",
"url": "https://fuchsia.googlesource.com/integration/+/77e9e9759d959a1dfb8d54a29af9edbf30b5bb01"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "77e9e9759d959a1dfb8d54a29af9edbf30b5bb01",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8939112219261575440"
},
{
"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"
}
}
[15:08:08.983] Scheduled build:
{
"id": "8773682157197307601",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2023-08-04T15:08:08.577302568Z",
"updateTime": "2023-08-04T15:08:08.577302568Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "77e9e9759d959a1dfb8d54a29af9edbf30b5bb01",
"ref": "refs/heads/main"
}
},
"canary": true
}
[15:08:08.983] Task URL: https://cr-buildbucket.appspot.com/build/8773682157197307601
[15:08:08.983] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939112219261575440:2:0) after 1m19s
[15:08:09.437] Received PubSub notification, asking Buildbucket for the build status
[15:08:09.491] Build status: SCHEDULED
[15:08:13.273] Received PubSub notification, asking Buildbucket for the build status
[15:08:13.290] Build status: STARTED
[15:09:28.021] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939112219261575440:2:0)
[15:09:28.094] Build status: STARTED
[15:09:28.094] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939112219261575440:5:0) after 1m3s
[15:10:31.345] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939112219261575440:5:0)
[15:10:31.374] Build status: STARTED
[15:10:31.374] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939112219261575440:6:0) after 2m42s
[15:13:13.779] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939112219261575440:6:0)
[15:13:13.803] Build status: STARTED
[15:13:13.803] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939112219261575440:7:0) after 6m32s
[15:19:45.957] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939112219261575440:7:0)
[15:19:45.978] Build status: STARTED
[15:19:45.978] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939112219261575440:8:0) after 8m59s
[15:28:44.992] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939112219261575440:8:0)
[15:28:45.085] Build status: STARTED
[15:28:45.085] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939112219261575440:9:0) after 2m9s
[15:30:54.670] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939112219261575440:9:0)
[15:30:54.730] Build status: STARTED
[15:30:54.730] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939112219261575440:10:0) after 2m57s
[15:33:51.808] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939112219261575440:10:0)
[15:33:51.834] Build status: STARTED
[15:33:51.834] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939112219261575440:11:0) after 5m12s
[15:39:04.037] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939112219261575440:11:0)
[15:39:04.068] Build status: STARTED
[15:39:04.068] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939112219261575440:12:0) after 2m33s
[15:41:37.948] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939112219261575440:12:0)
[15:41:37.968] Build status: STARTED
[15:41:37.968] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939112219261575440:13:0) after 7m31s
[15:49:09.029] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939112219261575440:13:0)
[15:49:09.063] Build status: STARTED
[15:49:09.063] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939112219261575440:14:0) after 2m23s
[15:51:32.107] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939112219261575440:14:0)
[15:51:32.135] Build status: STARTED
[15:51:32.135] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939112219261575440:15:0) after 8m53s
[16:00:25.209] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939112219261575440:15:0)
[16:00:25.244] Build status: STARTED
[16:00:25.244] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939112219261575440:16:0) after 4m11s
[16:04:36.267] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939112219261575440:16:0)
[16:04:36.298] Build status: STARTED
[16:04:36.298] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939112219261575440:17:0) after 2m3s
[16:06:39.579] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939112219261575440:17:0)
[16:06:39.611] Build status: STARTED
[16:06:39.611] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939112219261575440:18:0) after 5m55s
[16:12:34.729] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939112219261575440:18:0)
[16:12:34.754] Build status: STARTED
[16:12:34.754] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939112219261575440:19:0) after 7m2s
[16:19:36.770] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939112219261575440:19:0)
[16:19:36.789] Build status: STARTED
[16:19:36.789] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939112219261575440:20:0) after 8m58s
[16:28:34.953] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939112219261575440:20:0)
[16:28:35.121] Build status: STARTED
[16:28:35.121] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939112219261575440:21:0) after 9m1s
[16:37:36.507] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939112219261575440:21:0)
[16:37:36.530] Build status: STARTED
[16:37:36.530] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939112219261575440:22:0) after 8m9s
[16:45:45.550] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939112219261575440:22:0)
[16:45:45.567] Build status: STARTED
[16:45:45.567] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8939112219261575440:23:0) after 9m48s
[16:54:51.623] Received PubSub notification, asking Buildbucket for the build status
[16:54:51.640] Build:
{
"id": "8773682157197307601",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2023-08-04T15:08:08.577302568Z",
"startTime": "2023-08-04T15:08:12.675730Z",
"endTime": "2023-08-04T16:54:50.866852877Z",
"updateTime": "2023-08-04T16:54:50.866852877Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "77e9e9759d959a1dfb8d54a29af9edbf30b5bb01",
"ref": "refs/heads/main"
}
},
"canary": true
}
[16:54:51.640] Invocation finished in 1h46m44.415355388s with status SUCCEEDED