[14:11:46.150] New invocation is queued and will start shortly
[14:11:46.150] Triggered by user:webrtc-ci-builder@chops-service-accounts.iam.gserviceaccount.com
[14:11:47.302] Starting the invocation (attempt 1)
[14:11:47.333] Popped buildset tag "buildset:commit/gitiles/webrtc.googlesource.com/src/+/31af34ba8ce17f60c2f4be643a84c7ed23b6f879"
[14:11:47.333] Popped gitiles commit info from properties and tags
[14:11:47.334] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:11:47.334] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:11:47.334] Buildbucket request:
{
"requestId": "8950168594489161776",
"builder": {
"project": "webrtc",
"bucket": "perf",
"builder": "Perf Mac 11"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8950168594489161776",
"job": "webrtc/Perf Mac 11",
"triggers": [
{
"gitiles": {
"properties": {
"deps_revision_overrides": {
"src": "527196508c9c6ea620281e18f202c02ac649f3ef"
},
"parent_builder_group": "client.webrtc",
"parent_buildername": "Mac64 Builder",
"parent_got_revision": "527196508c9c6ea620281e18f202c02ac649f3ef",
"parent_got_revision_cp": "refs/heads/main@{#39756}",
"revision": "527196508c9c6ea620281e18f202c02ac649f3ef",
"swarm_hashes": {
"low_bandwidth_audio_perf_test": "9a0ca3c671f2823b76c5c3852885f83408ace45f5debfce556a8911ef15d15ea/575",
"webrtc_perf_tests": "f3240cf251beda391ce0d3b6071f86ec40c33a88675e70cceb4e5c22d743e43f/406"
},
"swarming_command_lines": {
"low_bandwidth_audio_perf_test": [
"vpython3",
"../../audio/test/low_bandwidth_audio_test.py"
],
"webrtc_perf_tests": [
"bin/run_webrtc_perf_tests"
]
}
},
"ref": "refs/heads/main",
"repo": "https://webrtc.googlesource.com/src",
"revision": "527196508c9c6ea620281e18f202c02ac649f3ef",
"tags": [
"parent_buildername:Mac64 Builder",
"parent_buildnumber:3010",
"user_agent:recipe"
]
},
"id": "2a11302a-daa5-46e2-b23f-28725241722e",
"title": "Mac64 Builder/3010"
},
{
"gitiles": {
"properties": {
"deps_revision_overrides": {
"src": "31af34ba8ce17f60c2f4be643a84c7ed23b6f879"
},
"parent_builder_group": "client.webrtc",
"parent_buildername": "Mac64 Builder",
"parent_got_revision": "31af34ba8ce17f60c2f4be643a84c7ed23b6f879",
"parent_got_revision_cp": "refs/heads/main@{#39757}",
"revision": "31af34ba8ce17f60c2f4be643a84c7ed23b6f879",
"swarm_hashes": {
"low_bandwidth_audio_perf_test": "f31975ff6054e2f9c57160e6168cda5dc33d2350ad0263123cf324814597003c/575",
"webrtc_perf_tests": "7bba325425123596c9bf89130c3fb15f01269e348a57f16dc579c6fc2e7ddb35/406"
},
"swarming_command_lines": {
"low_bandwidth_audio_perf_test": [
"vpython3",
"../../audio/test/low_bandwidth_audio_test.py"
],
"webrtc_perf_tests": [
"bin/run_webrtc_perf_tests"
]
}
},
"ref": "refs/heads/main",
"repo": "https://webrtc.googlesource.com/src",
"revision": "31af34ba8ce17f60c2f4be643a84c7ed23b6f879",
"tags": [
"parent_buildername:Mac64 Builder",
"parent_buildnumber:3011",
"user_agent:recipe"
]
},
"id": "cbb5b824-fe10-4cdb-b043-8ab3bd867821",
"title": "Mac64 Builder/3011"
}
]
},
"deps_revision_overrides": {
"src": "31af34ba8ce17f60c2f4be643a84c7ed23b6f879"
},
"parent_builder_group": "client.webrtc",
"parent_buildername": "Mac64 Builder",
"parent_got_revision": "31af34ba8ce17f60c2f4be643a84c7ed23b6f879",
"parent_got_revision_cp": "refs/heads/main@{#39757}",
"swarm_hashes": {
"low_bandwidth_audio_perf_test": "f31975ff6054e2f9c57160e6168cda5dc33d2350ad0263123cf324814597003c/575",
"webrtc_perf_tests": "7bba325425123596c9bf89130c3fb15f01269e348a57f16dc579c6fc2e7ddb35/406"
},
"swarming_command_lines": {
"low_bandwidth_audio_perf_test": [
"vpython3",
"../../audio/test/low_bandwidth_audio_test.py"
],
"webrtc_perf_tests": [
"bin/run_webrtc_perf_tests"
]
}
},
"gitilesCommit": {
"host": "webrtc.googlesource.com",
"project": "src",
"id": "31af34ba8ce17f60c2f4be643a84c7ed23b6f879",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8950168594489161776"
},
{
"key": "scheduler_job_id",
"value": "webrtc/Perf Mac 11"
},
{
"key": "user_agent",
"value": "luci-scheduler"
},
{
"key": "parent_buildername",
"value": "Mac64 Builder"
},
{
"key": "parent_buildnumber",
"value": "3011"
},
{
"key": "user_agent",
"value": "recipe"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[14:11:47.819] Scheduled build:
{
"id": "8784738532495695841",
"builder": {
"project": "webrtc",
"bucket": "perf",
"builder": "Perf Mac 11"
},
"number": 2299,
"createdBy": "project:webrtc",
"createTime": "2023-04-04T14:11:47.422878213Z",
"updateTime": "2023-04-04T14:11:47.422878213Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "webrtc.googlesource.com",
"project": "src",
"id": "31af34ba8ce17f60c2f4be643a84c7ed23b6f879",
"ref": "refs/heads/main"
}
}
}
[14:11:47.819] Task URL: https://cr-buildbucket.appspot.com/build/8784738532495695841
[14:11:47.819] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:2:0) after 9m27s
[14:13:27.048] Received PubSub notification, asking Buildbucket for the build status
[14:13:27.103] Build status: STARTED
[14:21:14.832] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:2:0)
[14:21:14.889] Build status: STARTED
[14:21:14.889] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:4:0) after 2m51s
[14:24:05.912] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:4:0)
[14:24:05.951] Build status: STARTED
[14:24:05.951] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:5:0) after 9m35s
[14:33:41.223] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:5:0)
[14:33:41.251] Build status: STARTED
[14:33:41.251] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:6:0) after 2m35s
[14:36:16.353] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:6:0)
[14:36:16.374] Build status: STARTED
[14:36:16.374] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:7:0) after 8m33s
[14:44:49.391] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:7:0)
[14:44:49.419] Build status: STARTED
[14:44:49.419] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:8:0) after 6m39s
[14:51:28.548] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:8:0)
[14:51:28.587] Build status: STARTED
[14:51:28.587] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:9:0) after 7m21s
[14:58:49.835] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:9:0)
[14:58:49.865] Build status: STARTED
[14:58:49.865] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:10:0) after 8m5s
[15:06:54.899] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:10:0)
[15:06:54.925] Build status: STARTED
[15:06:54.925] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:11:0) after 3m30s
[15:10:25.119] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:11:0)
[15:10:25.188] Build status: STARTED
[15:10:25.188] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:12:0) after 4m28s
[15:14:53.516] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:12:0)
[15:14:53.536] Build status: STARTED
[15:14:53.536] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:13:0) after 1m11s
[15:16:04.546] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:13:0)
[15:16:04.584] Build status: STARTED
[15:16:04.584] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:14:0) after 2m38s
[15:18:42.610] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:14:0)
[15:18:42.632] Build status: STARTED
[15:18:42.632] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:15:0) after 2m1s
[15:20:43.649] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:15:0)
[15:20:43.671] Build status: STARTED
[15:20:43.671] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:16:0) after 8m35s
[15:29:18.669] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:16:0)
[15:29:18.715] Build status: STARTED
[15:29:18.715] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:17:0) after 1m49s
[15:31:07.761] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:17:0)
[15:31:07.811] Build status: STARTED
[15:31:07.811] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:18:0) after 3m50s
[15:34:58.031] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:18:0)
[15:34:58.052] Build status: STARTED
[15:34:58.052] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:19:0) after 1m9s
[15:36:07.075] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:19:0)
[15:36:07.101] Build status: STARTED
[15:36:07.101] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:20:0) after 7m27s
[15:43:34.357] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:20:0)
[15:43:34.385] Build status: STARTED
[15:43:34.385] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:21:0) after 1m34s
[15:45:08.409] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:21:0)
[15:45:08.430] Build status: STARTED
[15:45:08.430] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:22:0) after 6m5s
[15:51:13.405] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:22:0)
[15:51:13.423] Build status: STARTED
[15:51:13.423] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:23:0) after 7m30s
[15:58:43.459] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:23:0)
[15:58:43.483] Build status: STARTED
[15:58:43.483] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:24:0) after 2m18s
[16:01:01.550] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:24:0)
[16:01:01.579] Build status: STARTED
[16:01:01.579] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:25:0) after 1m56s
[16:02:57.714] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:25:0)
[16:02:57.735] Build status: STARTED
[16:02:57.735] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:26:0) after 6m55s
[16:09:52.892] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:26:0)
[16:09:52.915] Build status: STARTED
[16:09:52.915] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac 11:8950168594489161776:27:0) after 7m3s
[16:10:28.636] Received PubSub notification, asking Buildbucket for the build status
[16:10:28.666] Build:
{
"id": "8784738532495695841",
"builder": {
"project": "webrtc",
"bucket": "perf",
"builder": "Perf Mac 11"
},
"number": 2299,
"createdBy": "project:webrtc",
"createTime": "2023-04-04T14:11:47.422878213Z",
"startTime": "2023-04-04T14:13:22.325574Z",
"endTime": "2023-04-04T16:10:28.362097736Z",
"updateTime": "2023-04-04T16:10:28.362097736Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "webrtc.googlesource.com",
"project": "src",
"id": "31af34ba8ce17f60c2f4be643a84c7ed23b6f879",
"ref": "refs/heads/main"
}
}
}
[16:10:28.666] Invocation finished in 1h58m42.528624389s with status SUCCEEDED