[21:18:53.059] New invocation is queued and will start shortly
[21:18:54.552] Starting the invocation (attempt 1)
[21:18:54.592] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d7f02f0caaa1fdeaa20be30bca1e3b18af997680"
[21:18:54.592] Popped gitiles commit info from properties and tags
[21:18:54.592] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:18:54.592] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:18:54.593] Buildbucket request:
{
"requestId": "9017364671807112160",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "mac-official"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d7f02f0caaa1fdeaa20be30bca1e3b18af997680",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9017364671807112160"
},
{
"key": "scheduler_job_id",
"value": "chromium/mac-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[21:18:55.093] Scheduled build:
{
"id": "8851934609422327360",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "mac-official"
},
"number": 1249,
"createdBy": "project:chromium",
"createTime": "2021-03-23T21:18:54.694070Z",
"updateTime": "2021-03-23T21:18:55.002795Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d7f02f0caaa1fdeaa20be30bca1e3b18af997680",
"ref": "refs/heads/master"
}
}
}
[21:18:55.093] Task URL: https://cr-buildbucket.appspot.com/build/8851934609422327360
[21:18:55.093] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:2:0) after 6m44s
[21:18:59.536] Received PubSub notification, asking Buildbucket for the build status
[21:18:59.564] Build status: STARTED
[21:25:39.140] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:2:0)
[21:25:39.166] Build status: STARTED
[21:25:39.166] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:4:0) after 1m15s
[21:26:54.142] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:4:0)
[21:26:54.172] Build status: STARTED
[21:26:54.172] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:5:0) after 2m19s
[21:29:13.165] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:5:0)
[21:29:13.206] Build status: STARTED
[21:29:13.206] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:6:0) after 8m19s
[21:37:32.231] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:6:0)
[21:37:32.268] Build status: STARTED
[21:37:32.268] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:7:0) after 8m32s
[21:46:04.512] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:7:0)
[21:46:04.547] Build status: STARTED
[21:46:04.548] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:8:0) after 8m23s
[21:54:27.567] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:8:0)
[21:54:27.604] Build status: STARTED
[21:54:27.604] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:9:0) after 5m0s
[21:59:27.690] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:9:0)
[21:59:27.717] Build status: STARTED
[21:59:27.717] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:10:0) after 7m32s
[22:06:59.825] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:10:0)
[22:06:59.915] Build status: STARTED
[22:06:59.915] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:11:0) after 4m9s
[22:11:08.943] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:11:0)
[22:11:08.969] Build status: STARTED
[22:11:08.969] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:12:0) after 7m49s
[22:18:58.028] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:12:0)
[22:18:58.060] Build status: STARTED
[22:18:58.060] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:13:0) after 3m29s
[22:22:27.086] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:13:0)
[22:22:27.121] Build status: STARTED
[22:22:27.121] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:14:0) after 6m19s
[22:28:46.293] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:14:0)
[22:28:46.327] Build status: STARTED
[22:28:46.327] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:15:0) after 4m16s
[22:33:02.349] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:15:0)
[22:33:02.381] Build status: STARTED
[22:33:02.381] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:16:0) after 4m49s
[22:37:51.455] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:16:0)
[22:37:51.488] Build status: STARTED
[22:37:51.488] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:17:0) after 6m7s
[22:43:58.576] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:17:0)
[22:43:58.614] Build status: STARTED
[22:43:58.614] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:18:0) after 3m14s
[22:47:12.736] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:18:0)
[22:47:12.765] Build status: STARTED
[22:47:12.765] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:19:0) after 9m14s
[22:56:26.905] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:19:0)
[22:56:26.981] Build status: STARTED
[22:56:26.981] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:20:0) after 1m17s
[22:57:44.101] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:20:0)
[22:57:44.130] Build status: STARTED
[22:57:44.130] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:21:0) after 4m11s
[23:01:55.285] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:21:0)
[23:01:55.320] Build status: STARTED
[23:01:55.320] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:22:0) after 4m56s
[23:06:51.417] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:22:0)
[23:06:51.464] Build status: STARTED
[23:06:51.464] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:23:0) after 9m2s
[23:15:53.625] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:23:0)
[23:15:53.669] Build status: STARTED
[23:15:53.669] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:24:0) after 2m21s
[23:18:14.700] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:24:0)
[23:18:14.733] Build status: STARTED
[23:18:14.733] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:25:0) after 7m8s
[23:25:22.754] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:25:0)
[23:25:22.785] Build status: STARTED
[23:25:22.785] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:26:0) after 1m49s
[23:27:11.812] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:26:0)
[23:27:11.845] Build status: STARTED
[23:27:11.845] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:27:0) after 7m30s
[23:34:41.989] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:27:0)
[23:34:42.021] Build status: STARTED
[23:34:42.021] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:28:0) after 9m4s
[23:43:46.094] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:28:0)
[23:43:46.130] Build status: STARTED
[23:43:46.130] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:29:0) after 6m34s
[23:50:20.518] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:29:0)
[23:50:20.550] Build status: STARTED
[23:50:20.550] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:30:0) after 5m4s
[23:55:24.597] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:30:0)
[23:55:24.705] Build status: STARTED
[23:55:24.705] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:31:0) after 8m36s
[00:04:00.991] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:31:0)
[00:04:01.020] Build status: STARTED
[00:04:01.020] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:32:0) after 6m34s
[00:10:35.059] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:32:0)
[00:10:35.091] Build status: STARTED
[00:10:35.091] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:33:0) after 7m34s
[00:18:09.290] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:33:0)
[00:18:09.320] Build status: STARTED
[00:18:09.320] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:34:0) after 6m43s
[00:24:52.361] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:34:0)
[00:24:52.392] Build status: STARTED
[00:24:52.392] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:35:0) after 5m3s
[00:29:55.419] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:35:0)
[00:29:55.460] Build status: STARTED
[00:29:55.460] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:36:0) after 9m30s
[00:39:25.551] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:36:0)
[00:39:25.578] Build status: STARTED
[00:39:25.578] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:37:0) after 8m45s
[00:48:10.630] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:37:0)
[00:48:10.660] Build status: STARTED
[00:48:10.661] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:38:0) after 4m57s
[00:53:07.772] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:38:0)
[00:53:07.797] Build status: STARTED
[00:53:07.797] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:39:0) after 9m37s
[01:02:44.830] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:39:0)
[01:02:44.864] Build status: STARTED
[01:02:44.864] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:40:0) after 4m55s
[01:07:40.172] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:40:0)
[01:07:40.206] Build status: STARTED
[01:07:40.206] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:41:0) after 3m34s
[01:11:14.228] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:41:0)
[01:11:14.257] Build status: STARTED
[01:11:14.257] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:42:0) after 9m8s
[01:20:22.318] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:42:0)
[01:20:22.388] Build status: STARTED
[01:20:22.388] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:43:0) after 7m33s
[01:27:55.425] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:43:0)
[01:27:55.475] Build status: STARTED
[01:27:55.475] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9017364671807112160:44:0) after 3m51s
[01:30:46.250] Received PubSub notification, asking Buildbucket for the build status
[01:30:46.278] Build:
{
"id": "8851934609422327360",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "mac-official"
},
"number": 1249,
"createdBy": "project:chromium",
"createTime": "2021-03-23T21:18:54.694070Z",
"startTime": "2021-03-23T21:18:58.739687Z",
"endTime": "2021-03-24T01:30:45.588163Z",
"updateTime": "2021-03-24T01:30:45.908699Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d7f02f0caaa1fdeaa20be30bca1e3b18af997680",
"ref": "refs/heads/master"
}
}
}
[01:30:46.278] Invocation finished in 4h11m53.242853785s with status SUCCEEDED