[23:42:40.523] New invocation is queued and will start shortly
[23:42:41.986] Starting the invocation (attempt 1)
[23:42:42.032] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/deede2cb2842628f227afde0d88465d46e18446e"
[23:42:42.032] Popped gitiles commit info from properties and tags
[23:42:42.032] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:42:42.034] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:42:42.035] Buildbucket request:
{
"requestId": "8932919252559792416",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8932919252559792416",
"job": "fuchsia/core.x64-debug-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b617f66b9f79d252f2d84fe2f8ff14defa2c5774"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b617f66b9f79d252f2d84fe2f8ff14defa2c5774",
"title": "b617f66b9f79d252f2d84fe2f8ff14defa2c5774",
"url": "https://fuchsia.googlesource.com/integration/+/b617f66b9f79d252f2d84fe2f8ff14defa2c5774"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2106e8ed464522a7a4e5ef28e6021defd214242c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2106e8ed464522a7a4e5ef28e6021defd214242c",
"title": "2106e8ed464522a7a4e5ef28e6021defd214242c",
"url": "https://fuchsia.googlesource.com/integration/+/2106e8ed464522a7a4e5ef28e6021defd214242c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4339e8f49de4ebae26793d3502e05d7e23561691"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4339e8f49de4ebae26793d3502e05d7e23561691",
"title": "4339e8f49de4ebae26793d3502e05d7e23561691",
"url": "https://fuchsia.googlesource.com/integration/+/4339e8f49de4ebae26793d3502e05d7e23561691"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "fcf8c26429fa627a018154630bc6da9ad60cb0ca"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@fcf8c26429fa627a018154630bc6da9ad60cb0ca",
"title": "fcf8c26429fa627a018154630bc6da9ad60cb0ca",
"url": "https://fuchsia.googlesource.com/integration/+/fcf8c26429fa627a018154630bc6da9ad60cb0ca"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f59209f0612d6277d6b656634276c7a4f47ea956"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f59209f0612d6277d6b656634276c7a4f47ea956",
"title": "f59209f0612d6277d6b656634276c7a4f47ea956",
"url": "https://fuchsia.googlesource.com/integration/+/f59209f0612d6277d6b656634276c7a4f47ea956"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e685b6a6fca7a58abf4eb530c979a87c6fe6f3ad"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e685b6a6fca7a58abf4eb530c979a87c6fe6f3ad",
"title": "e685b6a6fca7a58abf4eb530c979a87c6fe6f3ad",
"url": "https://fuchsia.googlesource.com/integration/+/e685b6a6fca7a58abf4eb530c979a87c6fe6f3ad"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a67e0e41ba47166018aba2e7c4a33777fe407dfb"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a67e0e41ba47166018aba2e7c4a33777fe407dfb",
"title": "a67e0e41ba47166018aba2e7c4a33777fe407dfb",
"url": "https://fuchsia.googlesource.com/integration/+/a67e0e41ba47166018aba2e7c4a33777fe407dfb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3da6aba2db47a12b57f72c491bb1aa88f93c1088"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3da6aba2db47a12b57f72c491bb1aa88f93c1088",
"title": "3da6aba2db47a12b57f72c491bb1aa88f93c1088",
"url": "https://fuchsia.googlesource.com/integration/+/3da6aba2db47a12b57f72c491bb1aa88f93c1088"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6120aed51e873c4da70878e10866063059d13f1d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6120aed51e873c4da70878e10866063059d13f1d",
"title": "6120aed51e873c4da70878e10866063059d13f1d",
"url": "https://fuchsia.googlesource.com/integration/+/6120aed51e873c4da70878e10866063059d13f1d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4910798dd816ef80486d4425f87efd8e41ae9b40"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4910798dd816ef80486d4425f87efd8e41ae9b40",
"title": "4910798dd816ef80486d4425f87efd8e41ae9b40",
"url": "https://fuchsia.googlesource.com/integration/+/4910798dd816ef80486d4425f87efd8e41ae9b40"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "074a0c03797b11446a3306bca760be689afd1335"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@074a0c03797b11446a3306bca760be689afd1335",
"title": "074a0c03797b11446a3306bca760be689afd1335",
"url": "https://fuchsia.googlesource.com/integration/+/074a0c03797b11446a3306bca760be689afd1335"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "deede2cb2842628f227afde0d88465d46e18446e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@deede2cb2842628f227afde0d88465d46e18446e",
"title": "deede2cb2842628f227afde0d88465d46e18446e",
"url": "https://fuchsia.googlesource.com/integration/+/deede2cb2842628f227afde0d88465d46e18446e"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "deede2cb2842628f227afde0d88465d46e18446e",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8932919252559792416"
},
{
"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:42:42.624] Scheduled build:
{
"id": "8767489190205855169",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-10-11T23:42:42.139665689Z",
"updateTime": "2023-10-11T23:42:42.139665689Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "deede2cb2842628f227afde0d88465d46e18446e",
"ref": "refs/heads/main"
}
}
}
[23:42:42.624] Task URL: https://cr-buildbucket.appspot.com/build/8767489190205855169
[23:42:42.624] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932919252559792416:2:0) after 6m40s
[23:42:43.919] Received PubSub notification, asking Buildbucket for the build status
[23:42:44.009] Build status: SCHEDULED
[23:43:27.209] Received PubSub notification, asking Buildbucket for the build status
[23:43:27.224] Build status: STARTED
[23:49:22.668] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932919252559792416:2:0)
[23:49:22.746] Build status: STARTED
[23:49:22.746] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932919252559792416:5:0) after 7m54s
[23:57:16.780] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932919252559792416:5:0)
[23:57:16.836] Build status: STARTED
[23:57:16.836] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932919252559792416:6:0) after 7m23s
[00:04:40.264] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932919252559792416:6:0)
[00:04:40.509] Build status: STARTED
[00:04:40.509] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932919252559792416:7:0) after 2m35s
[00:07:15.551] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932919252559792416:7:0)
[00:07:15.568] Build status: STARTED
[00:07:15.568] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932919252559792416:8:0) after 1m59s
[00:09:14.676] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932919252559792416:8:0)
[00:09:14.693] Build status: STARTED
[00:09:14.693] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932919252559792416:9:0) after 2m58s
[00:12:12.838] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932919252559792416:9:0)
[00:12:12.908] Build status: STARTED
[00:12:12.908] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932919252559792416:10:0) after 7m22s
[00:19:34.926] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932919252559792416:10:0)
[00:19:34.950] Build status: STARTED
[00:19:34.950] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932919252559792416:11:0) after 4m4s
[00:23:38.969] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932919252559792416:11:0)
[00:23:38.985] Build status: STARTED
[00:23:38.986] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932919252559792416:12:0) after 4m32s
[00:28:11.089] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932919252559792416:12:0)
[00:28:11.108] Build status: STARTED
[00:28:11.108] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932919252559792416:13:0) after 8m51s
[00:37:02.137] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932919252559792416:13:0)
[00:37:02.155] Build status: STARTED
[00:37:02.155] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932919252559792416:14:0) after 8m8s
[00:45:10.175] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932919252559792416:14:0)
[00:45:10.195] Build status: STARTED
[00:45:10.195] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932919252559792416:15:0) after 7m44s
[00:49:21.420] Received PubSub notification, asking Buildbucket for the build status
[00:49:21.611] Build:
{
"id": "8767489190205855169",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-10-11T23:42:42.139665689Z",
"startTime": "2023-10-11T23:43:26.189659Z",
"endTime": "2023-10-12T00:49:20.845339702Z",
"updateTime": "2023-10-12T00:49:20.845339702Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "deede2cb2842628f227afde0d88465d46e18446e",
"ref": "refs/heads/main"
}
}
}
[00:49:21.611] Invocation finished in 1h6m41.101155091s with status SUCCEEDED