Database Error Authentication

Hello everyone !

I encounter a wierd issue on my manageiq infra, since a few days i have a wierd comportement i can’t produce vm or not correctly, so when i go saw the provider in the UI, the authentication status in error state, so i refreshed it and it’s good again, it’s seems to be only on one provider.

So i go throught logs and saw this errors :

[----] I, [2022-01-05T10:55:25.139458 #6309:2b134385f97c] INFO – automation: MiqAeEvent.build_evm_event >> event=<“login_failed”>
inputs=<{:event=>“authenticate_database”, :userid=>"", :message=>"Authentication failed for userid ",
“MiqEvent::miq_event”=>99000001731477, :miq_event_id=>99000001731477,
“EventStream::event_stream”=>99000001731477, :event_stream_id=>99000001731477}>

[----] I, [2022-01-05T10:20:50.707479 #2713:2ae5ea88796c] INFO – automation: MiqAeEvent.build_evm_event >>
event=<“ems_auth_error”> inputs=<{“MiqEvent::miq_event”=>99000001731438, :miq_event_id=>99000001731438,
“EventStream::event_stream”=>99000001731438, :event_stream_id=>99000001731438}>

[----] I, [2022-01-05T10:20:50.876318 #2713:2ae5ea88796c] INFO – automation: MiqAeEvent.build_evm_event >>
event=<“ems_auth_unreachable”> inputs=<{“MiqEvent::miq_event”=>99000001731439, :miq_event_id=>99000001731439,
“EventStream::event_stream”=>99000001731439, :event_stream_id=>99000001731439}>

I suppose it’s related with my error, so i checked if a number of simultaneous connection on same user is set but its limitless but i don’t know, with my manage iq worker’s can’t speak with the database sometimes so can’t speak with the provider but i’m lost because why i can produce VM on my other VMware provider

I tried to delete it and add it again but without success, it still in error

I search in pg log and i can’t found error of authentication but i find some very short connections but i don’t think it’s an issue :

2022-01-05 10:01:02 EST:0.0.0.0(52560):61d5b2ae.29221f:[unknown]@[unknown]:[2695711]:LOG: connection received: host=0.0.0.0 port=52560
2022-01-05 10:01:02 EST:0.0.0.0(52560):61d5b2ae.29221f:root@vmdb_production:[2695711]:LOG: connection authorized: user=root database=vmdb_production SSL enabled (protocol=TLSv1.3, cipher=TLS_AES_256_GCM_SHA384, compression=off)
2022-01-05 10:01:02 EST:0.0.0.0(52560):61d5b2ae.29221f:root@vmdb_production:[2695711]:LOG: disconnection: session time: 0:00:00.035 user=root database=vmdb_production host=0.0.0.0 port=52560

The only things i do on my appliance its to restart and clean logs. I already do that many times without impacts. I can only work on this the next monday, i will try to redo the link with v2_key and fix_auth but i don’t belive it’s the solution…

@agrare Have you seen anything like this before?

1 Like

Hm no I haven’t, I think you need to find the log line from the appliance where it runs the provider authentication check and find that error that should help us see what failed.

If it couldn’t connect to the database I’d expect the worker to fail and exit not mark the auth invalid so might be two different issues? Unless it is intermittent

1 Like

Hi @agrare , @Fryguy

Thanks for your time !

I already delete and re add the provider but nothing happen, if i remenber well i can’t this more information in logs i search in a lots of files without success, i find only thoses types of error without more information. If you know which log file i need to inspect ?

There is the weird problem, i saw some connections from all my server/ui in my pg logs, i saw only this error in automation.log

When i try to validate the connection in the ui, it’s a succes but once it’s done i got thoses error and the authentication status is in error or unknow