[10:01:46.204] New invocation is queued and will start shortly
[10:01:51.411] Starting the invocation (attempt 1)
[10:01:51.447] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/442b641dc1bb785a91466c8b8acd14001e030a90"
[10:01:51.447] Popped gitiles commit info from properties and tags
[10:01:51.447] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:01:51.448] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:01:51.448] Buildbucket request:
{
"requestId": "8965857598262628448",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8965857598262628448",
"job": "fuchsia/firebase-docs-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4fb2cc8103b9f7f4541401058e471daf710fd702"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4fb2cc8103b9f7f4541401058e471daf710fd702",
"title": "4fb2cc8103b9f7f4541401058e471daf710fd702",
"url": "https://fuchsia.googlesource.com/integration/+/4fb2cc8103b9f7f4541401058e471daf710fd702"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "03872d5b812baf935a5431f033c454ac88b50798"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@03872d5b812baf935a5431f033c454ac88b50798",
"title": "03872d5b812baf935a5431f033c454ac88b50798",
"url": "https://fuchsia.googlesource.com/integration/+/03872d5b812baf935a5431f033c454ac88b50798"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "63dd6869f4e313f794255333bc3c2c5f548fd3aa"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@63dd6869f4e313f794255333bc3c2c5f548fd3aa",
"title": "63dd6869f4e313f794255333bc3c2c5f548fd3aa",
"url": "https://fuchsia.googlesource.com/integration/+/63dd6869f4e313f794255333bc3c2c5f548fd3aa"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2022da4ee487c95b260dafbb8557ff53ddaf8436"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2022da4ee487c95b260dafbb8557ff53ddaf8436",
"title": "2022da4ee487c95b260dafbb8557ff53ddaf8436",
"url": "https://fuchsia.googlesource.com/integration/+/2022da4ee487c95b260dafbb8557ff53ddaf8436"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1d1ae037135990b5a49a0e8fff8826f0a6cff7f6"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1d1ae037135990b5a49a0e8fff8826f0a6cff7f6",
"title": "1d1ae037135990b5a49a0e8fff8826f0a6cff7f6",
"url": "https://fuchsia.googlesource.com/integration/+/1d1ae037135990b5a49a0e8fff8826f0a6cff7f6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d694f7ebf81338fda0098f6715a08b02bc8ac623"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d694f7ebf81338fda0098f6715a08b02bc8ac623",
"title": "d694f7ebf81338fda0098f6715a08b02bc8ac623",
"url": "https://fuchsia.googlesource.com/integration/+/d694f7ebf81338fda0098f6715a08b02bc8ac623"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "15039ef6760e493069747f62ef1f83e2e89081d1"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@15039ef6760e493069747f62ef1f83e2e89081d1",
"title": "15039ef6760e493069747f62ef1f83e2e89081d1",
"url": "https://fuchsia.googlesource.com/integration/+/15039ef6760e493069747f62ef1f83e2e89081d1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a4d280652ae01c02f5bcea6a184657f6ced98d7c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a4d280652ae01c02f5bcea6a184657f6ced98d7c",
"title": "a4d280652ae01c02f5bcea6a184657f6ced98d7c",
"url": "https://fuchsia.googlesource.com/integration/+/a4d280652ae01c02f5bcea6a184657f6ced98d7c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "7d72709c824a0213ed6aee7e86ec3a225fe87fab"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@7d72709c824a0213ed6aee7e86ec3a225fe87fab",
"title": "7d72709c824a0213ed6aee7e86ec3a225fe87fab",
"url": "https://fuchsia.googlesource.com/integration/+/7d72709c824a0213ed6aee7e86ec3a225fe87fab"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "822bffc51033ba03b69be721c1ffb6429ef536de"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@822bffc51033ba03b69be721c1ffb6429ef536de",
"title": "822bffc51033ba03b69be721c1ffb6429ef536de",
"url": "https://fuchsia.googlesource.com/integration/+/822bffc51033ba03b69be721c1ffb6429ef536de"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2c1986da60e15a9196d438a9ee5c674df35cf3df"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2c1986da60e15a9196d438a9ee5c674df35cf3df",
"title": "2c1986da60e15a9196d438a9ee5c674df35cf3df",
"url": "https://fuchsia.googlesource.com/integration/+/2c1986da60e15a9196d438a9ee5c674df35cf3df"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "442b641dc1bb785a91466c8b8acd14001e030a90"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@442b641dc1bb785a91466c8b8acd14001e030a90",
"title": "442b641dc1bb785a91466c8b8acd14001e030a90",
"url": "https://fuchsia.googlesource.com/integration/+/442b641dc1bb785a91466c8b8acd14001e030a90"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "442b641dc1bb785a91466c8b8acd14001e030a90",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8965857598262628448"
},
{
"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:01:51.779] Scheduled build:
{
"id": "8800427532011220881",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2022-10-13T10:01:51.534288551Z",
"updateTime": "2022-10-13T10:01:51.534288551Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "442b641dc1bb785a91466c8b8acd14001e030a90",
"ref": "refs/heads/main"
}
}
}
[10:01:51.779] Task URL: https://cr-buildbucket.appspot.com/build/8800427532011220881
[10:01:51.779] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:2:0) after 4m3s
[10:01:55.911] Received PubSub notification, asking Buildbucket for the build status
[10:01:55.931] Build status: STARTED
[10:05:54.869] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:2:0)
[10:05:54.901] Build status: STARTED
[10:05:54.902] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:4:0) after 2m56s
[10:08:50.902] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:4:0)
[10:08:50.926] Build status: STARTED
[10:08:50.927] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:5:0) after 8m49s
[10:17:39.947] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:5:0)
[10:17:39.974] Build status: STARTED
[10:17:39.974] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:6:0) after 5m14s
[10:22:54.061] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:6:0)
[10:22:54.093] Build status: STARTED
[10:22:54.094] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:7:0) after 6m40s
[10:29:34.196] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:7:0)
[10:29:34.225] Build status: STARTED
[10:29:34.225] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:8:0) after 5m31s
[10:35:05.452] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:8:0)
[10:35:05.480] Build status: STARTED
[10:35:05.480] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:9:0) after 8m38s
[10:43:43.781] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:9:0)
[10:43:43.806] Build status: STARTED
[10:43:43.806] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:10:0) after 5m2s
[10:48:46.291] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:10:0)
[10:48:46.317] Build status: STARTED
[10:48:46.317] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:11:0) after 9m10s
[10:57:56.416] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:11:0)
[10:57:56.445] Build status: STARTED
[10:57:56.445] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:12:0) after 6m35s
[11:04:31.588] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:12:0)
[11:04:31.616] Build status: STARTED
[11:04:31.616] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:13:0) after 7m51s
[11:12:22.636] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:13:0)
[11:12:22.662] Build status: STARTED
[11:12:22.662] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:14:0) after 7m44s
[11:20:06.700] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:14:0)
[11:20:06.725] Build status: STARTED
[11:20:06.725] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:15:0) after 6m52s
[11:26:58.831] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:15:0)
[11:26:58.857] Build status: STARTED
[11:26:58.857] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:16:0) after 7m44s
[11:34:42.879] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:16:0)
[11:34:42.906] Build status: STARTED
[11:34:42.906] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:17:0) after 5m41s
[11:40:23.926] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:17:0)
[11:40:23.969] Build status: STARTED
[11:40:23.970] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:18:0) after 4m0s
[11:44:24.001] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:18:0)
[11:44:24.027] Build status: STARTED
[11:44:24.027] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:19:0) after 1m16s
[11:45:40.145] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:19:0)
[11:45:40.171] Build status: STARTED
[11:45:40.171] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:20:0) after 1m21s
[11:47:01.316] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:20:0)
[11:47:01.340] Build status: STARTED
[11:47:01.340] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:21:0) after 5m31s
[11:52:32.635] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:21:0)
[11:52:32.683] Build status: STARTED
[11:52:32.683] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:22:0) after 7m54s
[12:00:26.809] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:22:0)
[12:00:26.833] Build status: STARTED
[12:00:26.833] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:23:0) after 9m30s
[12:09:56.859] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:23:0)
[12:09:56.884] Build status: STARTED
[12:09:56.884] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:24:0) after 8m11s
[12:18:07.957] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:24:0)
[12:18:07.985] Build status: STARTED
[12:18:07.985] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:25:0) after 6m55s
[12:25:03.152] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:25:0)
[12:25:03.178] Build status: STARTED
[12:25:03.178] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:26:0) after 5m4s
[12:30:07.242] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:26:0)
[12:30:07.267] Build status: STARTED
[12:30:07.267] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:27:0) after 5m14s
[12:35:21.406] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:27:0)
[12:35:21.428] Build status: STARTED
[12:35:21.428] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:28:0) after 2m14s
[12:37:35.449] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:28:0)
[12:37:35.476] Build status: STARTED
[12:37:35.476] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:29:0) after 6m34s
[12:44:09.512] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:29:0)
[12:44:09.535] Build status: STARTED
[12:44:09.535] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:30:0) after 7m58s
[12:52:07.572] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:30:0)
[12:52:07.602] Build status: STARTED
[12:52:07.602] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8965857598262628448:31:0) after 9m19s
[12:52:37.200] Received PubSub notification, asking Buildbucket for the build status
[12:52:37.232] Build:
{
"id": "8800427532011220881",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2022-10-13T10:01:51.534288551Z",
"startTime": "2022-10-13T10:01:54.341199Z",
"endTime": "2022-10-13T12:52:36.748781790Z",
"updateTime": "2022-10-13T12:52:36.748781790Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "442b641dc1bb785a91466c8b8acd14001e030a90",
"ref": "refs/heads/main"
}
}
}
[12:52:37.232] Invocation finished in 2h50m51.042508011s with status SUCCEEDED