+ rm -rf '/home/egress/tmp/*'
+ rm -rf /var/run/pulse /var/lib/pulse /home/egress/.config/pulse /home/egress/.cache/xdgr/pulse
+ pulseaudio -D --verbose --exit-idle-time=-1 --disallow-exit
I: [pulseaudio] main.c: Daemon startup successful.
+ exec egress
2025-07-29T18:17:23.506Z	INFO	egress	redis/redis.go:142	connecting to redis	{"nodeID": "NE_F2xjTYpeH279", "clusterID": "", "simple": true, "addr": "gvs-resque-redis-qa.fok9z5.ng.0001.usw2.cache.amazonaws.com:6379"}
2025-07-29T18:17:23.614Z	INFO	egress	stats/monitor.go:134	cpu available: 8.000000 max cost: 4.000000	{"nodeID": "NE_F2xjTYpeH279", "clusterID": ""}
2025-07-29T18:17:23.614Z	DEBUG	egress	server/server.go:140	starting service	{"nodeID": "NE_F2xjTYpeH279", "clusterID": "", "version": "1.8.4"}
2025-07-29T18:17:23.614Z	DEBUG	egress	server/server.go:132	starting template server on address localhost:7980	{"nodeID": "NE_F2xjTYpeH279", "clusterID": ""}
2025-07-29T18:17:23.614Z	INFO	egress	server/server.go:146	service ready	{"nodeID": "NE_F2xjTYpeH279", "clusterID": ""}
2025-07-29T18:18:11.278Z	DEBUG	egress	stats/monitor.go:173	cpu check	{"nodeID": "NE_F2xjTYpeH279", "clusterID": "", "total": 8, "pending": 0, "used": 0, "required": 4, "available": 8, "activeRequests": 0, "canAccept": true}
2025-07-29T18:18:11.781Z	DEBUG	egress	stats/monitor.go:173	cpu check	{"nodeID": "NE_F2xjTYpeH279", "clusterID": "", "total": 8, "pending": 0, "used": 0, "required": 4, "available": 8, "activeRequests": 0, "canAccept": true}
2025-07-29T18:18:11.781Z	INFO	egress	server/server_rpc.go:54	request received	{"nodeID": "NE_F2xjTYpeH279", "clusterID": "", "egressID": "EG_2oW6TARbSyVT"}
2025-07-29T18:18:11.785Z	INFO	egress	server/server_rpc.go:71	request validated	{"nodeID": "NE_F2xjTYpeH279", "clusterID": "", "egressID": "EG_2oW6TARbSyVT", "requestType": "room_composite", "outputType": "file", "room": "tj8n-e2p7", "request": {"RoomComposite":{"room_name":"tj8n-e2p7","layout":"grid","Output":{"File":{"filepath":"tj8n-e2p7.mp4","Output":{"S3":{"access_key":"{access_key}","secret":"{secret}","region":"us-west-1","bucket":"swift-auth-video-qa"}}}},"Options":null,"file_outputs":[{"filepath":"tj8n-e2p7.mp4","Output":{"S3":{"access_key":"{access_key}","secret":"{secret}","region":"us-west-1","bucket":"swift-auth-video-qa"}}}]}}}
2025-07-29T18:18:11.804Z	DEBUG	egress	server/main.go:177	handler launched	{"nodeID": "NE_F2xjTYpeH279", "handlerID": "EGH_5dGAvz7p3uXQ", "clusterID": "", "egressID": "EG_2oW6TARbSyVT"}
2025-07-29T18:18:11.804Z	INFO	egress	redis/redis.go:142	connecting to redis	{"nodeID": "NE_F2xjTYpeH279", "handlerID": "EGH_5dGAvz7p3uXQ", "clusterID": "", "egressID": "EG_2oW6TARbSyVT", "simple": true, "addr": "gvs-resque-redis-qa.fok9z5.ng.0001.usw2.cache.amazonaws.com:6379"}
2025-07-29T18:18:11.809Z	DEBUG	egress	source/web.go:147	creating pulse sink	{"nodeID": "NE_F2xjTYpeH279", "handlerID": "EGH_5dGAvz7p3uXQ", "clusterID": "", "egressID": "EG_2oW6TARbSyVT"}
2025-07-29T18:18:11.813Z	DEBUG	egress	source/web.go:171	creating X display	{"nodeID": "NE_F2xjTYpeH279", "handlerID": "EGH_5dGAvz7p3uXQ", "clusterID": "", "egressID": "EG_2oW6TARbSyVT", "display": ":273789828", "dims": "1280x720x24"}
2025-07-29T18:18:11.814Z	DEBUG	egress	source/web.go:202	launching chrome	{"nodeID": "NE_F2xjTYpeH279", "handlerID": "EGH_5dGAvz7p3uXQ", "clusterID": "", "egressID": "EG_2oW6TARbSyVT", "url": "http://localhost:7980/?layout=grid&token=eyJhbGciOiJIUzI1NiIsInR5cCI6IkpXVCJ9.eyJleHAiOjE3NTM4OTk0OTEsImlzcyI6IkFQSTZTbTluVXRDRThkSiIsImtpbmQiOiJlZ3Jlc3MiLCJuYmYiOjE3NTM4MTMwOTEsInN1YiI6IkVHXzJvVzZUQVJiU3lWVCIsInZpZGVvIjp7ImNhblB1Ymxpc2giOmZhbHNlLCJjYW5QdWJsaXNoRGF0YSI6ZmFsc2UsImNhblN1YnNjcmliZSI6dHJ1ZSwiaGlkZGVuIjp0cnVlLCJyZWNvcmRlciI6dHJ1ZSwicm9vbSI6InRqOG4tZTJwNyIsInJvb21Kb2luIjp0cnVlfX0.e-itKcFCHn8qDBeKM2nqlKBvXaAIXAbtFqxRPi_t7Bk&url=wss%3A%2F%2Flivekit-server-dev-k8s.gtldev.net", "sandbox": false, "insecure": false}
0:00:00.220866856    38 0x563243957040 WARN              cudaloader gstcudaloader.c:169:gst_cuda_load_library: Could not open library libcuda.so.1, libcuda.so.1: cannot open shared object file: No such file or directory
0:00:00.220893789    38 0x563243957040 WARN                 nvcodec plugin.c:94:plugin_init: Failed to load cuda library
0:00:00.257838234    38 0x563243957040 WARN                 default gstsfelement.c:97:gst_sf_create_audio_template_caps: format 0x120000: 'AVR (Audio Visual Research)' is not mapped
0:00:00.257859258    38 0x563243957040 WARN                 default gstsfelement.c:97:gst_sf_create_audio_template_caps: format 0x180000: 'CAF (Apple Core Audio File)' is not mapped
0:00:00.257863831    38 0x563243957040 WARN                 default gstsfelement.c:97:gst_sf_create_audio_template_caps: format 0x100000: 'HTK (HMM Tool Kit)' is not mapped
0:00:00.257872360    38 0x563243957040 WARN                 default gstsfelement.c:97:gst_sf_create_audio_template_caps: format 0xc0000: 'MAT4 (GNU Octave 2.0 / Matlab 4.2)' is not mapped
0:00:00.257877434    38 0x563243957040 WARN                 default gstsfelement.c:97:gst_sf_create_audio_template_caps: format 0xd0000: 'MAT5 (GNU Octave 2.1 / Matlab 5.0)' is not mapped
0:00:00.257882333    38 0x563243957040 WARN                 default gstsfelement.c:97:gst_sf_create_audio_template_caps: format 0x210000: 'MPC (Akai MPC 2k)' is not mapped
0:00:00.257887305    38 0x563243957040 WARN                 default gstsfelement.c:97:gst_sf_create_audio_template_caps: format 0x230000: 'MPEG-1/2 Audio' is not mapped
0:00:00.257892985    38 0x563243957040 WARN                 default gstsfelement.c:97:gst_sf_create_audio_template_caps: format 0xe0000: 'PVF (Portable Voice Format)' is not mapped
0:00:00.257898090    38 0x563243957040 WARN                 default gstsfelement.c:97:gst_sf_create_audio_template_caps: format 0x160000: 'SD2 (Sound Designer II)' is not mapped
0:00:00.257904918    38 0x563243957040 WARN                 default gstsfelement.c:97:gst_sf_create_audio_template_caps: format 0x190000: 'WVE (Psion Series 3)' is not mapped
2025-07-29T18:18:12.885Z	DEBUG	egress	uploader/s3.go:79	setting S3 config	{"nodeID": "NE_F2xjTYpeH279", "handlerID": "EGH_5dGAvz7p3uXQ", "clusterID": "", "egressID": "EG_2oW6TARbSyVT", "maxRetries": 3, "maxDelay": "5s", "minDelay": "100ms"}
2025-07-29T18:18:12.891Z	DEBUG	egress	gstreamer/bin.go:66	adding src audio to pipeline	{"nodeID": "NE_F2xjTYpeH279", "handlerID": "EGH_5dGAvz7p3uXQ", "clusterID": "", "egressID": "EG_2oW6TARbSyVT"}
2025-07-29T18:18:12.893Z	DEBUG	egress	gstreamer/bin.go:66	adding src video to pipeline	{"nodeID": "NE_F2xjTYpeH279", "handlerID": "EGH_5dGAvz7p3uXQ", "clusterID": "", "egressID": "EG_2oW6TARbSyVT"}
2025-07-29T18:18:12.895Z	DEBUG	egress	gstreamer/bin.go:72	adding sink file to pipeline	{"nodeID": "NE_F2xjTYpeH279", "handlerID": "EGH_5dGAvz7p3uXQ", "clusterID": "", "egressID": "EG_2oW6TARbSyVT"}
2025-07-29T18:18:12.899Z	DEBUG	egress	pipeline/controller.go:214	waiting for start signal	{"nodeID": "NE_F2xjTYpeH279", "handlerID": "EGH_5dGAvz7p3uXQ", "clusterID": "", "egressID": "EG_2oW6TARbSyVT"}
2025-07-29T18:18:28.180Z	INFO	egress	source/web.go:283	chrome: END_RECORDING	{"nodeID": "NE_F2xjTYpeH279", "handlerID": "EGH_5dGAvz7p3uXQ", "clusterID": "", "egressID": "EG_2oW6TARbSyVT"}
2025-07-29T18:18:28.180Z	DEBUG	egress	pipeline/controller.go:386	sending EOS	{"nodeID": "NE_F2xjTYpeH279", "handlerID": "EGH_5dGAvz7p3uXQ", "clusterID": "", "egressID": "EG_2oW6TARbSyVT"}
2025-07-29T18:18:28.180Z	DEBUG	egress	gstreamer/state.go:75	pipeline state building -> stopping	{"nodeID": "NE_F2xjTYpeH279", "handlerID": "EGH_5dGAvz7p3uXQ", "clusterID": "", "egressID": "EG_2oW6TARbSyVT"}
2025-07-29T18:18:28.180Z	DEBUG	egress	pipeline/controller.go:439	closing source	{"nodeID": "NE_F2xjTYpeH279", "handlerID": "EGH_5dGAvz7p3uXQ", "clusterID": "", "egressID": "EG_2oW6TARbSyVT"}
2025-07-29T18:18:28.180Z	DEBUG	egress	source/web.go:113	closing chrome	{"nodeID": "NE_F2xjTYpeH279", "handlerID": "EGH_5dGAvz7p3uXQ", "clusterID": "", "egressID": "EG_2oW6TARbSyVT"}
2025-07-29T18:18:28.180Z	DEBUG	egress	gstreamer/state.go:75	pipeline state stopping -> finished	{"nodeID": "NE_F2xjTYpeH279", "handlerID": "EGH_5dGAvz7p3uXQ", "clusterID": "", "egressID": "EG_2oW6TARbSyVT"}
2025-07-29T18:18:28.216Z	DEBUG	egress	source/web.go:119	closing X display	{"nodeID": "NE_F2xjTYpeH279", "handlerID": "EGH_5dGAvz7p3uXQ", "clusterID": "", "egressID": "EG_2oW6TARbSyVT"}
2025-07-29T18:18:28.219Z	DEBUG	egress	source/web.go:126	unloading pulse module	{"nodeID": "NE_F2xjTYpeH279", "handlerID": "EGH_5dGAvz7p3uXQ", "clusterID": "", "egressID": "EG_2oW6TARbSyVT"}
2025-07-29T18:18:28.223Z	DEBUG	egress	sink/file.go:73	removing temporary directory	{"nodeID": "NE_F2xjTYpeH279", "handlerID": "EGH_5dGAvz7p3uXQ", "clusterID": "", "egressID": "EG_2oW6TARbSyVT", "path": "/home/egress/tmp/EG_2oW6TARbSyVT/"}
2025-07-29T18:18:28.228Z	INFO	egress	service/io.go:75	egress updated	{"nodeID": "NE_F2xjTYpeH279", "clusterID": "", "egressID": "EG_2oW6TARbSyVT", "requestType": "room_composite", "outputType": "file", "status": "EGRESS_ABORTED"}