[01:09:58.310] New invocation is queued and will start shortly
[01:09:59.375] Starting the invocation (attempt 1)
[01:09:59.406] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/7a6cd9c7056a540371071ccc57ed6ecece72b291"
[01:09:59.406] Popped gitiles commit info from properties and tags
[01:09:59.406] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:09:59.407] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:09:59.407] Buildbucket request:
{
"requestId": "8897309152547109600",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8897309152547109600",
"job": "fuchsia/firebase-docs-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "be2e5fb469cb76e66275265cc664c26ef66cd22a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@be2e5fb469cb76e66275265cc664c26ef66cd22a",
"title": "be2e5fb469cb76e66275265cc664c26ef66cd22a",
"url": "https://fuchsia.googlesource.com/integration/+/be2e5fb469cb76e66275265cc664c26ef66cd22a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9a8eaec23f53277da4d2529840393a4d74830ed2"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9a8eaec23f53277da4d2529840393a4d74830ed2",
"title": "9a8eaec23f53277da4d2529840393a4d74830ed2",
"url": "https://fuchsia.googlesource.com/integration/+/9a8eaec23f53277da4d2529840393a4d74830ed2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "755df6c3960f6c05874027139b710a66925b38cc"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@755df6c3960f6c05874027139b710a66925b38cc",
"title": "755df6c3960f6c05874027139b710a66925b38cc",
"url": "https://fuchsia.googlesource.com/integration/+/755df6c3960f6c05874027139b710a66925b38cc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4f8f1d79aa3f43936c7b55a3b730797d3f79c083"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4f8f1d79aa3f43936c7b55a3b730797d3f79c083",
"title": "4f8f1d79aa3f43936c7b55a3b730797d3f79c083",
"url": "https://fuchsia.googlesource.com/integration/+/4f8f1d79aa3f43936c7b55a3b730797d3f79c083"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9368b7092d7ae9efc0ff90772f546ad0eb5709a5"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9368b7092d7ae9efc0ff90772f546ad0eb5709a5",
"title": "9368b7092d7ae9efc0ff90772f546ad0eb5709a5",
"url": "https://fuchsia.googlesource.com/integration/+/9368b7092d7ae9efc0ff90772f546ad0eb5709a5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ea225e641c2c1f631f54cde4f0bcc4575821bb25"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ea225e641c2c1f631f54cde4f0bcc4575821bb25",
"title": "ea225e641c2c1f631f54cde4f0bcc4575821bb25",
"url": "https://fuchsia.googlesource.com/integration/+/ea225e641c2c1f631f54cde4f0bcc4575821bb25"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "549967fc40459be671a17a221b6c1c0a0637d279"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@549967fc40459be671a17a221b6c1c0a0637d279",
"title": "549967fc40459be671a17a221b6c1c0a0637d279",
"url": "https://fuchsia.googlesource.com/integration/+/549967fc40459be671a17a221b6c1c0a0637d279"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ce6be16e581c29825c52049c5c86013e18633ef1"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ce6be16e581c29825c52049c5c86013e18633ef1",
"title": "ce6be16e581c29825c52049c5c86013e18633ef1",
"url": "https://fuchsia.googlesource.com/integration/+/ce6be16e581c29825c52049c5c86013e18633ef1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "7030b4f87abcab6413ed18333548ea3199e8c988"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@7030b4f87abcab6413ed18333548ea3199e8c988",
"title": "7030b4f87abcab6413ed18333548ea3199e8c988",
"url": "https://fuchsia.googlesource.com/integration/+/7030b4f87abcab6413ed18333548ea3199e8c988"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "025b68697aba5cdff681773da14c052f22196c83"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@025b68697aba5cdff681773da14c052f22196c83",
"title": "025b68697aba5cdff681773da14c052f22196c83",
"url": "https://fuchsia.googlesource.com/integration/+/025b68697aba5cdff681773da14c052f22196c83"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "437d76fbc043ec98bd6996cebdd16461e1159ab3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@437d76fbc043ec98bd6996cebdd16461e1159ab3",
"title": "437d76fbc043ec98bd6996cebdd16461e1159ab3",
"url": "https://fuchsia.googlesource.com/integration/+/437d76fbc043ec98bd6996cebdd16461e1159ab3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ab365e3f09cf5d9cf1ffaf0159743679c2691bbd"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ab365e3f09cf5d9cf1ffaf0159743679c2691bbd",
"title": "ab365e3f09cf5d9cf1ffaf0159743679c2691bbd",
"url": "https://fuchsia.googlesource.com/integration/+/ab365e3f09cf5d9cf1ffaf0159743679c2691bbd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0b79868ae852f7a0ee5cc522dddc63378f8dc077"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0b79868ae852f7a0ee5cc522dddc63378f8dc077",
"title": "0b79868ae852f7a0ee5cc522dddc63378f8dc077",
"url": "https://fuchsia.googlesource.com/integration/+/0b79868ae852f7a0ee5cc522dddc63378f8dc077"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4eb4f2fe8514f04ca7a2f4eba7f782e97a84f2f8"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4eb4f2fe8514f04ca7a2f4eba7f782e97a84f2f8",
"title": "4eb4f2fe8514f04ca7a2f4eba7f782e97a84f2f8",
"url": "https://fuchsia.googlesource.com/integration/+/4eb4f2fe8514f04ca7a2f4eba7f782e97a84f2f8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "7a6cd9c7056a540371071ccc57ed6ecece72b291"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@7a6cd9c7056a540371071ccc57ed6ecece72b291",
"title": "7a6cd9c7056a540371071ccc57ed6ecece72b291",
"url": "https://fuchsia.googlesource.com/integration/+/7a6cd9c7056a540371071ccc57ed6ecece72b291"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "7a6cd9c7056a540371071ccc57ed6ecece72b291",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8897309152547109600"
},
{
"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"
}
}
[01:09:59.893] Scheduled build:
{
"id": "8731879090672081777",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2024-11-08T01:09:59.469256882Z",
"updateTime": "2024-11-08T01:09:59.469256882Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "7a6cd9c7056a540371071ccc57ed6ecece72b291",
"ref": "refs/heads/main"
}
},
"canary": true
}
[01:09:59.893] Task URL: https://cr-buildbucket.appspot.com/build/8731879090672081777
[01:09:59.893] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:2:0) after 4m18s
[01:10:00.825] Received PubSub notification, asking Buildbucket for the build status
[01:10:00.843] Build status: SCHEDULED
[01:11:25.571] Received PubSub notification, asking Buildbucket for the build status
[01:11:25.595] Build status: STARTED
[01:14:17.937] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:2:0)
[01:14:17.961] Build status: STARTED
[01:14:17.961] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:5:0) after 3m28s
[01:17:46.032] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:5:0)
[01:17:46.087] Build status: STARTED
[01:17:46.087] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:6:0) after 7m2s
[01:24:48.136] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:6:0)
[01:24:48.156] Build status: STARTED
[01:24:48.156] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:7:0) after 2m23s
[01:27:11.180] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:7:0)
[01:27:11.206] Build status: STARTED
[01:27:11.206] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:8:0) after 5m35s
[01:32:46.232] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:8:0)
[01:32:46.250] Build status: STARTED
[01:32:46.250] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:9:0) after 6m29s
[01:39:15.267] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:9:0)
[01:39:15.310] Build status: STARTED
[01:39:15.310] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:10:0) after 8m59s
[01:48:14.306] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:10:0)
[01:48:14.325] Build status: STARTED
[01:48:14.325] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:11:0) after 2m2s
[01:50:16.341] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:11:0)
[01:50:16.357] Build status: STARTED
[01:50:16.357] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:12:0) after 7m30s
[01:57:46.451] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:12:0)
[01:57:46.535] Build status: STARTED
[01:57:46.535] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:13:0) after 7m32s
[02:05:18.549] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:13:0)
[02:05:18.571] Build status: STARTED
[02:05:18.571] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:14:0) after 9m42s
[02:15:00.641] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:14:0)
[02:15:00.664] Build status: STARTED
[02:15:00.664] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:15:0) after 4m30s
[02:19:30.680] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:15:0)
[02:19:30.700] Build status: STARTED
[02:19:30.700] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:16:0) after 9m39s
[02:29:09.678] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:16:0)
[02:29:09.730] Build status: STARTED
[02:29:09.730] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:17:0) after 8m40s
[02:37:49.748] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:17:0)
[02:37:49.774] Build status: STARTED
[02:37:49.774] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:18:0) after 4m21s
[02:42:10.790] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:18:0)
[02:42:10.810] Build status: STARTED
[02:42:10.810] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:19:0) after 6m55s
[02:49:05.830] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:19:0)
[02:49:05.852] Build status: STARTED
[02:49:05.852] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:20:0) after 6m17s
[02:55:22.869] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:20:0)
[02:55:22.925] Build status: STARTED
[02:55:22.925] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:21:0) after 2m48s
[02:58:11.019] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:21:0)
[02:58:11.036] Build status: STARTED
[02:58:11.036] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:22:0) after 3m25s
[03:01:36.065] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:22:0)
[03:01:36.084] Build status: STARTED
[03:01:36.084] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:23:0) after 1m26s
[03:03:02.155] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:23:0)
[03:03:02.180] Build status: STARTED
[03:03:02.180] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:24:0) after 7m8s
[03:10:10.290] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:24:0)
[03:10:10.348] Build status: STARTED
[03:10:10.348] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:25:0) after 2m27s
[03:12:37.435] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:25:0)
[03:12:37.459] Build status: STARTED
[03:12:37.459] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:26:0) after 3m45s
[03:16:22.488] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:26:0)
[03:16:22.542] Build status: STARTED
[03:16:22.542] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:27:0) after 6m58s
[03:23:20.560] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:27:0)
[03:23:20.609] Build status: STARTED
[03:23:20.609] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:28:0) after 4m35s
[03:27:55.643] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:28:0)
[03:27:55.668] Build status: STARTED
[03:27:55.668] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:29:0) after 2m38s
[03:30:33.706] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:29:0)
[03:30:33.746] Build status: STARTED
[03:30:33.746] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:30:0) after 1m39s
[03:32:12.810] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:30:0)
[03:32:12.836] Build status: STARTED
[03:32:12.836] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:31:0) after 9m43s
[03:41:56.106] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:31:0)
[03:41:56.125] Build status: STARTED
[03:41:56.125] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:32:0) after 6m34s
[03:48:30.526] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:32:0)
[03:48:30.542] Build status: STARTED
[03:48:30.542] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:33:0) after 5m20s
[03:53:50.665] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:33:0)
[03:53:50.740] Build status: STARTED
[03:53:50.740] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:34:0) after 8m51s
[04:02:41.761] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:34:0)
[04:02:41.836] Build status: STARTED
[04:02:41.836] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:35:0) after 5m41s
[04:08:23.706] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:35:0)
[04:08:23.737] Build status: STARTED
[04:08:23.738] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8897309152547109600:36:0) after 4m53s
[04:11:39.630] Received PubSub notification, asking Buildbucket for the build status
[04:11:39.647] Build:
{
"id": "8731879090672081777",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2024-11-08T01:09:59.469256882Z",
"startTime": "2024-11-08T01:11:25.381636872Z",
"endTime": "2024-11-08T04:11:38.990111459Z",
"updateTime": "2024-11-08T04:11:38.990111459Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "7a6cd9c7056a540371071ccc57ed6ecece72b291",
"ref": "refs/heads/main"
}
},
"canary": true
}
[04:11:39.647] Invocation finished in 3h1m41.350818447s with status SUCCEEDED