[22:48:04.372] New invocation is queued and will start shortly
[22:48:05.550] Starting the invocation (attempt 1)
[22:48:05.578] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/d1e0db32129ceffa3f7930704ca01a89eaa15611"
[22:48:05.578] Popped gitiles commit info from properties and tags
[22:48:05.578] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:48:05.579] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:48:05.580] Buildbucket request:
{
"requestId": "8938811490662374128",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8938811490662374128",
"job": "fuchsia/core.x64-debug-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "98fa0fdd70852c3ffd497fb252c1704d5ea21ebd"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@98fa0fdd70852c3ffd497fb252c1704d5ea21ebd",
"title": "98fa0fdd70852c3ffd497fb252c1704d5ea21ebd",
"url": "https://fuchsia.googlesource.com/integration/+/98fa0fdd70852c3ffd497fb252c1704d5ea21ebd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a82b7ffa46f54f4524fb7234339233710f1935f7"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a82b7ffa46f54f4524fb7234339233710f1935f7",
"title": "a82b7ffa46f54f4524fb7234339233710f1935f7",
"url": "https://fuchsia.googlesource.com/integration/+/a82b7ffa46f54f4524fb7234339233710f1935f7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b1a7fdd95caa53cf25a0b3118d45963098355e71"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b1a7fdd95caa53cf25a0b3118d45963098355e71",
"title": "b1a7fdd95caa53cf25a0b3118d45963098355e71",
"url": "https://fuchsia.googlesource.com/integration/+/b1a7fdd95caa53cf25a0b3118d45963098355e71"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6135d1a6d59f4473f954d1bcbc6ff9c9234e39c0"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6135d1a6d59f4473f954d1bcbc6ff9c9234e39c0",
"title": "6135d1a6d59f4473f954d1bcbc6ff9c9234e39c0",
"url": "https://fuchsia.googlesource.com/integration/+/6135d1a6d59f4473f954d1bcbc6ff9c9234e39c0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "db40fb65cfd859a48312dbda6d8cce47f5daafdd"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@db40fb65cfd859a48312dbda6d8cce47f5daafdd",
"title": "db40fb65cfd859a48312dbda6d8cce47f5daafdd",
"url": "https://fuchsia.googlesource.com/integration/+/db40fb65cfd859a48312dbda6d8cce47f5daafdd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9c916400f17e0897ed3e01d7b9a080ead70eba95"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9c916400f17e0897ed3e01d7b9a080ead70eba95",
"title": "9c916400f17e0897ed3e01d7b9a080ead70eba95",
"url": "https://fuchsia.googlesource.com/integration/+/9c916400f17e0897ed3e01d7b9a080ead70eba95"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e4263338517f791cc3a8a13e7d1c125b2618b28c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e4263338517f791cc3a8a13e7d1c125b2618b28c",
"title": "e4263338517f791cc3a8a13e7d1c125b2618b28c",
"url": "https://fuchsia.googlesource.com/integration/+/e4263338517f791cc3a8a13e7d1c125b2618b28c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d9cc580cb49012a43b2dc466e28cc6301c600170"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d9cc580cb49012a43b2dc466e28cc6301c600170",
"title": "d9cc580cb49012a43b2dc466e28cc6301c600170",
"url": "https://fuchsia.googlesource.com/integration/+/d9cc580cb49012a43b2dc466e28cc6301c600170"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "137a9c6a1402e828f7c665879c84fc236a969880"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@137a9c6a1402e828f7c665879c84fc236a969880",
"title": "137a9c6a1402e828f7c665879c84fc236a969880",
"url": "https://fuchsia.googlesource.com/integration/+/137a9c6a1402e828f7c665879c84fc236a969880"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "99e833e6e7c364768c3a0d3af38c148c5ef52270"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@99e833e6e7c364768c3a0d3af38c148c5ef52270",
"title": "99e833e6e7c364768c3a0d3af38c148c5ef52270",
"url": "https://fuchsia.googlesource.com/integration/+/99e833e6e7c364768c3a0d3af38c148c5ef52270"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "58b8ffbfa9fdedad38dd6e28da6b8db28f27908e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@58b8ffbfa9fdedad38dd6e28da6b8db28f27908e",
"title": "58b8ffbfa9fdedad38dd6e28da6b8db28f27908e",
"url": "https://fuchsia.googlesource.com/integration/+/58b8ffbfa9fdedad38dd6e28da6b8db28f27908e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "bc8bfa86d9b9f8a864340a38b3005d938ce65186"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@bc8bfa86d9b9f8a864340a38b3005d938ce65186",
"title": "bc8bfa86d9b9f8a864340a38b3005d938ce65186",
"url": "https://fuchsia.googlesource.com/integration/+/bc8bfa86d9b9f8a864340a38b3005d938ce65186"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1604d43b341d6e53bd746978f5b9598495258ade"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1604d43b341d6e53bd746978f5b9598495258ade",
"title": "1604d43b341d6e53bd746978f5b9598495258ade",
"url": "https://fuchsia.googlesource.com/integration/+/1604d43b341d6e53bd746978f5b9598495258ade"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3a6d57edc49cd030968e345b05acf194d210e644"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3a6d57edc49cd030968e345b05acf194d210e644",
"title": "3a6d57edc49cd030968e345b05acf194d210e644",
"url": "https://fuchsia.googlesource.com/integration/+/3a6d57edc49cd030968e345b05acf194d210e644"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "68102263871cdd2023bc04d094289670565503f4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@68102263871cdd2023bc04d094289670565503f4",
"title": "68102263871cdd2023bc04d094289670565503f4",
"url": "https://fuchsia.googlesource.com/integration/+/68102263871cdd2023bc04d094289670565503f4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "54990d76b3454ebdd42a026f1385ac0b2c74abe7"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@54990d76b3454ebdd42a026f1385ac0b2c74abe7",
"title": "54990d76b3454ebdd42a026f1385ac0b2c74abe7",
"url": "https://fuchsia.googlesource.com/integration/+/54990d76b3454ebdd42a026f1385ac0b2c74abe7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "806451d74f067634192874c1ad44e8965908f1f0"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@806451d74f067634192874c1ad44e8965908f1f0",
"title": "806451d74f067634192874c1ad44e8965908f1f0",
"url": "https://fuchsia.googlesource.com/integration/+/806451d74f067634192874c1ad44e8965908f1f0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "68816a076f0be0a203ddd4100cc3a213c0ab4de9"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@68816a076f0be0a203ddd4100cc3a213c0ab4de9",
"title": "68816a076f0be0a203ddd4100cc3a213c0ab4de9",
"url": "https://fuchsia.googlesource.com/integration/+/68816a076f0be0a203ddd4100cc3a213c0ab4de9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e17bdbf457fed75a36a24e6fc61f0f7d2090132f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e17bdbf457fed75a36a24e6fc61f0f7d2090132f",
"title": "e17bdbf457fed75a36a24e6fc61f0f7d2090132f",
"url": "https://fuchsia.googlesource.com/integration/+/e17bdbf457fed75a36a24e6fc61f0f7d2090132f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d1e0db32129ceffa3f7930704ca01a89eaa15611"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d1e0db32129ceffa3f7930704ca01a89eaa15611",
"title": "d1e0db32129ceffa3f7930704ca01a89eaa15611",
"url": "https://fuchsia.googlesource.com/integration/+/d1e0db32129ceffa3f7930704ca01a89eaa15611"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "d1e0db32129ceffa3f7930704ca01a89eaa15611",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8938811490662374128"
},
{
"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"
}
}
[22:48:06.117] Scheduled build:
{
"id": "8773381428660473553",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-08-07T22:48:05.658861854Z",
"updateTime": "2023-08-07T22:48:05.658861854Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "d1e0db32129ceffa3f7930704ca01a89eaa15611",
"ref": "refs/heads/main"
}
}
}
[22:48:06.117] Task URL: https://cr-buildbucket.appspot.com/build/8773381428660473553
[22:48:06.117] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938811490662374128:2:0) after 6m14s
[22:48:07.107] Received PubSub notification, asking Buildbucket for the build status
[22:48:07.128] Build status: SCHEDULED
[22:48:07.958] Received PubSub notification, asking Buildbucket for the build status
[22:48:07.993] Build status: STARTED
[22:54:20.233] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938811490662374128:2:0)
[22:54:20.257] Build status: STARTED
[22:54:20.257] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938811490662374128:5:0) after 7m17s
[23:01:37.284] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938811490662374128:5:0)
[23:01:37.301] Build status: STARTED
[23:01:37.301] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938811490662374128:6:0) after 4m56s
[23:06:33.319] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938811490662374128:6:0)
[23:06:33.350] Build status: STARTED
[23:06:33.350] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938811490662374128:7:0) after 8m14s
[23:14:47.711] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938811490662374128:7:0)
[23:14:47.731] Build status: STARTED
[23:14:47.731] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938811490662374128:8:0) after 6m46s
[23:21:33.749] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938811490662374128:8:0)
[23:21:33.770] Build status: STARTED
[23:21:33.770] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938811490662374128:9:0) after 2m25s
[23:23:58.818] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938811490662374128:9:0)
[23:23:58.849] Build status: STARTED
[23:23:58.849] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938811490662374128:10:0) after 9m21s
[23:33:19.873] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938811490662374128:10:0)
[23:33:19.892] Build status: STARTED
[23:33:19.892] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938811490662374128:11:0) after 1m35s
[23:34:54.863] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938811490662374128:11:0)
[23:34:54.881] Build status: STARTED
[23:34:54.881] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938811490662374128:12:0) after 7m3s
[23:41:57.902] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938811490662374128:12:0)
[23:41:57.917] Build status: STARTED
[23:41:57.917] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938811490662374128:13:0) after 7m39s
[23:49:36.937] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938811490662374128:13:0)
[23:49:36.982] Build status: STARTED
[23:49:36.982] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938811490662374128:14:0) after 4m41s
[23:54:18.229] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938811490662374128:14:0)
[23:54:18.246] Build status: STARTED
[23:54:18.246] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938811490662374128:15:0) after 3m59s
[23:58:17.300] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938811490662374128:15:0)
[23:58:17.318] Build status: STARTED
[23:58:17.318] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938811490662374128:16:0) after 3m45s
[00:02:02.616] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938811490662374128:16:0)
[00:02:02.636] Build status: STARTED
[00:02:02.637] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938811490662374128:17:0) after 6m11s
[00:04:13.177] Received PubSub notification, asking Buildbucket for the build status
[00:04:13.206] Build:
{
"id": "8773381428660473553",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-08-07T22:48:05.658861854Z",
"startTime": "2023-08-07T22:48:06.963326Z",
"endTime": "2023-08-08T00:04:12.693679001Z",
"updateTime": "2023-08-08T00:04:12.693679001Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "d1e0db32129ceffa3f7930704ca01a89eaa15611",
"ref": "refs/heads/main"
}
}
}
[00:04:13.206] Invocation finished in 1h16m8.83995103s with status SUCCEEDED