[18:33:04.741] New invocation is queued and will start shortly
[18:33:05.849] Starting the invocation (attempt 1)
[18:33:05.878] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/0066d69bd8500ddfdf331f8cb68982cd4e04e4e2"
[18:33:05.878] Popped gitiles commit info from properties and tags
[18:33:05.878] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:33:05.878] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:33:05.878] Buildbucket request:
{
"requestId": "8926687539996779552",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8926687539996779552",
"job": "fuchsia/core.x64-debug-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f96e03624fffbd4ccb43bed6ada998f15d8b5fd3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f96e03624fffbd4ccb43bed6ada998f15d8b5fd3",
"title": "f96e03624fffbd4ccb43bed6ada998f15d8b5fd3",
"url": "https://fuchsia.googlesource.com/integration/+/f96e03624fffbd4ccb43bed6ada998f15d8b5fd3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3e928b9cfbb49202e0c21d8fb0a23a65e24164ba"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3e928b9cfbb49202e0c21d8fb0a23a65e24164ba",
"title": "3e928b9cfbb49202e0c21d8fb0a23a65e24164ba",
"url": "https://fuchsia.googlesource.com/integration/+/3e928b9cfbb49202e0c21d8fb0a23a65e24164ba"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "08527a6a32b6b082542f79246f0d50be7a2d1039"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@08527a6a32b6b082542f79246f0d50be7a2d1039",
"title": "08527a6a32b6b082542f79246f0d50be7a2d1039",
"url": "https://fuchsia.googlesource.com/integration/+/08527a6a32b6b082542f79246f0d50be7a2d1039"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "bdbfc9e768ece38f848bf7d2be16c4e68d55f198"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@bdbfc9e768ece38f848bf7d2be16c4e68d55f198",
"title": "bdbfc9e768ece38f848bf7d2be16c4e68d55f198",
"url": "https://fuchsia.googlesource.com/integration/+/bdbfc9e768ece38f848bf7d2be16c4e68d55f198"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e885d759e237a0af3786e6b7f2047d12b2878203"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e885d759e237a0af3786e6b7f2047d12b2878203",
"title": "e885d759e237a0af3786e6b7f2047d12b2878203",
"url": "https://fuchsia.googlesource.com/integration/+/e885d759e237a0af3786e6b7f2047d12b2878203"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "90c5142993530732d789d8086c683980197867e5"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@90c5142993530732d789d8086c683980197867e5",
"title": "90c5142993530732d789d8086c683980197867e5",
"url": "https://fuchsia.googlesource.com/integration/+/90c5142993530732d789d8086c683980197867e5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "15462edcd4ce858f4388da4aeefa32b72effab2f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@15462edcd4ce858f4388da4aeefa32b72effab2f",
"title": "15462edcd4ce858f4388da4aeefa32b72effab2f",
"url": "https://fuchsia.googlesource.com/integration/+/15462edcd4ce858f4388da4aeefa32b72effab2f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b23e5faa629adc1ac84e2f056a0837e92db23958"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b23e5faa629adc1ac84e2f056a0837e92db23958",
"title": "b23e5faa629adc1ac84e2f056a0837e92db23958",
"url": "https://fuchsia.googlesource.com/integration/+/b23e5faa629adc1ac84e2f056a0837e92db23958"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "13bf4f2a3cd0f5535af27dd3ef388b3811fcb514"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@13bf4f2a3cd0f5535af27dd3ef388b3811fcb514",
"title": "13bf4f2a3cd0f5535af27dd3ef388b3811fcb514",
"url": "https://fuchsia.googlesource.com/integration/+/13bf4f2a3cd0f5535af27dd3ef388b3811fcb514"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "605121789868cd87a26d272dc0c5b278873ba83d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@605121789868cd87a26d272dc0c5b278873ba83d",
"title": "605121789868cd87a26d272dc0c5b278873ba83d",
"url": "https://fuchsia.googlesource.com/integration/+/605121789868cd87a26d272dc0c5b278873ba83d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a6db42127d250f77009f0c3e88557729ebe23b7a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a6db42127d250f77009f0c3e88557729ebe23b7a",
"title": "a6db42127d250f77009f0c3e88557729ebe23b7a",
"url": "https://fuchsia.googlesource.com/integration/+/a6db42127d250f77009f0c3e88557729ebe23b7a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "038e1a468f55ddac2f123094bb7cfa79de5cb58e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@038e1a468f55ddac2f123094bb7cfa79de5cb58e",
"title": "038e1a468f55ddac2f123094bb7cfa79de5cb58e",
"url": "https://fuchsia.googlesource.com/integration/+/038e1a468f55ddac2f123094bb7cfa79de5cb58e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0066d69bd8500ddfdf331f8cb68982cd4e04e4e2"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0066d69bd8500ddfdf331f8cb68982cd4e04e4e2",
"title": "0066d69bd8500ddfdf331f8cb68982cd4e04e4e2",
"url": "https://fuchsia.googlesource.com/integration/+/0066d69bd8500ddfdf331f8cb68982cd4e04e4e2"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "0066d69bd8500ddfdf331f8cb68982cd4e04e4e2",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8926687539996779552"
},
{
"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"
}
}
[18:33:06.318] Scheduled build:
{
"id": "8761257478093976625",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-12-19T18:33:05.927509101Z",
"updateTime": "2023-12-19T18:33:05.927509101Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "0066d69bd8500ddfdf331f8cb68982cd4e04e4e2",
"ref": "refs/heads/main"
}
}
}
[18:33:06.318] Task URL: https://cr-buildbucket.appspot.com/build/8761257478093976625
[18:33:06.318] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926687539996779552:2:0) after 3m47s
[18:33:07.335] Received PubSub notification, asking Buildbucket for the build status
[18:33:07.353] Build status: SCHEDULED
[18:33:37.112] Received PubSub notification, asking Buildbucket for the build status
[18:33:37.135] Build status: STARTED
[18:36:53.353] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926687539996779552:2:0)
[18:36:53.375] Build status: STARTED
[18:36:53.375] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926687539996779552:5:0) after 8m19s
[18:45:12.406] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926687539996779552:5:0)
[18:45:12.458] Build status: STARTED
[18:45:12.458] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926687539996779552:6:0) after 6m52s
[18:52:04.476] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926687539996779552:6:0)
[18:52:05.382] Build status: STARTED
[18:52:05.382] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926687539996779552:7:0) after 2m39s
[18:54:44.422] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926687539996779552:7:0)
[18:54:44.454] Build status: STARTED
[18:54:44.454] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926687539996779552:8:0) after 3m56s
[18:58:40.512] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926687539996779552:8:0)
[18:58:40.597] Build status: STARTED
[18:58:40.597] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926687539996779552:9:0) after 1m50s
[19:00:30.615] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926687539996779552:9:0)
[19:00:30.653] Build status: STARTED
[19:00:30.653] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926687539996779552:10:0) after 1m7s
[19:01:37.670] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926687539996779552:10:0)
[19:01:37.707] Build status: STARTED
[19:01:37.708] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926687539996779552:11:0) after 9m5s
[19:10:42.733] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926687539996779552:11:0)
[19:10:42.776] Build status: STARTED
[19:10:42.776] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926687539996779552:12:0) after 4m30s
[19:15:12.872] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926687539996779552:12:0)
[19:15:12.898] Build status: STARTED
[19:15:12.898] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926687539996779552:13:0) after 6m43s
[19:21:55.913] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926687539996779552:13:0)
[19:21:56.028] Build status: STARTED
[19:21:56.029] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926687539996779552:14:0) after 1m53s
[19:23:49.066] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926687539996779552:14:0)
[19:23:49.083] Build status: STARTED
[19:23:49.083] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926687539996779552:15:0) after 1m43s
[19:25:32.098] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926687539996779552:15:0)
[19:25:32.115] Build status: STARTED
[19:25:32.115] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926687539996779552:16:0) after 3m45s
[19:29:17.136] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926687539996779552:16:0)
[19:29:17.235] Build status: STARTED
[19:29:17.235] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926687539996779552:17:0) after 5m14s
[19:34:31.251] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926687539996779552:17:0)
[19:34:31.280] Build status: STARTED
[19:34:31.280] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926687539996779552:18:0) after 7m45s
[19:41:18.692] Received PubSub notification, asking Buildbucket for the build status
[19:41:18.725] Build:
{
"id": "8761257478093976625",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-12-19T18:33:05.927509101Z",
"startTime": "2023-12-19T18:33:36.282452Z",
"endTime": "2023-12-19T19:41:16.775171Z",
"updateTime": "2023-12-19T19:41:16.775171Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "0066d69bd8500ddfdf331f8cb68982cd4e04e4e2",
"ref": "refs/heads/main"
}
}
}
[19:41:18.725] Invocation finished in 1h8m13.995618481s with status SUCCEEDED