[21:29:34.719] New invocation is queued and will start shortly
[21:29:38.552] Starting the invocation (attempt 1)
[21:29:38.579] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/a0064ba93c358049afb366e2e03071487ce8e519"
[21:29:38.579] Popped gitiles commit info from properties and tags
[21:29:38.579] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:29:38.579] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:29:38.580] Buildbucket request:
{
"requestId": "8932202850547365920",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8932202850547365920",
"job": "fuchsia/core.x64-debug-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6386ce20d8062806ae03ae0f234e20cd1050d191"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6386ce20d8062806ae03ae0f234e20cd1050d191",
"title": "6386ce20d8062806ae03ae0f234e20cd1050d191",
"url": "https://fuchsia.googlesource.com/integration/+/6386ce20d8062806ae03ae0f234e20cd1050d191"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2cab8867bf1e4e73ef22e05bec70e53659279096"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2cab8867bf1e4e73ef22e05bec70e53659279096",
"title": "2cab8867bf1e4e73ef22e05bec70e53659279096",
"url": "https://fuchsia.googlesource.com/integration/+/2cab8867bf1e4e73ef22e05bec70e53659279096"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "09939407693152ecea513a9ccb14bc2be518a0d4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@09939407693152ecea513a9ccb14bc2be518a0d4",
"title": "09939407693152ecea513a9ccb14bc2be518a0d4",
"url": "https://fuchsia.googlesource.com/integration/+/09939407693152ecea513a9ccb14bc2be518a0d4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "36a8d3726856ca160161c735c3940afca24e1cf8"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@36a8d3726856ca160161c735c3940afca24e1cf8",
"title": "36a8d3726856ca160161c735c3940afca24e1cf8",
"url": "https://fuchsia.googlesource.com/integration/+/36a8d3726856ca160161c735c3940afca24e1cf8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4b2a689cbe422f6603cd91f391a47b29975d69ac"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4b2a689cbe422f6603cd91f391a47b29975d69ac",
"title": "4b2a689cbe422f6603cd91f391a47b29975d69ac",
"url": "https://fuchsia.googlesource.com/integration/+/4b2a689cbe422f6603cd91f391a47b29975d69ac"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "81968e9d041c83a5774bbd78ae3b48707d58e411"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@81968e9d041c83a5774bbd78ae3b48707d58e411",
"title": "81968e9d041c83a5774bbd78ae3b48707d58e411",
"url": "https://fuchsia.googlesource.com/integration/+/81968e9d041c83a5774bbd78ae3b48707d58e411"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "22e5ec5a7205ffeab77da9db1d8ad43ec34a88f4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@22e5ec5a7205ffeab77da9db1d8ad43ec34a88f4",
"title": "22e5ec5a7205ffeab77da9db1d8ad43ec34a88f4",
"url": "https://fuchsia.googlesource.com/integration/+/22e5ec5a7205ffeab77da9db1d8ad43ec34a88f4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f0c623756d442f436e2b56b401722c299148659c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f0c623756d442f436e2b56b401722c299148659c",
"title": "f0c623756d442f436e2b56b401722c299148659c",
"url": "https://fuchsia.googlesource.com/integration/+/f0c623756d442f436e2b56b401722c299148659c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1d412df252f79921ba1bd61126a9eb8df1eaadd4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1d412df252f79921ba1bd61126a9eb8df1eaadd4",
"title": "1d412df252f79921ba1bd61126a9eb8df1eaadd4",
"url": "https://fuchsia.googlesource.com/integration/+/1d412df252f79921ba1bd61126a9eb8df1eaadd4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2e7949baddace71ee0803498b6d359d49e8ed494"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2e7949baddace71ee0803498b6d359d49e8ed494",
"title": "2e7949baddace71ee0803498b6d359d49e8ed494",
"url": "https://fuchsia.googlesource.com/integration/+/2e7949baddace71ee0803498b6d359d49e8ed494"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0f52f6b3c5da1903272c0057928ba7785f00985a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0f52f6b3c5da1903272c0057928ba7785f00985a",
"title": "0f52f6b3c5da1903272c0057928ba7785f00985a",
"url": "https://fuchsia.googlesource.com/integration/+/0f52f6b3c5da1903272c0057928ba7785f00985a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a0064ba93c358049afb366e2e03071487ce8e519"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a0064ba93c358049afb366e2e03071487ce8e519",
"title": "a0064ba93c358049afb366e2e03071487ce8e519",
"url": "https://fuchsia.googlesource.com/integration/+/a0064ba93c358049afb366e2e03071487ce8e519"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "a0064ba93c358049afb366e2e03071487ce8e519",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8932202850547365920"
},
{
"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"
}
}
[21:29:39.157] Scheduled build:
{
"id": "8766772785777905137",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-10-19T21:29:38.642142228Z",
"updateTime": "2023-10-19T21:29:38.642142228Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "a0064ba93c358049afb366e2e03071487ce8e519",
"ref": "refs/heads/main"
}
}
}
[21:29:39.157] Task URL: https://cr-buildbucket.appspot.com/build/8766772785777905137
[21:29:39.157] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932202850547365920:2:0) after 9m20s
[21:29:39.495] Received PubSub notification, asking Buildbucket for the build status
[21:29:39.535] Build status: SCHEDULED
[21:30:00.701] Received PubSub notification, asking Buildbucket for the build status
[21:30:00.735] Build status: STARTED
[21:38:59.508] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932202850547365920:2:0)
[21:38:59.523] Build status: STARTED
[21:38:59.523] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932202850547365920:5:0) after 7m32s
[21:46:31.611] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932202850547365920:5:0)
[21:46:31.641] Build status: STARTED
[21:46:31.641] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932202850547365920:6:0) after 1m17s
[21:47:48.725] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932202850547365920:6:0)
[21:47:48.759] Build status: STARTED
[21:47:48.759] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932202850547365920:7:0) after 2m8s
[21:49:56.787] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932202850547365920:7:0)
[21:49:56.807] Build status: STARTED
[21:49:56.807] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932202850547365920:8:0) after 6m44s
[21:56:40.830] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932202850547365920:8:0)
[21:56:40.861] Build status: STARTED
[21:56:40.861] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932202850547365920:9:0) after 7m33s
[22:04:13.887] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932202850547365920:9:0)
[22:04:13.971] Build status: STARTED
[22:04:13.971] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932202850547365920:10:0) after 6m14s
[22:10:28.094] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932202850547365920:10:0)
[22:10:28.214] Build status: STARTED
[22:10:28.214] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932202850547365920:11:0) after 1m12s
[22:11:40.379] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932202850547365920:11:0)
[22:11:40.397] Build status: STARTED
[22:11:40.397] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932202850547365920:12:0) after 8m55s
[22:20:35.515] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932202850547365920:12:0)
[22:20:35.534] Build status: STARTED
[22:20:35.534] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932202850547365920:13:0) after 5m37s
[22:26:12.551] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932202850547365920:13:0)
[22:26:12.585] Build status: STARTED
[22:26:12.585] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932202850547365920:14:0) after 6m49s
[22:33:01.617] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932202850547365920:14:0)
[22:33:01.641] Build status: STARTED
[22:33:01.641] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932202850547365920:15:0) after 1m2s
[22:33:15.367] Received PubSub notification, asking Buildbucket for the build status
[22:33:15.387] Build:
{
"id": "8766772785777905137",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-10-19T21:29:38.642142228Z",
"startTime": "2023-10-19T21:30:00.055495Z",
"endTime": "2023-10-19T22:33:12.451309827Z",
"updateTime": "2023-10-19T22:33:12.451309827Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "a0064ba93c358049afb366e2e03071487ce8e519",
"ref": "refs/heads/main"
}
}
}
[22:33:15.387] Invocation finished in 1h3m40.676590516s with status SUCCEEDED