FastMixer.cpp revision 0a14c4ce1a41bc09eb7855fa531a3af629a69139
197b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten/*
297b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten * Copyright (C) 2012 The Android Open Source Project
397b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten *
497b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten * Licensed under the Apache License, Version 2.0 (the "License");
597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten * you may not use this file except in compliance with the License.
697b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten * You may obtain a copy of the License at
797b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten *
897b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten *      http://www.apache.org/licenses/LICENSE-2.0
997b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten *
1097b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten * Unless required by applicable law or agreed to in writing, software
1197b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten * distributed under the License is distributed on an "AS IS" BASIS,
1297b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten * WITHOUT WARRANTIES OR CONDITIONS OF ANY KIND, either express or implied.
1397b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten * See the License for the specific language governing permissions and
1497b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten * limitations under the License.
1597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten */
1697b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten
1797b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten#define LOG_TAG "FastMixer"
1897b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten//#define LOG_NDEBUG 0
1997b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten
2097b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten#include <sys/atomics.h>
2197b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten#include <time.h>
2297b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten#include <utils/Log.h>
23d8e6fd35ec2b59ee7d873daf1f1d9d348221c7bcGlenn Kasten#include <utils/Trace.h>
2497b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten#include <system/audio.h>
2597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten#ifdef FAST_MIXER_STATISTICS
2697b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten#include <cpustats/CentralTendencyStatistics.h>
270a14c4ce1a41bc09eb7855fa531a3af629a69139Glenn Kasten#ifdef CPU_FREQUENCY_STATISTICS
2842d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten#include <cpustats/ThreadCpuUsage.h>
2997b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten#endif
300a14c4ce1a41bc09eb7855fa531a3af629a69139Glenn Kasten#endif
3197b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten#include "AudioMixer.h"
3297b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten#include "FastMixer.h"
3397b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten
3497b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten#define FAST_HOT_IDLE_NS     1000000L   // 1 ms: time to sleep while hot idling
3597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten#define FAST_DEFAULT_NS    999999999L   // ~1 sec: default time to sleep
36288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten#define MAX_WARMUP_CYCLES         10    // maximum number of loop cycles to wait for warmup
3797b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten
3897b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kastennamespace android {
3997b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten
4097b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten// Fast mixer thread
4197b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kastenbool FastMixer::threadLoop()
4297b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten{
4397b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten    static const FastMixerState initial;
4497b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten    const FastMixerState *previous = &initial, *current = &initial;
4597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten    FastMixerState preIdle; // copy of state before we went into idle
4697b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten    struct timespec oldTs = {0, 0};
4797b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten    bool oldTsValid = false;
4897b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten    long slopNs = 0;    // accumulated time we've woken up too early (> 0) or too late (< 0)
4997b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten    long sleepNs = -1;  // -1: busy wait, 0: sched_yield, > 0: nanosleep
5097b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten    int fastTrackNames[FastMixerState::kMaxFastTracks]; // handles used by mixer to identify tracks
5197b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten    int generations[FastMixerState::kMaxFastTracks];    // last observed mFastTracks[i].mGeneration
5297b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten    unsigned i;
5397b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten    for (i = 0; i < FastMixerState::kMaxFastTracks; ++i) {
5497b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        fastTrackNames[i] = -1;
5597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        generations[i] = 0;
5697b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten    }
5797b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten    NBAIO_Sink *outputSink = NULL;
5897b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten    int outputSinkGen = 0;
5997b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten    AudioMixer* mixer = NULL;
6097b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten    short *mixBuffer = NULL;
6197b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten    enum {UNDEFINED, MIXED, ZEROED} mixBufferState = UNDEFINED;
6297b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten    NBAIO_Format format = Format_Invalid;
6397b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten    unsigned sampleRate = 0;
6497b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten    int fastTracksGen = 0;
6597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten    long periodNs = 0;      // expected period; the time required to render one mix buffer
66288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten    long underrunNs = 0;    // underrun likely when write cycle is greater than this value
67288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten    long overrunNs = 0;     // overrun likely when write cycle is less than this value
68288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten    long warmupNs = 0;      // warmup complete when write cycle is greater than to this value
6997b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten    FastMixerDumpState dummyDumpState, *dumpState = &dummyDumpState;
7097b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten    bool ignoreNextOverrun = true;  // used to ignore initial overrun and first after an underrun
7197b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten#ifdef FAST_MIXER_STATISTICS
7242d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten    struct timespec oldLoad = {0, 0};    // previous value of clock_gettime(CLOCK_THREAD_CPUTIME_ID)
7342d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten    bool oldLoadValid = false;  // whether oldLoad is valid
7442d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten    uint32_t bounds = 0;
7542d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten    bool full = false;      // whether we have collected at least kSamplingN samples
760a14c4ce1a41bc09eb7855fa531a3af629a69139Glenn Kasten#ifdef CPU_FREQUENCY_STATISTICS
7742d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten    ThreadCpuUsage tcu;     // for reading the current CPU clock frequency in kHz
7897b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten#endif
790a14c4ce1a41bc09eb7855fa531a3af629a69139Glenn Kasten#endif
8097b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten    unsigned coldGen = 0;   // last observed mColdGen
81288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten    bool isWarm = false;    // true means ready to mix, false means wait for warmup before mixing
82288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten    struct timespec measuredWarmupTs = {0, 0};  // how long did it take for warmup to complete
83288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten    uint32_t warmupCycles = 0;  // counter of number of loop cycles required to warmup
84fbae5dae5187aca9d974cbe15ec818e9c6f56705Glenn Kasten    NBAIO_Sink* teeSink = NULL; // if non-NULL, then duplicate write() to this non-blocking sink
8597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten
8697b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten    for (;;) {
8797b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten
8897b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        // either nanosleep, sched_yield, or busy wait
8997b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        if (sleepNs >= 0) {
9097b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            if (sleepNs > 0) {
9197b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                ALOG_ASSERT(sleepNs < 1000000000);
9297b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                const struct timespec req = {0, sleepNs};
9397b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                nanosleep(&req, NULL);
9497b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            } else {
9597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                sched_yield();
9697b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            }
9797b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        }
9897b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        // default to long sleep for next cycle
9997b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        sleepNs = FAST_DEFAULT_NS;
10097b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten
10197b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        // poll for state change
10297b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        const FastMixerState *next = mSQ.poll();
10397b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        if (next == NULL) {
10497b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            // continue to use the default initial state until a real state is available
10597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            ALOG_ASSERT(current == &initial && previous == &initial);
10697b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            next = current;
10797b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        }
10897b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten
10997b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        FastMixerState::Command command = next->mCommand;
11097b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        if (next != current) {
11197b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten
11297b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            // As soon as possible of learning of a new dump area, start using it
11397b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            dumpState = next->mDumpState != NULL ? next->mDumpState : &dummyDumpState;
114fbae5dae5187aca9d974cbe15ec818e9c6f56705Glenn Kasten            teeSink = next->mTeeSink;
11597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten
11697b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            // We want to always have a valid reference to the previous (non-idle) state.
11797b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            // However, the state queue only guarantees access to current and previous states.
11897b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            // So when there is a transition from a non-idle state into an idle state, we make a
11997b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            // copy of the last known non-idle state so it is still available on return from idle.
12097b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            // The possible transitions are:
12197b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            //  non-idle -> non-idle    update previous from current in-place
12297b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            //  non-idle -> idle        update previous from copy of current
12397b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            //  idle     -> idle        don't update previous
12497b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            //  idle     -> non-idle    don't update previous
12597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            if (!(current->mCommand & FastMixerState::IDLE)) {
12697b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                if (command & FastMixerState::IDLE) {
12797b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    preIdle = *current;
12897b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    current = &preIdle;
12997b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    oldTsValid = false;
13042d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                    oldLoadValid = false;
13197b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    ignoreNextOverrun = true;
13297b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                }
13397b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                previous = current;
13497b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            }
13597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            current = next;
13697b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        }
13797b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten#if !LOG_NDEBUG
13897b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        next = NULL;    // not referenced again
13997b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten#endif
14097b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten
14197b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        dumpState->mCommand = command;
14297b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten
14397b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        switch (command) {
14497b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        case FastMixerState::INITIAL:
14597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        case FastMixerState::HOT_IDLE:
14697b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            sleepNs = FAST_HOT_IDLE_NS;
14797b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            continue;
14897b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        case FastMixerState::COLD_IDLE:
14997b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            // only perform a cold idle command once
15021e8c50bd13ebe44f3088e26c9c6df0e163c469cGlenn Kasten            // FIXME consider checking previous state and only perform if previous != COLD_IDLE
15197b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            if (current->mColdGen != coldGen) {
15297b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                int32_t *coldFutexAddr = current->mColdFutexAddr;
15397b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                ALOG_ASSERT(coldFutexAddr != NULL);
15497b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                int32_t old = android_atomic_dec(coldFutexAddr);
15597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                if (old <= 0) {
15697b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    __futex_syscall4(coldFutexAddr, FUTEX_WAIT_PRIVATE, old - 1, NULL);
15797b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                }
158288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                // This may be overly conservative; there could be times that the normal mixer
159288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                // requests such a brief cold idle that it doesn't require resetting this flag.
160288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                isWarm = false;
161288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                measuredWarmupTs.tv_sec = 0;
162288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                measuredWarmupTs.tv_nsec = 0;
163288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                warmupCycles = 0;
16497b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                sleepNs = -1;
16597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                coldGen = current->mColdGen;
16642d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                bounds = 0;
16742d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                full = false;
16804a4ca4217e9b30342d5865024c340013d7184e8Glenn Kasten                oldTsValid = !clock_gettime(CLOCK_MONOTONIC, &oldTs);
16997b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            } else {
17097b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                sleepNs = FAST_HOT_IDLE_NS;
17197b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            }
17297b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            continue;
17397b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        case FastMixerState::EXIT:
17497b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            delete mixer;
17597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            delete[] mixBuffer;
17697b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            return false;
17797b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        case FastMixerState::MIX:
17897b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        case FastMixerState::WRITE:
17997b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        case FastMixerState::MIX_WRITE:
18097b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            break;
18197b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        default:
18297b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            LOG_FATAL("bad command %d", command);
18397b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        }
18497b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten
18597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        // there is a non-idle state available to us; did the state change?
18697b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        size_t frameCount = current->mFrameCount;
18797b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        if (current != previous) {
18897b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten
18997b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            // handle state change here, but since we want to diff the state,
19097b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            // we're prepared for previous == &initial the first time through
19197b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            unsigned previousTrackMask;
19297b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten
19397b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            // check for change in output HAL configuration
19497b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            NBAIO_Format previousFormat = format;
19597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            if (current->mOutputSinkGen != outputSinkGen) {
19697b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                outputSink = current->mOutputSink;
19797b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                outputSinkGen = current->mOutputSinkGen;
19897b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                if (outputSink == NULL) {
19997b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    format = Format_Invalid;
20097b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    sampleRate = 0;
20197b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                } else {
20297b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    format = outputSink->format();
20397b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    sampleRate = Format_sampleRate(format);
20497b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    ALOG_ASSERT(Format_channelCount(format) == 2);
20597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                }
20621e8c50bd13ebe44f3088e26c9c6df0e163c469cGlenn Kasten                dumpState->mSampleRate = sampleRate;
20797b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            }
20897b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten
20997b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            if ((format != previousFormat) || (frameCount != previous->mFrameCount)) {
21097b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                // FIXME to avoid priority inversion, don't delete here
21197b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                delete mixer;
21297b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                mixer = NULL;
21397b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                delete[] mixBuffer;
21497b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                mixBuffer = NULL;
21597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                if (frameCount > 0 && sampleRate > 0) {
21697b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    // FIXME new may block for unbounded time at internal mutex of the heap
21797b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    //       implementation; it would be better to have normal mixer allocate for us
21897b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    //       to avoid blocking here and to prevent possible priority inversion
21997b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    mixer = new AudioMixer(frameCount, sampleRate, FastMixerState::kMaxFastTracks);
22097b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    mixBuffer = new short[frameCount * 2];
22197b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    periodNs = (frameCount * 1000000000LL) / sampleRate;    // 1.00
22297b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    underrunNs = (frameCount * 1750000000LL) / sampleRate;  // 1.75
22397b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    overrunNs = (frameCount * 250000000LL) / sampleRate;    // 0.25
224288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                    warmupNs = (frameCount * 500000000LL) / sampleRate;     // 0.50
22597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                } else {
22697b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    periodNs = 0;
22797b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    underrunNs = 0;
22897b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    overrunNs = 0;
22997b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                }
23097b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                mixBufferState = UNDEFINED;
23197b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten#if !LOG_NDEBUG
23297b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                for (i = 0; i < FastMixerState::kMaxFastTracks; ++i) {
23397b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    fastTrackNames[i] = -1;
23497b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                }
23597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten#endif
23697b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                // we need to reconfigure all active tracks
23797b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                previousTrackMask = 0;
23897b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                fastTracksGen = current->mFastTracksGen - 1;
23921e8c50bd13ebe44f3088e26c9c6df0e163c469cGlenn Kasten                dumpState->mFrameCount = frameCount;
24097b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            } else {
24197b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                previousTrackMask = previous->mTrackMask;
24297b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            }
24397b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten
24497b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            // check for change in active track set
24597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            unsigned currentTrackMask = current->mTrackMask;
2461295bb4dcff7b29c75cd23746816df12a871d72cGlenn Kasten            dumpState->mTrackMask = currentTrackMask;
24797b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            if (current->mFastTracksGen != fastTracksGen) {
24897b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                ALOG_ASSERT(mixBuffer != NULL);
24997b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                int name;
25097b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten
25197b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                // process removed tracks first to avoid running out of track names
25297b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                unsigned removedTracks = previousTrackMask & ~currentTrackMask;
25397b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                while (removedTracks != 0) {
25497b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    i = __builtin_ctz(removedTracks);
25597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    removedTracks &= ~(1 << i);
25697b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    const FastTrack* fastTrack = &current->mFastTracks[i];
257288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                    ALOG_ASSERT(fastTrack->mBufferProvider == NULL);
25897b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    if (mixer != NULL) {
25997b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                        name = fastTrackNames[i];
26097b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                        ALOG_ASSERT(name >= 0);
26197b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                        mixer->deleteTrackName(name);
26297b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    }
26397b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten#if !LOG_NDEBUG
26497b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    fastTrackNames[i] = -1;
26597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten#endif
266288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                    // don't reset track dump state, since other side is ignoring it
26797b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    generations[i] = fastTrack->mGeneration;
26897b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                }
26997b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten
27097b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                // now process added tracks
27197b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                unsigned addedTracks = currentTrackMask & ~previousTrackMask;
27297b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                while (addedTracks != 0) {
27397b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    i = __builtin_ctz(addedTracks);
27497b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    addedTracks &= ~(1 << i);
27597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    const FastTrack* fastTrack = &current->mFastTracks[i];
27697b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    AudioBufferProvider *bufferProvider = fastTrack->mBufferProvider;
27797b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    ALOG_ASSERT(bufferProvider != NULL && fastTrackNames[i] == -1);
27897b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    if (mixer != NULL) {
2799bd23229fdec1657398abc682ccccfce1c95f8aaJean-Michel Trivi                        // calling getTrackName with default channel mask
2809bd23229fdec1657398abc682ccccfce1c95f8aaJean-Michel Trivi                        name = mixer->getTrackName(AUDIO_CHANNEL_OUT_STEREO);
28197b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                        ALOG_ASSERT(name >= 0);
28297b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                        fastTrackNames[i] = name;
28397b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                        mixer->setBufferProvider(name, bufferProvider);
28497b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                        mixer->setParameter(name, AudioMixer::TRACK, AudioMixer::MAIN_BUFFER,
28597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                                (void *) mixBuffer);
28697b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                        // newly allocated track names default to full scale volume
28721e8c50bd13ebe44f3088e26c9c6df0e163c469cGlenn Kasten                        if (fastTrack->mSampleRate != 0 && fastTrack->mSampleRate != sampleRate) {
28821e8c50bd13ebe44f3088e26c9c6df0e163c469cGlenn Kasten                            mixer->setParameter(name, AudioMixer::RESAMPLE,
28921e8c50bd13ebe44f3088e26c9c6df0e163c469cGlenn Kasten                                    AudioMixer::SAMPLE_RATE, (void*) fastTrack->mSampleRate);
29021e8c50bd13ebe44f3088e26c9c6df0e163c469cGlenn Kasten                        }
29121e8c50bd13ebe44f3088e26c9c6df0e163c469cGlenn Kasten                        mixer->setParameter(name, AudioMixer::TRACK, AudioMixer::CHANNEL_MASK,
29221e8c50bd13ebe44f3088e26c9c6df0e163c469cGlenn Kasten                                (void *) fastTrack->mChannelMask);
29397b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                        mixer->enable(name);
29497b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    }
29597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    generations[i] = fastTrack->mGeneration;
29697b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                }
29797b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten
29897b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                // finally process modified tracks; these use the same slot
29997b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                // but may have a different buffer provider or volume provider
30097b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                unsigned modifiedTracks = currentTrackMask & previousTrackMask;
30197b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                while (modifiedTracks != 0) {
30297b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    i = __builtin_ctz(modifiedTracks);
30397b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    modifiedTracks &= ~(1 << i);
30497b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    const FastTrack* fastTrack = &current->mFastTracks[i];
30597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    if (fastTrack->mGeneration != generations[i]) {
30697b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                        AudioBufferProvider *bufferProvider = fastTrack->mBufferProvider;
30797b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                        ALOG_ASSERT(bufferProvider != NULL);
30897b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                        if (mixer != NULL) {
30997b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                            name = fastTrackNames[i];
31097b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                            ALOG_ASSERT(name >= 0);
31197b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                            mixer->setBufferProvider(name, bufferProvider);
31297b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                            if (fastTrack->mVolumeProvider == NULL) {
31397b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                                mixer->setParameter(name, AudioMixer::VOLUME, AudioMixer::VOLUME0,
31497b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                                        (void *)0x1000);
31597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                                mixer->setParameter(name, AudioMixer::VOLUME, AudioMixer::VOLUME1,
31697b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                                        (void *)0x1000);
31797b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                            }
31821e8c50bd13ebe44f3088e26c9c6df0e163c469cGlenn Kasten                            if (fastTrack->mSampleRate != 0 &&
31921e8c50bd13ebe44f3088e26c9c6df0e163c469cGlenn Kasten                                    fastTrack->mSampleRate != sampleRate) {
32021e8c50bd13ebe44f3088e26c9c6df0e163c469cGlenn Kasten                                mixer->setParameter(name, AudioMixer::RESAMPLE,
32121e8c50bd13ebe44f3088e26c9c6df0e163c469cGlenn Kasten                                        AudioMixer::SAMPLE_RATE, (void*) fastTrack->mSampleRate);
32221e8c50bd13ebe44f3088e26c9c6df0e163c469cGlenn Kasten                            } else {
32321e8c50bd13ebe44f3088e26c9c6df0e163c469cGlenn Kasten                                mixer->setParameter(name, AudioMixer::RESAMPLE,
32421e8c50bd13ebe44f3088e26c9c6df0e163c469cGlenn Kasten                                        AudioMixer::REMOVE, NULL);
32521e8c50bd13ebe44f3088e26c9c6df0e163c469cGlenn Kasten                            }
32621e8c50bd13ebe44f3088e26c9c6df0e163c469cGlenn Kasten                            mixer->setParameter(name, AudioMixer::TRACK, AudioMixer::CHANNEL_MASK,
32721e8c50bd13ebe44f3088e26c9c6df0e163c469cGlenn Kasten                                    (void *) fastTrack->mChannelMask);
32897b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                            // already enabled
32997b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                        }
33097b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                        generations[i] = fastTrack->mGeneration;
33197b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    }
33297b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                }
33397b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten
33497b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                fastTracksGen = current->mFastTracksGen;
33597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten
33697b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                dumpState->mNumTracks = popcount(currentTrackMask);
33797b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            }
33897b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten
33997b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten#if 1   // FIXME shouldn't need this
34097b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            // only process state change once
34197b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            previous = current;
34297b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten#endif
34397b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        }
34497b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten
34597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        // do work using current state here
346288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten        if ((command & FastMixerState::MIX) && (mixer != NULL) && isWarm) {
34797b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            ALOG_ASSERT(mixBuffer != NULL);
348288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten            // for each track, update volume and check for underrun
349288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten            unsigned currentTrackMask = current->mTrackMask;
350288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten            while (currentTrackMask != 0) {
351288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                i = __builtin_ctz(currentTrackMask);
352288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                currentTrackMask &= ~(1 << i);
35397b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                const FastTrack* fastTrack = &current->mFastTracks[i];
35497b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                int name = fastTrackNames[i];
35597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                ALOG_ASSERT(name >= 0);
35697b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                if (fastTrack->mVolumeProvider != NULL) {
35797b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    uint32_t vlr = fastTrack->mVolumeProvider->getVolumeLR();
35897b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    mixer->setParameter(name, AudioMixer::VOLUME, AudioMixer::VOLUME0,
35997b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                            (void *)(vlr & 0xFFFF));
36097b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    mixer->setParameter(name, AudioMixer::VOLUME, AudioMixer::VOLUME1,
36197b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                            (void *)(vlr >> 16));
36297b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                }
363288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                // FIXME The current implementation of framesReady() for fast tracks
364288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                // takes a tryLock, which can block
365288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                // up to 1 ms.  If enough active tracks all blocked in sequence, this would result
366288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                // in the overall fast mix cycle being delayed.  Should use a non-blocking FIFO.
367288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                size_t framesReady = fastTrack->mBufferProvider->framesReady();
36899c99d00beb43b939dedc9ffb07adb89f6a85ba5Glenn Kasten#if defined(ATRACE_TAG) && (ATRACE_TAG != ATRACE_TAG_NEVER)
36999c99d00beb43b939dedc9ffb07adb89f6a85ba5Glenn Kasten                // I wish we had formatted trace names
37099c99d00beb43b939dedc9ffb07adb89f6a85ba5Glenn Kasten                char traceName[16];
37199c99d00beb43b939dedc9ffb07adb89f6a85ba5Glenn Kasten                strcpy(traceName, "framesReady");
37299c99d00beb43b939dedc9ffb07adb89f6a85ba5Glenn Kasten                traceName[11] = i + (i < 10 ? '0' : 'A' - 10);
37399c99d00beb43b939dedc9ffb07adb89f6a85ba5Glenn Kasten                traceName[12] = '\0';
37499c99d00beb43b939dedc9ffb07adb89f6a85ba5Glenn Kasten                ATRACE_INT(traceName, framesReady);
37599c99d00beb43b939dedc9ffb07adb89f6a85ba5Glenn Kasten#endif
376288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                FastTrackDump *ftDump = &dumpState->mTracks[i];
37709474df67278c0cd621b57c4aef1deaec4d8447fGlenn Kasten                FastTrackUnderruns underruns = ftDump->mUnderruns;
378288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                if (framesReady < frameCount) {
379288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                    if (framesReady == 0) {
38009474df67278c0cd621b57c4aef1deaec4d8447fGlenn Kasten                        underruns.mBitFields.mEmpty++;
38109474df67278c0cd621b57c4aef1deaec4d8447fGlenn Kasten                        underruns.mBitFields.mMostRecent = UNDERRUN_EMPTY;
382288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                        mixer->disable(name);
383288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                    } else {
384288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                        // allow mixing partial buffer
38509474df67278c0cd621b57c4aef1deaec4d8447fGlenn Kasten                        underruns.mBitFields.mPartial++;
38609474df67278c0cd621b57c4aef1deaec4d8447fGlenn Kasten                        underruns.mBitFields.mMostRecent = UNDERRUN_PARTIAL;
387288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                        mixer->enable(name);
388288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                    }
38909474df67278c0cd621b57c4aef1deaec4d8447fGlenn Kasten                } else {
39009474df67278c0cd621b57c4aef1deaec4d8447fGlenn Kasten                    underruns.mBitFields.mFull++;
39109474df67278c0cd621b57c4aef1deaec4d8447fGlenn Kasten                    underruns.mBitFields.mMostRecent = UNDERRUN_FULL;
392288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                    mixer->enable(name);
393288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                }
39409474df67278c0cd621b57c4aef1deaec4d8447fGlenn Kasten                ftDump->mUnderruns = underruns;
3951295bb4dcff7b29c75cd23746816df12a871d72cGlenn Kasten                ftDump->mFramesReady = framesReady;
39697b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            }
39797b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            // process() is CPU-bound
39897b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            mixer->process(AudioBufferProvider::kInvalidPTS);
39997b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            mixBufferState = MIXED;
40097b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        } else if (mixBufferState == MIXED) {
40197b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            mixBufferState = UNDEFINED;
40297b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        }
403288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten        bool attemptedWrite = false;
404288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten        //bool didFullWrite = false;    // dumpsys could display a count of partial writes
40597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        if ((command & FastMixerState::WRITE) && (outputSink != NULL) && (mixBuffer != NULL)) {
40697b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            if (mixBufferState == UNDEFINED) {
40797b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                memset(mixBuffer, 0, frameCount * 2 * sizeof(short));
40897b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                mixBufferState = ZEROED;
40997b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            }
410fbae5dae5187aca9d974cbe15ec818e9c6f56705Glenn Kasten            if (teeSink != NULL) {
411fbae5dae5187aca9d974cbe15ec818e9c6f56705Glenn Kasten                (void) teeSink->write(mixBuffer, frameCount);
412fbae5dae5187aca9d974cbe15ec818e9c6f56705Glenn Kasten            }
41397b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            // FIXME write() is non-blocking and lock-free for a properly implemented NBAIO sink,
41497b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            //       but this code should be modified to handle both non-blocking and blocking sinks
41597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            dumpState->mWriteSequence++;
41699c99d00beb43b939dedc9ffb07adb89f6a85ba5Glenn Kasten#if defined(ATRACE_TAG) && (ATRACE_TAG != ATRACE_TAG_NEVER)
417d8e6fd35ec2b59ee7d873daf1f1d9d348221c7bcGlenn Kasten            Tracer::traceBegin(ATRACE_TAG, "write");
41899c99d00beb43b939dedc9ffb07adb89f6a85ba5Glenn Kasten#endif
41997b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            ssize_t framesWritten = outputSink->write(mixBuffer, frameCount);
42099c99d00beb43b939dedc9ffb07adb89f6a85ba5Glenn Kasten#if defined(ATRACE_TAG) && (ATRACE_TAG != ATRACE_TAG_NEVER)
421d8e6fd35ec2b59ee7d873daf1f1d9d348221c7bcGlenn Kasten            Tracer::traceEnd(ATRACE_TAG);
42299c99d00beb43b939dedc9ffb07adb89f6a85ba5Glenn Kasten#endif
42397b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            dumpState->mWriteSequence++;
42497b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            if (framesWritten >= 0) {
425288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                ALOG_ASSERT(framesWritten <= frameCount);
42697b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                dumpState->mFramesWritten += framesWritten;
427288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                //if ((size_t) framesWritten == frameCount) {
428288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                //    didFullWrite = true;
429288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                //}
43097b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            } else {
43197b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                dumpState->mWriteErrors++;
43297b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            }
433288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten            attemptedWrite = true;
43497b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            // FIXME count # of writes blocked excessively, CPU usage, etc. for dump
43597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        }
43697b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten
43797b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        // To be exactly periodic, compute the next sleep time based on current time.
43897b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        // This code doesn't have long-term stability when the sink is non-blocking.
43997b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        // FIXME To avoid drift, use the local audio clock or watch the sink's fill status.
44097b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        struct timespec newTs;
44197b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        int rc = clock_gettime(CLOCK_MONOTONIC, &newTs);
44297b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        if (rc == 0) {
44397b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            if (oldTsValid) {
44497b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                time_t sec = newTs.tv_sec - oldTs.tv_sec;
44597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                long nsec = newTs.tv_nsec - oldTs.tv_nsec;
44697b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                if (nsec < 0) {
44797b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    --sec;
44897b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    nsec += 1000000000;
44997b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                }
450288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                // To avoid an initial underrun on fast tracks after exiting standby,
451288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                // do not start pulling data from tracks and mixing until warmup is complete.
452288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                // Warmup is considered complete after the earlier of:
453288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                //      first successful single write() that blocks for more than warmupNs
454288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                //      MAX_WARMUP_CYCLES write() attempts.
455288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                // This is overly conservative, but to get better accuracy requires a new HAL API.
456288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                if (!isWarm && attemptedWrite) {
457288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                    measuredWarmupTs.tv_sec += sec;
458288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                    measuredWarmupTs.tv_nsec += nsec;
459288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                    if (measuredWarmupTs.tv_nsec >= 1000000000) {
460288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                        measuredWarmupTs.tv_sec++;
461288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                        measuredWarmupTs.tv_nsec -= 1000000000;
462288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                    }
463288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                    ++warmupCycles;
464288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                    if ((attemptedWrite && nsec > warmupNs) ||
465288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                            (warmupCycles >= MAX_WARMUP_CYCLES)) {
466288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                        isWarm = true;
467288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                        dumpState->mMeasuredWarmupTs = measuredWarmupTs;
468288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                        dumpState->mWarmupCycles = warmupCycles;
469288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                    }
470288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten                }
47197b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                if (sec > 0 || nsec > underrunNs) {
47299c99d00beb43b939dedc9ffb07adb89f6a85ba5Glenn Kasten#if defined(ATRACE_TAG) && (ATRACE_TAG != ATRACE_TAG_NEVER)
473d8e6fd35ec2b59ee7d873daf1f1d9d348221c7bcGlenn Kasten                    ScopedTrace st(ATRACE_TAG, "underrun");
47499c99d00beb43b939dedc9ffb07adb89f6a85ba5Glenn Kasten#endif
47597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    // FIXME only log occasionally
47697b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    ALOGV("underrun: time since last cycle %d.%03ld sec",
47797b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                            (int) sec, nsec / 1000000L);
47897b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    dumpState->mUnderruns++;
47997b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    sleepNs = -1;
48097b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    ignoreNextOverrun = true;
48197b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                } else if (nsec < overrunNs) {
48297b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    if (ignoreNextOverrun) {
48397b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                        ignoreNextOverrun = false;
48497b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    } else {
48597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                        // FIXME only log occasionally
48697b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                        ALOGV("overrun: time since last cycle %d.%03ld sec",
48797b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                                (int) sec, nsec / 1000000L);
48897b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                        dumpState->mOverruns++;
48997b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    }
4909a0d82df8fd681257bc5b29fb5e6c65fcc7cef5aEric Laurent                    // Code for non blocking audio HAL. Sleep time must be tuned to allow
4919a0d82df8fd681257bc5b29fb5e6c65fcc7cef5aEric Laurent                    // catching up after an underrun
4929a0d82df8fd681257bc5b29fb5e6c65fcc7cef5aEric Laurent                    //     sleepNs = periodNs - overrunNs;
4939a0d82df8fd681257bc5b29fb5e6c65fcc7cef5aEric Laurent                    sleepNs = -1;
49497b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                } else {
49597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    sleepNs = -1;
49697b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                    ignoreNextOverrun = false;
49797b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                }
49897b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten#ifdef FAST_MIXER_STATISTICS
49942d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                // advance the FIFO queue bounds
50042d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                size_t i = bounds & (FastMixerDumpState::kSamplingN - 1);
501e58ccce45598bcf4b4874b0e87cd1eb8d05ba9a9Glenn Kasten                bounds = (bounds & 0xFFFF0000) | ((bounds + 1) & 0xFFFF);
50242d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                if (full) {
50342d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                    bounds += 0x10000;
50442d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                } else if (!(bounds & (FastMixerDumpState::kSamplingN - 1))) {
50542d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                    full = true;
50697b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                }
50742d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                // compute the delta value of clock_gettime(CLOCK_MONOTONIC)
50842d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                uint32_t monotonicNs = nsec;
50942d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                if (sec > 0 && sec < 4) {
51042d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                    monotonicNs += sec * 1000000000;
51142d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                }
51242d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                // compute the raw CPU load = delta value of clock_gettime(CLOCK_THREAD_CPUTIME_ID)
51342d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                uint32_t loadNs = 0;
51442d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                struct timespec newLoad;
51542d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                rc = clock_gettime(CLOCK_THREAD_CPUTIME_ID, &newLoad);
51642d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                if (rc == 0) {
51742d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                    if (oldLoadValid) {
51842d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                        sec = newLoad.tv_sec - oldLoad.tv_sec;
51942d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                        nsec = newLoad.tv_nsec - oldLoad.tv_nsec;
52042d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                        if (nsec < 0) {
52142d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                            --sec;
52242d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                            nsec += 1000000000;
52342d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                        }
52442d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                        loadNs = nsec;
52542d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                        if (sec > 0 && sec < 4) {
52642d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                            loadNs += sec * 1000000000;
52742d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                        }
52842d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                    } else {
52942d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                        // first time through the loop
53042d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                        oldLoadValid = true;
53142d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                    }
53242d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                    oldLoad = newLoad;
53342d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                }
5340a14c4ce1a41bc09eb7855fa531a3af629a69139Glenn Kasten#ifdef CPU_FREQUENCY_STATISTICS
53542d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                // get the absolute value of CPU clock frequency in kHz
53642d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                int cpuNum = sched_getcpu();
53742d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                uint32_t kHz = tcu.getCpukHz(cpuNum);
538c059bd4246c1b3944965be921e5b334d51cd236cGlenn Kasten                kHz = (kHz << 4) | (cpuNum & 0xF);
5390a14c4ce1a41bc09eb7855fa531a3af629a69139Glenn Kasten#endif
54042d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                // save values in FIFO queues for dumpsys
54142d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                // these stores #1, #2, #3 are not atomic with respect to each other,
54242d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                // or with respect to store #4 below
54342d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                dumpState->mMonotonicNs[i] = monotonicNs;
54442d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                dumpState->mLoadNs[i] = loadNs;
5450a14c4ce1a41bc09eb7855fa531a3af629a69139Glenn Kasten#ifdef CPU_FREQUENCY_STATISTICS
54642d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                dumpState->mCpukHz[i] = kHz;
5470a14c4ce1a41bc09eb7855fa531a3af629a69139Glenn Kasten#endif
54842d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                // this store #4 is not atomic with respect to stores #1, #2, #3 above, but
54942d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                // the newest open and oldest closed halves are atomic with respect to each other
55042d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                dumpState->mBounds = bounds;
55199c99d00beb43b939dedc9ffb07adb89f6a85ba5Glenn Kasten#if defined(ATRACE_TAG) && (ATRACE_TAG != ATRACE_TAG_NEVER)
55299c99d00beb43b939dedc9ffb07adb89f6a85ba5Glenn Kasten                ATRACE_INT("cycle_ms", monotonicNs / 1000000);
55399c99d00beb43b939dedc9ffb07adb89f6a85ba5Glenn Kasten                ATRACE_INT("load_us", loadNs / 1000);
55499c99d00beb43b939dedc9ffb07adb89f6a85ba5Glenn Kasten#endif
55597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten#endif
55697b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            } else {
55797b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                // first time through the loop
55897b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                oldTsValid = true;
55997b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                sleepNs = periodNs;
56097b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                ignoreNextOverrun = true;
56197b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            }
56297b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            oldTs = newTs;
56397b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        } else {
56497b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            // monotonic clock is broken
56597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            oldTsValid = false;
56697b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten            sleepNs = periodNs;
56797b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        }
56897b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten
56942d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten
57097b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten    }   // for (;;)
57197b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten
57297b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten    // never return 'true'; Thread::_threadLoop() locks mutex which can result in priority inversion
57397b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten}
57497b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten
57597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn KastenFastMixerDumpState::FastMixerDumpState() :
57697b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten    mCommand(FastMixerState::INITIAL), mWriteSequence(0), mFramesWritten(0),
57721e8c50bd13ebe44f3088e26c9c6df0e163c469cGlenn Kasten    mNumTracks(0), mWriteErrors(0), mUnderruns(0), mOverruns(0),
5781295bb4dcff7b29c75cd23746816df12a871d72cGlenn Kasten    mSampleRate(0), mFrameCount(0), /* mMeasuredWarmupTs({0, 0}), */ mWarmupCycles(0),
5791295bb4dcff7b29c75cd23746816df12a871d72cGlenn Kasten    mTrackMask(0)
58097b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten#ifdef FAST_MIXER_STATISTICS
58142d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten    , mBounds(0)
58297b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten#endif
58397b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten{
584288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten    mMeasuredWarmupTs.tv_sec = 0;
585288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten    mMeasuredWarmupTs.tv_nsec = 0;
58642d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten    // sample arrays aren't accessed atomically with respect to the bounds,
58742d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten    // so clearing reduces chance for dumpsys to read random uninitialized samples
58842d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten    memset(&mMonotonicNs, 0, sizeof(mMonotonicNs));
58942d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten    memset(&mLoadNs, 0, sizeof(mLoadNs));
5900a14c4ce1a41bc09eb7855fa531a3af629a69139Glenn Kasten#ifdef CPU_FREQUENCY_STATISTICS
59142d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten    memset(&mCpukHz, 0, sizeof(mCpukHz));
5920a14c4ce1a41bc09eb7855fa531a3af629a69139Glenn Kasten#endif
59397b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten}
59497b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten
59597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn KastenFastMixerDumpState::~FastMixerDumpState()
59697b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten{
59797b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten}
59897b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten
59997b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kastenvoid FastMixerDumpState::dump(int fd)
60097b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten{
601868c0abe9f26852c217bc201b337e72c68f829b8Glenn Kasten    if (mCommand == FastMixerState::INITIAL) {
602868c0abe9f26852c217bc201b337e72c68f829b8Glenn Kasten        fdprintf(fd, "FastMixer not initialized\n");
603868c0abe9f26852c217bc201b337e72c68f829b8Glenn Kasten        return;
604868c0abe9f26852c217bc201b337e72c68f829b8Glenn Kasten    }
60597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten#define COMMAND_MAX 32
60697b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten    char string[COMMAND_MAX];
60797b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten    switch (mCommand) {
60897b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten    case FastMixerState::INITIAL:
60997b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        strcpy(string, "INITIAL");
61097b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        break;
61197b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten    case FastMixerState::HOT_IDLE:
61297b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        strcpy(string, "HOT_IDLE");
61397b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        break;
61497b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten    case FastMixerState::COLD_IDLE:
61597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        strcpy(string, "COLD_IDLE");
61697b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        break;
61797b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten    case FastMixerState::EXIT:
61897b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        strcpy(string, "EXIT");
61997b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        break;
62097b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten    case FastMixerState::MIX:
62197b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        strcpy(string, "MIX");
62297b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        break;
62397b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten    case FastMixerState::WRITE:
62497b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        strcpy(string, "WRITE");
62597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        break;
62697b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten    case FastMixerState::MIX_WRITE:
62797b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        strcpy(string, "MIX_WRITE");
62897b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        break;
62997b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten    default:
63097b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        snprintf(string, COMMAND_MAX, "%d", mCommand);
63197b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten        break;
63297b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten    }
63342d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten    double measuredWarmupMs = (mMeasuredWarmupTs.tv_sec * 1000.0) +
634288ed2103d96f3aabd7e6bea3c080ab6db164049Glenn Kasten            (mMeasuredWarmupTs.tv_nsec / 1000000.0);
63542d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten    double mixPeriodSec = (double) mFrameCount / (double) mSampleRate;
63697b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten    fdprintf(fd, "FastMixer command=%s writeSequence=%u framesWritten=%u\n"
63721e8c50bd13ebe44f3088e26c9c6df0e163c469cGlenn Kasten                 "          numTracks=%u writeErrors=%u underruns=%u overruns=%u\n"
63842d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                 "          sampleRate=%u frameCount=%u measuredWarmup=%.3g ms, warmupCycles=%u\n"
63942d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                 "          mixPeriod=%.2f ms\n",
64097b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten                 string, mWriteSequence, mFramesWritten,
64121e8c50bd13ebe44f3088e26c9c6df0e163c469cGlenn Kasten                 mNumTracks, mWriteErrors, mUnderruns, mOverruns,
64242d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                 mSampleRate, mFrameCount, measuredWarmupMs, mWarmupCycles,
64342d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                 mixPeriodSec * 1e3);
64497b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten#ifdef FAST_MIXER_STATISTICS
64542d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten    // find the interval of valid samples
64642d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten    uint32_t bounds = mBounds;
64742d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten    uint32_t newestOpen = bounds & 0xFFFF;
64842d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten    uint32_t oldestClosed = bounds >> 16;
64942d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten    uint32_t n = (newestOpen - oldestClosed) & 0xFFFF;
65042d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten    if (n > kSamplingN) {
65142d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten        ALOGE("too many samples %u", n);
65242d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten        n = kSamplingN;
65342d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten    }
65442d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten    // statistics for monotonic (wall clock) time, thread raw CPU load in time, CPU clock frequency,
65542d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten    // and adjusted CPU load in MHz normalized for CPU clock frequency
6560a14c4ce1a41bc09eb7855fa531a3af629a69139Glenn Kasten    CentralTendencyStatistics wall, loadNs;
6570a14c4ce1a41bc09eb7855fa531a3af629a69139Glenn Kasten#ifdef CPU_FREQUENCY_STATISTICS
6580a14c4ce1a41bc09eb7855fa531a3af629a69139Glenn Kasten    CentralTendencyStatistics kHz, loadMHz;
65942d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten    uint32_t previousCpukHz = 0;
6600a14c4ce1a41bc09eb7855fa531a3af629a69139Glenn Kasten#endif
66142d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten    // loop over all the samples
66242d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten    for (; n > 0; --n) {
66342d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten        size_t i = oldestClosed++ & (kSamplingN - 1);
66442d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten        uint32_t wallNs = mMonotonicNs[i];
66542d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten        wall.sample(wallNs);
66642d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten        uint32_t sampleLoadNs = mLoadNs[i];
66742d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten        loadNs.sample(sampleLoadNs);
6680a14c4ce1a41bc09eb7855fa531a3af629a69139Glenn Kasten#ifdef CPU_FREQUENCY_STATISTICS
6690a14c4ce1a41bc09eb7855fa531a3af629a69139Glenn Kasten        uint32_t sampleCpukHz = mCpukHz[i];
670c059bd4246c1b3944965be921e5b334d51cd236cGlenn Kasten        // skip bad kHz samples
671c059bd4246c1b3944965be921e5b334d51cd236cGlenn Kasten        if ((sampleCpukHz & ~0xF) != 0) {
672c059bd4246c1b3944965be921e5b334d51cd236cGlenn Kasten            kHz.sample(sampleCpukHz >> 4);
673c059bd4246c1b3944965be921e5b334d51cd236cGlenn Kasten            if (sampleCpukHz == previousCpukHz) {
674c059bd4246c1b3944965be921e5b334d51cd236cGlenn Kasten                double megacycles = (double) sampleLoadNs * (double) (sampleCpukHz >> 4) * 1e-12;
675c059bd4246c1b3944965be921e5b334d51cd236cGlenn Kasten                double adjMHz = megacycles / mixPeriodSec;  // _not_ wallNs * 1e9
676c059bd4246c1b3944965be921e5b334d51cd236cGlenn Kasten                loadMHz.sample(adjMHz);
677c059bd4246c1b3944965be921e5b334d51cd236cGlenn Kasten            }
67842d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten        }
67942d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten        previousCpukHz = sampleCpukHz;
6800a14c4ce1a41bc09eb7855fa531a3af629a69139Glenn Kasten#endif
68142d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten    }
68242d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten    fdprintf(fd, "Simple moving statistics over last %.1f seconds:\n", wall.n() * mixPeriodSec);
68342d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten    fdprintf(fd, "  wall clock time in ms per mix cycle:\n"
68442d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                 "    mean=%.2f min=%.2f max=%.2f stddev=%.2f\n",
68542d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                 wall.mean()*1e-6, wall.minimum()*1e-6, wall.maximum()*1e-6, wall.stddev()*1e-6);
68642d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten    fdprintf(fd, "  raw CPU load in us per mix cycle:\n"
68742d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                 "    mean=%.0f min=%.0f max=%.0f stddev=%.0f\n",
68842d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                 loadNs.mean()*1e-3, loadNs.minimum()*1e-3, loadNs.maximum()*1e-3,
68942d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                 loadNs.stddev()*1e-3);
6900a14c4ce1a41bc09eb7855fa531a3af629a69139Glenn Kasten#ifdef CPU_FREQUENCY_STATISTICS
69142d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten    fdprintf(fd, "  CPU clock frequency in MHz:\n"
69242d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                 "    mean=%.0f min=%.0f max=%.0f stddev=%.0f\n",
69342d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                 kHz.mean()*1e-3, kHz.minimum()*1e-3, kHz.maximum()*1e-3, kHz.stddev()*1e-3);
69442d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten    fdprintf(fd, "  adjusted CPU load in MHz (i.e. normalized for CPU clock frequency):\n"
69542d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                 "    mean=%.1f min=%.1f max=%.1f stddev=%.1f\n",
69642d45cfd0c3d62357a6549c62f535e4d4fe08d91Glenn Kasten                 loadMHz.mean(), loadMHz.minimum(), loadMHz.maximum(), loadMHz.stddev());
69797b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten#endif
6980a14c4ce1a41bc09eb7855fa531a3af629a69139Glenn Kasten#endif
6991295bb4dcff7b29c75cd23746816df12a871d72cGlenn Kasten    // The active track mask and track states are updated non-atomically.
7001295bb4dcff7b29c75cd23746816df12a871d72cGlenn Kasten    // So if we relied on isActive to decide whether to display,
7011295bb4dcff7b29c75cd23746816df12a871d72cGlenn Kasten    // then we might display an obsolete track or omit an active track.
7021295bb4dcff7b29c75cd23746816df12a871d72cGlenn Kasten    // Instead we always display all tracks, with an indication
7031295bb4dcff7b29c75cd23746816df12a871d72cGlenn Kasten    // of whether we think the track is active.
7041295bb4dcff7b29c75cd23746816df12a871d72cGlenn Kasten    uint32_t trackMask = mTrackMask;
7051295bb4dcff7b29c75cd23746816df12a871d72cGlenn Kasten    fdprintf(fd, "Fast tracks: kMaxFastTracks=%u activeMask=%#x\n",
7061295bb4dcff7b29c75cd23746816df12a871d72cGlenn Kasten            FastMixerState::kMaxFastTracks, trackMask);
7071295bb4dcff7b29c75cd23746816df12a871d72cGlenn Kasten    fdprintf(fd, "Index Active Full Partial Empty  Recent Ready\n");
7081295bb4dcff7b29c75cd23746816df12a871d72cGlenn Kasten    for (uint32_t i = 0; i < FastMixerState::kMaxFastTracks; ++i, trackMask >>= 1) {
7091295bb4dcff7b29c75cd23746816df12a871d72cGlenn Kasten        bool isActive = trackMask & 1;
7101295bb4dcff7b29c75cd23746816df12a871d72cGlenn Kasten        const FastTrackDump *ftDump = &mTracks[i];
7111295bb4dcff7b29c75cd23746816df12a871d72cGlenn Kasten        const FastTrackUnderruns& underruns = ftDump->mUnderruns;
7121295bb4dcff7b29c75cd23746816df12a871d72cGlenn Kasten        const char *mostRecent;
7131295bb4dcff7b29c75cd23746816df12a871d72cGlenn Kasten        switch (underruns.mBitFields.mMostRecent) {
7141295bb4dcff7b29c75cd23746816df12a871d72cGlenn Kasten        case UNDERRUN_FULL:
7151295bb4dcff7b29c75cd23746816df12a871d72cGlenn Kasten            mostRecent = "full";
7161295bb4dcff7b29c75cd23746816df12a871d72cGlenn Kasten            break;
7171295bb4dcff7b29c75cd23746816df12a871d72cGlenn Kasten        case UNDERRUN_PARTIAL:
7181295bb4dcff7b29c75cd23746816df12a871d72cGlenn Kasten            mostRecent = "partial";
7191295bb4dcff7b29c75cd23746816df12a871d72cGlenn Kasten            break;
7201295bb4dcff7b29c75cd23746816df12a871d72cGlenn Kasten        case UNDERRUN_EMPTY:
7211295bb4dcff7b29c75cd23746816df12a871d72cGlenn Kasten            mostRecent = "empty";
7221295bb4dcff7b29c75cd23746816df12a871d72cGlenn Kasten            break;
7231295bb4dcff7b29c75cd23746816df12a871d72cGlenn Kasten        default:
7241295bb4dcff7b29c75cd23746816df12a871d72cGlenn Kasten            mostRecent = "?";
7251295bb4dcff7b29c75cd23746816df12a871d72cGlenn Kasten            break;
7261295bb4dcff7b29c75cd23746816df12a871d72cGlenn Kasten        }
7271295bb4dcff7b29c75cd23746816df12a871d72cGlenn Kasten        fdprintf(fd, "%5u %6s %4u %7u %5u %7s %5u\n", i, isActive ? "yes" : "no",
7281295bb4dcff7b29c75cd23746816df12a871d72cGlenn Kasten                (underruns.mBitFields.mFull) & UNDERRUN_MASK,
7291295bb4dcff7b29c75cd23746816df12a871d72cGlenn Kasten                (underruns.mBitFields.mPartial) & UNDERRUN_MASK,
7301295bb4dcff7b29c75cd23746816df12a871d72cGlenn Kasten                (underruns.mBitFields.mEmpty) & UNDERRUN_MASK,
7311295bb4dcff7b29c75cd23746816df12a871d72cGlenn Kasten                mostRecent, ftDump->mFramesReady);
7321295bb4dcff7b29c75cd23746816df12a871d72cGlenn Kasten    }
73397b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten}
73497b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten
73597b5d0d5b5ef766eb5dd680d05a5d199662d4ae0Glenn Kasten}   // namespace android
736