[23:48:32.895] New invocation is queued and will start shortly
[23:48:34.019] Starting the invocation (attempt 1)
[23:48:34.059] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/cb216c00d06875b5c238a5ff91dcd8d15010ff26"
[23:48:34.059] Popped gitiles commit info from properties and tags
[23:48:34.059] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:48:34.064] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:48:34.064] Buildbucket request:
{
"requestId": "8932737689130424224",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8932737689130424224",
"job": "fuchsia/core.x64-debug-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "86b41f3f31244c68eccb92191410fa867480fbbc"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@86b41f3f31244c68eccb92191410fa867480fbbc",
"title": "86b41f3f31244c68eccb92191410fa867480fbbc",
"url": "https://fuchsia.googlesource.com/integration/+/86b41f3f31244c68eccb92191410fa867480fbbc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "24be65c9db418e08ec631e00f285ded8d182ec09"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@24be65c9db418e08ec631e00f285ded8d182ec09",
"title": "24be65c9db418e08ec631e00f285ded8d182ec09",
"url": "https://fuchsia.googlesource.com/integration/+/24be65c9db418e08ec631e00f285ded8d182ec09"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "66a53c5f4f15953538cf73740bc2117bb78625b7"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@66a53c5f4f15953538cf73740bc2117bb78625b7",
"title": "66a53c5f4f15953538cf73740bc2117bb78625b7",
"url": "https://fuchsia.googlesource.com/integration/+/66a53c5f4f15953538cf73740bc2117bb78625b7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0cb4a4854c98dcdba899440625f5f6ae687b3a96"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0cb4a4854c98dcdba899440625f5f6ae687b3a96",
"title": "0cb4a4854c98dcdba899440625f5f6ae687b3a96",
"url": "https://fuchsia.googlesource.com/integration/+/0cb4a4854c98dcdba899440625f5f6ae687b3a96"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d2e876fd71afd13e214880eb87e22077e203dcb5"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d2e876fd71afd13e214880eb87e22077e203dcb5",
"title": "d2e876fd71afd13e214880eb87e22077e203dcb5",
"url": "https://fuchsia.googlesource.com/integration/+/d2e876fd71afd13e214880eb87e22077e203dcb5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "cb216c00d06875b5c238a5ff91dcd8d15010ff26"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@cb216c00d06875b5c238a5ff91dcd8d15010ff26",
"title": "cb216c00d06875b5c238a5ff91dcd8d15010ff26",
"url": "https://fuchsia.googlesource.com/integration/+/cb216c00d06875b5c238a5ff91dcd8d15010ff26"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "cb216c00d06875b5c238a5ff91dcd8d15010ff26",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8932737689130424224"
},
{
"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:48:34.507] Scheduled build:
{
"id": "8767307627184610849",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-10-13T23:48:34.129559688Z",
"updateTime": "2023-10-13T23:48:34.129559688Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "cb216c00d06875b5c238a5ff91dcd8d15010ff26",
"ref": "refs/heads/main"
}
}
}
[23:48:34.508] Task URL: https://cr-buildbucket.appspot.com/build/8767307627184610849
[23:48:34.508] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932737689130424224:2:0) after 9m36s
[23:48:35.445] Received PubSub notification, asking Buildbucket for the build status
[23:48:35.583] Build status: SCHEDULED
[23:48:35.905] Received PubSub notification, asking Buildbucket for the build status
[23:48:35.939] Build status: STARTED
[23:58:10.639] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932737689130424224:2:0)
[23:58:10.711] Build status: STARTED
[23:58:10.711] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932737689130424224:5:0) after 7m29s
[00:05:39.855] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932737689130424224:5:0)
[00:05:39.885] Build status: STARTED
[00:05:39.885] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932737689130424224:6:0) after 8m8s
[00:13:48.027] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932737689130424224:6:0)
[00:13:48.067] Build status: STARTED
[00:13:48.067] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932737689130424224:7:0) after 5m50s
[00:19:38.286] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932737689130424224:7:0)
[00:19:38.329] Build status: STARTED
[00:19:38.329] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932737689130424224:8:0) after 4m4s
[00:23:42.394] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932737689130424224:8:0)
[00:23:42.415] Build status: STARTED
[00:23:42.415] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932737689130424224:9:0) after 6m15s
[00:29:57.537] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932737689130424224:9:0)
[00:29:57.556] Build status: STARTED
[00:29:57.556] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932737689130424224:10:0) after 2m40s
[00:32:37.639] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932737689130424224:10:0)
[00:32:37.667] Build status: STARTED
[00:32:37.667] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932737689130424224:11:0) after 5m54s
[00:38:31.683] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932737689130424224:11:0)
[00:38:31.700] Build status: STARTED
[00:38:31.700] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932737689130424224:12:0) after 8m11s
[00:39:59.651] Received PubSub notification, asking Buildbucket for the build status
[00:39:59.752] Build:
{
"id": "8767307627184610849",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-10-13T23:48:34.129559688Z",
"startTime": "2023-10-13T23:48:34.835733Z",
"endTime": "2023-10-14T00:39:59.289751366Z",
"updateTime": "2023-10-14T00:39:59.289751366Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "cb216c00d06875b5c238a5ff91dcd8d15010ff26",
"ref": "refs/heads/main"
}
}
}
[00:39:59.752] Invocation finished in 51m26.861819627s with status SUCCEEDED