[21:10:29.042] New invocation is queued and will start shortly
[21:10:31.196] Starting the invocation (attempt 1)
[21:10:31.223] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/a01a487b44dacd77e2b20fb340109467824a6274"
[21:10:31.223] Popped gitiles commit info from properties and tags
[21:10:31.223] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:10:31.223] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:10:31.224] Buildbucket request:
{
"requestId": "8928580173217082352",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8928580173217082352",
"job": "fuchsia/core.x64-debug-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0adc9c41fe42d2e66737508e4eee0e09426d457b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0adc9c41fe42d2e66737508e4eee0e09426d457b",
"title": "0adc9c41fe42d2e66737508e4eee0e09426d457b",
"url": "https://fuchsia.googlesource.com/integration/+/0adc9c41fe42d2e66737508e4eee0e09426d457b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1d150b8581ced5c088fa774c9b37046edb113f30"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1d150b8581ced5c088fa774c9b37046edb113f30",
"title": "1d150b8581ced5c088fa774c9b37046edb113f30",
"url": "https://fuchsia.googlesource.com/integration/+/1d150b8581ced5c088fa774c9b37046edb113f30"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e950cdda7921ed79bf09a792170765c569929e4e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e950cdda7921ed79bf09a792170765c569929e4e",
"title": "e950cdda7921ed79bf09a792170765c569929e4e",
"url": "https://fuchsia.googlesource.com/integration/+/e950cdda7921ed79bf09a792170765c569929e4e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ede7aebe71a819e0db6dd7befe438edb4120ca7b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ede7aebe71a819e0db6dd7befe438edb4120ca7b",
"title": "ede7aebe71a819e0db6dd7befe438edb4120ca7b",
"url": "https://fuchsia.googlesource.com/integration/+/ede7aebe71a819e0db6dd7befe438edb4120ca7b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "03102da3d51d1797bd99494460dbfeadaf6c3787"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@03102da3d51d1797bd99494460dbfeadaf6c3787",
"title": "03102da3d51d1797bd99494460dbfeadaf6c3787",
"url": "https://fuchsia.googlesource.com/integration/+/03102da3d51d1797bd99494460dbfeadaf6c3787"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2416a0e4f555b25f8eff9b2f4f140eadc0ac82fd"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2416a0e4f555b25f8eff9b2f4f140eadc0ac82fd",
"title": "2416a0e4f555b25f8eff9b2f4f140eadc0ac82fd",
"url": "https://fuchsia.googlesource.com/integration/+/2416a0e4f555b25f8eff9b2f4f140eadc0ac82fd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f6666a84d54f41e5f586b83f25ec3f66d6c51b41"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f6666a84d54f41e5f586b83f25ec3f66d6c51b41",
"title": "f6666a84d54f41e5f586b83f25ec3f66d6c51b41",
"url": "https://fuchsia.googlesource.com/integration/+/f6666a84d54f41e5f586b83f25ec3f66d6c51b41"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "315870fc3fc9ea24bb163cdc33fdee31090f6024"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@315870fc3fc9ea24bb163cdc33fdee31090f6024",
"title": "315870fc3fc9ea24bb163cdc33fdee31090f6024",
"url": "https://fuchsia.googlesource.com/integration/+/315870fc3fc9ea24bb163cdc33fdee31090f6024"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3c14dff2e22eef35fc1e37cafd11d65ca0e69b49"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3c14dff2e22eef35fc1e37cafd11d65ca0e69b49",
"title": "3c14dff2e22eef35fc1e37cafd11d65ca0e69b49",
"url": "https://fuchsia.googlesource.com/integration/+/3c14dff2e22eef35fc1e37cafd11d65ca0e69b49"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "21da76587628e82d37de62a7b35ddcfbde7706f9"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@21da76587628e82d37de62a7b35ddcfbde7706f9",
"title": "21da76587628e82d37de62a7b35ddcfbde7706f9",
"url": "https://fuchsia.googlesource.com/integration/+/21da76587628e82d37de62a7b35ddcfbde7706f9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ecd12b99b913343fcb773e101034fab76fbc69c6"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ecd12b99b913343fcb773e101034fab76fbc69c6",
"title": "ecd12b99b913343fcb773e101034fab76fbc69c6",
"url": "https://fuchsia.googlesource.com/integration/+/ecd12b99b913343fcb773e101034fab76fbc69c6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a01a487b44dacd77e2b20fb340109467824a6274"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a01a487b44dacd77e2b20fb340109467824a6274",
"title": "a01a487b44dacd77e2b20fb340109467824a6274",
"url": "https://fuchsia.googlesource.com/integration/+/a01a487b44dacd77e2b20fb340109467824a6274"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "a01a487b44dacd77e2b20fb340109467824a6274",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8928580173217082352"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/core.x64-debug-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[21:10:31.787] Scheduled build:
{
"id": "8763150110221199473",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-11-28T21:10:31.277324127Z",
"updateTime": "2023-11-28T21:10:31.277324127Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "a01a487b44dacd77e2b20fb340109467824a6274",
"ref": "refs/heads/main"
}
}
}
[21:10:31.787] Task URL: https://cr-buildbucket.appspot.com/build/8763150110221199473
[21:10:31.787] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928580173217082352:2:0) after 8m31s
[21:10:32.835] Received PubSub notification, asking Buildbucket for the build status
[21:10:32.862] Build status: SCHEDULED
[21:10:40.220] Received PubSub notification, asking Buildbucket for the build status
[21:10:40.287] Build status: STARTED
[21:19:02.803] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928580173217082352:2:0)
[21:19:02.835] Build status: STARTED
[21:19:02.835] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928580173217082352:5:0) after 1m8s
[21:20:10.868] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928580173217082352:5:0)
[21:20:10.903] Build status: STARTED
[21:20:10.903] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928580173217082352:6:0) after 9m38s
[21:29:48.934] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928580173217082352:6:0)
[21:29:48.957] Build status: STARTED
[21:29:48.957] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928580173217082352:7:0) after 8m40s
[21:38:29.006] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928580173217082352:7:0)
[21:38:29.197] Build status: STARTED
[21:38:29.197] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928580173217082352:8:0) after 2m34s
[21:41:03.265] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928580173217082352:8:0)
[21:41:03.313] Build status: STARTED
[21:41:03.313] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928580173217082352:9:0) after 3m0s
[21:44:03.375] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928580173217082352:9:0)
[21:44:03.415] Build status: STARTED
[21:44:03.415] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928580173217082352:10:0) after 6m2s
[21:50:05.433] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928580173217082352:10:0)
[21:50:05.460] Build status: STARTED
[21:50:05.460] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928580173217082352:11:0) after 6m51s
[21:56:56.483] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928580173217082352:11:0)
[21:56:56.499] Build status: STARTED
[21:56:56.499] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928580173217082352:12:0) after 1m10s
[21:58:06.525] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928580173217082352:12:0)
[21:58:06.543] Build status: STARTED
[21:58:06.543] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928580173217082352:13:0) after 2m16s
[22:00:22.573] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928580173217082352:13:0)
[22:00:22.611] Build status: STARTED
[22:00:22.611] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928580173217082352:14:0) after 2m31s
[22:02:53.629] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928580173217082352:14:0)
[22:02:53.650] Build status: STARTED
[22:02:53.650] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928580173217082352:15:0) after 5m33s
[22:08:26.820] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928580173217082352:15:0)
[22:08:26.843] Build status: STARTED
[22:08:26.843] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928580173217082352:16:0) after 5m54s
[22:14:20.869] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928580173217082352:16:0)
[22:14:20.955] Build status: STARTED
[22:14:20.955] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928580173217082352:17:0) after 3m47s
[22:18:07.976] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928580173217082352:17:0)
[22:18:07.996] Build status: STARTED
[22:18:07.996] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928580173217082352:18:0) after 2m37s
[22:20:45.047] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928580173217082352:18:0)
[22:20:45.069] Build status: STARTED
[22:20:45.069] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928580173217082352:19:0) after 3m10s
[22:23:55.230] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928580173217082352:19:0)
[22:23:55.261] Build status: STARTED
[22:23:55.261] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928580173217082352:20:0) after 9m58s
[22:33:53.378] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928580173217082352:20:0)
[22:33:53.409] Build status: STARTED
[22:33:53.409] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928580173217082352:21:0) after 7m50s
[22:35:28.138] Received PubSub notification, asking Buildbucket for the build status
[22:35:28.157] Build:
{
"id": "8763150110221199473",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-11-28T21:10:31.277324127Z",
"startTime": "2023-11-28T21:10:39.128268Z",
"endTime": "2023-11-28T22:35:23.730035Z",
"updateTime": "2023-11-28T22:35:23.730035Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "a01a487b44dacd77e2b20fb340109467824a6274",
"ref": "refs/heads/main"
}
}
}
[22:35:28.157] Invocation finished in 1h24m59.121066327s with status SUCCEEDED