[Icecast] I can't find what's causing this warning?

Tue Jan 1 13:25:12 UTC 2019

Hi,

On 1/1/19 1:16 PM, Robert Chalmers wrote:
> This is also on Stack Overflow…

Yes and I am providing the answer in both places for the benefit of the
next person looking into it.
https://stackoverflow.com/q/53995268/2648865

> It’s odd, because sometimes it seems to wrk, and sometimes not???
>
> error.log
>
> [2019-01-01  13:08:31] INFO admin/admin_handle_request Bad or missing password on admin command request (command: stats.xml)
> [2019-01-01  13:08:31] INFO admin/admin_handle_request Bad or missing password on admin command request (command: stats.xml)
> [2019-01-01  13:08:31] INFO admin/admin_handle_request Bad or missing password on admin command request (command: stats.xml)
> [2019-01-01  13:08:44] INFO format-vorbis/initial_vorbis_page seen initial vorbis header
> [2019-01-01  13:08:44] INFO admin/admin_handle_request Received admin command metadata on mount "/live.aac"
> [2019-01-01  13:08:44] INFO admin/command_metadata Metadata on mountpoint /live.aac changed to "Kostas Pavlidis - Fake Life"
> [2019-01-01  13:08:44] INFO admin/admin_handle_request Received admin command metadata on mount "/live.mp3"
> [2019-01-01  13:08:44] INFO admin/command_metadata Metadata on mountpoint /live.mp3 changed to "Kostas Pavlidis - Fake Life"
> [2019-01-01  13:09:31] INFO admin/admin_handle_request Bad or missing password on admin command request (command: stats.xml)
> [2019-01-01  13:09:31] INFO admin/admin_handle_request Bad or missing password on admin command request (command: stats.xml)
> [2019-01-01  13:09:31] INFO admin/admin_handle_request Bad or missing password on admin command request (command: stats.xml)
> [2019-01-01  13:10:32] INFO admin/admin_handle_request Bad or missing password on admin command request (command: stats.xml)
> [2019-01-01  13:10:32] INFO admin/admin_handle_request Bad or missing password on admin command request (command: stats.xml)
> [2019-01-01  13:10:32] INFO admin/admin_handle_request Bad or missing password on admin command request (command: stats.xml)
>
>
> access.log
>
> 192.168.0.7 - - [01/Jan/2019:13:08:31 +0000] "GET /admin/stats.xml HTTP/1.1" 401 360 "-" "sambc/2018.10" 0
> 192.168.0.7 - - [01/Jan/2019:13:08:31 +0000] "GET /admin/stats.xml HTTP/1.1" 401 360 "-" "sambc/2018.10" 0
> 192.168.0.7 - - [01/Jan/2019:13:08:31 +0000] "GET /admin/stats.xml HTTP/1.1" 401 360 "-" "sambc/2018.10" 0
> 192.168.0.7 - - [01/Jan/2019:13:08:31 +0000] "GET /admin/stats.xml HTTP/1.0" 200 4439 "-" "sambc/2018.10" 0
> 192.168.0.7 - - [01/Jan/2019:13:08:31 +0000] "GET /admin/stats.xml HTTP/1.0" 200 4439 "-" "sambc/2018.10" 0
> 192.168.0.7 - - [01/Jan/2019:13:08:31 +0000] "GET /admin/stats.xml HTTP/1.0" 200 4439 "-" "sambc/2018.10" 0
> 192.168.0.7 - - [01/Jan/2019:13:08:44 +0000] "GET /admin/metadata HTTP/1.0" 200 396 "-" "sambc/2018.10" 0
> 192.168.0.7 - - [01/Jan/2019:13:08:44 +0000] "GET /admin/metadata HTTP/1.0" 200 396 "-" "sambc/2018.10" 0
> 192.168.0.7 - - [01/Jan/2019:13:09:31 +0000] "GET /admin/stats.xml HTTP/1.1" 401 360 "-" "sambc/2018.10" 0
> 192.168.0.7 - - [01/Jan/2019:13:09:31 +0000] "GET /admin/stats.xml HTTP/1.1" 401 360 "-" "sambc/2018.10" 0
> 192.168.0.7 - - [01/Jan/2019:13:09:31 +0000] "GET /admin/stats.xml HTTP/1.1" 401 360 "-" "sambc/2018.10" 0
> 192.168.0.7 - - [01/Jan/2019:13:09:32 +0000] "GET /admin/stats.xml HTTP/1.0" 200 4415 "-" "sambc/2018.10" 1
> 192.168.0.7 - - [01/Jan/2019:13:09:32 +0000] "GET /admin/stats.xml HTTP/1.0" 200 4415 "-" "sambc/2018.10" 1
> 192.168.0.7 - - [01/Jan/2019:13:09:32 +0000] "GET /admin/stats.xml HTTP/1.0" 200 4415 "-" "sambc/2018.10" 1
> 192.168.0.7 - - [01/Jan/2019:13:10:32 +0000] "GET /admin/stats.xml HTTP/1.1" 401 360 "-" "sambc/2018.10" 0
> 192.168.0.7 - - [01/Jan/2019:13:10:32 +0000] "GET /admin/stats.xml HTTP/1.1" 401 360 "-" "sambc/2018.10" 0
> 192.168.0.7 - - [01/Jan/2019:13:10:32 +0000] "GET /admin/stats.xml HTTP/1.1" 401 360 "-" "sambc/2018.10" 0
> 192.168.0.7 - - [01/Jan/2019:13:10:32 +0000] "GET /admin/stats.xml HTTP/1.0" 200 4415 "-" "sambc/2018.10" 0
> 192.168.0.7 - - [01/Jan/2019:13:10:32 +0000] "GET /admin/stats.xml HTTP/1.0" 200 4415 "-" "sambc/2018.10" 0
> 192.168.0.7 - - [01/Jan/2019:13:10:32 +0000] "GET /admin/stats.xml HTTP/1.0" 200 4415 "-" "sambc/2018.10" 0
> 192.168.0.7 - - [01/Jan/2019:13:11:32 +0000] "GET /admin/stats.xml HTTP/1.1" 401 360 "-" "sambc/2018.10" 0
> 192.168.0.7 - - [01/Jan/2019:13:11:32 +0000] "GET /admin/stats.xml HTTP/1.1" 401 360 "-" "sambc/2018.10" 0
>

