Volume turning itself down

[aido]

I'm seeing a really strange one here - the volume on my LX3 seems to turn itself down randomly!

I thought it was the mrs or my little girl turning it down while I was out but I'm just sat watching TV and can see the box appearing.

No remotes will be touched and the volume bar will appear and turn down a notch.

I've taken the batteries out of the remote and it's still doing it!

I'm currently sat here with tail running against the debug log and you see events where the volume turns down.

For example:

<  2190.794> [eInputDeviceInit] 1 72 1
<  2190.795> [InfoBarGenerics] KEY: 114
<  2190.795> [ActionMap] GlobalActions volumeDown
<  2190.795> [eDVBVolumecontrol] Setvolume: raw: 95 95, -1db: 4 4
<  2190.796> [Volume] setValue 95
<  2190.797> [eInputDeviceInit] 0 72 1
<  2190.798> [InfoBarGenerics] KEY: 114
<  2306.433> [eInputDeviceInit] 1 72 1
<  2306.434> [InfoBarGenerics] KEY: 114
<  2306.434> [ActionMap] GlobalActions volumeDown
<  2306.434> [eDVBVolumecontrol] Setvolume: raw: 90 90, -1db: 7 7
<  2306.436> [Volume] setValue 90
<  2306.436> [eInputDeviceInit] 0 72 1
<  2306.437> [InfoBarGenerics] KEY: 114
<  2306.724> [eInputDeviceInit] 1 72 1
<  2306.725> [InfoBarGenerics] KEY: 114
<  2306.726> [ActionMap] GlobalActions volumeDown
<  2306.726> [eDVBVolumecontrol] Setvolume: raw: 85 85, -1db: 10 10
<  2306.727> [Volume] setValue 85
<  2306.774> [eInputDeviceInit] 0 72 1
<  2306.775> [InfoBarGenerics] KEY: 114
<  2948.057> [eInputDeviceInit] 1 72 1
<  2948.058> [InfoBarGenerics] KEY: 114
<  2948.058> [ActionMap] GlobalActions volumeDown
<  2948.058> [eDVBVolumecontrol] Setvolume: raw: 80 80, -1db: 13 13
<  2948.059> [Volume] setValue 80
<  2948.060> [eInputDeviceInit] 0 72 1
<  2948.061> [InfoBarGenerics] KEY: 114
<  3118.657> [eInputDeviceInit] 1 72 1
<  3118.658> [InfoBarGenerics] KEY: 114
<  3118.658> [ActionMap] GlobalActions volumeDown
<  3118.658> [eDVBVolumecontrol] Setvolume: raw: 75 75, -1db: 16 16
<  3118.659> [Volume] setValue 75
<  3118.747> [eInputDeviceInit] 0 72 1
<  3118.748> [InfoBarGenerics] KEY: 114
<  3158.581> [eInputDeviceInit] 1 72 1
<  3158.582> [InfoBarGenerics] KEY: 114
<  3158.582> [ActionMap] GlobalActions volumeDown
<  3158.582> [eDVBVolumecontrol] Setvolume: raw: 70 70, -1db: 19 19
<  3158.583> [Volume] setValue 70
<  3158.701> [eInputDeviceInit] 0 72 1
<  3158.702> [InfoBarGenerics] KEY: 114
<  3286.191> [eInputDeviceInit] 1 72 1
<  3286.192> [InfoBarGenerics] KEY: 114
<  3286.192> [ActionMap] GlobalActions volumeDown
<  3286.192> [eDVBVolumecontrol] Setvolume: raw: 65 65, -1db: 23 23
<  3286.193> [Volume] setValue 65
<  3286.301> [eInputDeviceInit] 0 72 1
<  3286.302> [InfoBarGenerics] KEY: 114
<  3288.061> [eInputDeviceInit] 1 72 1
<  3288.061> [InfoBarGenerics] KEY: 114
<  3288.062> [ActionMap] GlobalActions volumeDown
<  3288.062> [eDVBVolumecontrol] Setvolume: raw: 60 60, -1db: 26 26
<  3288.062> [Volume] setValue 60
<  3288.131> [eInputDeviceInit] 0 72 1
<  3288.131> [InfoBarGenerics] KEY: 114

It's really strange as in there you can see at one point there's an exactly 30 seconds then 40 second gap between the events which would suggest it's not random enough to be a remote misfiring.

No other remotes are being touched when this happens and there are no active OpenWebif connections.

Any ideas please?

I've got a debug log attached below:
Enigma2_debug_2017-05-28_15-43-59.log.zip

[Joe_90]

Are you sure you don't have a port forwarded on your router?

[aido]

Definitely nothing can access from the internet at the moment and it's still happening - it's bonkers!

