[11:18:55.658] New invocation is queued and will start shortly
[11:18:57.147] Starting the invocation (attempt 1)
[11:18:57.182] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/ed0dc2d1a0905b914a975d15ecdef65fdb6d1cac"
[11:18:57.182] Popped gitiles commit info from properties and tags
[11:18:57.182] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:18:57.183] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:18:57.183] Buildbucket request:
{
"requestId": "8965399759099648032",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8965399759099648032",
"job": "fuchsia/firebase-docs-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "aee85a76ce5111c9f964d84b641f61d36f4bd1d7"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@aee85a76ce5111c9f964d84b641f61d36f4bd1d7",
"title": "aee85a76ce5111c9f964d84b641f61d36f4bd1d7",
"url": "https://fuchsia.googlesource.com/integration/+/aee85a76ce5111c9f964d84b641f61d36f4bd1d7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b5c7188b697c27acc305227bb2b0d2d8117c609d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b5c7188b697c27acc305227bb2b0d2d8117c609d",
"title": "b5c7188b697c27acc305227bb2b0d2d8117c609d",
"url": "https://fuchsia.googlesource.com/integration/+/b5c7188b697c27acc305227bb2b0d2d8117c609d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "291caa2502d2b5a66dc73a4ad148df833b3ee6c7"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@291caa2502d2b5a66dc73a4ad148df833b3ee6c7",
"title": "291caa2502d2b5a66dc73a4ad148df833b3ee6c7",
"url": "https://fuchsia.googlesource.com/integration/+/291caa2502d2b5a66dc73a4ad148df833b3ee6c7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8c78fd5054b945ce363375ddd4dc0e1b0b25bfe7"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8c78fd5054b945ce363375ddd4dc0e1b0b25bfe7",
"title": "8c78fd5054b945ce363375ddd4dc0e1b0b25bfe7",
"url": "https://fuchsia.googlesource.com/integration/+/8c78fd5054b945ce363375ddd4dc0e1b0b25bfe7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a4f78c67b22c7a632775a329e1ff7bd67f95505e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a4f78c67b22c7a632775a329e1ff7bd67f95505e",
"title": "a4f78c67b22c7a632775a329e1ff7bd67f95505e",
"url": "https://fuchsia.googlesource.com/integration/+/a4f78c67b22c7a632775a329e1ff7bd67f95505e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ed0dc2d1a0905b914a975d15ecdef65fdb6d1cac"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ed0dc2d1a0905b914a975d15ecdef65fdb6d1cac",
"title": "ed0dc2d1a0905b914a975d15ecdef65fdb6d1cac",
"url": "https://fuchsia.googlesource.com/integration/+/ed0dc2d1a0905b914a975d15ecdef65fdb6d1cac"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "ed0dc2d1a0905b914a975d15ecdef65fdb6d1cac",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8965399759099648032"
},
{
"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"
}
}
[11:18:57.674] Scheduled build:
{
"id": "8799969696744583169",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2022-10-18T11:18:57.269465585Z",
"updateTime": "2022-10-18T11:18:57.269465585Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "ed0dc2d1a0905b914a975d15ecdef65fdb6d1cac",
"ref": "refs/heads/main"
}
}
}
[11:18:57.674] Task URL: https://cr-buildbucket.appspot.com/build/8799969696744583169
[11:18:57.675] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:2:0) after 7m54s
[11:19:23.492] Received PubSub notification, asking Buildbucket for the build status
[11:19:23.526] Build status: STARTED
[11:26:51.799] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:2:0)
[11:26:51.880] Build status: STARTED
[11:26:51.880] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:4:0) after 2m43s
[11:29:34.980] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:4:0)
[11:29:35.003] Build status: STARTED
[11:29:35.003] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:5:0) after 4m23s
[11:33:58.024] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:5:0)
[11:33:58.051] Build status: STARTED
[11:33:58.051] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:6:0) after 1m41s
[11:35:39.281] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:6:0)
[11:35:39.310] Build status: STARTED
[11:35:39.310] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:7:0) after 5m12s
[11:40:51.339] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:7:0)
[11:40:51.382] Build status: STARTED
[11:40:51.382] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:8:0) after 2m27s
[11:43:18.406] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:8:0)
[11:43:18.434] Build status: STARTED
[11:43:18.434] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:9:0) after 3m57s
[11:47:15.463] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:9:0)
[11:47:15.492] Build status: STARTED
[11:47:15.492] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:10:0) after 9m34s
[11:56:49.755] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:10:0)
[11:56:49.785] Build status: STARTED
[11:56:49.785] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:11:0) after 9m39s
[12:06:29.045] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:11:0)
[12:06:29.097] Build status: STARTED
[12:06:29.097] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:12:0) after 3m12s
[12:09:41.140] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:12:0)
[12:09:41.162] Build status: STARTED
[12:09:41.162] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:13:0) after 8m42s
[12:18:23.213] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:13:0)
[12:18:23.264] Build status: STARTED
[12:18:23.264] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:14:0) after 7m27s
[12:25:50.299] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:14:0)
[12:25:50.328] Build status: STARTED
[12:25:50.328] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:15:0) after 1m25s
[12:27:15.375] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:15:0)
[12:27:15.400] Build status: STARTED
[12:27:15.400] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:16:0) after 1m15s
[12:28:30.727] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:16:0)
[12:28:30.780] Build status: STARTED
[12:28:30.781] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:17:0) after 2m55s
[12:31:26.067] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:17:0)
[12:31:26.107] Build status: STARTED
[12:31:26.107] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:18:0) after 9m32s
[12:40:58.535] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:18:0)
[12:40:58.560] Build status: STARTED
[12:40:58.560] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:19:0) after 9m28s
[12:50:26.583] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:19:0)
[12:50:26.613] Build status: STARTED
[12:50:26.613] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:20:0) after 4m36s
[12:55:02.791] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:20:0)
[12:55:02.841] Build status: STARTED
[12:55:02.841] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:21:0) after 6m19s
[13:01:21.939] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:21:0)
[13:01:21.969] Build status: STARTED
[13:01:21.969] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:22:0) after 4m40s
[13:06:02.075] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:22:0)
[13:06:02.101] Build status: STARTED
[13:06:02.101] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:23:0) after 1m7s
[13:07:09.209] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:23:0)
[13:07:09.234] Build status: STARTED
[13:07:09.234] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:24:0) after 2m35s
[13:09:44.267] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:24:0)
[13:09:44.299] Build status: STARTED
[13:09:44.299] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:25:0) after 5m12s
[13:14:56.371] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:25:0)
[13:14:56.396] Build status: STARTED
[13:14:56.396] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:26:0) after 9m38s
[13:24:34.420] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:26:0)
[13:24:34.503] Build status: STARTED
[13:24:34.503] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:27:0) after 2m45s
[13:27:19.692] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:27:0)
[13:27:19.755] Build status: STARTED
[13:27:19.755] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:28:0) after 9m41s
[13:37:00.781] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:28:0)
[13:37:00.810] Build status: STARTED
[13:37:00.810] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:29:0) after 2m54s
[13:39:54.906] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:29:0)
[13:39:54.930] Build status: STARTED
[13:39:54.930] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:30:0) after 4m38s
[13:44:33.064] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:30:0)
[13:44:33.099] Build status: STARTED
[13:44:33.099] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:31:0) after 6m1s
[13:50:34.165] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:31:0)
[13:50:34.187] Build status: STARTED
[13:50:34.188] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:32:0) after 2m22s
[13:52:56.320] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:32:0)
[13:52:56.345] Build status: STARTED
[13:52:56.345] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:33:0) after 6m22s
[13:59:18.494] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:33:0)
[13:59:18.515] Build status: STARTED
[13:59:18.515] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:34:0) after 6m35s
[14:05:53.651] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:34:0)
[14:05:53.706] Build status: STARTED
[14:05:53.706] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:35:0) after 6m17s
[14:12:10.877] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:35:0)
[14:12:10.903] Build status: STARTED
[14:12:10.903] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:36:0) after 9m31s
[14:21:42.085] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:36:0)
[14:21:42.189] Build status: STARTED
[14:21:42.189] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:37:0) after 4m13s
[14:25:55.308] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:37:0)
[14:25:55.332] Build status: STARTED
[14:25:55.332] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:38:0) after 5m30s
[14:31:25.360] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:38:0)
[14:31:25.384] Build status: STARTED
[14:31:25.384] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965399759099648032:39:0) after 4m34s
[14:32:16.896] Received PubSub notification, asking Buildbucket for the build status
[14:32:16.922] Build:
{
"id": "8799969696744583169",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2022-10-18T11:18:57.269465585Z",
"startTime": "2022-10-18T11:19:22.998993Z",
"endTime": "2022-10-18T14:32:16.262489937Z",
"updateTime": "2022-10-18T14:32:16.262489937Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "ed0dc2d1a0905b914a975d15ecdef65fdb6d1cac",
"ref": "refs/heads/main"
}
}
}
[14:32:16.922] Invocation finished in 3h13m21.282928028s with status SUCCEEDED