Magnolia recording failed no login form found

Failed to reauth starting a recording at 12:20pm.
I was able to reauth the channel later by viewing the channel live.
Appears it was a timout?

Logs have been submitted as a7df2777-362d-47d7-8094-59fffc1316bd.

Another one. Same channel, different server.

This channel requires reauth every 7-8 days.
Recording job started at 9:57am.
After retries it eventually authenticated and started recording 3.5 minutes later.

Logs have been submitted as 63db3f04-2b19-45fe-bd17-51a5ba302ae2.

Don't know if it's related, but my other server was getting the following error today trying to view any Discovery Network channel.
[TVE] action=science_stream error={[{access.denied.missingpackage 403}] {{[]}}}

I linked provider at the science channel website with no problem and played a few channels (Science, Magnolia, Discover). While they were playing I was then able to watch then in Channels DVR.
I then unlinked provider at the science channel website and Channels DVR continues to work.

1 Like

Happened again today. Same as first post.
Magnolia channel failed to authenticate for scheduled recording

Not sure if this timeout is the reason [TVE] action=wait_for_interstitial err=timeout

2022/12/05 12:20:01.003218 [DVR] Starting job 1670271601-ch6108 Canary DIY Recording on ch=[6108]
2022/12/05 12:21:09.250525 [TVE] action=wait_for_interstitial err=timeout
2022/12/05 12:21:14.844752 [ERR] Failed to start stream on channel 6108 via TVE-Comcast_SSO: TVE: "no login form found"

I was able to get it authenticated by playing the channel live in the web UI
See 12:51:08 - 12:52:23 in log

Diagnostics submitted c1c9faa8-1731-4115-ada8-cb11543f385b

Version 2022.12.22.0210
Failed again with invalid token
The activation code has expired
Log entries

2022/12/22 13:20:01.000790 [DVR] Starting job 1671744001-ch6108 Canary DIY Recording on ch=[6108]
2022/12/22 13:20:01.424031 [TVE] action=science_stream error={[{invalid.token Token invalid 400}] {{[]}}}
2022/12/22 13:20:01.520561 [ERR] Failed to start stream on channel 6108 via TVE-Comcast_SSO: TVE: 400: Token invalid (invalid.token)
2022/12/22 13:20:59.244974 [TVE] action=wait_for_interstitial err=timeout
2022/12/22 13:20:59.797010 [TVE] action=screenshot size=9512
2022/12/22 13:21:02.173930 [ERR] Failed to start stream on channel 6108 via TVE-Comcast_SSO: TVE: "no login form found"

Logs have been submitted as 90feda0a-9f72-4343-97b0-b14fdfe882a3

Version 2023.01.06.2203
Failed again

2023/01/08 13:20:00.004150 [DVR] Starting job 1673212800-ch6108 Canary DIY Recording on ch=[6108]
2023/01/08 13:20:01.181724 [TVE] action=version product=Chrome/97.0.4692.56 jsVersion=9.7.106.18 protocol=1.3 revision=@04da6c66398ca50e603cc236a07dc7dfd3bbc750
2023/01/08 13:20:10.401888 [TVE] action=wait_for_interstitial timeout=12s
2023/01/08 13:21:01.922931 [TVE] action=wait_for_interstitial err=timeout
2023/01/08 13:21:02.838261 [TVE] action=fill_form u=xxxxxxx
2023/01/08 13:21:03.009123 [TVE] action=screenshot size=9512
2023/01/08 13:21:05.687079 [ERR] Failed to start stream on channel 6108 via TVE-Comcast_SSO: TVE: "no login form found"

Logs have been submitted as 38ed3978-de74-406b-b4ad-6e3a0052be2f

I was able to re-auth just by playing the channel live from the web UI player.

I run this every Sunday morning to reset my password to original password and then run a scan. Haven't had many authentication issues and if there is a problem I can easily tell by the amount of channels.

curl -XPUT --data-binary '{"pass":"yourpassword"}' http://10.0.0.2:8089/providers/tve/mvpds/Comcast_SSO/credentials && curl -XPOST http://10.0.0.2:8089/providers/tve/mvpds/Comcast_SSO/scan?all=true
1 Like

I don't clear the chromedata folder anymore since Aman made some auth changes last year.
Like I said, just viewing the channel live reauthed it.
Seems to be an issue with the 12 second interstitial timeout.

When I read last year, I said to myself that is a long time ago looked at my Calendar and last year was only 9 days ago lol.

