[13:36:21.520] New invocation is queued and will start shortly
[13:36:22.717] Starting the invocation (attempt 1)
[13:36:22.762] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/c5239cb08d178392fdd710425910a429495bbda4"
[13:36:22.762] Popped gitiles commit info from properties and tags
[13:36:22.762] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:36:22.762] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:36:22.762] Buildbucket request:
{
"requestId": "8960227085593135456",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8960227085593135456",
"job": "fuchsia/firebase-docs-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b40a9b4634cc86c1966b7baf794f315507c22597"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b40a9b4634cc86c1966b7baf794f315507c22597",
"title": "b40a9b4634cc86c1966b7baf794f315507c22597",
"url": "https://fuchsia.googlesource.com/integration/+/b40a9b4634cc86c1966b7baf794f315507c22597"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4f6cb4ac3ebebbd19a298dd721b755706e8b286d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4f6cb4ac3ebebbd19a298dd721b755706e8b286d",
"title": "4f6cb4ac3ebebbd19a298dd721b755706e8b286d",
"url": "https://fuchsia.googlesource.com/integration/+/4f6cb4ac3ebebbd19a298dd721b755706e8b286d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a8944e23bb17934f019ab0f9fd2fe91e9bb6bdff"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a8944e23bb17934f019ab0f9fd2fe91e9bb6bdff",
"title": "a8944e23bb17934f019ab0f9fd2fe91e9bb6bdff",
"url": "https://fuchsia.googlesource.com/integration/+/a8944e23bb17934f019ab0f9fd2fe91e9bb6bdff"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b6c21954d75866e8a3f8ae97f9c96504f0395d3d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b6c21954d75866e8a3f8ae97f9c96504f0395d3d",
"title": "b6c21954d75866e8a3f8ae97f9c96504f0395d3d",
"url": "https://fuchsia.googlesource.com/integration/+/b6c21954d75866e8a3f8ae97f9c96504f0395d3d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "80d37771459183179237fd2aa83fe1e309409370"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@80d37771459183179237fd2aa83fe1e309409370",
"title": "80d37771459183179237fd2aa83fe1e309409370",
"url": "https://fuchsia.googlesource.com/integration/+/80d37771459183179237fd2aa83fe1e309409370"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "48dafbb80babdb42ca6ed744921718c9904f0a08"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@48dafbb80babdb42ca6ed744921718c9904f0a08",
"title": "48dafbb80babdb42ca6ed744921718c9904f0a08",
"url": "https://fuchsia.googlesource.com/integration/+/48dafbb80babdb42ca6ed744921718c9904f0a08"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "bb24dd22693b47cee94453e784ff85cfcd4d1315"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@bb24dd22693b47cee94453e784ff85cfcd4d1315",
"title": "bb24dd22693b47cee94453e784ff85cfcd4d1315",
"url": "https://fuchsia.googlesource.com/integration/+/bb24dd22693b47cee94453e784ff85cfcd4d1315"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "36980ea9d0df7b5e354efc0b31537a2720486665"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@36980ea9d0df7b5e354efc0b31537a2720486665",
"title": "36980ea9d0df7b5e354efc0b31537a2720486665",
"url": "https://fuchsia.googlesource.com/integration/+/36980ea9d0df7b5e354efc0b31537a2720486665"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "af6773cd85e7ecbcad2941af0f20d9c656afa125"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@af6773cd85e7ecbcad2941af0f20d9c656afa125",
"title": "af6773cd85e7ecbcad2941af0f20d9c656afa125",
"url": "https://fuchsia.googlesource.com/integration/+/af6773cd85e7ecbcad2941af0f20d9c656afa125"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c5239cb08d178392fdd710425910a429495bbda4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c5239cb08d178392fdd710425910a429495bbda4",
"title": "c5239cb08d178392fdd710425910a429495bbda4",
"url": "https://fuchsia.googlesource.com/integration/+/c5239cb08d178392fdd710425910a429495bbda4"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "c5239cb08d178392fdd710425910a429495bbda4",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8960227085593135456"
},
{
"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"
}
}
[13:36:23.185] Scheduled build:
{
"id": "8794797023496002337",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2022-12-14T13:36:22.893048423Z",
"updateTime": "2022-12-14T13:36:22.893048423Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "c5239cb08d178392fdd710425910a429495bbda4",
"ref": "refs/heads/main"
}
}
}
[13:36:23.185] Task URL: https://cr-buildbucket.appspot.com/build/8794797023496002337
[13:36:23.185] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:2:0) after 1m42s
[13:36:57.844] Received PubSub notification, asking Buildbucket for the build status
[13:36:57.864] Build status: STARTED
[13:38:05.207] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:2:0)
[13:38:05.246] Build status: STARTED
[13:38:05.246] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:4:0) after 2m3s
[13:40:08.310] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:4:0)
[13:40:08.389] Build status: STARTED
[13:40:08.389] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:5:0) after 3m15s
[13:43:23.411] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:5:0)
[13:43:23.444] Build status: STARTED
[13:43:23.444] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:6:0) after 7m19s
[13:50:42.574] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:6:0)
[13:50:42.619] Build status: STARTED
[13:50:42.619] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:7:0) after 8m34s
[13:59:16.840] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:7:0)
[13:59:16.880] Build status: STARTED
[13:59:16.880] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:8:0) after 9m38s
[14:08:55.114] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:8:0)
[14:08:55.144] Build status: STARTED
[14:08:55.145] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:9:0) after 2m31s
[14:11:26.611] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:9:0)
[14:11:26.644] Build status: STARTED
[14:11:26.644] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:10:0) after 4m49s
[14:16:16.006] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:10:0)
[14:16:16.032] Build status: STARTED
[14:16:16.032] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:11:0) after 8m56s
[14:25:12.246] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:11:0)
[14:25:12.271] Build status: STARTED
[14:25:12.271] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:12:0) after 4m47s
[14:29:59.706] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:12:0)
[14:29:59.794] Build status: STARTED
[14:29:59.795] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:13:0) after 6m51s
[14:36:50.816] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:13:0)
[14:36:50.845] Build status: STARTED
[14:36:50.845] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:14:0) after 1m25s
[14:38:16.208] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:14:0)
[14:38:16.241] Build status: STARTED
[14:38:16.241] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:15:0) after 5m49s
[14:44:05.280] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:15:0)
[14:44:05.370] Build status: STARTED
[14:44:05.370] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:16:0) after 2m17s
[14:46:22.585] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:16:0)
[14:46:22.610] Build status: STARTED
[14:46:22.610] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:17:0) after 4m29s
[14:50:51.783] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:17:0)
[14:50:51.812] Build status: STARTED
[14:50:51.812] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:18:0) after 3m22s
[14:54:13.813] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:18:0)
[14:54:13.835] Build status: STARTED
[14:54:13.835] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:19:0) after 5m36s
[14:59:49.870] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:19:0)
[14:59:49.929] Build status: STARTED
[14:59:49.929] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:20:0) after 8m37s
[15:08:27.171] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:20:0)
[15:08:27.245] Build status: STARTED
[15:08:27.245] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:21:0) after 1m15s
[15:09:42.324] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:21:0)
[15:09:42.345] Build status: STARTED
[15:09:42.345] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:22:0) after 3m28s
[15:13:10.796] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:22:0)
[15:13:10.884] Build status: STARTED
[15:13:10.884] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:23:0) after 9m25s
[15:22:36.069] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:23:0)
[15:22:36.095] Build status: STARTED
[15:22:36.095] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:24:0) after 4m3s
[15:26:39.320] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:24:0)
[15:26:39.341] Build status: STARTED
[15:26:39.341] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:25:0) after 7m37s
[15:34:16.363] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:25:0)
[15:34:16.387] Build status: STARTED
[15:34:16.387] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:26:0) after 6m40s
[15:40:56.397] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:26:0)
[15:40:56.468] Build status: STARTED
[15:40:56.468] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:27:0) after 5m24s
[15:46:20.490] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:27:0)
[15:46:20.520] Build status: STARTED
[15:46:20.520] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:28:0) after 8m51s
[15:55:11.702] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:28:0)
[15:55:11.725] Build status: STARTED
[15:55:11.725] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:29:0) after 7m7s
[16:02:18.891] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:29:0)
[16:02:18.956] Build status: STARTED
[16:02:18.956] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:30:0) after 3m44s
[16:06:03.059] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:30:0)
[16:06:03.098] Build status: STARTED
[16:06:03.098] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:31:0) after 9m10s
[16:15:13.217] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:31:0)
[16:15:13.243] Build status: STARTED
[16:15:13.243] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:32:0) after 4m32s
[16:19:45.432] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:32:0)
[16:19:45.453] Build status: STARTED
[16:19:45.453] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:33:0) after 1m15s
[16:21:00.963] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:33:0)
[16:21:00.989] Build status: STARTED
[16:21:00.989] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:34:0) after 3m5s
[16:24:06.228] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:34:0)
[16:24:06.266] Build status: STARTED
[16:24:06.266] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:35:0) after 8m8s
[16:32:14.287] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:35:0)
[16:32:14.319] Build status: STARTED
[16:32:14.319] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:36:0) after 6m59s
[16:39:13.668] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:36:0)
[16:39:13.693] Build status: STARTED
[16:39:13.693] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:37:0) after 7m57s
[16:47:10.817] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:37:0)
[16:47:10.841] Build status: STARTED
[16:47:10.841] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:38:0) after 6m43s
[16:53:54.164] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:38:0)
[16:53:54.191] Build status: STARTED
[16:53:54.191] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:39:0) after 4m56s
[16:58:50.545] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:39:0)
[16:58:50.577] Build status: STARTED
[16:58:50.577] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960227085593135456:40:0) after 6m48s
[17:04:02.275] Received PubSub notification, asking Buildbucket for the build status
[17:04:02.313] Build:
{
"id": "8794797023496002337",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2022-12-14T13:36:22.893048423Z",
"startTime": "2022-12-14T13:36:56.963974Z",
"endTime": "2022-12-14T17:04:01.445603121Z",
"updateTime": "2022-12-14T17:04:01.445603121Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "c5239cb08d178392fdd710425910a429495bbda4",
"ref": "refs/heads/main"
}
}
}
[17:04:02.313] Invocation finished in 3h27m40.803128507s with status SUCCEEDED