[17:38:46.465] New invocation is queued and will start shortly
[17:38:47.532] Starting the invocation (attempt 1)
[17:38:47.557] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/6b1ada2f77a03a650201afee2cd378a735e3ed38"
[17:38:47.557] Popped gitiles commit info from properties and tags
[17:38:47.557] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:38:47.557] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:38:47.557] Buildbucket request:
{
"requestId": "8938559159139778864",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8938559159139778864",
"job": "fuchsia/core.x64-debug-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "84dfdced736becb488df75ddebfdd28e235b03e4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@84dfdced736becb488df75ddebfdd28e235b03e4",
"title": "84dfdced736becb488df75ddebfdd28e235b03e4",
"url": "https://fuchsia.googlesource.com/integration/+/84dfdced736becb488df75ddebfdd28e235b03e4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "23da4ddb55430623b0497c48a0a8211d58c05e1e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@23da4ddb55430623b0497c48a0a8211d58c05e1e",
"title": "23da4ddb55430623b0497c48a0a8211d58c05e1e",
"url": "https://fuchsia.googlesource.com/integration/+/23da4ddb55430623b0497c48a0a8211d58c05e1e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8cc164caeb4e470f4cd79616d1b8c103f9e312fa"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8cc164caeb4e470f4cd79616d1b8c103f9e312fa",
"title": "8cc164caeb4e470f4cd79616d1b8c103f9e312fa",
"url": "https://fuchsia.googlesource.com/integration/+/8cc164caeb4e470f4cd79616d1b8c103f9e312fa"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "56dbd90452549e8fd2700e4047334671829b3b93"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@56dbd90452549e8fd2700e4047334671829b3b93",
"title": "56dbd90452549e8fd2700e4047334671829b3b93",
"url": "https://fuchsia.googlesource.com/integration/+/56dbd90452549e8fd2700e4047334671829b3b93"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6b1ada2f77a03a650201afee2cd378a735e3ed38"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6b1ada2f77a03a650201afee2cd378a735e3ed38",
"title": "6b1ada2f77a03a650201afee2cd378a735e3ed38",
"url": "https://fuchsia.googlesource.com/integration/+/6b1ada2f77a03a650201afee2cd378a735e3ed38"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "6b1ada2f77a03a650201afee2cd378a735e3ed38",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8938559159139778864"
},
{
"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"
}
}
[17:38:48.111] Scheduled build:
{
"id": "8773129097266160241",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-08-10T17:38:47.628460089Z",
"updateTime": "2023-08-10T17:38:47.628460089Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "6b1ada2f77a03a650201afee2cd378a735e3ed38",
"ref": "refs/heads/main"
}
}
}
[17:38:48.111] Task URL: https://cr-buildbucket.appspot.com/build/8773129097266160241
[17:38:48.111] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938559159139778864:2:0) after 8m1s
[17:38:49.015] Received PubSub notification, asking Buildbucket for the build status
[17:38:49.045] Build status: SCHEDULED
[17:39:40.549] Received PubSub notification, asking Buildbucket for the build status
[17:39:40.579] Build status: STARTED
[17:46:49.184] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938559159139778864:2:0)
[17:46:49.206] Build status: STARTED
[17:46:49.206] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938559159139778864:5:0) after 3m47s
[17:50:36.215] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938559159139778864:5:0)
[17:50:36.235] Build status: STARTED
[17:50:36.235] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938559159139778864:6:0) after 6m42s
[17:57:18.296] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938559159139778864:6:0)
[17:57:18.420] Build status: STARTED
[17:57:18.420] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938559159139778864:7:0) after 2m25s
[17:59:43.566] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938559159139778864:7:0)
[17:59:43.586] Build status: STARTED
[17:59:43.587] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938559159139778864:8:0) after 2m24s
[18:02:07.607] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938559159139778864:8:0)
[18:02:07.624] Build status: STARTED
[18:02:07.624] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938559159139778864:9:0) after 7m34s
[18:09:41.645] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938559159139778864:9:0)
[18:09:41.733] Build status: STARTED
[18:09:41.733] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938559159139778864:10:0) after 6m0s
[18:15:41.829] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938559159139778864:10:0)
[18:15:41.884] Build status: STARTED
[18:15:41.884] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938559159139778864:11:0) after 2m0s
[18:17:42.108] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938559159139778864:11:0)
[18:17:42.138] Build status: STARTED
[18:17:42.138] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938559159139778864:12:0) after 6m13s
[18:23:55.593] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938559159139778864:12:0)
[18:23:55.614] Build status: STARTED
[18:23:55.615] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938559159139778864:13:0) after 4m53s
[18:28:48.710] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938559159139778864:13:0)
[18:28:48.829] Build status: STARTED
[18:28:48.829] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938559159139778864:14:0) after 3m57s
[18:32:45.859] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938559159139778864:14:0)
[18:32:45.882] Build status: STARTED
[18:32:45.882] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938559159139778864:15:0) after 8m43s
[18:41:28.929] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938559159139778864:15:0)
[18:41:28.957] Build status: STARTED
[18:41:28.957] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938559159139778864:16:0) after 5m58s
[18:47:27.036] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938559159139778864:16:0)
[18:47:27.059] Build status: STARTED
[18:47:27.059] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938559159139778864:17:0) after 4m22s
[18:51:49.313] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938559159139778864:17:0)
[18:51:49.332] Build status: STARTED
[18:51:49.334] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938559159139778864:18:0) after 2m31s
[18:54:20.574] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938559159139778864:18:0)
[18:54:20.591] Build status: STARTED
[18:54:20.591] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938559159139778864:19:0) after 6m46s
[18:58:44.915] Received PubSub notification, asking Buildbucket for the build status
[18:58:44.938] Build:
{
"id": "8773129097266160241",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-08-10T17:38:47.628460089Z",
"startTime": "2023-08-10T17:39:39.751165Z",
"endTime": "2023-08-10T18:58:44.364831110Z",
"updateTime": "2023-08-10T18:58:44.364831110Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "6b1ada2f77a03a650201afee2cd378a735e3ed38",
"ref": "refs/heads/main"
}
}
}
[18:58:44.938] Invocation finished in 1h19m58.479495778s with status SUCCEEDED