Since this one, specifically.

Version 2023.01.17.0457 Synology Package install w/Chrome v97
Failed again recording from Magnolia with no login form found, screenshot "The activation code has expired."

2023/01/18 13:20:13.034152 [TVE] action=wait_for_interstitial timeout=12s
2023/01/18 13:21:04.912083 [TVE] action=wait_for_interstitial err=timeout

2023/01/18 13:20:01.002499 [DVR] Starting job 1674076801-ch6108 Canary DIY Recording on ch=[6108]
2023/01/18 13:20:01.437737 [TVE] action=science_stream error={[{invalid.token Token invalid 400}] {{[]}}}
2023/01/18 13:20:01.550647 [ERR] Failed to start stream on channel 6108 via TVE-Comcast_SSO: TVE: 400: Token invalid (invalid.token)
2023/01/18 13:20:01.550736 [DVR] Error running job 1674076801-ch6108 Canary DIY Recording: could not start stream on channels=[6108]: TVE: 400: Token invalid (invalid.token)
2023/01/18 13:20:02.558208 [DVR] Starting job 1674076801-ch6108 Canary DIY Recording on ch=[6108]
2023/01/18 13:20:03.761443 [TVE] action=version product=Chrome/97.0.4692.56 jsVersion=9.7.106.18 protocol=1.3 revision=@04da6c66398ca50e603cc236a07dc7dfd3bbc750
2023/01/18 13:20:03.786322 [TVE] action=navigate url=https://auth.sciencechannel.com/login-affiliates?returnUrl=https://www.sciencechannel.com/live-now&hostUrl=us1-prod-direct.sciencechannel.com
2023/01/18 13:20:03.789293 [TVE] action=request type=Document method=GET url=https://auth.sciencechannel.com/login-affiliates
2023/01/18 13:20:03.789335 [TVE] action=auth_domain domain=auth.sciencechannel.com
2023/01/18 13:20:03.789342 [TVE] action=scienceauth reason=login
2023/01/18 13:20:04.443898 [TVE] action=wait_for_page
2023/01/18 13:20:11.001702 [TVE] action=page_ready
2023/01/18 13:20:11.001761 [TVE] action=wait_for_page done=true reason=page_ready
2023/01/18 13:20:13.002081 [TVE] action=click_interstitial
2023/01/18 13:20:13.006387 [TVE] action=tvejs msg="scienceSelect: path=/login-affiliates"
2023/01/18 13:20:13.034152 [TVE] action=wait_for_interstitial timeout=12s
2023/01/18 13:20:15.181475 [TVE] action=request type=Document method=GET url=https://api.auth.adobe.com/api/v1/authenticate
2023/01/18 13:20:15.470666 [TVE] action=request type=Document method=GET url=https://sp.auth.adobe.com/api/v1/authenticate
2023/01/18 13:20:15.834852 [TVE] action=request type=Document method=GET url=https://oauth.xfinity.com/oauth/authorize
2023/01/18 13:20:16.264104 [TVE] action=request type=Document method=GET url=https://login.xfinity.com/login
2023/01/18 13:20:20.845866 [TVE] action=error_response type=Image error=net::ERR_NAME_NOT_RESOLVED url=https://match.rundsp.com/match.gif 
2023/01/18 13:20:21.748895 [TVE] action=request type=Document method=GET url=https://oauth.xfinity.com/oauth/authorize
2023/01/18 13:20:22.049180 [TVE] action=request type=Document method=GET url=https://sp.auth.adobe.com/adobe-services/oauth2
2023/01/18 13:20:28.235197 [TVE] action=request type=Document method=GET url=https://us1-prod.disco-api.com/v1/gauth/callback/ca81d4b4-25bd-46b0-8544-81c107b81933
2023/01/18 13:20:28.636768 [TVE] action=request type=Document method=GET url=https://auth.sciencechannel.com/gauth-sync
2023/01/18 13:20:28.636809 [TVE] action=scienceauth reason=sync
2023/01/18 13:20:34.910773 [TVE] action=page_ready
2023/01/18 13:20:34.910842 [TVE] action=scienceauth event=page_ready
2023/01/18 13:21:04.912083 [TVE] action=wait_for_interstitial err=timeout
2023/01/18 13:21:06.150360 [TVE] action=fill_form u=username
2023/01/18 13:21:06.155758 [TVE] action=screenshot
2023/01/18 13:21:06.331681 [TVE] action=screenshot size=9512
2023/01/18 13:21:06.345983 [TVE] action=capture_html
2023/01/18 13:21:06.361194 [TVE] action=capture_html size=187940
2023/01/18 13:21:06.361967 [TVE] action=script_error err=&runtime.ExceptionDetails{ExceptionID:1, Text:"Uncaught", LineNumber:9, ColumnNumber:5, ScriptID:"83", URL:"", StackTrace:(*runtime.StackTrace)(0xc00003e400), Exception:(*runtime.RemoteObject)(0xc000f47830), ExecutionContextID:0, ExceptionMetaData:easyjson.RawMessage(nil)} exp=&runtime.RemoteObject{Type:"string", Subtype:"", ClassName:"", Value:easyjson.RawMessage{0x22, 0x6e, 0x6f, 0x20, 0x6c, 0x6f, 0x67, 0x69, 0x6e, 0x20, 0x66, 0x6f, 0x72, 0x6d, 0x20, 0x66, 0x6f, 0x75, 0x6e, 0x64, 0x22}, UnserializableValue:"", Description:"", WebDriverValue:(*runtime.WebDriverValue)(nil), ObjectID:"", Preview:(*runtime.ObjectPreview)(nil), CustomPreview:(*runtime.CustomPreview)(nil)} exp_value="no login form found"
2023/01/18 13:21:08.668945 [ERR] Failed to start stream on channel 6108 via TVE-Comcast_SSO: TVE: "no login form found"
2023/01/18 13:21:08.669022 [DVR] Error running job 1674076801-ch6108 Canary DIY Recording: could not start stream on channels=[6108]: TVE: "no login form found"

