[10:40:09.442] New invocation is queued and will start shortly
[10:40:10.523] Starting the invocation (attempt 1)
[10:40:10.557] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/069c715709df28f6163d03d24103c9a7126d8b6b"
[10:40:10.557] Popped gitiles commit info from properties and tags
[10:40:10.557] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:40:10.557] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:40:10.558] Buildbucket request:
{
"requestId": "8948641759518713936",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8948641759518713936",
"job": "fuchsia/firebase-docs-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b300ac12a2309816a9c910d8c6a1b56dd38a8863"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b300ac12a2309816a9c910d8c6a1b56dd38a8863",
"title": "b300ac12a2309816a9c910d8c6a1b56dd38a8863",
"url": "https://fuchsia.googlesource.com/integration/+/b300ac12a2309816a9c910d8c6a1b56dd38a8863"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6e5f0acf3ec9d2d76c0ca89694f0846ec12bba61"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6e5f0acf3ec9d2d76c0ca89694f0846ec12bba61",
"title": "6e5f0acf3ec9d2d76c0ca89694f0846ec12bba61",
"url": "https://fuchsia.googlesource.com/integration/+/6e5f0acf3ec9d2d76c0ca89694f0846ec12bba61"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ae543ff576d5d30bc8d2a053f6c63bdf1e44cc73"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ae543ff576d5d30bc8d2a053f6c63bdf1e44cc73",
"title": "ae543ff576d5d30bc8d2a053f6c63bdf1e44cc73",
"url": "https://fuchsia.googlesource.com/integration/+/ae543ff576d5d30bc8d2a053f6c63bdf1e44cc73"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f754c395d03408354ed6b912d57428678e8c1ca9"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f754c395d03408354ed6b912d57428678e8c1ca9",
"title": "f754c395d03408354ed6b912d57428678e8c1ca9",
"url": "https://fuchsia.googlesource.com/integration/+/f754c395d03408354ed6b912d57428678e8c1ca9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2bd2efd502cef74e6de6cd4f3a8da6c79004f4a2"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2bd2efd502cef74e6de6cd4f3a8da6c79004f4a2",
"title": "2bd2efd502cef74e6de6cd4f3a8da6c79004f4a2",
"url": "https://fuchsia.googlesource.com/integration/+/2bd2efd502cef74e6de6cd4f3a8da6c79004f4a2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "22f45d572e4189691eb0ff48dc89e3256e7989eb"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@22f45d572e4189691eb0ff48dc89e3256e7989eb",
"title": "22f45d572e4189691eb0ff48dc89e3256e7989eb",
"url": "https://fuchsia.googlesource.com/integration/+/22f45d572e4189691eb0ff48dc89e3256e7989eb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "bbe6cf5071cb505e1a1e4f1214d83e58b14057e4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@bbe6cf5071cb505e1a1e4f1214d83e58b14057e4",
"title": "bbe6cf5071cb505e1a1e4f1214d83e58b14057e4",
"url": "https://fuchsia.googlesource.com/integration/+/bbe6cf5071cb505e1a1e4f1214d83e58b14057e4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "069c715709df28f6163d03d24103c9a7126d8b6b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@069c715709df28f6163d03d24103c9a7126d8b6b",
"title": "069c715709df28f6163d03d24103c9a7126d8b6b",
"url": "https://fuchsia.googlesource.com/integration/+/069c715709df28f6163d03d24103c9a7126d8b6b"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "069c715709df28f6163d03d24103c9a7126d8b6b",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8948641759518713936"
},
{
"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"
}
}
[10:40:10.860] Scheduled build:
{
"id": "8783211697619100497",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2023-04-21T10:40:10.629287222Z",
"updateTime": "2023-04-21T10:40:10.629287222Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "069c715709df28f6163d03d24103c9a7126d8b6b",
"ref": "refs/heads/main"
}
}
}
[10:40:10.860] Task URL: https://cr-buildbucket.appspot.com/build/8783211697619100497
[10:40:10.860] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948641759518713936:2:0) after 9m49s
[10:43:07.909] Received PubSub notification, asking Buildbucket for the build status
[10:43:07.940] Build status: STARTED
[10:50:00.011] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948641759518713936:2:0)
[10:50:00.042] Build status: STARTED
[10:50:00.042] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948641759518713936:4:0) after 6m21s
[10:56:21.149] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948641759518713936:4:0)
[10:56:21.225] Build status: STARTED
[10:56:21.225] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948641759518713936:5:0) after 7m38s
[11:03:59.577] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948641759518713936:5:0)
[11:03:59.604] Build status: STARTED
[11:03:59.605] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948641759518713936:6:0) after 8m45s
[11:12:44.683] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948641759518713936:6:0)
[11:12:44.730] Build status: STARTED
[11:12:44.730] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948641759518713936:7:0) after 9m12s
[11:21:56.753] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948641759518713936:7:0)
[11:21:56.775] Build status: STARTED
[11:21:56.775] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948641759518713936:8:0) after 3m41s
[11:25:37.794] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948641759518713936:8:0)
[11:25:37.817] Build status: STARTED
[11:25:37.817] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948641759518713936:9:0) after 8m11s
[11:33:48.837] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948641759518713936:9:0)
[11:33:48.859] Build status: STARTED
[11:33:48.859] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948641759518713936:10:0) after 9m56s
[11:43:44.979] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948641759518713936:10:0)
[11:43:45.013] Build status: STARTED
[11:43:45.013] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948641759518713936:11:0) after 3m34s
[11:47:19.084] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948641759518713936:11:0)
[11:47:19.107] Build status: STARTED
[11:47:19.107] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948641759518713936:12:0) after 5m43s
[11:53:02.213] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948641759518713936:12:0)
[11:53:02.233] Build status: STARTED
[11:53:02.233] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948641759518713936:13:0) after 1m55s
[11:54:57.344] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948641759518713936:13:0)
[11:54:57.364] Build status: STARTED
[11:54:57.364] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948641759518713936:14:0) after 7m28s
[12:02:25.383] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948641759518713936:14:0)
[12:02:25.441] Build status: STARTED
[12:02:25.441] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948641759518713936:15:0) after 8m23s
[12:10:48.539] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948641759518713936:15:0)
[12:10:48.558] Build status: STARTED
[12:10:48.558] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948641759518713936:16:0) after 6m53s
[12:17:41.588] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948641759518713936:16:0)
[12:17:41.620] Build status: STARTED
[12:17:41.620] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948641759518713936:17:0) after 4m26s
[12:22:07.595] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948641759518713936:17:0)
[12:22:07.629] Build status: STARTED
[12:22:07.629] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948641759518713936:18:0) after 1m13s
[12:23:11.159] Received PubSub notification, asking Buildbucket for the build status
[12:23:11.179] Build:
{
"id": "8783211697619100497",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2023-04-21T10:40:10.629287222Z",
"startTime": "2023-04-21T10:43:07.075872Z",
"endTime": "2023-04-21T12:23:10.590270334Z",
"updateTime": "2023-04-21T12:23:10.590270334Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "069c715709df28f6163d03d24103c9a7126d8b6b",
"ref": "refs/heads/main"
}
}
}
[12:23:11.179] Invocation finished in 1h43m1.744267638s with status SUCCEEDED