[23:41:21.008] New invocation is queued and will start shortly
[23:41:22.461] Starting the invocation (attempt 1)
[23:41:22.493] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/7df4bd7d0eb5715b995c723b8c07781badcfadf2"
[23:41:22.493] Popped gitiles commit info from properties and tags
[23:41:22.493] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:41:22.494] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:41:22.494] Buildbucket request:
{
"requestId": "8938626944815115696",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8938626944815115696",
"job": "fuchsia/core.x64-debug-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b092125263b9c21cca629d537178e2363896562d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b092125263b9c21cca629d537178e2363896562d",
"title": "b092125263b9c21cca629d537178e2363896562d",
"url": "https://fuchsia.googlesource.com/integration/+/b092125263b9c21cca629d537178e2363896562d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b94d1c3a4986d09791282047875c24600f05d0b9"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b94d1c3a4986d09791282047875c24600f05d0b9",
"title": "b94d1c3a4986d09791282047875c24600f05d0b9",
"url": "https://fuchsia.googlesource.com/integration/+/b94d1c3a4986d09791282047875c24600f05d0b9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "cb8830a62839cf61c16fd3f04456d0fc4e97c763"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@cb8830a62839cf61c16fd3f04456d0fc4e97c763",
"title": "cb8830a62839cf61c16fd3f04456d0fc4e97c763",
"url": "https://fuchsia.googlesource.com/integration/+/cb8830a62839cf61c16fd3f04456d0fc4e97c763"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f8a2e3d4431e29e087498cc63311ddcf220367a2"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f8a2e3d4431e29e087498cc63311ddcf220367a2",
"title": "f8a2e3d4431e29e087498cc63311ddcf220367a2",
"url": "https://fuchsia.googlesource.com/integration/+/f8a2e3d4431e29e087498cc63311ddcf220367a2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2ac30402633f1f30aa83b4b337f71184844294d5"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2ac30402633f1f30aa83b4b337f71184844294d5",
"title": "2ac30402633f1f30aa83b4b337f71184844294d5",
"url": "https://fuchsia.googlesource.com/integration/+/2ac30402633f1f30aa83b4b337f71184844294d5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1d3ac00869cd9164cf8d3a9e32becc6999821401"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1d3ac00869cd9164cf8d3a9e32becc6999821401",
"title": "1d3ac00869cd9164cf8d3a9e32becc6999821401",
"url": "https://fuchsia.googlesource.com/integration/+/1d3ac00869cd9164cf8d3a9e32becc6999821401"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "724fecb965b6e9f5f101c9a375962c978a95aaa6"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@724fecb965b6e9f5f101c9a375962c978a95aaa6",
"title": "724fecb965b6e9f5f101c9a375962c978a95aaa6",
"url": "https://fuchsia.googlesource.com/integration/+/724fecb965b6e9f5f101c9a375962c978a95aaa6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c8a6dd561a5fcb7571f77225ab7c6229b18acd1b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c8a6dd561a5fcb7571f77225ab7c6229b18acd1b",
"title": "c8a6dd561a5fcb7571f77225ab7c6229b18acd1b",
"url": "https://fuchsia.googlesource.com/integration/+/c8a6dd561a5fcb7571f77225ab7c6229b18acd1b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "296136b21850a945f10ef7d99d70184934dfb400"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@296136b21850a945f10ef7d99d70184934dfb400",
"title": "296136b21850a945f10ef7d99d70184934dfb400",
"url": "https://fuchsia.googlesource.com/integration/+/296136b21850a945f10ef7d99d70184934dfb400"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8a96669fdc538b97d1c0a84feb98d7a7bac0d7b1"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8a96669fdc538b97d1c0a84feb98d7a7bac0d7b1",
"title": "8a96669fdc538b97d1c0a84feb98d7a7bac0d7b1",
"url": "https://fuchsia.googlesource.com/integration/+/8a96669fdc538b97d1c0a84feb98d7a7bac0d7b1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "bee9bf59a3078a28168b10d6097fc15d360735fb"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@bee9bf59a3078a28168b10d6097fc15d360735fb",
"title": "bee9bf59a3078a28168b10d6097fc15d360735fb",
"url": "https://fuchsia.googlesource.com/integration/+/bee9bf59a3078a28168b10d6097fc15d360735fb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "017964b295e7ea0185dec1873f10cbe94b4cba26"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@017964b295e7ea0185dec1873f10cbe94b4cba26",
"title": "017964b295e7ea0185dec1873f10cbe94b4cba26",
"url": "https://fuchsia.googlesource.com/integration/+/017964b295e7ea0185dec1873f10cbe94b4cba26"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "7df4bd7d0eb5715b995c723b8c07781badcfadf2"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@7df4bd7d0eb5715b995c723b8c07781badcfadf2",
"title": "7df4bd7d0eb5715b995c723b8c07781badcfadf2",
"url": "https://fuchsia.googlesource.com/integration/+/7df4bd7d0eb5715b995c723b8c07781badcfadf2"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "7df4bd7d0eb5715b995c723b8c07781badcfadf2",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8938626944815115696"
},
{
"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"
}
}
[23:41:23.113] Scheduled build:
{
"id": "8773196882547923553",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-08-09T23:41:22.546558219Z",
"updateTime": "2023-08-09T23:41:22.546558219Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "7df4bd7d0eb5715b995c723b8c07781badcfadf2",
"ref": "refs/heads/main"
}
}
}
[23:41:23.113] Task URL: https://cr-buildbucket.appspot.com/build/8773196882547923553
[23:41:23.113] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938626944815115696:2:0) after 6m2s
[23:41:23.276] Received PubSub notification, asking Buildbucket for the build status
[23:41:23.370] Build status: SCHEDULED
[23:41:46.983] Received PubSub notification, asking Buildbucket for the build status
[23:41:47.011] Build status: STARTED
[23:47:25.219] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938626944815115696:2:0)
[23:47:25.259] Build status: STARTED
[23:47:25.259] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938626944815115696:5:0) after 9m31s
[23:56:56.278] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938626944815115696:5:0)
[23:56:56.302] Build status: STARTED
[23:56:56.302] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938626944815115696:6:0) after 3m21s
[00:00:17.534] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938626944815115696:6:0)
[00:00:17.565] Build status: STARTED
[00:00:17.565] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938626944815115696:7:0) after 7m56s
[00:08:13.649] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938626944815115696:7:0)
[00:08:13.672] Build status: STARTED
[00:08:13.672] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938626944815115696:8:0) after 7m20s
[00:15:33.693] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938626944815115696:8:0)
[00:15:33.710] Build status: STARTED
[00:15:33.710] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938626944815115696:9:0) after 3m39s
[00:19:12.758] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938626944815115696:9:0)
[00:19:12.841] Build status: STARTED
[00:19:12.841] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938626944815115696:10:0) after 2m24s
[00:21:37.049] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938626944815115696:10:0)
[00:21:37.066] Build status: STARTED
[00:21:37.066] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938626944815115696:11:0) after 3m52s
[00:25:29.130] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938626944815115696:11:0)
[00:25:29.151] Build status: STARTED
[00:25:29.151] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938626944815115696:12:0) after 6m28s
[00:31:57.309] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938626944815115696:12:0)
[00:31:57.328] Build status: STARTED
[00:31:57.328] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938626944815115696:13:0) after 2m37s
[00:34:34.346] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938626944815115696:13:0)
[00:34:34.372] Build status: STARTED
[00:34:34.372] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938626944815115696:14:0) after 2m40s
[00:37:14.573] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938626944815115696:14:0)
[00:37:14.591] Build status: STARTED
[00:37:14.591] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938626944815115696:15:0) after 8m55s
[00:46:09.609] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938626944815115696:15:0)
[00:46:09.628] Build status: STARTED
[00:46:09.628] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938626944815115696:16:0) after 6m7s
[00:52:17.016] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938626944815115696:16:0)
[00:52:17.033] Build status: STARTED
[00:52:17.033] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938626944815115696:17:0) after 6m52s
[00:59:09.108] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938626944815115696:17:0)
[00:59:09.134] Build status: STARTED
[00:59:09.134] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938626944815115696:18:0) after 1m57s
[00:59:51.635] Received PubSub notification, asking Buildbucket for the build status
[00:59:51.652] Build:
{
"id": "8773196882547923553",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-08-09T23:41:22.546558219Z",
"startTime": "2023-08-09T23:41:46.461176Z",
"endTime": "2023-08-10T00:59:50.934974079Z",
"updateTime": "2023-08-10T00:59:50.934974079Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "7df4bd7d0eb5715b995c723b8c07781badcfadf2",
"ref": "refs/heads/main"
}
}
}
[00:59:51.652] Invocation finished in 1h18m30.651911682s with status SUCCEEDED