[Hero CDMA Android Development] Decad3nce:
<6>[ 512.991912] msm_open_common: open control0
<6>[ 513.116638] adsp: opening module QCAMTASK
<6>[ 513.118316] adsp: module QCAMTASK has been registered
<6>[ 513.118743] adsp: opening module VFETASK
<6>[ 513.174224] adsp: module VFETASK has been registered
<6>[ 513.174682] wait event : 1 timeout:3000
<6>[ 513.374511] s5k3e2fx_ctrl->prev_res:0
<6>[ 513.375488] s5k3e2fx_setting rupdate:0 g_usModuleVersion:0
<6>[ 513.459075] >>>>>>>>(2011-03-24 19:16:44.077006097 UTC)<<<<<<<
<6>[ 513.460052] msm_open_common: open config0
<6>[ 513.470947] s5k3e2fx: otp_vendorid1[0]:0x 1
<6>[ 513.471923] s5k3e2fx: otp_vendorid2[0]:0x 40
<6>[ 513.474243] s5k3e2fx: otp_vendorid3[0]:0x 2
<6>[ 513.488983] s5k3e2fx: otp_vendorid1[1]:0x 0
<6>[ 513.489196] s5k3e2fx: otp_vendorid2[1]:0x 0
<6>[ 513.489501] s5k3e2fx: otp_vendorid3[1]:0x 0
<6>[ 513.497375] s5k3e2fx: otp_fuseid1[0]:0x b8
<6>[ 513.497589] s5k3e2fx: otp_fuseid2[0]:0x 4f
<6>[ 513.497924] s5k3e2fx: otp_fuseid3[0]:0x 25
<6>[ 513.503845] s5k3e2fx: otp_fuseid1[1]:0x 0
<6>[ 513.504333] s5k3e2fx: otp_fuseid2[1]:0x 0
<6>[ 513.505187] s5k3e2fx: otp_fuseid3[1]:0x 0
<6>[ 513.505645] s5k3e2fx: fuse->fuse_id_word1:0
<6>[ 513.506500] s5k3e2fx: fuse->fuse_id_word2:0
<6>[ 513.506988] s5k3e2fx: fuse->fuse_id_word3:0x00014002
<6>[ 513.507446] s5k3e2fx: fuse->fuse_id_word4:0x00b84f25
<6>[ 513.541595] msm_enqueue: queue control new max is 1
<3>[ 513.548980] adsp: module VFETASK not enabled before write
<3>[ 513.550964] adsp: module VFETASK not enabled before write
<3>[ 513.625457] adsp: module VFETASK not enabled before write
<3>[ 513.626098] adsp: module VFETASK not enabled before write
<3>[ 513.656677] adsp: module VFETASK not enabled before write
<3>[ 513.657318] adsp: module VFETASK not enabled before write
<6>[ 513.664245] CFG_SET_MODE
<6>[ 513.664459] s5k3e2fx_setting rupdate:1 g_usModuleVersion:0
<6>[ 513.741851] >>>>>>>>(2011-03-24 19:16:44.359536585 UTC)<<<<<<<
<6>[ 513.743408] msm_open_common: open frame0
<3>[ 513.748321] msm_camera_flash: camera flash is not supported.
<6>[ 513.755371] msm_adsp_enable() 'QCAMTASK'state[0] id[17227151]
<6>[ 513.766052] adsp: rpc event=0, proc_id=2, module=17227151, image=0
<6>[ 513.767028] adsp: module QCAMTASK: READY
<6>[ 513.768920] msm_adsp_enable() 'VFETASK'state[0] id[17227140]
<6>[ 513.771881] adsp: rpc event=0, proc_id=2, module=17227140, image=0
<6>[ 513.774841] adsp: module VFETASK: READY
<6>[ 513.831481] CFG_SET_MODE
<6>[ 513.831695] s5k3e2fx_setting rupdate:1 g_usModuleVersion:0
<6>[ 514.680114] hw3d_open: pid 816 tid 891 opening client node
<6>[ 514.680328] hw3d_open: pid 816 tid 891 got gpu
<4>[ 514.738372] remapping device 1b3
<6>[ 516.499389] msm_open_common: open control0
<6>[ 516.702514] s5k3e2fx_move_focus step_direction_pre_define 20
<6>[ 516.761016] s5k3e2fx_move_focus step_direction_pre_define 20
<6>[ 516.824493] s5k3e2fx_move_focus step_direction_pre_define 20
<6>[ 516.879821] s5k3e2fx_move_focus step_direction_pre_define 20
<6>[ 516.994049] s5k3e2fx_move_focus step_direction_pre_define 20
<6>[ 517.112182] s5k3e2fx_move_focus step_direction_pre_define 20
<6>[ 517.226715] s5k3e2fx_move_focus step_direction_pre_define 20
<6>[ 517.291870] s5k3e2fx_move_focus step_direction_pre_define 20
<6>[ 517.343322] s5k3e2fx_move_focus step_direction_pre_define 20
<6>[ 517.518096] s5k3e2fx_move_focus step_direction_pre_define 20
<6>[ 517.581146] s5k3e2fx_move_focus step_direction_pre_define 20
<6>[ 517.634338] s5k3e2fx_move_focus step_direction_pre_define 20
<6>[ 517.692687] s5k3e2fx_move_focus step_direction_pre_define 20
<6>[ 517.750793] s5k3e2fx_move_focus step_direction_pre_define 20
<6>[ 517.810943] s5k3e2fx_move_focus step_direction_pre_define 20
<6>[ 517.925872] s5k3e2fx_move_focus step_direction_pre_define 20
<6>[ 517.984161] s5k3e2fx_move_focus step_direction_pre_define 20
<6>[ 518.052032] msm_enqueue: queue control new max is 1
<6>[ 518.058288] audio_enable()
<6>[ 518.058532] ++++++++++++++++++++++++++++++
<6>[ 518.058898] AUDIO: AUDIO PLAYBACK START at 514432540256 (2011-03-24 19:16:48.672463414 UTC)
<6>[ 518.061096] audmgr: rpc_reply status 0
<6>[ 518.062164] msm_release_control: control0
<6>[ 518.062469] __msm_release:sync->opencnt:4
<6>[ 518.071105] audmgr: rpc READY handle=0x00000000
<6>[ 518.135101] audmgr: rpc CODEC_CONFIG volume=0x00002000
<6>[ 518.135345] audpp: enable
<6>[ 518.135559] adsp: opening module AUDPPTASK
<6>[ 518.138916] adsp: module AUDPPTASK has been registered
<6>[ 518.139831] msm_adsp_enable() 'AUDPPTASK'state[0] id[17227122]
<6>[ 518.142303] adsp: rpc event=0, proc_id=2, module=17227122, image=0
<6>[ 518.143280] adsp: module AUDPPTASK: READY
<6>[ 518.146667] audpp: ENABLE
<6>[ 518.152465] audio_dsp_event: CFG_MSG ENABLE
<6>[ 518.158020] audpp: module enabled
<6>[ 518.914306] CFG_SET_MODE
<6>[ 518.914886] s5k3e2fx_setting rupdate:1 g_usModuleVersion:0
<6>[ 519.032806] msm_release_frame: frame0
<6>[ 519.033203] __msm_release:sync->opencnt:3
<6>[ 519.033386] release frame atomic_set pmsm->opened as 0
<6>[ 519.111419] CFG_SET_MODE
<6>[ 519.112304] KPI PA: start sensor snapshot config: 3044
<6>[ 519.112487] s5k3e2fx_setting rupdate:1 g_usModuleVersion:0
<6>[ 519.248291] s5k3e2fx_set_pict_exp_gain gain 110 line 2768
<6>[ 519.658782] msm_vfe_sync: sending snapshot to config
<6>[ 519.695343] adsp_open() name = 'LPMTASK'
<6>[ 519.695556] adsp: opening module LPMTASK
<6>[ 519.700653] adsp: module LPMTASK has been registered
<6>[ 519.701019] adsp_open() module 'LPMTASK' adev cc4d1aa8
<6>[ 519.701263] adsp_pmem_add module LPMTASK vaddr:0x4130c000 paddr:0x27000000 len:7372800
<6>[ 519.701629] adsp_pmem_add module LPMTASK vaddr:0x4110c000 paddr:0x26a00000 len:1048576
<6>[ 519.703216] adsp_pmem_add module LPMTASK vaddr:0x4221c000 paddr:0x26b00000 len:1048576
<6>[ 519.703582] msm_adsp_enable() 'LPMTASK'state[0] id[17227150]
<6>[ 519.705200] adsp: rpc event=0, proc_id=2, module=17227150, image=0
<6>[ 519.706909] adsp: module LPMTASK: READY
<6>[ 521.092437] msm_adsp_disable() 'LPMTASK'
<6>[ 521.095764] adsp_pmem_del name LPMTASK vaddr:0x4221c000 paddr:0x26b00000 len:1048576
<6>[ 521.097564] adsp_pmem_del name LPMTASK vaddr:0x4110c000 paddr:0x26a00000 len:1048576
<6>[ 521.098541] adsp_pmem_del name LPMTASK vaddr:0x4130c000 paddr:0x27000000 len:7372800
<6>[ 521.101593] adsp: closing module LPMTASK
<6>[ 521.103179] msm_pp_release: delivering pp_snap
<6>[ 521.105163] msm_get_pic: flushing cache for [d1000000, d1708000)
<6>[ 521.205383] pmem: request for physical address of pmem region from process 895.
<6>[ 521.205780] pmem: request for physical address of pmem region from process 895.
<6>[ 521.207672] adsp_open() name = 'JPEGTASK'
<6>[ 521.207885] adsp: opening module JPEGTASK
<6>[ 521.211578] adsp: module JPEGTASK has been registered
<6>[ 521.211975] adsp_open() module 'JPEGTASK' adev cc4d1b04
<6>[ 521.212188] msm_adsp_enable() 'JPEGTASK'state[0] id[17227149]
<6>[ 521.213775] adsp: rpc event=0, proc_id=2, module=17227149, image=0
<6>[ 521.214263] adsp: module JPEGTASK: READY
<6>[ 521.215972] pmem: request for physical address of pmem region from process 895.
<6>[ 521.216552] pmem: request for physical address of pmem region from process 895.
<6>[ 521.216918] adsp_pmem_add module JPEGTASK vaddr:0x40cd8000 paddr:0x26812000 len:4096
<6>[ 521.217346] adsp_pmem_add module JPEGTASK vaddr:0x40cdc000 paddr:0x26813000 len:4096
<6>[ 521.217681] adsp_pmem_add module JPEGTASK vaddr:0x40c24000 paddr:0x26880000 len:524288
<6>[ 521.246826] adsp_pmem_del name JPEGTASK vaddr:0x40c24000 paddr:0x26880000 len:524288
<6>[ 521.247314] adsp_pmem_del name JPEGTASK vaddr:0x40cdc000 paddr:0x26813000 len:4096
<6>[ 521.247558] adsp_pmem_del name JPEGTASK vaddr:0x40cd8000 paddr:0x26812000 len:4096
<6>[ 521.248046] adsp_pmem_add module JPEGTASK vaddr:0x40cd8000 paddr:0x26812000 len:4096
<6>[ 521.248504] adsp_pmem_add module JPEGTASK vaddr:0x40cdc000 paddr:0x26813000 len:4096
<6>[ 521.251159] adsp_pmem_add module JPEGTASK vaddr:0x4130c000 paddr:0x27000000 len:7372800
<6>[ 522.074859] adsp_pmem_del name JPEGTASK vaddr:0x4130c000 paddr:0x27000000 len:7372800
<6>[ 522.075500] adsp_pmem_del name JPEGTASK vaddr:0x40cdc000 paddr:0x26813000 len:4096
<6>[ 522.075775] adsp_pmem_del name JPEGTASK vaddr:0x40cd8000 paddr:0x26812000 len:4096
<6>[ 522.076385] adsp: closing module JPEGTASK
<6>[ 522.076599] adsp: disabling module JPEGTASK

3 successive shots right now. Change to htc auto white balance + ondemand governor.

I've noticed that ondemand also makes for the most "stable" camera behavior.

I've also noticed that using "Interactive" or "Smartass" causes an issue on successive attempts to shoot a picture

<6>[ 451.685577] s5k3e2fx_set_pict_exp_gain gain 110 line 2768

The driver idles out on gain.

The next shot after that fail:

<3>[ 504.444122] msm_pp_grab: postproc 1 is already enabled

Timing issue?