[18:55:42.979] New invocation is queued and will start shortly
[18:55:44.717] Starting the invocation (attempt 1)
[18:55:44.757] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/f006bc2e49dc438ee1892574ac84d6cbcad26150"
[18:55:44.757] Popped gitiles commit info from properties and tags
[18:55:44.757] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:55:44.757] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:55:44.758] Buildbucket request:
{
"requestId": "8966367587067820128",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8966367587067820128",
"job": "fuchsia/firebase-docs-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ecf145bf8a5bc1c65dd478b4ddcd09511306e442"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ecf145bf8a5bc1c65dd478b4ddcd09511306e442",
"title": "ecf145bf8a5bc1c65dd478b4ddcd09511306e442",
"url": "https://fuchsia.googlesource.com/integration/+/ecf145bf8a5bc1c65dd478b4ddcd09511306e442"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "7a992fb5294ca6fc2e0baa5ebf5c7778a1c32146"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@7a992fb5294ca6fc2e0baa5ebf5c7778a1c32146",
"title": "7a992fb5294ca6fc2e0baa5ebf5c7778a1c32146",
"url": "https://fuchsia.googlesource.com/integration/+/7a992fb5294ca6fc2e0baa5ebf5c7778a1c32146"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "56910a81ac0b339cb8c7f092c7ea54ce37020be0"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@56910a81ac0b339cb8c7f092c7ea54ce37020be0",
"title": "56910a81ac0b339cb8c7f092c7ea54ce37020be0",
"url": "https://fuchsia.googlesource.com/integration/+/56910a81ac0b339cb8c7f092c7ea54ce37020be0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "5ac0c60d60fa23bf1f64ab5b425d00442a776bf0"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@5ac0c60d60fa23bf1f64ab5b425d00442a776bf0",
"title": "5ac0c60d60fa23bf1f64ab5b425d00442a776bf0",
"url": "https://fuchsia.googlesource.com/integration/+/5ac0c60d60fa23bf1f64ab5b425d00442a776bf0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9425f4afb72c33811b80f8f909b80874e53ce775"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9425f4afb72c33811b80f8f909b80874e53ce775",
"title": "9425f4afb72c33811b80f8f909b80874e53ce775",
"url": "https://fuchsia.googlesource.com/integration/+/9425f4afb72c33811b80f8f909b80874e53ce775"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "18301fbdd57e2bd596fe757fa1ee9cbbd991274c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@18301fbdd57e2bd596fe757fa1ee9cbbd991274c",
"title": "18301fbdd57e2bd596fe757fa1ee9cbbd991274c",
"url": "https://fuchsia.googlesource.com/integration/+/18301fbdd57e2bd596fe757fa1ee9cbbd991274c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2ea5fe7da9cac00ec9e79d440fdb6f2abeb13e2e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2ea5fe7da9cac00ec9e79d440fdb6f2abeb13e2e",
"title": "2ea5fe7da9cac00ec9e79d440fdb6f2abeb13e2e",
"url": "https://fuchsia.googlesource.com/integration/+/2ea5fe7da9cac00ec9e79d440fdb6f2abeb13e2e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ff24697020ccefd9c7a8228193f1740e20423ffc"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ff24697020ccefd9c7a8228193f1740e20423ffc",
"title": "ff24697020ccefd9c7a8228193f1740e20423ffc",
"url": "https://fuchsia.googlesource.com/integration/+/ff24697020ccefd9c7a8228193f1740e20423ffc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b8d8d832166851cc43e47f6694372e472d825d45"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b8d8d832166851cc43e47f6694372e472d825d45",
"title": "b8d8d832166851cc43e47f6694372e472d825d45",
"url": "https://fuchsia.googlesource.com/integration/+/b8d8d832166851cc43e47f6694372e472d825d45"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "150af7beafaa543baec3a5e96f40519b57ce8b70"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@150af7beafaa543baec3a5e96f40519b57ce8b70",
"title": "150af7beafaa543baec3a5e96f40519b57ce8b70",
"url": "https://fuchsia.googlesource.com/integration/+/150af7beafaa543baec3a5e96f40519b57ce8b70"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "422acc23e16f50377e5275815547b31a6f4e275a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@422acc23e16f50377e5275815547b31a6f4e275a",
"title": "422acc23e16f50377e5275815547b31a6f4e275a",
"url": "https://fuchsia.googlesource.com/integration/+/422acc23e16f50377e5275815547b31a6f4e275a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6920e1864b907197a96695f8f35e077ada2eb73c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6920e1864b907197a96695f8f35e077ada2eb73c",
"title": "6920e1864b907197a96695f8f35e077ada2eb73c",
"url": "https://fuchsia.googlesource.com/integration/+/6920e1864b907197a96695f8f35e077ada2eb73c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8d6585aa2b788fb62c8999e240b6d789cf17737a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8d6585aa2b788fb62c8999e240b6d789cf17737a",
"title": "8d6585aa2b788fb62c8999e240b6d789cf17737a",
"url": "https://fuchsia.googlesource.com/integration/+/8d6585aa2b788fb62c8999e240b6d789cf17737a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f006bc2e49dc438ee1892574ac84d6cbcad26150"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f006bc2e49dc438ee1892574ac84d6cbcad26150",
"title": "f006bc2e49dc438ee1892574ac84d6cbcad26150",
"url": "https://fuchsia.googlesource.com/integration/+/f006bc2e49dc438ee1892574ac84d6cbcad26150"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "f006bc2e49dc438ee1892574ac84d6cbcad26150",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8966367587067820128"
},
{
"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"
}
}
[18:55:45.145] Scheduled build:
{
"id": "8800937524429108129",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2022-10-07T18:55:44.863095418Z",
"updateTime": "2022-10-07T18:55:44.863095418Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "f006bc2e49dc438ee1892574ac84d6cbcad26150",
"ref": "refs/heads/main"
}
}
}
[18:55:45.145] Task URL: https://cr-buildbucket.appspot.com/build/8800937524429108129
[18:55:45.145] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:2:0) after 3m9s
[18:58:37.898] Received PubSub notification, asking Buildbucket for the build status
[18:58:37.933] Build status: STARTED
[18:58:54.246] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:2:0)
[18:58:54.284] Build status: STARTED
[18:58:54.284] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:4:0) after 2m7s
[19:01:01.853] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:4:0)
[19:01:01.880] Build status: STARTED
[19:01:01.880] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:5:0) after 1m40s
[19:02:41.906] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:5:0)
[19:02:41.933] Build status: STARTED
[19:02:41.933] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:6:0) after 3m25s
[19:06:07.133] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:6:0)
[19:06:07.169] Build status: STARTED
[19:06:07.169] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:7:0) after 6m32s
[19:12:39.242] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:7:0)
[19:12:39.264] Build status: STARTED
[19:12:39.264] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:8:0) after 9m56s
[19:22:35.377] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:8:0)
[19:22:35.437] Build status: STARTED
[19:22:35.437] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:9:0) after 5m10s
[19:27:45.669] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:9:0)
[19:27:45.693] Build status: STARTED
[19:27:45.693] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:10:0) after 4m4s
[19:31:49.718] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:10:0)
[19:31:49.759] Build status: STARTED
[19:31:49.759] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:11:0) after 8m31s
[19:40:20.975] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:11:0)
[19:40:21.006] Build status: STARTED
[19:40:21.006] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:12:0) after 1m40s
[19:42:01.656] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:12:0)
[19:42:01.742] Build status: STARTED
[19:42:01.742] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:13:0) after 4m1s
[19:46:03.613] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:13:0)
[19:46:03.705] Build status: STARTED
[19:46:03.705] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:14:0) after 7m59s
[19:54:03.027] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:14:0)
[19:54:03.061] Build status: STARTED
[19:54:03.062] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:15:0) after 8m47s
[20:02:50.214] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:15:0)
[20:02:50.246] Build status: STARTED
[20:02:50.246] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:16:0) after 9m19s
[20:12:09.556] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:16:0)
[20:12:09.596] Build status: STARTED
[20:12:09.596] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:17:0) after 8m26s
[20:20:35.752] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:17:0)
[20:20:35.776] Build status: STARTED
[20:20:35.776] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:18:0) after 7m49s
[20:28:24.800] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:18:0)
[20:28:24.831] Build status: STARTED
[20:28:24.831] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:19:0) after 2m27s
[20:30:51.858] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:19:0)
[20:30:51.886] Build status: STARTED
[20:30:51.886] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:20:0) after 1m48s
[20:32:39.907] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:20:0)
[20:32:39.943] Build status: STARTED
[20:32:39.943] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:21:0) after 3m21s
[20:36:00.967] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:21:0)
[20:36:01.062] Build status: STARTED
[20:36:01.062] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:22:0) after 9m16s
[20:45:17.624] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:22:0)
[20:45:17.662] Build status: STARTED
[20:45:17.662] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:23:0) after 7m29s
[20:52:46.758] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:23:0)
[20:52:46.959] Build status: STARTED
[20:52:46.959] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:24:0) after 9m51s
[21:02:38.296] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:24:0)
[21:02:38.324] Build status: STARTED
[21:02:38.324] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:25:0) after 3m44s
[21:06:22.358] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:25:0)
[21:06:22.386] Build status: STARTED
[21:06:22.386] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:26:0) after 2m1s
[21:08:23.362] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:26:0)
[21:08:23.389] Build status: STARTED
[21:08:23.389] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:27:0) after 7m3s
[21:15:26.589] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:27:0)
[21:15:26.614] Build status: STARTED
[21:15:26.614] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:28:0) after 9m17s
[21:24:43.848] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:28:0)
[21:24:43.873] Build status: STARTED
[21:24:43.873] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:29:0) after 1m58s
[21:26:42.084] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:29:0)
[21:26:42.120] Build status: STARTED
[21:26:42.120] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:30:0) after 5m13s
[21:31:55.143] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:30:0)
[21:31:55.178] Build status: STARTED
[21:31:55.178] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:31:0) after 3m37s
[21:35:32.470] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:31:0)
[21:35:32.494] Build status: STARTED
[21:35:32.494] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:32:0) after 5m7s
[21:40:39.913] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:32:0)
[21:40:39.942] Build status: STARTED
[21:40:39.942] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:33:0) after 8m32s
[21:49:11.966] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:33:0)
[21:49:11.989] Build status: STARTED
[21:49:11.989] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:34:0) after 4m26s
[21:53:38.055] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:34:0)
[21:53:38.086] Build status: STARTED
[21:53:38.086] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:35:0) after 1m13s
[21:54:51.917] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:35:0)
[21:54:51.939] Build status: STARTED
[21:54:51.939] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:36:0) after 1m15s
[21:56:06.971] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:36:0)
[21:56:07.007] Build status: STARTED
[21:56:07.007] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966367587067820128:37:0) after 8m38s
[22:00:07.580] Received PubSub notification, asking Buildbucket for the build status
[22:00:07.615] Build:
{
"id": "8800937524429108129",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2022-10-07T18:55:44.863095418Z",
"startTime": "2022-10-07T18:58:36.905546Z",
"endTime": "2022-10-07T22:00:06.809491770Z",
"updateTime": "2022-10-07T22:00:06.809491770Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "f006bc2e49dc438ee1892574ac84d6cbcad26150",
"ref": "refs/heads/main"
}
}
}
[22:00:07.615] Invocation finished in 3h4m24.651880987s with status SUCCEEDED