>From the combined logs it's pretty obvious what's happening here:

 - You are running at least 3 source clients into mountpoints
 - The source client each time seems to be `sambc/2018.10` (Possibly SAM
Broadcaster?)
 - These source clients are making stream metadata update requests via
`/admin/metadata`
  - For Ogg that's actually a bug and likely the metadata is broken for
listeners.
  - It should embed the metadata inside the stream that it sends to the
server instead!
 - These source clients are each polling statistics via `/admin/stats.xml`
  - For some reason the source client doesn't cache the fact that
authentication is necessary for this URL and follows the 'from zero'
HTTP request procedure every time where
   1. first it doesn't send credentials
   2. It gets refused with a HTTP 401 status
   3. It sends the same request again, but including credentials

Summarizing: *The behaviour you are concerned about is perfectly within
what's defined by the HTTP standards. Icecast is just a bit wordy on
that particular event.*

>
>
>> On 1 Jan 2019, at 12:41, Thomas B. Rücker <thomas at ruecker.fi> wrote:
>>
>> Hi,
>>
>> On 1/1/19 11:53 AM, Robert Chalmers wrote:
>>> I’ve started getting this warning, and nothing I do seems to solve it?
>>>
>>>
>>> INFO admin/admin_handle_request Bad or missing password on admin command request (command: stats.xml)
>>>
>>> It repeats about every 15 secinds?
>>
>> Please provide associated and unaltered lines from access.log for and
>> around the timestamp(s) of this message. Which version of Icecast is this?
>>

Cheers,

TBR