[20:09:34.355] New invocation is queued and will start shortly
[20:09:35.403] Starting the invocation (attempt 1)
[20:09:35.427] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/6281a9cd8b3b03db676b9373efb84ba9db0a0f22"
[20:09:35.427] Popped gitiles commit info from properties and tags
[20:09:35.427] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:09:35.428] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:09:35.428] Buildbucket request:
{
"requestId": "8929942959941895520",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8929942959941895520",
"job": "fuchsia/core.x64-debug-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c2192c391797e693827ec33d7f5cd937e2d1bbb8"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c2192c391797e693827ec33d7f5cd937e2d1bbb8",
"title": "c2192c391797e693827ec33d7f5cd937e2d1bbb8",
"url": "https://fuchsia.googlesource.com/integration/+/c2192c391797e693827ec33d7f5cd937e2d1bbb8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f49fd66bdbdd77c9e744db90b5e23aa98c3acf0f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f49fd66bdbdd77c9e744db90b5e23aa98c3acf0f",
"title": "f49fd66bdbdd77c9e744db90b5e23aa98c3acf0f",
"url": "https://fuchsia.googlesource.com/integration/+/f49fd66bdbdd77c9e744db90b5e23aa98c3acf0f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "373047901de5bcd1f03a0654945ee00eeea5e507"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@373047901de5bcd1f03a0654945ee00eeea5e507",
"title": "373047901de5bcd1f03a0654945ee00eeea5e507",
"url": "https://fuchsia.googlesource.com/integration/+/373047901de5bcd1f03a0654945ee00eeea5e507"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e7434711f604d3e488ccbc3ffa3ce8cc0cc89238"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e7434711f604d3e488ccbc3ffa3ce8cc0cc89238",
"title": "e7434711f604d3e488ccbc3ffa3ce8cc0cc89238",
"url": "https://fuchsia.googlesource.com/integration/+/e7434711f604d3e488ccbc3ffa3ce8cc0cc89238"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9df7d5c14990865cd745f8dc0d314c22c5c5026e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9df7d5c14990865cd745f8dc0d314c22c5c5026e",
"title": "9df7d5c14990865cd745f8dc0d314c22c5c5026e",
"url": "https://fuchsia.googlesource.com/integration/+/9df7d5c14990865cd745f8dc0d314c22c5c5026e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1ef7a0e288e13669a3665803e3ebcf21d514de04"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1ef7a0e288e13669a3665803e3ebcf21d514de04",
"title": "1ef7a0e288e13669a3665803e3ebcf21d514de04",
"url": "https://fuchsia.googlesource.com/integration/+/1ef7a0e288e13669a3665803e3ebcf21d514de04"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4341686924e3cfe8f15c07632bc8c04897c22977"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4341686924e3cfe8f15c07632bc8c04897c22977",
"title": "4341686924e3cfe8f15c07632bc8c04897c22977",
"url": "https://fuchsia.googlesource.com/integration/+/4341686924e3cfe8f15c07632bc8c04897c22977"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d2459850d5b6281b98369e7fdac445b42c2d8adf"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d2459850d5b6281b98369e7fdac445b42c2d8adf",
"title": "d2459850d5b6281b98369e7fdac445b42c2d8adf",
"url": "https://fuchsia.googlesource.com/integration/+/d2459850d5b6281b98369e7fdac445b42c2d8adf"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "062fe9fc7ca8156db0750890c83c3495e5981680"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@062fe9fc7ca8156db0750890c83c3495e5981680",
"title": "062fe9fc7ca8156db0750890c83c3495e5981680",
"url": "https://fuchsia.googlesource.com/integration/+/062fe9fc7ca8156db0750890c83c3495e5981680"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "5deb5537eb89d31f5946febc33373b9ab42bce0f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@5deb5537eb89d31f5946febc33373b9ab42bce0f",
"title": "5deb5537eb89d31f5946febc33373b9ab42bce0f",
"url": "https://fuchsia.googlesource.com/integration/+/5deb5537eb89d31f5946febc33373b9ab42bce0f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4624b03b76e477f2f593dccf2ef50182235e352d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4624b03b76e477f2f593dccf2ef50182235e352d",
"title": "4624b03b76e477f2f593dccf2ef50182235e352d",
"url": "https://fuchsia.googlesource.com/integration/+/4624b03b76e477f2f593dccf2ef50182235e352d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f38b11051739ee6d6820969b8753f7717e9b5eac"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f38b11051739ee6d6820969b8753f7717e9b5eac",
"title": "f38b11051739ee6d6820969b8753f7717e9b5eac",
"url": "https://fuchsia.googlesource.com/integration/+/f38b11051739ee6d6820969b8753f7717e9b5eac"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "fea8764bce9d9298555725d346da7c7ca59be30d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@fea8764bce9d9298555725d346da7c7ca59be30d",
"title": "fea8764bce9d9298555725d346da7c7ca59be30d",
"url": "https://fuchsia.googlesource.com/integration/+/fea8764bce9d9298555725d346da7c7ca59be30d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "658afc979e9ed72c37eb54c5d119821756ded3af"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@658afc979e9ed72c37eb54c5d119821756ded3af",
"title": "658afc979e9ed72c37eb54c5d119821756ded3af",
"url": "https://fuchsia.googlesource.com/integration/+/658afc979e9ed72c37eb54c5d119821756ded3af"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "7bc46c921dc68064944673f72006b99372f775cd"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@7bc46c921dc68064944673f72006b99372f775cd",
"title": "7bc46c921dc68064944673f72006b99372f775cd",
"url": "https://fuchsia.googlesource.com/integration/+/7bc46c921dc68064944673f72006b99372f775cd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6281a9cd8b3b03db676b9373efb84ba9db0a0f22"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6281a9cd8b3b03db676b9373efb84ba9db0a0f22",
"title": "6281a9cd8b3b03db676b9373efb84ba9db0a0f22",
"url": "https://fuchsia.googlesource.com/integration/+/6281a9cd8b3b03db676b9373efb84ba9db0a0f22"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "6281a9cd8b3b03db676b9373efb84ba9db0a0f22",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8929942959941895520"
},
{
"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"
}
}
[20:09:36.367] Scheduled build:
{
"id": "8764512898020642113",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-11-13T20:09:35.554663484Z",
"updateTime": "2023-11-13T20:09:35.554663484Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "6281a9cd8b3b03db676b9373efb84ba9db0a0f22",
"ref": "refs/heads/main"
}
}
}
[20:09:36.367] Task URL: https://cr-buildbucket.appspot.com/build/8764512898020642113
[20:09:36.367] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929942959941895520:2:0) after 9m22s
[20:09:37.140] Received PubSub notification, asking Buildbucket for the build status
[20:09:37.168] Build status: SCHEDULED
[20:10:07.259] Received PubSub notification, asking Buildbucket for the build status
[20:10:07.294] Build status: STARTED
[20:18:58.402] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929942959941895520:2:0)
[20:18:58.444] Build status: STARTED
[20:18:58.445] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929942959941895520:5:0) after 9m30s
[20:28:28.580] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929942959941895520:5:0)
[20:28:28.599] Build status: STARTED
[20:28:28.599] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929942959941895520:6:0) after 9m40s
[20:38:08.677] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929942959941895520:6:0)
[20:38:08.702] Build status: STARTED
[20:38:08.702] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929942959941895520:7:0) after 8m38s
[20:46:46.723] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929942959941895520:7:0)
[20:46:46.749] Build status: STARTED
[20:46:46.749] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929942959941895520:8:0) after 3m55s
[20:50:41.806] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929942959941895520:8:0)
[20:50:41.829] Build status: STARTED
[20:50:41.829] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929942959941895520:9:0) after 6m12s
[20:56:53.846] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929942959941895520:9:0)
[20:56:53.907] Build status: STARTED
[20:56:53.907] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929942959941895520:10:0) after 2m39s
[20:59:32.934] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929942959941895520:10:0)
[20:59:32.961] Build status: STARTED
[20:59:32.961] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929942959941895520:11:0) after 6m5s
[21:05:37.981] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929942959941895520:11:0)
[21:05:38.002] Build status: STARTED
[21:05:38.002] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929942959941895520:12:0) after 2m41s
[21:08:19.068] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929942959941895520:12:0)
[21:08:19.101] Build status: STARTED
[21:08:19.101] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929942959941895520:13:0) after 1m5s
[21:09:24.150] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929942959941895520:13:0)
[21:09:24.205] Build status: STARTED
[21:09:24.205] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929942959941895520:14:0) after 9m1s
[21:18:25.239] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929942959941895520:14:0)
[21:18:25.259] Build status: STARTED
[21:18:25.259] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929942959941895520:15:0) after 1m12s
[21:19:37.283] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929942959941895520:15:0)
[21:19:37.331] Build status: STARTED
[21:19:37.331] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929942959941895520:16:0) after 9m0s
[21:26:00.617] Received PubSub notification, asking Buildbucket for the build status
[21:26:00.650] Build:
{
"id": "8764512898020642113",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-11-13T20:09:35.554663484Z",
"startTime": "2023-11-13T20:10:05.767651Z",
"endTime": "2023-11-13T21:25:59.814681Z",
"updateTime": "2023-11-13T21:25:59.814681Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "6281a9cd8b3b03db676b9373efb84ba9db0a0f22",
"ref": "refs/heads/main"
}
}
}
[21:26:00.650] Invocation finished in 1h16m26.311747387s with status SUCCEEDED