I can't see a difference in the debug log though from a real remote press to a remote press in OpenWebIf which makes it harder to work out if it's a physical IR command or a command in the box.

Does OpenWebif have a log anywhere I can see if it's being hit that way please?

I've shutdown NCam too just in case that's doing something random (clutching at straws!)

If I can work out port mirroring on the switch I guess I can see if something is sending a command over the network (and if so what!)

[aido]

Actually I'm not thinking straight with my hangover - I've shut the network port down so will see if it happens then as that will prove if it's something external to the box

[Joe_90]

If it's something external then you have a security issue. This can affect more than just your STB, so be cautious with port forwarding. You really need a VPN to handle remote access to the STB.

Or else you have a compatible IR remote somewhere else in the room (under the cushions!) and it's being accidentally pressed.

[birdman]

Do you have any other remote controls (for anything) in the room?

[birdman]

Are you sure you don't have a port forwarded on your router?

From what I can see any volume change via OpenWebIF doesn't report any "[ActionMap] GlobalActions volumeDown" entry. so it won't be that.

[aido]

From what I can see any volume change via OpenWebIF doesn't report any "[ActionMap] GlobalActions volumeDown" entry. so it won't be that.

Thanks, tried again and I'm seeing the line "[eInputDeviceInit] 0 72 1" missing when changing the volume via the OpenWebIf virtual remote - as that is in the log it is suggesting it's coming in the IR sensor. I do seem to get that ActionMap command on this box though when done via the virtual remote.

Silly question but do the IR sensors ever go wrong?

I've moved all the remotes out of the room for now to see what happens - this is definitely a random one

[aido]

Bizarrely it seems it could be NCam 1.5 doing this.

It turned out earlier that even though I had stopped it in the softcam manager it was getting restarted again by something when the launch at startup flag was set.

Not sure why it's triggering after a manual stop though but I noticed it was there in the process list.

While NCam was stopped the volume didn't change for over 15 minutes - as soon as NCam was started it changed within seconds!

Looks like it's back to Oscam for now to see if that triggers it

[aido]

Actually no scratch that - NCam has been stopped and I'm watching an IPTV stream and it's done it again multiple times.

It seems it just takes longer to happen when a cam isn't running!

< 8708.058> [eDVBVolumecontrol] Setvolume: raw: 100 100, -1db: 0 0
< 8708.058> [Volume] setValue 100
< 9747.509> [eDVBVolumecontrol] Setvolume: raw: 95 95, -1db: 4 4
< 9747.510> [Volume] setValue 95
< 9879.337> [eDVBVolumecontrol] Setvolume: raw: 90 90, -1db: 7 7
< 9879.338> [Volume] setValue 90
< 10029.057> [eDVBVolumecontrol] Setvolume: raw: 85 85, -1db: 10 10
< 10029.058> [Volume] setValue 85

Is anyone else here using the Xtream Player IPTV plugin? Wondering if maybe it's that doing something weird.

I'm leaving the box on BBC 1 HD for the night with nothing running to see how that goes - if it's still doing it overnight I guess I'll try a fresh build to see if it does the same thing.

[birdman]

I do seem to get that ActionMap command on this box though when done via the virtual remote.

Ah, yes - so do I. I was just using the volume slider rather than the virtual remote.

[aido]

OK so the box has been sat all night with no plugins running, no cams and tuned to BBC One HD and it's slowly turned the volume off overnight!

There were no batteries in the remote either - this is bonkers

The box is running 5.0.016 now as it's been updated again after that was fixed and still the same.

