1 /* 2 * Copyright (C) 2016 The Android Open Source Project 3 * 4 * Licensed under the Apache License, Version 2.0 (the "License"); 5 * you may not use this file except in compliance with the License. 6 * You may obtain a copy of the License at 7 * 8 * http://www.apache.org/licenses/LICENSE-2.0 9 * 10 * Unless required by applicable law or agreed to in writing, software 11 * distributed under the License is distributed on an "AS IS" BASIS, 12 * WITHOUT WARRANTIES OR CONDITIONS OF ANY KIND, either express or implied. 13 * See the License for the specific language governing permissions and 14 * limitations under the License 15 */ 16 17 package com.android.server.telecom; 18 19 import android.content.Context; 20 import android.os.SystemClock; 21 import android.telecom.Logging.EventManager; 22 import android.telecom.Logging.EventManager.TimedEventPair; 23 24 import com.android.internal.annotations.VisibleForTesting; 25 26 import java.util.HashMap; 27 import java.util.Map; 28 29 /** 30 * Temporary location of new Logging class 31 */ 32 33 public class LogUtils { 34 35 private static final String TAG = "Telecom"; 36 private static final String LOGUTILS_TAG = "LogUtils"; 37 38 public static final boolean SYSTRACE_DEBUG = false; /* STOP SHIP if true */ 39 private static boolean sInitializedLoggerListeners = false; // used to gate re-init of listeners 40 private static int sInitializedCounter = 0; /* For testing purposes only */ 41 private static final Object sLock = new Object(); // Coarse lock for all of LogUtils 42 43 public static class EventTimer { 44 private long mLastElapsedMillis; 45 private Map<String, Long> mTimings = new HashMap<>(); 46 EventTimer()47 public EventTimer() { 48 mLastElapsedMillis = SystemClock.elapsedRealtime(); 49 } 50 record(String label)51 public void record(String label) { 52 long newElapsedMillis = SystemClock.elapsedRealtime(); 53 mTimings.put(label, newElapsedMillis - mLastElapsedMillis); 54 mLastElapsedMillis = newElapsedMillis; 55 } 56 57 @Override toString()58 public String toString() { 59 StringBuilder sb = new StringBuilder(); 60 for (Map.Entry<String, Long> entry : mTimings.entrySet()) { 61 sb.append(entry.getKey()).append(": ").append(entry.getValue()).append(", "); 62 } 63 return sb.toString(); 64 } 65 } 66 67 public static final class Sessions { 68 public static final String ICA_ANSWER_CALL = "ICA.aC"; 69 public static final String ICA_DEFLECT_CALL = "ICA.defC"; 70 public static final String ICA_REJECT_CALL = "ICA.rC"; 71 public static final String ICA_TRANSFER_CALL = "ICA.tC"; 72 public static final String ICA_CONSULTATIVE_TRANSFER = "ICA.cT"; 73 public static final String ICA_DISCONNECT_CALL = "ICA.dC"; 74 public static final String ICA_HOLD_CALL = "ICA.hC"; 75 public static final String ICA_UNHOLD_CALL = "ICA.uC"; 76 public static final String ICA_MUTE = "ICA.m"; 77 public static final String ICA_SET_AUDIO_ROUTE = "ICA.sAR"; 78 public static final String ICA_ENTER_AUDIO_PROCESSING = "ICA.enBAP"; 79 public static final String ICA_EXIT_AUDIO_PROCESSING = "ICA.exBAP"; 80 public static final String ICA_CONFERENCE = "ICA.c"; 81 public static final String CSW_HANDLE_CREATE_CONNECTION_COMPLETE = "CSW.hCCC"; 82 public static final String CSW_SET_ACTIVE = "CSW.sA"; 83 public static final String CSW_SET_RINGING = "CSW.sR"; 84 public static final String CSW_SET_DIALING = "CSW.sD"; 85 public static final String CSW_SET_PULLING = "CSW.sP"; 86 public static final String CSW_SET_DISCONNECTED = "CSW.sDc"; 87 public static final String CSW_SET_ON_HOLD = "CSW.sOH"; 88 public static final String CSW_REMOVE_CALL = "CSW.rC"; 89 public static final String CSW_SET_IS_CONFERENCED = "CSW.sIC"; 90 public static final String CSW_ADD_CONFERENCE_CALL = "CSW.aCC"; 91 } 92 93 public final static class Events { 94 public static final String CREATED = "CREATED"; 95 public static final String USER_CONFIRMATION = "USER_CONFIRMATION"; 96 public static final String USER_CONFIRMED = "USER_CONFIRMED"; 97 public static final String USER_CANCELLED = "USER_CANCELLED"; 98 public static final String DESTROYED = "DESTROYED"; 99 public static final String SET_CONNECTING = "SET_CONNECTING"; 100 public static final String SET_DIALING = "SET_DIALING"; 101 public static final String SET_PULLING = "SET_PULLING"; 102 public static final String SET_ACTIVE = "SET_ACTIVE"; 103 public static final String SET_HOLD = "SET_HOLD"; 104 public static final String SET_RINGING = "SET_RINGING"; 105 public static final String SET_ANSWERED = "SET_ANSWERED"; 106 public static final String SET_DISCONNECTED = "SET_DISCONNECTED"; 107 public static final String SET_DISCONNECTING = "SET_DISCONNECTING"; 108 public static final String SET_SELECT_PHONE_ACCOUNT = "SET_SELECT_PHONE_ACCOUNT"; 109 public static final String SET_AUDIO_PROCESSING = "SET_AUDIO_PROCESSING"; 110 public static final String SET_SIMULATED_RINGING = "SET_SIMULATED_RINGING"; 111 public static final String REQUEST_HOLD = "REQUEST_HOLD"; 112 public static final String REQUEST_UNHOLD = "REQUEST_UNHOLD"; 113 public static final String REQUEST_DISCONNECT = "REQUEST_DISCONNECT"; 114 public static final String REQUEST_ACCEPT = "REQUEST_ACCEPT"; 115 public static final String REQUEST_SIMULATED_ACCEPT = "REQUEST_SIMULATED_ACCEPT"; 116 public static final String REQUEST_PICKUP_FOR_AUDIO_PROCESSING = 117 "REQUEST_PICKUP_FOR_AUDIO_PROCESSING"; 118 public static final String REQUEST_DEFLECT = "REQUEST_DEFLECT"; 119 public static final String REQUEST_REJECT = "REQUEST_REJECT"; 120 public static final String REQUEST_TRANSFER = "REQUEST_TRANSFER"; 121 public static final String REQUEST_CONSULTATIVE_TRANSFER = "REQUEST_CONSULTATIVE_TRANSFER"; 122 public static final String START_DTMF = "START_DTMF"; 123 public static final String STOP_DTMF = "STOP_DTMF"; 124 public static final String START_RINGER = "START_RINGER"; 125 public static final String STOP_RINGER = "STOP_RINGER"; 126 public static final String START_VIBRATOR = "START_VIBRATOR"; 127 public static final String STOP_VIBRATOR = "STOP_VIBRATOR"; 128 public static final String SKIP_VIBRATION = "SKIP_VIBRATION"; 129 public static final String SKIP_RINGING = "SKIP_RINGING"; 130 public static final String START_CALL_WAITING_TONE = "START_CALL_WAITING_TONE"; 131 public static final String STOP_CALL_WAITING_TONE = "STOP_CALL_WAITING_TONE"; 132 public static final String START_CONNECTION = "START_CONNECTION"; 133 public static final String CREATE_CONNECTION_FAILED = "CREATE_CONNECTION_FAILED"; 134 public static final String START_CONFERENCE = "START_CONFERENCE"; 135 public static final String CREATE_CONFERENCE_FAILED = "CREATE_CONFERENCE_FAILED"; 136 public static final String BIND_CS = "BIND_CS"; 137 public static final String CS_BOUND = "CS_BOUND"; 138 public static final String CONFERENCE_WITH = "CONF_WITH"; 139 public static final String SPLIT_FROM_CONFERENCE = "CONF_SPLIT"; 140 public static final String SWAP = "SWAP"; 141 public static final String ADD_PARTICIPANT = "ADD_PARTICIPANT"; 142 public static final String ADD_CHILD = "ADD_CHILD"; 143 public static final String REMOVE_CHILD = "REMOVE_CHILD"; 144 public static final String SET_PARENT = "SET_PARENT"; 145 public static final String CONF_STATE_CHANGED = "CONF_STATE_CHANGED"; 146 public static final String CONF_CALLS_CHANGED = "CONF_CALLS_CHANGED"; 147 public static final String CALL_DIRECTION_CHANGED = "CALL_DIRECTION_CHANGED"; 148 public static final String MUTE = "MUTE"; 149 public static final String UNMUTE = "UNMUTE"; 150 public static final String AUDIO_ROUTE = "AUDIO_ROUTE"; 151 public static final String AUDIO_ROUTE_EARPIECE = "AUDIO_ROUTE_EARPIECE"; 152 public static final String AUDIO_ROUTE_HEADSET = "AUDIO_ROUTE_HEADSET"; 153 public static final String AUDIO_ROUTE_BT = "AUDIO_ROUTE_BT"; 154 public static final String AUDIO_ROUTE_SPEAKER = "AUDIO_ROUTE_SPEAKER"; 155 public static final String ERROR_LOG = "ERROR"; 156 public static final String USER_LOG_MARK = "USER_LOG_MARK"; 157 public static final String SILENCE = "SILENCE"; 158 public static final String BIND_SCREENING = "BIND_SCREENING"; 159 public static final String SCREENING_BOUND = "SCREENING_BOUND"; 160 public static final String SCREENING_SENT = "SCREENING_SENT"; 161 public static final String SCREENING_SKIPPED = "SCREENING_SKIPPED"; 162 public static final String CONTROLLER_SCREENING_COMPLETED = 163 "CONTROLLER_SCREENING_COMPLETED"; 164 public static final String SCREENING_COMPLETED = "SCREENING_COMPLETED"; 165 public static final String CALL_IDENTIFICATION_SET = "CALL_IDENTIFICATION_SET"; 166 public static final String BLOCK_CHECK_INITIATED = "BLOCK_CHECK_INITIATED"; 167 public static final String BLOCK_CHECK_FINISHED = "BLOCK_CHECK_FINISHED"; 168 public static final String DIRECT_TO_VM_INITIATED = "DIRECT_TO_VM_INITIATED"; 169 public static final String DIRECT_TO_VM_FINISHED = "DIRECT_TO_VM_FINISHED"; 170 public static final String FILTERING_INITIATED = "FILTERING_INITIATED"; 171 public static final String FILTERING_COMPLETED = "FILTERING_COMPLETED"; 172 public static final String FILTERING_TIMED_OUT = "FILTERING_TIMED_OUT"; 173 public static final String REMOTELY_HELD = "REMOTELY_HELD"; 174 public static final String REMOTELY_UNHELD = "REMOTELY_UNHELD"; 175 public static final String REQUEST_PULL = "PULL"; 176 public static final String INFO = "INFO"; 177 public static final String VIDEO_STATE_CHANGED = "VIDEO_STATE_CHANGED"; 178 public static final String RECEIVE_VIDEO_REQUEST = "RECEIVE_VIDEO_REQUEST"; 179 public static final String RECEIVE_VIDEO_RESPONSE = "RECEIVE_VIDEO_RESPONSE"; 180 public static final String SEND_VIDEO_REQUEST = "SEND_VIDEO_REQUEST"; 181 public static final String SEND_VIDEO_RESPONSE = "SEND_VIDEO_RESPONSE"; 182 public static final String IS_EXTERNAL = "IS_EXTERNAL"; 183 public static final String PROPERTY_CHANGE = "PROPERTY_CHANGE"; 184 public static final String CAPABILITY_CHANGE = "CAPABILITY_CHANGE"; 185 public static final String CONNECTION_EVENT = "CONNECTION_EVENT"; 186 public static final String CALL_EVENT = "CALL_EVENT"; 187 public static final String HANDOVER_REQUEST = "HANDOVER_REQUEST"; 188 public static final String START_HANDOVER = "START_HANDOVER"; 189 public static final String ACCEPT_HANDOVER = "ACCEPT_HANDOVER"; 190 public static final String HANDOVER_COMPLETE = "HANDOVER_COMPLETE"; 191 public static final String HANDOVER_FAILED = "HANDOVER_FAILED"; 192 public static final String START_RINBACK = "START_RINGBACK"; 193 public static final String STOP_RINGBACK = "STOP_RINGBACK"; 194 public static final String REDIRECTION_BOUND_USER = "REDIRECTION_BOUND_USER"; 195 public static final String REDIRECTION_BOUND_CARRIER = "REDIRECTION_BOUND_CARRIER"; 196 public static final String REDIRECTION_SENT_USER = "REDIRECTION_SENT_USER"; 197 public static final String REDIRECTION_SENT_CARRIER = "REDIRECTION_SENT_CARRIER"; 198 public static final String REDIRECTION_COMPLETED_USER = "REDIRECTION_COMPLETED_USER"; 199 public static final String REDIRECTION_COMPLETED_CARRIER = "REDIRECTION_COMPLETED_CARRIER"; 200 public static final String REDIRECTION_TIMED_OUT_USER = "REDIRECTION_TIMED_OUT_USER"; 201 public static final String REDIRECTION_TIMED_OUT_CARRIER = "REDIRECTION_TIMED_OUT_CARRIER"; 202 public static final String REDIRECTION_USER_CONFIRMATION = "REDIRECTION_USER_CONFIRMATION"; 203 public static final String REDIRECTION_USER_CONFIRMED = "REDIRECTION_USER_CONFIRMED"; 204 public static final String REDIRECTION_USER_CANCELLED = "REDIRECTION_USER_CANCELLED"; 205 public static final String BT_QUALITY_REPORT = "BT_QUALITY_REPORT"; 206 public static final String SET_DISCONNECTED_ORIG = "SET_DISCONNECTED_ORIG"; 207 public static final String OVERRIDE_DISCONNECT_MESSAGE = "OVERRIDE_DISCONNECT_MSG"; 208 public static final String CALL_DIAGNOSTIC_SERVICE_TIMEOUT = 209 "CALL_DIAGNOSTIC_SERVICE_TIMEOUT"; 210 public static final String VERSTAT_CHANGED = "VERSTAT_CHANGED"; 211 public static final String SET_VOIP_MODE = "SET_VOIP_MODE"; 212 213 public static class Timings { 214 public static final String ACCEPT_TIMING = "accept"; 215 public static final String REJECT_TIMING = "reject"; 216 public static final String DISCONNECT_TIMING = "disconnect"; 217 public static final String HOLD_TIMING = "hold"; 218 public static final String UNHOLD_TIMING = "unhold"; 219 public static final String OUTGOING_TIME_TO_DIALING_TIMING = "outgoing_time_to_dialing"; 220 public static final String BIND_CS_TIMING = "bind_cs"; 221 public static final String SCREENING_COMPLETED_TIMING = "screening_completed"; 222 public static final String DIRECT_TO_VM_FINISHED_TIMING = "direct_to_vm_finished"; 223 public static final String BLOCK_CHECK_FINISHED_TIMING = "block_check_finished"; 224 public static final String FILTERING_COMPLETED_TIMING = "filtering_completed"; 225 public static final String FILTERING_TIMED_OUT_TIMING = "filtering_timed_out"; 226 public static final String START_CONNECTION_TO_REQUEST_DISCONNECT_TIMING = 227 "start_connection_to_request_disconnect"; 228 229 private static final TimedEventPair[] sTimedEvents = { 230 new TimedEventPair(REQUEST_ACCEPT, SET_ACTIVE, ACCEPT_TIMING), 231 new TimedEventPair(REQUEST_REJECT, SET_DISCONNECTED, REJECT_TIMING), 232 new TimedEventPair(REQUEST_DISCONNECT, SET_DISCONNECTED, DISCONNECT_TIMING), 233 new TimedEventPair(REQUEST_HOLD, SET_HOLD, HOLD_TIMING), 234 new TimedEventPair(REQUEST_UNHOLD, SET_ACTIVE, UNHOLD_TIMING), 235 new TimedEventPair(START_CONNECTION, SET_DIALING, 236 OUTGOING_TIME_TO_DIALING_TIMING), 237 new TimedEventPair(BIND_CS, CS_BOUND, BIND_CS_TIMING), 238 new TimedEventPair(SCREENING_SENT, SCREENING_COMPLETED, 239 SCREENING_COMPLETED_TIMING), 240 new TimedEventPair(DIRECT_TO_VM_INITIATED, DIRECT_TO_VM_FINISHED, 241 DIRECT_TO_VM_FINISHED_TIMING), 242 new TimedEventPair(BLOCK_CHECK_INITIATED, BLOCK_CHECK_FINISHED, 243 BLOCK_CHECK_FINISHED_TIMING), 244 new TimedEventPair(FILTERING_INITIATED, FILTERING_COMPLETED, 245 FILTERING_COMPLETED_TIMING), 246 new TimedEventPair(FILTERING_INITIATED, FILTERING_TIMED_OUT, 247 FILTERING_TIMED_OUT_TIMING, 6000L), 248 new TimedEventPair(START_CONNECTION, REQUEST_DISCONNECT, 249 START_CONNECTION_TO_REQUEST_DISCONNECT_TIMING), 250 }; 251 } 252 } 253 eventRecordAdded(EventManager.EventRecord eventRecord)254 private static void eventRecordAdded(EventManager.EventRecord eventRecord) { 255 // Only Calls will be added as event records in this case 256 EventManager.Loggable recordEntry = eventRecord.getRecordEntry(); 257 if (recordEntry instanceof Call) { 258 Call callRecordEntry = (Call) recordEntry; 259 Analytics.CallInfo callInfo = callRecordEntry.getAnalytics(); 260 if(callInfo != null) { 261 callInfo.setCallEvents(eventRecord); 262 } else { 263 if(!android.telecom.Log.isUnitTestingEnabled()) { 264 android.telecom.Log.w(LOGUTILS_TAG, "Could not get Analytics CallInfo."); 265 } 266 } 267 } else { 268 android.telecom.Log.w(LOGUTILS_TAG, "Non-Call EventRecord Added."); 269 } 270 } 271 initLogging(Context context)272 public static void initLogging(Context context) { 273 android.telecom.Log.d(LOGUTILS_TAG, "initLogging: attempting to acquire LogUtils sLock"); 274 synchronized (sLock) { 275 android.telecom.Log.d(LOGUTILS_TAG, "initLogging: grabbed LogUtils sLock"); 276 if (!sInitializedLoggerListeners) { 277 android.telecom.Log.d(LOGUTILS_TAG, 278 "initLogging: called for first time. Registering the EventListener &" 279 + " SessionListener."); 280 281 android.telecom.Log.setTag(TAG); 282 android.telecom.Log.setSessionContext(context); 283 for (EventManager.TimedEventPair p : Events.Timings.sTimedEvents) { 284 android.telecom.Log.addRequestResponsePair(p); 285 } 286 android.telecom.Log.registerEventListener(LogUtils::eventRecordAdded); 287 // Store analytics about recently completed Sessions. 288 android.telecom.Log.registerSessionListener(Analytics::addSessionTiming); 289 290 // Ensure LogUtils#initLogging(Context) is called once throughout the entire 291 // lifecycle of not only TelecomSystem, but the Testing Framework. 292 sInitializedLoggerListeners = true; 293 sInitializedCounter++; /* For testing purposes only */ 294 } else { 295 android.telecom.Log.d(LOGUTILS_TAG, "initLogging: called again. Doing nothing."); 296 } 297 } 298 } 299 300 /** 301 * Needed in order to test if the registerEventListener & registerSessionListener are ever 302 * re-initialized in the entire process of the Testing Framework or TelecomSystem. 303 * 304 * @return the number of times initLogging(Context) listeners have been initialized 305 */ 306 @VisibleForTesting getInitializedCounter()307 public static int getInitializedCounter() { 308 return sInitializedCounter; 309 } 310 } 311