Logs have been submitted as 49653f51-927e-4f20-af03-063716370400
I was able to re-auth just by playing the channel live from the web UI player.

Hopefully the last post I make in this topic.
Since I can't update headless-chrome past v97 on my Synology Package installs, I've migrated them to TVE docker containers using the same record path and port after stopping the Synology Packages.
Since the new :tve docker container uses chrome v109, I'm hoping this solves my problem.
I should know next time Channels DVR needs to re-auth Magnolia, which should be Jan. 25 and Jan 29 for my two servers using TVE sources. :crossed_fingers:

1 Like

Failed recording due to auth again

2023/02/18 13:20:00.003674 [DVR] Starting job 1676755200-ch6108 Canary DIY Recording on ch=[6108]
2023/02/18 13:20:45.285810 [TVE] action=screenshot size=28355
2023/02/18 13:20:45.303150 [TVE] action=capture_html size=54745
2023/02/18 13:20:45.318693 [TVE] action=script_error err=&runtime.ExceptionDetails{ExceptionID:1, Text:"Uncaught", LineNumber:9, ColumnNumber:5, ScriptID:"166", URL:"", StackTrace:(*runtime.StackTrace)(0xc001111f40), Exception:(*runtime.RemoteObject)(0xc000e1c5a0), ExecutionContextID:0, ExceptionMetaData:easyjson.RawMessage(nil)} exp=&runtime.RemoteObject{Type:"string", Subtype:"", ClassName:"", Value:easyjson.RawMessage{0x22, 0x6e, 0x6f, 0x20, 0x6c, 0x6f, 0x67, 0x69, 0x6e, 0x20, 0x66, 0x6f, 0x72, 0x6d, 0x20, 0x66, 0x6f, 0x75, 0x6e, 0x64, 0x22}, UnserializableValue:"", Description:"", WebDriverValue:(*runtime.WebDriverValue)(nil), ObjectID:"", Preview:(*runtime.ObjectPreview)(nil), CustomPreview:(*runtime.CustomPreview)(nil)} exp_value="no login form found"
2023/02/18 13:20:45.582548 [ERR] Failed to start stream on channel 6108 via TVE-Comcast_SSO: TVE: "no login form found"
2023/02/18 13:20:45.582607 [DVR] Error running job 1676755200-ch6108 Canary DIY Recording: could not start stream on channels=[6108]: TVE: "no login form found"

I tuned in live and it reauthed
2023/02/18 13:55:33.858416 [TVE] action=scienceauth done=true
2023/02/18 13:55:33.858462 [TVE] action=authed

Logs have been submitted as 9465a00e-5032-4f04-8a9d-e235d07dec9b

Just an update that since moving my TVE servers to docker containers using chrome v109, no issues.