< 11351.560> [eDVBVolumecontrol] Setvolume: raw: 100 100, -1db: 0 0
< 11351.561> [Volume] setValue 100
< 11575.173> [eDVBVolumecontrol] Setvolume: raw: 95 95, -1db: 4 4
< 11575.175> [Volume] setValue 95
< 12519.531> [eDVBVolumecontrol] Setvolume: raw: 90 90, -1db: 7 7
< 12519.532> [Volume] setValue 90
< 16110.710> [eDVBVolumecontrol] Setvolume: raw: 85 85, -1db: 10 10
< 16110.711> [Volume] setValue 85
< 18485.367> [eDVBVolumecontrol] Setvolume: raw: 80 80, -1db: 13 13
< 18485.368> [Volume] setValue 80
< 19839.550> [eDVBVolumecontrol] Setvolume: raw: 75 75, -1db: 16 16
< 19839.551> [Volume] setValue 75
< 20561.251> [eDVBVolumecontrol] Setvolume: raw: 70 70, -1db: 19 19
< 20561.252> [Volume] setValue 70
< 22638.401> [eDVBVolumecontrol] Setvolume: raw: 65 65, -1db: 23 23
< 22638.402> [Volume] setValue 65
< 25682.892> [eDVBVolumecontrol] Setvolume: raw: 60 60, -1db: 26 26
< 25682.893> [Volume] setValue 60
< 26043.209> [eDVBVolumecontrol] Setvolume: raw: 55 55, -1db: 29 29
< 26043.210> [Volume] setValue 55
< 26575.775> [eDVBVolumecontrol] Setvolume: raw: 50 50, -1db: 32 32
< 26575.777> [Volume] setValue 50
< 27017.376> [eDVBVolumecontrol] Setvolume: raw: 45 45, -1db: 35 35
< 27017.377> [Volume] setValue 45
< 27129.755> [eDVBVolumecontrol] Setvolume: raw: 40 40, -1db: 38 38
< 27129.756> [Volume] setValue 40
< 27645.781> [eDVBVolumecontrol] Setvolume: raw: 35 35, -1db: 41 41
< 27645.782> [Volume] setValue 35
< 30815.766> [eDVBVolumecontrol] Setvolume: raw: 30 30, -1db: 45 45
< 30815.768> [Volume] setValue 30
< 32115.524> [eDVBVolumecontrol] Setvolume: raw: 25 25, -1db: 48 48
< 32115.525> [Volume] setValue 25
< 33566.751> [eDVBVolumecontrol] Setvolume: raw: 20 20, -1db: 51 51
< 33566.753> [Volume] setValue 20
< 36583.364> [eDVBVolumecontrol] Setvolume: raw: 15 15, -1db: 54 54
< 36583.365> [Volume] setValue 15
< 39063.595> [eDVBVolumecontrol] Setvolume: raw: 10 10, -1db: 57 57
< 39063.596> [Volume] setValue 10
< 43499.145> [eDVBVolumecontrol] Setvolume: raw: 5 5, -1db: 60 60
< 43499.147> [Volume] setValue 5
< 43822.087> [eDVBVolumecontrol] Setvolume: raw: 0 0, -1db: 63 63
< 43822.089> [Volume] setValue 0
< 43822.089> [Volume] setValue 0
< 43905.574> [eDVBVolumecontrol] Setvolume: raw: 0 0, -1db: 63 63
< 43905.576> [Volume] setValue 0
< 43954.864> [eDVBVolumecontrol] Setvolume: raw: 0 0, -1db: 63 63
< 43954.865> [Volume] setValue 0
< 44092.082> [eDVBVolumecontrol] Setvolume: raw: 0 0, -1db: 63 63
< 44092.083> [Volume] setValue 0
< 44411.827> [eDVBVolumecontrol] Setvolume: raw: 0 0, -1db: 63 63
< 44411.829> [Volume] setValue 0
< 44532.149> [eDVBVolumecontrol] Setvolume: raw: 0 0, -1db: 63 63
< 44532.150> [Volume] setValue 0
< 44579.103> [eDVBVolumecontrol] Setvolume: raw: 0 0, -1db: 63 63
< 44579.104> [Volume] setValue 0
< 45444.616> [eDVBVolumecontrol] Setvolume: raw: 0 0, -1db: 63 63
< 45444.617> [Volume] setValue 0
< 47401.432> [eDVBVolumecontrol] Setvolume: raw: 0 0, -1db: 63 63
< 47401.433> [Volume] setValue 0
root@xpeedlx3:/media/hdd/logs#

There's nothing in the logs about it coming from HDMI CEC but I've disabled that now too.

The only bulb in the room is a Philips Hue bulb but isn't turned on (I've seen reference to certain types of light bulbs causing interference in the past).

[aido]

Still doing it with HDMI CEC disabled too (I'm just grep'ing Volume to keep it easier to read for now):

<  1856.058> [eDVBVolumecontrol] Setvolume: raw: 100 100, -1db: 0 0
<  1856.059> [Volume] setValue 100
<  3008.712> [eDVBVolumecontrol] Setvolume: raw: 95 95, -1db: 4 4
<  3008.713> [Volume] setValue 95
<  4766.268> [eDVBVolumecontrol] Setvolume: raw: 90 90, -1db: 7 7
<  4766.269> [Volume] setValue 90

[aido]

Just flashing the latest OpenATV and I'll leave that while we're out to see if it does the same thing on that (just trying to work out if it's the hardware or software causing this)

If it does still do it then I'll RMA the box when WoS reopen as it's still under warranty.

[birdman]

The only bulb in the room is a Philips Hue bulb but isn't turned on (I've seen reference to certain types of light bulbs causing interference in the past).

Put a book (or similar) over the front of the box to shade it from everything.