[14:40:50.466] New invocation is queued and will start shortly
[14:40:50.466] Triggered by user:webrtc-ci-builder@chops-service-accounts.iam.gserviceaccount.com
[14:40:51.690] Starting the invocation (attempt 1)
[14:40:51.729] Popped buildset tag "buildset:commit/gitiles/webrtc.googlesource.com/src/+/dc8f81652bb9a99dda1afee2e2a70fc78c67e2ee"
[14:40:51.729] Popped gitiles commit info from properties and tags
[14:40:51.729] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:40:51.730] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:40:51.731] Buildbucket request:
{
"requestId": "8932862744852337504",
"builder": {
"project": "webrtc",
"bucket": "perf",
"builder": "Perf Mac M1 Arm64 12"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8932862744852337504",
"job": "webrtc/Perf Mac M1 Arm64 12",
"triggers": [
{
"gitiles": {
"properties": {
"deps_revision_overrides": {
"src": "af74dff19ee8fffd4b4dd1c807518eebb401db39"
},
"parent_build_id": "8767449757795543489",
"parent_builder_group": "client.webrtc",
"parent_buildername": "MacArm64 Builder",
"parent_got_revision": "af74dff19ee8fffd4b4dd1c807518eebb401db39",
"parent_got_revision_cp": "refs/heads/main@{#40917}",
"revision": "af74dff19ee8fffd4b4dd1c807518eebb401db39",
"swarm_hashes": {
"video_codec_perf_tests": "57d1d8929719685b0de3c51a2d3dfa2a10d532b876eb36fb62a88ba1eb16ccfa/405",
"webrtc_perf_tests": "81004139117e6e0082789b18776cb42e82e0a62d6a4dd47aa839bd497fc9af57/406"
},
"swarming_command_lines": {
"video_codec_perf_tests": [
"bin/run_video_codec_perf_tests"
],
"webrtc_perf_tests": [
"bin/run_webrtc_perf_tests"
]
}
},
"ref": "refs/heads/main",
"repo": "https://webrtc.googlesource.com/src",
"revision": "af74dff19ee8fffd4b4dd1c807518eebb401db39",
"tags": [
"parent_buildername:MacArm64 Builder",
"parent_buildnumber:3910",
"user_agent:recipe"
]
},
"id": "f2672119-74ba-4efb-a338-dffd70b43e8f",
"title": "MacArm64 Builder/3910"
},
{
"gitiles": {
"properties": {
"deps_revision_overrides": {
"src": "dc8f81652bb9a99dda1afee2e2a70fc78c67e2ee"
},
"parent_build_id": "8767440303533548417",
"parent_builder_group": "client.webrtc",
"parent_buildername": "MacArm64 Builder",
"parent_got_revision": "dc8f81652bb9a99dda1afee2e2a70fc78c67e2ee",
"parent_got_revision_cp": "refs/heads/main@{#40918}",
"revision": "dc8f81652bb9a99dda1afee2e2a70fc78c67e2ee",
"swarm_hashes": {
"video_codec_perf_tests": "57d1d8929719685b0de3c51a2d3dfa2a10d532b876eb36fb62a88ba1eb16ccfa/405",
"webrtc_perf_tests": "81004139117e6e0082789b18776cb42e82e0a62d6a4dd47aa839bd497fc9af57/406"
},
"swarming_command_lines": {
"video_codec_perf_tests": [
"bin/run_video_codec_perf_tests"
],
"webrtc_perf_tests": [
"bin/run_webrtc_perf_tests"
]
}
},
"ref": "refs/heads/main",
"repo": "https://webrtc.googlesource.com/src",
"revision": "dc8f81652bb9a99dda1afee2e2a70fc78c67e2ee",
"tags": [
"parent_buildername:MacArm64 Builder",
"parent_buildnumber:3911",
"user_agent:recipe"
]
},
"id": "863a840b-d29b-48ed-910f-4d7f5cbace84",
"title": "MacArm64 Builder/3911"
}
]
},
"deps_revision_overrides": {
"src": "dc8f81652bb9a99dda1afee2e2a70fc78c67e2ee"
},
"parent_build_id": "8767440303533548417",
"parent_builder_group": "client.webrtc",
"parent_buildername": "MacArm64 Builder",
"parent_got_revision": "dc8f81652bb9a99dda1afee2e2a70fc78c67e2ee",
"parent_got_revision_cp": "refs/heads/main@{#40918}",
"swarm_hashes": {
"video_codec_perf_tests": "57d1d8929719685b0de3c51a2d3dfa2a10d532b876eb36fb62a88ba1eb16ccfa/405",
"webrtc_perf_tests": "81004139117e6e0082789b18776cb42e82e0a62d6a4dd47aa839bd497fc9af57/406"
},
"swarming_command_lines": {
"video_codec_perf_tests": [
"bin/run_video_codec_perf_tests"
],
"webrtc_perf_tests": [
"bin/run_webrtc_perf_tests"
]
}
},
"gitilesCommit": {
"host": "webrtc.googlesource.com",
"project": "src",
"id": "dc8f81652bb9a99dda1afee2e2a70fc78c67e2ee",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8932862744852337504"
},
{
"key": "scheduler_job_id",
"value": "webrtc/Perf Mac M1 Arm64 12"
},
{
"key": "user_agent",
"value": "luci-scheduler"
},
{
"key": "parent_buildername",
"value": "MacArm64 Builder"
},
{
"key": "parent_buildnumber",
"value": "3911"
},
{
"key": "user_agent",
"value": "recipe"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[14:40:52.475] Scheduled build:
{
"id": "8767432682784235057",
"builder": {
"project": "webrtc",
"bucket": "perf",
"builder": "Perf Mac M1 Arm64 12"
},
"number": 3214,
"createdBy": "project:webrtc",
"createTime": "2023-10-12T14:40:51.815543959Z",
"updateTime": "2023-10-12T14:40:51.815543959Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "webrtc.googlesource.com",
"project": "src",
"id": "dc8f81652bb9a99dda1afee2e2a70fc78c67e2ee",
"ref": "refs/heads/main"
}
}
}
[14:40:52.475] Task URL: https://cr-buildbucket.appspot.com/build/8767432682784235057
[14:40:52.475] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:2:0) after 6m0s
[14:40:53.440] Received PubSub notification, asking Buildbucket for the build status
[14:40:53.467] Build status: SCHEDULED
[14:46:53.081] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:2:0)
[14:46:53.100] Build status: SCHEDULED
[14:46:53.100] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:4:0) after 6m58s
[14:53:51.114] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:4:0)
[14:53:51.131] Build status: SCHEDULED
[14:53:51.131] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:5:0) after 7m47s
[15:01:38.105] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:5:0)
[15:01:38.134] Build status: SCHEDULED
[15:01:38.134] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:6:0) after 8m19s
[15:09:57.161] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:6:0)
[15:09:57.186] Build status: SCHEDULED
[15:09:57.186] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:7:0) after 7m15s
[15:17:12.256] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:7:0)
[15:17:12.309] Build status: SCHEDULED
[15:17:12.310] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:8:0) after 2m59s
[15:20:11.337] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:8:0)
[15:20:11.359] Build status: SCHEDULED
[15:20:11.359] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:9:0) after 1m32s
[15:21:43.484] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:9:0)
[15:21:43.502] Build status: SCHEDULED
[15:21:43.502] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:10:0) after 1m31s
[15:23:14.520] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:10:0)
[15:23:14.542] Build status: SCHEDULED
[15:23:14.542] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:11:0) after 5m22s
[15:28:37.059] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:11:0)
[15:28:37.083] Build status: SCHEDULED
[15:28:37.083] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:12:0) after 8m11s
[15:36:48.230] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:12:0)
[15:36:48.256] Build status: SCHEDULED
[15:36:48.256] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:13:0) after 4m32s
[15:41:20.278] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:13:0)
[15:41:20.296] Build status: SCHEDULED
[15:41:20.296] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:14:0) after 2m45s
[15:44:05.318] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:14:0)
[15:44:05.345] Build status: SCHEDULED
[15:44:05.345] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:15:0) after 5m29s
[15:49:34.358] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:15:0)
[15:49:34.453] Build status: SCHEDULED
[15:49:34.453] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:16:0) after 8m45s
[15:58:19.536] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:16:0)
[15:58:19.558] Build status: SCHEDULED
[15:58:19.558] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:17:0) after 4m56s
[16:03:15.613] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:17:0)
[16:03:15.630] Build status: SCHEDULED
[16:03:15.630] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:18:0) after 6m8s
[16:09:23.616] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:18:0)
[16:09:23.661] Build status: SCHEDULED
[16:09:23.662] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:19:0) after 7m54s
[16:17:17.750] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:19:0)
[16:17:17.799] Build status: SCHEDULED
[16:17:17.799] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:20:0) after 2m39s
[16:18:38.218] Received PubSub notification, asking Buildbucket for the build status
[16:18:38.331] Build status: STARTED
[16:19:56.824] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:20:0)
[16:19:56.845] Build status: STARTED
[16:19:56.845] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:22:0) after 5m37s
[16:25:33.894] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:22:0)
[16:25:33.921] Build status: STARTED
[16:25:33.921] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:23:0) after 7m6s
[16:32:40.845] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:23:0)
[16:32:40.867] Build status: STARTED
[16:32:40.867] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:24:0) after 1m6s
[16:33:46.884] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:24:0)
[16:33:46.902] Build status: STARTED
[16:33:46.902] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:25:0) after 6m51s
[16:40:37.920] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:25:0)
[16:40:37.962] Build status: STARTED
[16:40:37.962] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:26:0) after 3m31s
[16:44:08.998] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:26:0)
[16:44:09.027] Build status: STARTED
[16:44:09.027] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:27:0) after 3m42s
[16:47:51.191] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:27:0)
[16:47:51.208] Build status: STARTED
[16:47:51.208] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:28:0) after 5m57s
[16:53:48.252] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:28:0)
[16:53:48.284] Build status: STARTED
[16:53:48.284] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:29:0) after 5m5s
[16:58:53.369] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:29:0)
[16:58:53.420] Build status: STARTED
[16:58:53.420] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:30:0) after 2m50s
[17:01:43.632] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:30:0)
[17:01:43.674] Build status: STARTED
[17:01:43.674] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:31:0) after 3m2s
[17:04:45.908] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:31:0)
[17:04:45.927] Build status: STARTED
[17:04:45.927] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:32:0) after 4m14s
[17:08:59.956] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:32:0)
[17:08:59.978] Build status: STARTED
[17:08:59.978] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:33:0) after 6m52s
[17:15:51.979] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:33:0)
[17:15:52.000] Build status: STARTED
[17:15:52.000] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:34:0) after 9m18s
[17:25:10.034] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:34:0)
[17:25:10.075] Build status: STARTED
[17:25:10.075] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:35:0) after 4m45s
[17:29:55.092] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:35:0)
[17:29:55.119] Build status: STARTED
[17:29:55.119] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:36:0) after 1m47s
[17:31:42.680] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:36:0)
[17:31:42.698] Build status: STARTED
[17:31:42.698] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:37:0) after 5m32s
[17:37:14.722] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:37:0)
[17:37:14.843] Build status: STARTED
[17:37:14.843] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:38:0) after 9m21s
[17:46:35.866] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:38:0)
[17:46:35.947] Build status: STARTED
[17:46:35.947] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:39:0) after 3m17s
[17:49:52.968] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:39:0)
[17:49:52.990] Build status: STARTED
[17:49:52.990] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:40:0) after 9m56s
[17:59:49.147] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:40:0)
[17:59:49.172] Build status: STARTED
[17:59:49.172] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:41:0) after 5m43s
[18:05:32.210] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:41:0)
[18:05:32.260] Build status: STARTED
[18:05:32.260] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:42:0) after 3m29s
[18:09:01.298] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:42:0)
[18:09:01.319] Build status: STARTED
[18:09:01.319] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:43:0) after 8m46s
[18:17:47.476] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:43:0)
[18:17:47.500] Build status: STARTED
[18:17:47.500] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:44:0) after 2m17s
[18:20:04.526] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:44:0)
[18:20:04.549] Build status: STARTED
[18:20:04.549] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:45:0) after 1m38s
[18:21:42.695] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:45:0)
[18:21:42.736] Build status: STARTED
[18:21:42.737] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:46:0) after 4m15s
[18:25:58.164] Handling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:46:0)
[18:25:58.199] Build status: STARTED
[18:25:58.199] Scheduling timer "check-buildbucket-build-status" (webrtc/Perf Mac M1 Arm64 12:8932862744852337504:47:0) after 4m25s
[18:26:37.098] Received PubSub notification, asking Buildbucket for the build status
[18:26:37.165] Build:
{
"id": "8767432682784235057",
"builder": {
"project": "webrtc",
"bucket": "perf",
"builder": "Perf Mac M1 Arm64 12"
},
"number": 3214,
"createdBy": "project:webrtc",
"createTime": "2023-10-12T14:40:51.815543959Z",
"startTime": "2023-10-12T16:18:36.833633Z",
"endTime": "2023-10-12T18:26:36.800773158Z",
"updateTime": "2023-10-12T18:26:36.800773158Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "webrtc.googlesource.com",
"project": "src",
"id": "dc8f81652bb9a99dda1afee2e2a70fc78c67e2ee",
"ref": "refs/heads/main"
}
}
}
[18:26:37.165] Invocation finished in 3h45m46.704279072s with status SUCCEEDED