[18:03:48.349] New invocation is queued and will start shortly
[18:03:49.418] Starting the invocation (attempt 1)
[18:03:49.460] Ignoring unrecognized buildset tag "buildset:commit/git/c303f82f4d1f770824f5704b5a7da6d231b3f7bd"
[18:03:49.460] Popped buildset tag "buildset:commit/gitiles/webrtc.googlesource.com/src/+/c303f82f4d1f770824f5704b5a7da6d231b3f7bd"
[18:03:49.460] Popped gitiles commit info from properties and tags
[18:03:49.460] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:03:49.460] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:03:49.461] Buildbucket request:
{
"requestId": "9017467542051842512",
"builder": {
"project": "webrtc",
"bucket": "ci",
"builder": "Mac Asan"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://webrtc.googlesource.com/src",
"revision": "47350c2dba9ec8699875c41d122e877486b6b1db"
},
"id": "https://webrtc.googlesource.com/src/+/refs/heads/master@47350c2dba9ec8699875c41d122e877486b6b1db",
"title": "47350c2dba9ec8699875c41d122e877486b6b1db",
"url": "https://webrtc.googlesource.com/src/+/47350c2dba9ec8699875c41d122e877486b6b1db"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://webrtc.googlesource.com/src",
"revision": "eb282985e9dd8b40417a6b8c03d2350104cf24bb"
},
"id": "https://webrtc.googlesource.com/src/+/refs/heads/master@eb282985e9dd8b40417a6b8c03d2350104cf24bb",
"title": "eb282985e9dd8b40417a6b8c03d2350104cf24bb",
"url": "https://webrtc.googlesource.com/src/+/eb282985e9dd8b40417a6b8c03d2350104cf24bb"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://webrtc.googlesource.com/src",
"revision": "ffb7603b6025fbd6e79f360d293ab49092bded54"
},
"id": "https://webrtc.googlesource.com/src/+/refs/heads/master@ffb7603b6025fbd6e79f360d293ab49092bded54",
"title": "ffb7603b6025fbd6e79f360d293ab49092bded54",
"url": "https://webrtc.googlesource.com/src/+/ffb7603b6025fbd6e79f360d293ab49092bded54"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://webrtc.googlesource.com/src",
"revision": "2ff25db72aa34a20308e82a271260b8719da0254"
},
"id": "https://webrtc.googlesource.com/src/+/refs/heads/master@2ff25db72aa34a20308e82a271260b8719da0254",
"title": "2ff25db72aa34a20308e82a271260b8719da0254",
"url": "https://webrtc.googlesource.com/src/+/2ff25db72aa34a20308e82a271260b8719da0254"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://webrtc.googlesource.com/src",
"revision": "bd9e4a95eb6b3d0a4e686f32b1fe5a0bf433fa02"
},
"id": "https://webrtc.googlesource.com/src/+/refs/heads/master@bd9e4a95eb6b3d0a4e686f32b1fe5a0bf433fa02",
"title": "bd9e4a95eb6b3d0a4e686f32b1fe5a0bf433fa02",
"url": "https://webrtc.googlesource.com/src/+/bd9e4a95eb6b3d0a4e686f32b1fe5a0bf433fa02"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://webrtc.googlesource.com/src",
"revision": "efad89cb58dd386d02c63166c65a70d905639183"
},
"id": "https://webrtc.googlesource.com/src/+/refs/heads/master@efad89cb58dd386d02c63166c65a70d905639183",
"title": "efad89cb58dd386d02c63166c65a70d905639183",
"url": "https://webrtc.googlesource.com/src/+/efad89cb58dd386d02c63166c65a70d905639183"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://webrtc.googlesource.com/src",
"revision": "464bcd450230789386906cbcefb50adfc43ac94b"
},
"id": "https://webrtc.googlesource.com/src/+/refs/heads/master@464bcd450230789386906cbcefb50adfc43ac94b",
"title": "464bcd450230789386906cbcefb50adfc43ac94b",
"url": "https://webrtc.googlesource.com/src/+/464bcd450230789386906cbcefb50adfc43ac94b"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://webrtc.googlesource.com/src",
"revision": "c303f82f4d1f770824f5704b5a7da6d231b3f7bd"
},
"id": "https://webrtc.googlesource.com/src/+/refs/heads/master@c303f82f4d1f770824f5704b5a7da6d231b3f7bd",
"title": "c303f82f4d1f770824f5704b5a7da6d231b3f7bd",
"url": "https://webrtc.googlesource.com/src/+/c303f82f4d1f770824f5704b5a7da6d231b3f7bd"
}
]
}
},
"gitilesCommit": {
"host": "webrtc.googlesource.com",
"project": "src",
"id": "c303f82f4d1f770824f5704b5a7da6d231b3f7bd",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9017467542051842512"
},
{
"key": "scheduler_job_id",
"value": "webrtc/Mac Asan"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[18:03:50.199] Scheduled build:
{
"id": "8852037480126295072",
"builder": {
"project": "webrtc",
"bucket": "ci",
"builder": "Mac Asan"
},
"number": 26146,
"createdBy": "project:webrtc",
"createTime": "2021-03-22T18:03:49.546047Z",
"updateTime": "2021-03-22T18:03:50.105606Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "webrtc.googlesource.com",
"project": "src",
"id": "c303f82f4d1f770824f5704b5a7da6d231b3f7bd",
"ref": "refs/heads/master"
}
}
}
[18:03:50.199] Task URL: https://cr-buildbucket.appspot.com/build/8852037480126295072
[18:03:50.199] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:2:0) after 9m27s
[18:13:17.284] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:2:0)
[18:13:17.385] Build status: SCHEDULED
[18:13:17.385] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:3:0) after 5m16s
[18:18:33.459] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:3:0)
[18:18:33.486] Build status: SCHEDULED
[18:18:33.486] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:4:0) after 5m9s
[18:23:42.593] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:4:0)
[18:23:42.621] Build status: SCHEDULED
[18:23:42.621] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:5:0) after 3m53s
[18:27:35.643] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:5:0)
[18:27:35.674] Build status: SCHEDULED
[18:27:35.674] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:6:0) after 6m7s
[18:33:43.131] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:6:0)
[18:33:43.163] Build status: SCHEDULED
[18:33:43.163] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:7:0) after 8m22s
[18:42:05.188] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:7:0)
[18:42:05.227] Build status: SCHEDULED
[18:42:05.228] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:8:0) after 7m21s
[18:49:26.434] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:8:0)
[18:49:26.496] Build status: SCHEDULED
[18:49:26.496] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:9:0) after 8m47s
[18:58:13.882] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:9:0)
[18:58:13.910] Build status: SCHEDULED
[18:58:13.910] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:10:0) after 6m43s
[19:04:56.977] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:10:0)
[19:04:57.004] Build status: SCHEDULED
[19:04:57.004] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:11:0) after 1m50s
[19:06:47.147] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:11:0)
[19:06:47.193] Build status: SCHEDULED
[19:06:47.193] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:12:0) after 2m49s
[19:09:36.607] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:12:0)
[19:09:36.635] Build status: SCHEDULED
[19:09:36.635] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:13:0) after 6m15s
[19:15:51.681] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:13:0)
[19:15:51.709] Build status: SCHEDULED
[19:15:51.709] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:14:0) after 9m52s
[19:25:44.127] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:14:0)
[19:25:44.157] Build status: SCHEDULED
[19:25:44.157] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:15:0) after 2m26s
[19:28:10.558] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:15:0)
[19:28:10.589] Build status: SCHEDULED
[19:28:10.589] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:16:0) after 1m49s
[19:29:59.702] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:16:0)
[19:29:59.731] Build status: SCHEDULED
[19:29:59.731] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:17:0) after 1m59s
[19:31:58.851] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:17:0)
[19:31:58.876] Build status: SCHEDULED
[19:31:58.876] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:18:0) after 4m33s
[19:36:32.303] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:18:0)
[19:36:32.346] Build status: SCHEDULED
[19:36:32.346] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:19:0) after 8m31s
[19:45:03.365] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:19:0)
[19:45:03.390] Build status: SCHEDULED
[19:45:03.390] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:20:0) after 1m50s
[19:46:53.409] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:20:0)
[19:46:53.435] Build status: SCHEDULED
[19:46:53.435] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:21:0) after 1m7s
[19:48:00.417] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:21:0)
[19:48:00.458] Build status: SCHEDULED
[19:48:00.458] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:22:0) after 1m37s
[19:49:37.632] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:22:0)
[19:49:37.658] Build status: SCHEDULED
[19:49:37.658] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:23:0) after 8m14s
[19:57:51.715] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:23:0)
[19:57:51.744] Build status: SCHEDULED
[19:57:51.744] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:24:0) after 3m15s
[20:01:06.782] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:24:0)
[20:01:06.817] Build status: SCHEDULED
[20:01:06.817] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:25:0) after 7m54s
[20:09:00.929] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:25:0)
[20:09:00.955] Build status: SCHEDULED
[20:09:00.955] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:26:0) after 3m31s
[20:12:31.938] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:26:0)
[20:12:31.965] Build status: SCHEDULED
[20:12:31.965] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:27:0) after 3m7s
[20:15:38.984] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:27:0)
[20:15:39.015] Build status: SCHEDULED
[20:15:39.015] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:28:0) after 8m44s
[20:24:23.104] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:28:0)
[20:24:23.135] Build status: SCHEDULED
[20:24:23.135] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:29:0) after 1m33s
[20:25:56.625] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:29:0)
[20:25:56.659] Build status: SCHEDULED
[20:25:56.659] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:30:0) after 9m54s
[20:35:50.698] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:30:0)
[20:35:50.725] Build status: SCHEDULED
[20:35:50.725] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:31:0) after 2m29s
[20:38:19.755] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:31:0)
[20:38:19.787] Build status: SCHEDULED
[20:38:19.787] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:32:0) after 9m31s
[20:47:50.846] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:32:0)
[20:47:50.877] Build status: SCHEDULED
[20:47:50.877] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:33:0) after 3m40s
[20:51:31.117] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:33:0)
[20:51:31.153] Build status: SCHEDULED
[20:51:31.153] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:34:0) after 1m2s
[20:52:33.397] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:34:0)
[20:52:33.425] Build status: SCHEDULED
[20:52:33.425] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:35:0) after 4m32s
[20:57:05.556] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:35:0)
[20:57:05.583] Build status: SCHEDULED
[20:57:05.583] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:36:0) after 2m46s
[20:59:51.615] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:36:0)
[20:59:51.660] Build status: SCHEDULED
[20:59:51.660] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:37:0) after 4m48s
[21:04:39.832] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:37:0)
[21:04:39.862] Build status: SCHEDULED
[21:04:39.862] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:38:0) after 1m47s
[21:06:27.311] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:38:0)
[21:06:27.347] Build status: SCHEDULED
[21:06:27.347] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:39:0) after 3m24s
[21:09:51.395] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:39:0)
[21:09:51.425] Build status: SCHEDULED
[21:09:51.425] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:40:0) after 2m20s
[21:12:11.571] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:40:0)
[21:12:11.602] Build status: SCHEDULED
[21:12:11.602] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:41:0) after 1m11s
[21:13:22.619] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:41:0)
[21:13:22.651] Build status: SCHEDULED
[21:13:22.651] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:42:0) after 2m5s
[21:15:27.827] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:42:0)
[21:15:27.871] Build status: SCHEDULED
[21:15:27.871] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:43:0) after 2m58s
[21:18:26.002] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:43:0)
[21:18:26.029] Build status: SCHEDULED
[21:18:26.029] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:44:0) after 6m1s
[21:22:23.448] Received PubSub notification, asking Buildbucket for the build status
[21:22:23.474] Build status: STARTED
[21:24:27.194] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:44:0)
[21:24:27.226] Build status: STARTED
[21:24:27.226] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:46:0) after 6m46s
[21:31:13.298] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:46:0)
[21:31:13.324] Build status: STARTED
[21:31:13.324] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:47:0) after 4m14s
[21:35:27.399] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:47:0)
[21:35:27.435] Build status: STARTED
[21:35:27.435] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:48:0) after 3m56s
[21:39:23.453] Handling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:48:0)
[21:39:23.516] Build status: STARTED
[21:39:23.516] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac Asan:9017467542051842512:49:0) after 9m58s
[21:43:39.866] Received PubSub notification, asking Buildbucket for the build status
[21:43:39.893] Build:
{
"id": "8852037480126295072",
"builder": {
"project": "webrtc",
"bucket": "ci",
"builder": "Mac Asan"
},
"number": 26146,
"createdBy": "project:webrtc",
"createTime": "2021-03-22T18:03:49.546047Z",
"startTime": "2021-03-22T21:22:22.153649Z",
"endTime": "2021-03-22T21:43:39.215247Z",
"updateTime": "2021-03-22T21:43:39.689964Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "webrtc.googlesource.com",
"project": "src",
"id": "c303f82f4d1f770824f5704b5a7da6d231b3f7bd",
"ref": "refs/heads/master"
}
}
}
[21:43:39.893] Invocation finished in 3h39m51.566573759s with status SUCCEEDED