[02:56:54.247] New invocation is queued and will start shortly
[02:56:55.312] Starting the invocation (attempt 1)
[02:56:55.340] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/296c17d5f6e6a1ec779d09c8cd24ce0930f3f040"
[02:56:55.340] Popped gitiles commit info from properties and tags
[02:56:55.340] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:56:55.340] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:56:55.341] Buildbucket request:
{
"requestId": "8935987329596045024",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8935987329596045024",
"job": "fuchsia/firebase-docs-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9a47f48ebe68be0981ba07c66ae01c53a69cc7da"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9a47f48ebe68be0981ba07c66ae01c53a69cc7da",
"title": "9a47f48ebe68be0981ba07c66ae01c53a69cc7da",
"url": "https://fuchsia.googlesource.com/integration/+/9a47f48ebe68be0981ba07c66ae01c53a69cc7da"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0f01c160aefee260c31d16d9f0227ad1faf2a4da"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0f01c160aefee260c31d16d9f0227ad1faf2a4da",
"title": "0f01c160aefee260c31d16d9f0227ad1faf2a4da",
"url": "https://fuchsia.googlesource.com/integration/+/0f01c160aefee260c31d16d9f0227ad1faf2a4da"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "28bf39f584651cc7538e040b25da2501c4febb3b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@28bf39f584651cc7538e040b25da2501c4febb3b",
"title": "28bf39f584651cc7538e040b25da2501c4febb3b",
"url": "https://fuchsia.googlesource.com/integration/+/28bf39f584651cc7538e040b25da2501c4febb3b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9e7911fe0303fe29d0b3a924fa2989cd319c08c5"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9e7911fe0303fe29d0b3a924fa2989cd319c08c5",
"title": "9e7911fe0303fe29d0b3a924fa2989cd319c08c5",
"url": "https://fuchsia.googlesource.com/integration/+/9e7911fe0303fe29d0b3a924fa2989cd319c08c5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6d9cd39bf9205f1e6bba19599dd273d0f1e91945"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6d9cd39bf9205f1e6bba19599dd273d0f1e91945",
"title": "6d9cd39bf9205f1e6bba19599dd273d0f1e91945",
"url": "https://fuchsia.googlesource.com/integration/+/6d9cd39bf9205f1e6bba19599dd273d0f1e91945"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "862397d78bc828de8f6e8f98299b90ad4c2142cd"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@862397d78bc828de8f6e8f98299b90ad4c2142cd",
"title": "862397d78bc828de8f6e8f98299b90ad4c2142cd",
"url": "https://fuchsia.googlesource.com/integration/+/862397d78bc828de8f6e8f98299b90ad4c2142cd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d4cad4326a9ec18b149bf616b789e4d3c1ee0c0c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d4cad4326a9ec18b149bf616b789e4d3c1ee0c0c",
"title": "d4cad4326a9ec18b149bf616b789e4d3c1ee0c0c",
"url": "https://fuchsia.googlesource.com/integration/+/d4cad4326a9ec18b149bf616b789e4d3c1ee0c0c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8186807b0dda070c97f81ec6dd13b58285612afc"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8186807b0dda070c97f81ec6dd13b58285612afc",
"title": "8186807b0dda070c97f81ec6dd13b58285612afc",
"url": "https://fuchsia.googlesource.com/integration/+/8186807b0dda070c97f81ec6dd13b58285612afc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "fd7c2570a0fbb190ac0afaff0d82418f86d6db89"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@fd7c2570a0fbb190ac0afaff0d82418f86d6db89",
"title": "fd7c2570a0fbb190ac0afaff0d82418f86d6db89",
"url": "https://fuchsia.googlesource.com/integration/+/fd7c2570a0fbb190ac0afaff0d82418f86d6db89"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "814dd78e7f65df78a8e5e6c0dfbc92740c512df3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@814dd78e7f65df78a8e5e6c0dfbc92740c512df3",
"title": "814dd78e7f65df78a8e5e6c0dfbc92740c512df3",
"url": "https://fuchsia.googlesource.com/integration/+/814dd78e7f65df78a8e5e6c0dfbc92740c512df3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c7e1db606ef650549fac0f1430034fbcafd21455"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c7e1db606ef650549fac0f1430034fbcafd21455",
"title": "c7e1db606ef650549fac0f1430034fbcafd21455",
"url": "https://fuchsia.googlesource.com/integration/+/c7e1db606ef650549fac0f1430034fbcafd21455"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "914c42881b5f336f6f81602bda11fb772fbf180a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@914c42881b5f336f6f81602bda11fb772fbf180a",
"title": "914c42881b5f336f6f81602bda11fb772fbf180a",
"url": "https://fuchsia.googlesource.com/integration/+/914c42881b5f336f6f81602bda11fb772fbf180a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "23073e87d0162baf810ed6e65b04c691beed4000"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@23073e87d0162baf810ed6e65b04c691beed4000",
"title": "23073e87d0162baf810ed6e65b04c691beed4000",
"url": "https://fuchsia.googlesource.com/integration/+/23073e87d0162baf810ed6e65b04c691beed4000"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "7c4cec4a6b2e5c2600824063f4b4f2ce6930da23"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@7c4cec4a6b2e5c2600824063f4b4f2ce6930da23",
"title": "7c4cec4a6b2e5c2600824063f4b4f2ce6930da23",
"url": "https://fuchsia.googlesource.com/integration/+/7c4cec4a6b2e5c2600824063f4b4f2ce6930da23"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "72b95e0e7d97cc5c10a8949e40730c91d2b4a5b9"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@72b95e0e7d97cc5c10a8949e40730c91d2b4a5b9",
"title": "72b95e0e7d97cc5c10a8949e40730c91d2b4a5b9",
"url": "https://fuchsia.googlesource.com/integration/+/72b95e0e7d97cc5c10a8949e40730c91d2b4a5b9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "669baf04560d3c69a50aa2750a182eaab6c44284"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@669baf04560d3c69a50aa2750a182eaab6c44284",
"title": "669baf04560d3c69a50aa2750a182eaab6c44284",
"url": "https://fuchsia.googlesource.com/integration/+/669baf04560d3c69a50aa2750a182eaab6c44284"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "926e5ffdc5f01dfeecd37effc9caac89afc97e71"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@926e5ffdc5f01dfeecd37effc9caac89afc97e71",
"title": "926e5ffdc5f01dfeecd37effc9caac89afc97e71",
"url": "https://fuchsia.googlesource.com/integration/+/926e5ffdc5f01dfeecd37effc9caac89afc97e71"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "296c17d5f6e6a1ec779d09c8cd24ce0930f3f040"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@296c17d5f6e6a1ec779d09c8cd24ce0930f3f040",
"title": "296c17d5f6e6a1ec779d09c8cd24ce0930f3f040",
"url": "https://fuchsia.googlesource.com/integration/+/296c17d5f6e6a1ec779d09c8cd24ce0930f3f040"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "296c17d5f6e6a1ec779d09c8cd24ce0930f3f040",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8935987329596045024"
},
{
"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"
}
}
[02:56:55.850] Scheduled build:
{
"id": "8770557267731706033",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2023-09-08T02:56:55.402173036Z",
"updateTime": "2023-09-08T02:56:55.402173036Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "296c17d5f6e6a1ec779d09c8cd24ce0930f3f040",
"ref": "refs/heads/main"
}
},
"canary": true
}
[02:56:55.850] Task URL: https://cr-buildbucket.appspot.com/build/8770557267731706033
[02:56:55.850] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:2:0) after 9m56s
[02:56:56.805] Received PubSub notification, asking Buildbucket for the build status
[02:56:56.835] Build status: SCHEDULED
[02:57:34.295] Received PubSub notification, asking Buildbucket for the build status
[02:57:34.339] Build status: STARTED
[03:06:51.978] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:2:0)
[03:06:51.999] Build status: STARTED
[03:06:51.999] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:5:0) after 1m49s
[03:08:41.194] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:5:0)
[03:08:41.230] Build status: STARTED
[03:08:41.230] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:6:0) after 1m14s
[03:09:55.344] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:6:0)
[03:09:55.366] Build status: STARTED
[03:09:55.366] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:7:0) after 5m30s
[03:15:25.484] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:7:0)
[03:15:25.512] Build status: STARTED
[03:15:25.512] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:8:0) after 5m1s
[03:20:27.028] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:8:0)
[03:20:27.044] Build status: STARTED
[03:20:27.044] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:9:0) after 7m22s
[03:27:49.689] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:9:0)
[03:27:49.708] Build status: STARTED
[03:27:49.708] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:10:0) after 5m31s
[03:33:20.742] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:10:0)
[03:33:20.764] Build status: STARTED
[03:33:20.764] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:11:0) after 4m49s
[03:38:09.782] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:11:0)
[03:38:09.881] Build status: STARTED
[03:38:09.881] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:12:0) after 7m2s
[03:45:12.093] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:12:0)
[03:45:12.116] Build status: STARTED
[03:45:12.116] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:13:0) after 1m42s
[03:46:54.236] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:13:0)
[03:46:54.253] Build status: STARTED
[03:46:54.253] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:14:0) after 7m20s
[03:54:14.326] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:14:0)
[03:54:14.350] Build status: STARTED
[03:54:14.350] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:15:0) after 6m21s
[04:00:35.854] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:15:0)
[04:00:35.871] Build status: STARTED
[04:00:35.871] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:16:0) after 4m35s
[04:05:11.267] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:16:0)
[04:05:11.289] Build status: STARTED
[04:05:11.289] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:17:0) after 3m18s
[04:08:29.477] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:17:0)
[04:08:29.500] Build status: STARTED
[04:08:29.500] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:18:0) after 6m0s
[04:14:29.596] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:18:0)
[04:14:29.617] Build status: STARTED
[04:14:29.617] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:19:0) after 4m51s
[04:19:20.964] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:19:0)
[04:19:20.981] Build status: STARTED
[04:19:20.981] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:20:0) after 7m57s
[04:27:18.524] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:20:0)
[04:27:18.541] Build status: STARTED
[04:27:18.541] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:21:0) after 3m55s
[04:31:13.577] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:21:0)
[04:31:13.601] Build status: STARTED
[04:31:13.601] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:22:0) after 3m43s
[04:34:56.653] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:22:0)
[04:34:56.767] Build status: STARTED
[04:34:56.767] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:23:0) after 2m14s
[04:37:10.835] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:23:0)
[04:37:10.853] Build status: STARTED
[04:37:10.853] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:24:0) after 2m32s
[04:39:42.978] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:24:0)
[04:39:43.042] Build status: STARTED
[04:39:43.042] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:25:0) after 6m36s
[04:46:19.179] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:25:0)
[04:46:19.204] Build status: STARTED
[04:46:19.204] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935987329596045024:26:0) after 9m20s
[04:48:15.118] Received PubSub notification, asking Buildbucket for the build status
[04:48:15.141] Build:
{
"id": "8770557267731706033",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2023-09-08T02:56:55.402173036Z",
"startTime": "2023-09-08T02:57:33.142259Z",
"endTime": "2023-09-08T04:48:14.160042609Z",
"updateTime": "2023-09-08T04:48:14.160042609Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "296c17d5f6e6a1ec779d09c8cd24ce0930f3f040",
"ref": "refs/heads/main"
}
},
"canary": true
}
[04:48:15.141] Invocation finished in 1h51m20.901675755s with status SUCCEEDED