[19:52:00.429] New invocation is queued and will start shortly
[19:52:02.071] Starting the invocation (attempt 1)
[19:52:02.102] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/cfa3e3bf1dafa8fa7ab8f5dc58d0d02c44923176"
[19:52:02.102] Popped gitiles commit info from properties and tags
[19:52:02.102] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:52:02.103] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:52:02.103] Buildbucket request:
{
"requestId": "8936829434508079984",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8936829434508079984",
"job": "fuchsia/core.x64-debug-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "96a3904773d705b992256489d06db5bbef7f49ba"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@96a3904773d705b992256489d06db5bbef7f49ba",
"title": "96a3904773d705b992256489d06db5bbef7f49ba",
"url": "https://fuchsia.googlesource.com/integration/+/96a3904773d705b992256489d06db5bbef7f49ba"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d9ec16495870a0af8b8f242dfce126489b67774c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d9ec16495870a0af8b8f242dfce126489b67774c",
"title": "d9ec16495870a0af8b8f242dfce126489b67774c",
"url": "https://fuchsia.googlesource.com/integration/+/d9ec16495870a0af8b8f242dfce126489b67774c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "366edfe0b9b5eba6cfcb54d3bb129ed4f3aed946"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@366edfe0b9b5eba6cfcb54d3bb129ed4f3aed946",
"title": "366edfe0b9b5eba6cfcb54d3bb129ed4f3aed946",
"url": "https://fuchsia.googlesource.com/integration/+/366edfe0b9b5eba6cfcb54d3bb129ed4f3aed946"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d70fefa2b654644b7bba4a2d937f315410d48c42"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d70fefa2b654644b7bba4a2d937f315410d48c42",
"title": "d70fefa2b654644b7bba4a2d937f315410d48c42",
"url": "https://fuchsia.googlesource.com/integration/+/d70fefa2b654644b7bba4a2d937f315410d48c42"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a27fad15ea075483de3db12650c412e10f1a159f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a27fad15ea075483de3db12650c412e10f1a159f",
"title": "a27fad15ea075483de3db12650c412e10f1a159f",
"url": "https://fuchsia.googlesource.com/integration/+/a27fad15ea075483de3db12650c412e10f1a159f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "572ed98bee72d888bfb347794fb8fbdef87e3bc4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@572ed98bee72d888bfb347794fb8fbdef87e3bc4",
"title": "572ed98bee72d888bfb347794fb8fbdef87e3bc4",
"url": "https://fuchsia.googlesource.com/integration/+/572ed98bee72d888bfb347794fb8fbdef87e3bc4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "85376fde9dc7148516cc0a212a7968de8fc213db"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@85376fde9dc7148516cc0a212a7968de8fc213db",
"title": "85376fde9dc7148516cc0a212a7968de8fc213db",
"url": "https://fuchsia.googlesource.com/integration/+/85376fde9dc7148516cc0a212a7968de8fc213db"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0700a8ff1166960d9ba917ba6b00b4f4c9bbf9ed"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0700a8ff1166960d9ba917ba6b00b4f4c9bbf9ed",
"title": "0700a8ff1166960d9ba917ba6b00b4f4c9bbf9ed",
"url": "https://fuchsia.googlesource.com/integration/+/0700a8ff1166960d9ba917ba6b00b4f4c9bbf9ed"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2d9c70ddf513000a302ae95697cd948cf7e36aa5"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2d9c70ddf513000a302ae95697cd948cf7e36aa5",
"title": "2d9c70ddf513000a302ae95697cd948cf7e36aa5",
"url": "https://fuchsia.googlesource.com/integration/+/2d9c70ddf513000a302ae95697cd948cf7e36aa5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b2618ab0a5708cab7f4cf811d2f41a702de40138"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b2618ab0a5708cab7f4cf811d2f41a702de40138",
"title": "b2618ab0a5708cab7f4cf811d2f41a702de40138",
"url": "https://fuchsia.googlesource.com/integration/+/b2618ab0a5708cab7f4cf811d2f41a702de40138"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8c856b45efb145297d434c42a9b889f5d2ae2af1"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8c856b45efb145297d434c42a9b889f5d2ae2af1",
"title": "8c856b45efb145297d434c42a9b889f5d2ae2af1",
"url": "https://fuchsia.googlesource.com/integration/+/8c856b45efb145297d434c42a9b889f5d2ae2af1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "cfa3e3bf1dafa8fa7ab8f5dc58d0d02c44923176"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@cfa3e3bf1dafa8fa7ab8f5dc58d0d02c44923176",
"title": "cfa3e3bf1dafa8fa7ab8f5dc58d0d02c44923176",
"url": "https://fuchsia.googlesource.com/integration/+/cfa3e3bf1dafa8fa7ab8f5dc58d0d02c44923176"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "cfa3e3bf1dafa8fa7ab8f5dc58d0d02c44923176",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8936829434508079984"
},
{
"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"
}
}
[19:52:02.617] Scheduled build:
{
"id": "8771399372028126673",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-08-29T19:52:02.162951291Z",
"updateTime": "2023-08-29T19:52:02.162951291Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "cfa3e3bf1dafa8fa7ab8f5dc58d0d02c44923176",
"ref": "refs/heads/main"
}
}
}
[19:52:02.618] Task URL: https://cr-buildbucket.appspot.com/build/8771399372028126673
[19:52:02.618] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936829434508079984:2:0) after 7m43s
[19:52:03.494] Received PubSub notification, asking Buildbucket for the build status
[19:52:03.514] Build status: SCHEDULED
[19:52:35.992] Received PubSub notification, asking Buildbucket for the build status
[19:52:36.045] Build status: STARTED
[19:59:45.625] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936829434508079984:2:0)
[19:59:45.645] Build status: STARTED
[19:59:45.645] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936829434508079984:5:0) after 3m43s
[20:03:29.165] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936829434508079984:5:0)
[20:03:29.210] Build status: STARTED
[20:03:29.210] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936829434508079984:6:0) after 2m42s
[20:06:11.309] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936829434508079984:6:0)
[20:06:11.378] Build status: STARTED
[20:06:11.378] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936829434508079984:7:0) after 2m37s
[20:08:48.498] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936829434508079984:7:0)
[20:08:48.513] Build status: STARTED
[20:08:48.513] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936829434508079984:8:0) after 7m8s
[20:15:56.530] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936829434508079984:8:0)
[20:15:56.550] Build status: STARTED
[20:15:56.550] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936829434508079984:9:0) after 8m41s
[20:24:37.587] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936829434508079984:9:0)
[20:24:37.605] Build status: STARTED
[20:24:37.605] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936829434508079984:10:0) after 5m38s
[20:30:15.635] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936829434508079984:10:0)
[20:30:15.679] Build status: STARTED
[20:30:15.679] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936829434508079984:11:0) after 7m3s
[20:37:18.719] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936829434508079984:11:0)
[20:37:18.742] Build status: STARTED
[20:37:18.742] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936829434508079984:12:0) after 3m49s
[20:41:07.857] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936829434508079984:12:0)
[20:41:07.876] Build status: STARTED
[20:41:07.876] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936829434508079984:13:0) after 3m35s
[20:44:42.906] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936829434508079984:13:0)
[20:44:42.930] Build status: STARTED
[20:44:42.930] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936829434508079984:14:0) after 4m20s
[20:49:02.950] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936829434508079984:14:0)
[20:49:02.972] Build status: STARTED
[20:49:02.972] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936829434508079984:15:0) after 4m9s
[20:53:12.169] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936829434508079984:15:0)
[20:53:12.191] Build status: STARTED
[20:53:12.191] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936829434508079984:16:0) after 3m46s
[20:56:58.266] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936829434508079984:16:0)
[20:56:58.294] Build status: STARTED
[20:56:58.295] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936829434508079984:17:0) after 5m49s
[21:02:47.317] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936829434508079984:17:0)
[21:02:47.339] Build status: STARTED
[21:02:47.339] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936829434508079984:18:0) after 9m45s
[21:08:31.383] Received PubSub notification, asking Buildbucket for the build status
[21:08:31.405] Build:
{
"id": "8771399372028126673",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-08-29T19:52:02.162951291Z",
"startTime": "2023-08-29T19:52:33.987613Z",
"endTime": "2023-08-29T21:08:30.897930348Z",
"updateTime": "2023-08-29T21:08:30.897930348Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "cfa3e3bf1dafa8fa7ab8f5dc58d0d02c44923176",
"ref": "refs/heads/main"
}
}
}
[21:08:31.405] Invocation finished in 1h16m30.990791734s with status SUCCEEDED