[19:13:16.989] New invocation is queued and will start shortly
[19:13:18.164] Starting the invocation (attempt 1)
[19:13:18.220] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/c965a542f32ee851f8157bb66aef34e44575f8ff"
[19:13:18.221] Popped gitiles commit info from properties and tags
[19:13:18.221] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:13:18.221] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:13:18.222] Buildbucket request:
{
"requestId": "8937828437436347184",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8937828437436347184",
"job": "fuchsia/core.x64-debug-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "fc03735fdbbfd6070b7f05c0e4252ccc2811eaf3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@fc03735fdbbfd6070b7f05c0e4252ccc2811eaf3",
"title": "fc03735fdbbfd6070b7f05c0e4252ccc2811eaf3",
"url": "https://fuchsia.googlesource.com/integration/+/fc03735fdbbfd6070b7f05c0e4252ccc2811eaf3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "89eeef3c3c57050904cc82cfaf9fa5e3b33069f0"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@89eeef3c3c57050904cc82cfaf9fa5e3b33069f0",
"title": "89eeef3c3c57050904cc82cfaf9fa5e3b33069f0",
"url": "https://fuchsia.googlesource.com/integration/+/89eeef3c3c57050904cc82cfaf9fa5e3b33069f0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "7c626ca4471d208852d4f85a92804bc4377dc85c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@7c626ca4471d208852d4f85a92804bc4377dc85c",
"title": "7c626ca4471d208852d4f85a92804bc4377dc85c",
"url": "https://fuchsia.googlesource.com/integration/+/7c626ca4471d208852d4f85a92804bc4377dc85c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b2eef9289ae1e9cbe51e350047a503c7b5e4974a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b2eef9289ae1e9cbe51e350047a503c7b5e4974a",
"title": "b2eef9289ae1e9cbe51e350047a503c7b5e4974a",
"url": "https://fuchsia.googlesource.com/integration/+/b2eef9289ae1e9cbe51e350047a503c7b5e4974a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ff47741623725f2a1fdae1ec4bac512746e48255"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ff47741623725f2a1fdae1ec4bac512746e48255",
"title": "ff47741623725f2a1fdae1ec4bac512746e48255",
"url": "https://fuchsia.googlesource.com/integration/+/ff47741623725f2a1fdae1ec4bac512746e48255"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8bb05e97920671c218c4135a3825f2ed560220c2"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8bb05e97920671c218c4135a3825f2ed560220c2",
"title": "8bb05e97920671c218c4135a3825f2ed560220c2",
"url": "https://fuchsia.googlesource.com/integration/+/8bb05e97920671c218c4135a3825f2ed560220c2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "240fb3e434e3dafb135b7f798c63aeb8f8ad87f0"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@240fb3e434e3dafb135b7f798c63aeb8f8ad87f0",
"title": "240fb3e434e3dafb135b7f798c63aeb8f8ad87f0",
"url": "https://fuchsia.googlesource.com/integration/+/240fb3e434e3dafb135b7f798c63aeb8f8ad87f0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "889e66b5833073283564d51d7af1d65b9879ff88"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@889e66b5833073283564d51d7af1d65b9879ff88",
"title": "889e66b5833073283564d51d7af1d65b9879ff88",
"url": "https://fuchsia.googlesource.com/integration/+/889e66b5833073283564d51d7af1d65b9879ff88"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4e1661334c5307c5ba499c1b160aad31339ec785"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4e1661334c5307c5ba499c1b160aad31339ec785",
"title": "4e1661334c5307c5ba499c1b160aad31339ec785",
"url": "https://fuchsia.googlesource.com/integration/+/4e1661334c5307c5ba499c1b160aad31339ec785"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "02443c081ec0bd516f9bf204272aeb2b0d80ad2c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@02443c081ec0bd516f9bf204272aeb2b0d80ad2c",
"title": "02443c081ec0bd516f9bf204272aeb2b0d80ad2c",
"url": "https://fuchsia.googlesource.com/integration/+/02443c081ec0bd516f9bf204272aeb2b0d80ad2c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "12a87568141bb87b02a9be0811774fdb567c627c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@12a87568141bb87b02a9be0811774fdb567c627c",
"title": "12a87568141bb87b02a9be0811774fdb567c627c",
"url": "https://fuchsia.googlesource.com/integration/+/12a87568141bb87b02a9be0811774fdb567c627c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "cde6890529a6af52c6c0cd8ae54faa7a3391f047"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@cde6890529a6af52c6c0cd8ae54faa7a3391f047",
"title": "cde6890529a6af52c6c0cd8ae54faa7a3391f047",
"url": "https://fuchsia.googlesource.com/integration/+/cde6890529a6af52c6c0cd8ae54faa7a3391f047"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9693f52138c698554f6d3e6b1d15abab79168466"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9693f52138c698554f6d3e6b1d15abab79168466",
"title": "9693f52138c698554f6d3e6b1d15abab79168466",
"url": "https://fuchsia.googlesource.com/integration/+/9693f52138c698554f6d3e6b1d15abab79168466"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c965a542f32ee851f8157bb66aef34e44575f8ff"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c965a542f32ee851f8157bb66aef34e44575f8ff",
"title": "c965a542f32ee851f8157bb66aef34e44575f8ff",
"url": "https://fuchsia.googlesource.com/integration/+/c965a542f32ee851f8157bb66aef34e44575f8ff"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "c965a542f32ee851f8157bb66aef34e44575f8ff",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8937828437436347184"
},
{
"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"
}
}
[19:13:18.667] Scheduled build:
{
"id": "8772398375422671809",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-08-18T19:13:18.283620298Z",
"updateTime": "2023-08-18T19:13:18.283620298Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "c965a542f32ee851f8157bb66aef34e44575f8ff",
"ref": "refs/heads/main"
}
}
}
[19:13:18.667] Task URL: https://cr-buildbucket.appspot.com/build/8772398375422671809
[19:13:18.667] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937828437436347184:2:0) after 7m58s
[19:13:18.941] Received PubSub notification, asking Buildbucket for the build status
[19:13:19.009] Build status: SCHEDULED
[19:13:43.750] Received PubSub notification, asking Buildbucket for the build status
[19:13:43.842] Build status: STARTED
[19:21:16.934] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937828437436347184:2:0)
[19:21:16.954] Build status: STARTED
[19:21:16.954] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937828437436347184:5:0) after 3m24s
[19:24:41.211] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937828437436347184:5:0)
[19:24:41.245] Build status: STARTED
[19:24:41.245] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937828437436347184:6:0) after 3m13s
[19:27:54.692] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937828437436347184:6:0)
[19:27:54.739] Build status: STARTED
[19:27:54.739] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937828437436347184:7:0) after 6m24s
[19:34:18.762] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937828437436347184:7:0)
[19:34:18.790] Build status: STARTED
[19:34:18.790] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937828437436347184:8:0) after 2m45s
[19:37:03.808] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937828437436347184:8:0)
[19:37:04.034] Build status: STARTED
[19:37:04.034] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937828437436347184:9:0) after 9m35s
[19:46:39.147] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937828437436347184:9:0)
[19:46:39.169] Build status: STARTED
[19:46:39.169] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937828437436347184:10:0) after 6m53s
[19:53:32.195] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937828437436347184:10:0)
[19:53:32.220] Build status: STARTED
[19:53:32.220] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937828437436347184:11:0) after 4m57s
[19:58:29.524] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937828437436347184:11:0)
[19:58:29.540] Build status: STARTED
[19:58:29.540] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937828437436347184:12:0) after 8m0s
[20:06:29.564] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937828437436347184:12:0)
[20:06:29.591] Build status: STARTED
[20:06:29.591] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937828437436347184:13:0) after 2m56s
[20:09:26.451] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937828437436347184:13:0)
[20:09:26.472] Build status: STARTED
[20:09:26.472] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937828437436347184:14:0) after 9m58s
[20:19:25.040] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937828437436347184:14:0)
[20:19:25.081] Build status: STARTED
[20:19:25.081] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937828437436347184:15:0) after 1m16s
[20:20:41.204] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937828437436347184:15:0)
[20:20:41.231] Build status: STARTED
[20:20:41.231] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937828437436347184:16:0) after 4m41s
[20:25:22.275] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937828437436347184:16:0)
[20:25:22.352] Build status: STARTED
[20:25:22.352] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937828437436347184:17:0) after 2m19s
[20:25:24.426] Received PubSub notification, asking Buildbucket for the build status
[20:25:24.464] Build:
{
"id": "8772398375422671809",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-08-18T19:13:18.283620298Z",
"startTime": "2023-08-18T19:13:42.989369Z",
"endTime": "2023-08-18T20:25:23.848791363Z",
"updateTime": "2023-08-18T20:25:23.848791363Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "c965a542f32ee851f8157bb66aef34e44575f8ff",
"ref": "refs/heads/main"
}
}
}
[20:25:24.464] Invocation finished in 1h12m7.485472343s with status SUCCEEDED