[22:28:05.289] New invocation is queued and will start shortly
[22:28:06.625] Starting the invocation (attempt 1)
[22:28:06.650] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/e0ae4960132882684fc9b64ae91371a369b479c0"
[22:28:06.650] Popped gitiles commit info from properties and tags
[22:28:06.650] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:28:06.650] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:28:06.651] Buildbucket request:
{
"requestId": "8935641854174154016",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8935641854174154016",
"job": "fuchsia/core.x64-debug-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f0670fbe193c2a03d670471d01e046ee142a6eae"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f0670fbe193c2a03d670471d01e046ee142a6eae",
"title": "f0670fbe193c2a03d670471d01e046ee142a6eae",
"url": "https://fuchsia.googlesource.com/integration/+/f0670fbe193c2a03d670471d01e046ee142a6eae"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "87def4b6ca75f7793e9e5cd92c5ccffb5508ddeb"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@87def4b6ca75f7793e9e5cd92c5ccffb5508ddeb",
"title": "87def4b6ca75f7793e9e5cd92c5ccffb5508ddeb",
"url": "https://fuchsia.googlesource.com/integration/+/87def4b6ca75f7793e9e5cd92c5ccffb5508ddeb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "80334378debeac757f97aebdbca46e9c134d1b96"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@80334378debeac757f97aebdbca46e9c134d1b96",
"title": "80334378debeac757f97aebdbca46e9c134d1b96",
"url": "https://fuchsia.googlesource.com/integration/+/80334378debeac757f97aebdbca46e9c134d1b96"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9f6e948ebd88fd64c258c03ab6b995e5409260a7"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9f6e948ebd88fd64c258c03ab6b995e5409260a7",
"title": "9f6e948ebd88fd64c258c03ab6b995e5409260a7",
"url": "https://fuchsia.googlesource.com/integration/+/9f6e948ebd88fd64c258c03ab6b995e5409260a7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a1a0fa0d50b6d75fbf86bdc39297e8a4affea081"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a1a0fa0d50b6d75fbf86bdc39297e8a4affea081",
"title": "a1a0fa0d50b6d75fbf86bdc39297e8a4affea081",
"url": "https://fuchsia.googlesource.com/integration/+/a1a0fa0d50b6d75fbf86bdc39297e8a4affea081"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e7724f742fcd69b6fa5e1a6bd31fe0a094a2866c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e7724f742fcd69b6fa5e1a6bd31fe0a094a2866c",
"title": "e7724f742fcd69b6fa5e1a6bd31fe0a094a2866c",
"url": "https://fuchsia.googlesource.com/integration/+/e7724f742fcd69b6fa5e1a6bd31fe0a094a2866c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "225928c5674f8c149d244785d126a2e4b084a454"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@225928c5674f8c149d244785d126a2e4b084a454",
"title": "225928c5674f8c149d244785d126a2e4b084a454",
"url": "https://fuchsia.googlesource.com/integration/+/225928c5674f8c149d244785d126a2e4b084a454"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9d54aab9b4ec9c9bd641c45283ec352e8c7153a4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9d54aab9b4ec9c9bd641c45283ec352e8c7153a4",
"title": "9d54aab9b4ec9c9bd641c45283ec352e8c7153a4",
"url": "https://fuchsia.googlesource.com/integration/+/9d54aab9b4ec9c9bd641c45283ec352e8c7153a4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2d3fdcbd719722d4dd29b97bcdf5d2a06b14802f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2d3fdcbd719722d4dd29b97bcdf5d2a06b14802f",
"title": "2d3fdcbd719722d4dd29b97bcdf5d2a06b14802f",
"url": "https://fuchsia.googlesource.com/integration/+/2d3fdcbd719722d4dd29b97bcdf5d2a06b14802f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "658c6b230d5d49cc34c541bce06e81f23b746081"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@658c6b230d5d49cc34c541bce06e81f23b746081",
"title": "658c6b230d5d49cc34c541bce06e81f23b746081",
"url": "https://fuchsia.googlesource.com/integration/+/658c6b230d5d49cc34c541bce06e81f23b746081"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "bd8f21dfa88d5e3398a1ce40a92d80963d84b259"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@bd8f21dfa88d5e3398a1ce40a92d80963d84b259",
"title": "bd8f21dfa88d5e3398a1ce40a92d80963d84b259",
"url": "https://fuchsia.googlesource.com/integration/+/bd8f21dfa88d5e3398a1ce40a92d80963d84b259"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "83918e533e238df5e99aeda9b41472e6a525d1c2"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@83918e533e238df5e99aeda9b41472e6a525d1c2",
"title": "83918e533e238df5e99aeda9b41472e6a525d1c2",
"url": "https://fuchsia.googlesource.com/integration/+/83918e533e238df5e99aeda9b41472e6a525d1c2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "73cde48f4ebed19b7fc56270d5ef281b6dd2445d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@73cde48f4ebed19b7fc56270d5ef281b6dd2445d",
"title": "73cde48f4ebed19b7fc56270d5ef281b6dd2445d",
"url": "https://fuchsia.googlesource.com/integration/+/73cde48f4ebed19b7fc56270d5ef281b6dd2445d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9d5e4a1f041d88e313e221a7a72d74237bcfe39a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9d5e4a1f041d88e313e221a7a72d74237bcfe39a",
"title": "9d5e4a1f041d88e313e221a7a72d74237bcfe39a",
"url": "https://fuchsia.googlesource.com/integration/+/9d5e4a1f041d88e313e221a7a72d74237bcfe39a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e0ae4960132882684fc9b64ae91371a369b479c0"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e0ae4960132882684fc9b64ae91371a369b479c0",
"title": "e0ae4960132882684fc9b64ae91371a369b479c0",
"url": "https://fuchsia.googlesource.com/integration/+/e0ae4960132882684fc9b64ae91371a369b479c0"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "e0ae4960132882684fc9b64ae91371a369b479c0",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8935641854174154016"
},
{
"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:28:07.124] Scheduled build:
{
"id": "8770211792022467377",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-09-11T22:28:06.712229235Z",
"updateTime": "2023-09-11T22:28:06.712229235Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "e0ae4960132882684fc9b64ae91371a369b479c0",
"ref": "refs/heads/main"
}
}
}
[22:28:07.124] Task URL: https://cr-buildbucket.appspot.com/build/8770211792022467377
[22:28:07.124] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935641854174154016:2:0) after 7m52s
[22:28:08.072] Received PubSub notification, asking Buildbucket for the build status
[22:28:08.107] Build status: SCHEDULED
[22:28:08.841] Received PubSub notification, asking Buildbucket for the build status
[22:28:08.949] Build status: STARTED
[22:36:30.098] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935641854174154016:2:0)
[22:36:30.366] Build status: STARTED
[22:36:30.366] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935641854174154016:5:0) after 4m33s
[22:41:03.382] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935641854174154016:5:0)
[22:41:03.444] Build status: STARTED
[22:41:03.445] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935641854174154016:6:0) after 1m34s
[22:42:38.059] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935641854174154016:6:0)
[22:42:38.120] Build status: STARTED
[22:42:38.120] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935641854174154016:7:0) after 9m33s
[22:52:11.250] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935641854174154016:7:0)
[22:52:11.304] Build status: STARTED
[22:52:11.304] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935641854174154016:8:0) after 4m45s
[22:56:56.423] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935641854174154016:8:0)
[22:56:56.440] Build status: STARTED
[22:56:56.440] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935641854174154016:9:0) after 3m36s
[23:00:32.538] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935641854174154016:9:0)
[23:00:32.559] Build status: STARTED
[23:00:32.559] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935641854174154016:10:0) after 5m39s
[23:06:12.294] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935641854174154016:10:0)
[23:06:12.322] Build status: STARTED
[23:06:12.322] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935641854174154016:11:0) after 8m22s
[23:14:34.386] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935641854174154016:11:0)
[23:14:34.415] Build status: STARTED
[23:14:34.415] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935641854174154016:12:0) after 7m48s
[23:22:22.529] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935641854174154016:12:0)
[23:22:22.610] Build status: STARTED
[23:22:22.610] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935641854174154016:13:0) after 7m8s
[23:29:31.250] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935641854174154016:13:0)
[23:29:31.271] Build status: STARTED
[23:29:31.271] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935641854174154016:14:0) after 4m43s
[23:34:14.400] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935641854174154016:14:0)
[23:34:14.417] Build status: STARTED
[23:34:14.417] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935641854174154016:15:0) after 9m14s
[23:39:04.272] Received PubSub notification, asking Buildbucket for the build status
[23:39:04.296] Build:
{
"id": "8770211792022467377",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-09-11T22:28:06.712229235Z",
"startTime": "2023-09-11T22:28:08.172715Z",
"endTime": "2023-09-11T23:39:03.847141910Z",
"updateTime": "2023-09-11T23:39:03.847141910Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "e0ae4960132882684fc9b64ae91371a369b479c0",
"ref": "refs/heads/main"
}
}
}
[23:39:04.296] Invocation finished in 1h10m59.018584378s with status SUCCEEDED