-
Notifications
You must be signed in to change notification settings - Fork 0
/
Copy pathgst-rtsp-trace.backup
1243 lines (1243 loc) · 203 KB
/
gst-rtsp-trace.backup
1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16
17
18
19
20
21
22
23
24
25
26
27
28
29
30
31
32
33
34
35
36
37
38
39
40
41
42
43
44
45
46
47
48
49
50
51
52
53
54
55
56
57
58
59
60
61
62
63
64
65
66
67
68
69
70
71
72
73
74
75
76
77
78
79
80
81
82
83
84
85
86
87
88
89
90
91
92
93
94
95
96
97
98
99
100
101
102
103
104
105
106
107
108
109
110
111
112
113
114
115
116
117
118
119
120
121
122
123
124
125
126
127
128
129
130
131
132
133
134
135
136
137
138
139
140
141
142
143
144
145
146
147
148
149
150
151
152
153
154
155
156
157
158
159
160
161
162
163
164
165
166
167
168
169
170
171
172
173
174
175
176
177
178
179
180
181
182
183
184
185
186
187
188
189
190
191
192
193
194
195
196
197
198
199
200
201
202
203
204
205
206
207
208
209
210
211
212
213
214
215
216
217
218
219
220
221
222
223
224
225
226
227
228
229
230
231
232
233
234
235
236
237
238
239
240
241
242
243
244
245
246
247
248
249
250
251
252
253
254
255
256
257
258
259
260
261
262
263
264
265
266
267
268
269
270
271
272
273
274
275
276
277
278
279
280
281
282
283
284
285
286
287
288
289
290
291
292
293
294
295
296
297
298
299
300
301
302
303
304
305
306
307
308
309
310
311
312
313
314
315
316
317
318
319
320
321
322
323
324
325
326
327
328
329
330
331
332
333
334
335
336
337
338
339
340
341
342
343
344
345
346
347
348
349
350
351
352
353
354
355
356
357
358
359
360
361
362
363
364
365
366
367
368
369
370
371
372
373
374
375
376
377
378
379
380
381
382
383
384
385
386
387
388
389
390
391
392
393
394
395
396
397
398
399
400
401
402
403
404
405
406
407
408
409
410
411
412
413
414
415
416
417
418
419
420
421
422
423
424
425
426
427
428
429
430
431
432
433
434
435
436
437
438
439
440
441
442
443
444
445
446
447
448
449
450
451
452
453
454
455
456
457
458
459
460
461
462
463
464
465
466
467
468
469
470
471
472
473
474
475
476
477
478
479
480
481
482
483
484
485
486
487
488
489
490
491
492
493
494
495
496
497
498
499
500
501
502
503
504
505
506
507
508
509
510
511
512
513
514
515
516
517
518
519
520
521
522
523
524
525
526
527
528
529
530
531
532
533
534
535
536
537
538
539
540
541
542
543
544
545
546
547
548
549
550
551
552
553
554
555
556
557
558
559
560
561
562
563
564
565
566
567
568
569
570
571
572
573
574
575
576
577
578
579
580
581
582
583
584
585
586
587
588
589
590
591
592
593
594
595
596
597
598
599
600
601
602
603
604
605
606
607
608
609
610
611
612
613
614
615
616
617
618
619
620
621
622
623
624
625
626
627
628
629
630
631
632
633
634
635
636
637
638
639
640
641
642
643
644
645
646
647
648
649
650
651
652
653
654
655
656
657
658
659
660
661
662
663
664
665
666
667
668
669
670
671
672
673
674
675
676
677
678
679
680
681
682
683
684
685
686
687
688
689
690
691
692
693
694
695
696
697
698
699
700
701
702
703
704
705
706
707
708
709
710
711
712
713
714
715
716
717
718
719
720
721
722
723
724
725
726
727
728
729
730
731
732
733
734
735
736
737
738
739
740
741
742
743
744
745
746
747
748
749
750
751
752
753
754
755
756
757
758
759
760
761
762
763
764
765
766
767
768
769
770
771
772
773
774
775
776
777
778
779
780
781
782
783
784
785
786
787
788
789
790
791
792
793
794
795
796
797
798
799
800
801
802
803
804
805
806
807
808
809
810
811
812
813
814
815
816
817
818
819
820
821
822
823
824
825
826
827
828
829
830
831
832
833
834
835
836
837
838
839
840
841
842
843
844
845
846
847
848
849
850
851
852
853
854
855
856
857
858
859
860
861
862
863
864
865
866
867
868
869
870
871
872
873
874
875
876
877
878
879
880
881
882
883
884
885
886
887
888
889
890
891
892
893
894
895
896
897
898
899
900
901
902
903
904
905
906
907
908
909
910
911
912
913
914
915
916
917
918
919
920
921
922
923
924
925
926
927
928
929
930
931
932
933
934
935
936
937
938
939
940
941
942
943
944
945
946
947
948
949
950
951
952
953
954
955
956
957
958
959
960
961
962
963
964
965
966
967
968
969
970
971
972
973
974
975
976
977
978
979
980
981
982
983
984
985
986
987
988
989
990
991
992
993
994
995
996
997
998
999
1000
0:00:00.000586000 9391 0x7fc18d4154c0 INFO GST_INIT gstmessage.c:129:_priv_gst_message_initialize: init messages
0:00:00.001158000 9391 0x7fc18d4154c0 INFO GST_INIT gstcontext.c:86:_priv_gst_context_initialize: init contexts
0:00:00.001337000 9391 0x7fc18d4154c0 INFO GST_PLUGIN_LOADING gstplugin.c:321:_priv_gst_plugin_initialize: registering 0 static plugins
0:00:00.001453000 9391 0x7fc18d4154c0 INFO GST_PLUGIN_LOADING gstplugin.c:229:gst_plugin_register_static: registered static plugin "staticelements"
0:00:00.001471000 9391 0x7fc18d4154c0 INFO GST_PLUGIN_LOADING gstplugin.c:231:gst_plugin_register_static: added static plugin "staticelements", result: 1
0:00:00.001634000 9391 0x7fc18d4154c0 INFO GST_REGISTRY gstregistry.c:1784:ensure_current_registry: reading registry cache: /Users/chris/.cache/gstreamer-1.0/registry.x86_64.bin
0:00:00.016198000 9391 0x7fc18d4154c0 INFO GST_REGISTRY gstregistrybinary.c:683:priv_gst_registry_binary_read_cache: loaded /Users/chris/.cache/gstreamer-1.0/registry.x86_64.bin in 0.014527 seconds
0:00:00.016264000 9391 0x7fc18d4154c0 INFO GST_REGISTRY gstregistry.c:1650:scan_and_update_registry: Validating plugins from registry cache: /Users/chris/.cache/gstreamer-1.0/registry.x86_64.bin
0:00:00.020719000 9391 0x7fc18d4154c0 INFO GST_REGISTRY gstregistry.c:1742:scan_and_update_registry: Registry cache has not changed
0:00:00.020735000 9391 0x7fc18d4154c0 INFO GST_REGISTRY gstregistry.c:1819:ensure_current_registry: registry reading and updating done, result = 1
0:00:00.020746000 9391 0x7fc18d4154c0 INFO GST_INIT gst.c:834:init_post: GLib runtime version: 2.62.6
0:00:00.020755000 9391 0x7fc18d4154c0 INFO GST_INIT gst.c:836:init_post: GLib headers version: 2.62.6
0:00:00.020761000 9391 0x7fc18d4154c0 INFO GST_INIT gst.c:837:init_post: initialized GStreamer successfully
0:00:00.021581000 9391 0x7fc18d4154c0 INFO rtspmountpoints rtsp-mount-points.c:360:gst_rtsp_mount_points_add_factory: adding media factory 0x7fc18d9590e0 for path /test
0:01:32.842991000 9391 0x7fc18d4154c0 INFO rtspclient rtsp-client.c:4657:gst_rtsp_client_set_connection: client 0x7fc18e00e190 connected to server ip 127.0.0.1, ipv6 = 0
0:01:32.843029000 9391 0x7fc18d4154c0 INFO rtspclient rtsp-client.c:4660:gst_rtsp_client_set_connection: added new client 0x7fc18e00e190 ip 127.0.0.1:60219
0:01:32.843101000 9391 0x7fc18d942140 INFO rtspthreadpool rtsp-thread-pool.c:329:do_loop: enter mainloop of thread 0x7fc18e012120
0:01:32.843121000 9391 0x7fc18d4154c0 INFO rtspclient rtsp-client.c:5307:gst_rtsp_client_attach: client 0x7fc18e00e190: attaching to context 0x7fc18d50c730
0:01:32.843702000 9391 0x7fc18d942140 INFO rtspclient rtsp-client.c:3981:handle_request: client 0x7fc18e00e190: received a request OPTIONS rtsp://localhost:8554/test 1.0
0:01:32.844414000 9391 0x7fc18d942140 INFO rtspclient rtsp-client.c:3981:handle_request: client 0x7fc18e00e190: received a request DESCRIBE rtsp://localhost:8554/test 1.0
0:01:32.844454000 9391 0x7fc18d942140 INFO rtspmountpoints rtsp-mount-points.c:305:gst_rtsp_mount_points_match: found media factory 0x7fc18d9590e0 for path /test
0:01:32.844482000 9391 0x7fc18d942140 INFO GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "bin"
0:01:32.844533000 9391 0x7fc18d942140 INFO GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "bin"
0:01:32.847434000 9391 0x7fc18d942140 INFO GST_PLUGIN_LOADING gstplugin.c:984:_priv_gst_plugin_load_file_for_registry: plugin "/Library/Frameworks/GStreamer.framework/Versions/1.0/lib/gstreamer-1.0/libgstplayback.dylib" loaded
0:01:32.847573000 9391 0x7fc18d942140 INFO GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "uridecodebin"
0:01:32.847748000 9391 0x7fc18d942140 INFO GST_PARENTAGE gstbin.c:4378:gst_bin_get_by_name: [GstRTSPMediaFactoryURI]: looking up child element pay0
0:01:32.847767000 9391 0x7fc18d942140 INFO GST_PARENTAGE gstbin.c:4378:gst_bin_get_by_name: [GstRTSPMediaFactoryURI]: looking up child element dynpay0
0:01:32.847779000 9391 0x7fc18d942140 INFO rtspmedia rtsp-media.c:2221:gst_rtsp_media_collect_streams: found dynamic element 0, 0x7fc18e014200
0:01:32.847789000 9391 0x7fc18d942140 INFO GST_PARENTAGE gstbin.c:4378:gst_bin_get_by_name: [GstRTSPMediaFactoryURI]: looking up child element depay0
0:01:32.847800000 9391 0x7fc18d942140 INFO GST_PARENTAGE gstbin.c:4378:gst_bin_get_by_name: [GstRTSPMediaFactoryURI]: looking up child element pay1
0:01:32.847812000 9391 0x7fc18d942140 INFO GST_PARENTAGE gstbin.c:4378:gst_bin_get_by_name: [GstRTSPMediaFactoryURI]: looking up child element dynpay1
0:01:32.847822000 9391 0x7fc18d942140 INFO GST_PARENTAGE gstbin.c:4378:gst_bin_get_by_name: [GstRTSPMediaFactoryURI]: looking up child element depay1
0:01:32.847837000 9391 0x7fc18d942140 INFO GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "pipeline"
0:01:32.847921000 9391 0x7fc18d942140 INFO rtspmediafactory rtsp-media-factory.c:1452:gst_rtsp_media_factory_construct: constructed media 0x7fc17e0181d0 for url /test
0:01:32.847972000 9391 0x7fc18d942140 INFO rtspmedia rtsp-media.c:3883:gst_rtsp_media_prepare: preparing media 0x7fc17e0181d0
0:01:32.847992000 9391 0x7fc18d934a30 INFO rtspthreadpool rtsp-thread-pool.c:329:do_loop: enter mainloop of thread 0x7fc17e011cb0
0:01:32.850764000 9391 0x7fc18d942140 INFO GST_PLUGIN_LOADING gstplugin.c:984:_priv_gst_plugin_load_file_for_registry: plugin "/Library/Frameworks/GStreamer.framework/Versions/1.0/lib/gstreamer-1.0/libgstrtpmanager.dylib" loaded
0:01:32.850785000 9391 0x7fc18d942140 INFO GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "rtpbin"
0:01:32.851173000 9391 0x7fc18d934a30 INFO rtspmedia rtsp-media.c:3721:start_prepare: adding callbacks for dynamic element 0x7fc18e014200
0:01:32.851200000 9391 0x7fc18d934a30 INFO rtspmedia rtsp-media.c:3540:start_preroll: setting pipeline to PAUSED for media 0x7fc17e0181d0
0:01:32.851214000 9391 0x7fc18d934a30 INFO rtspmedia rtsp-media.c:3152:set_target_state: set target state to PAUSED for media 0x7fc17e0181d0
0:01:32.851223000 9391 0x7fc18d934a30 INFO rtspmedia rtsp-media.c:3139:set_state: set state to PAUSED for media 0x7fc17e0181d0
0:01:32.851279000 9391 0x7fc18d934a30 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<rtpbin0> current NULL pending VOID_PENDING, desired next READY
0:01:32.851299000 9391 0x7fc18d934a30 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<rtpbin0> completed state change to READY
0:01:32.851309000 9391 0x7fc18d934a30 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<rtpbin0> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:01:32.851341000 9391 0x7fc18d934a30 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<media-pipeline> child 'rtpbin0' changed state to 2(READY) successfully
0:01:32.851353000 9391 0x7fc18d934a30 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<GstRTSPMediaFactoryURI> current NULL pending VOID_PENDING, desired next READY
0:01:32.851364000 9391 0x7fc18d934a30 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<dynpay0> current NULL pending VOID_PENDING, desired next READY
0:01:32.851375000 9391 0x7fc18d934a30 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<uribin> current NULL pending VOID_PENDING, desired next READY
0:01:32.851388000 9391 0x7fc18d934a30 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<uribin> completed state change to READY
0:01:32.851397000 9391 0x7fc18d934a30 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<uribin> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:01:32.851408000 9391 0x7fc18d934a30 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<dynpay0> child 'uribin' changed state to 2(READY) successfully
0:01:32.851417000 9391 0x7fc18d934a30 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<dynpay0> completed state change to READY
0:01:32.851425000 9391 0x7fc18d934a30 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<dynpay0> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:01:32.851435000 9391 0x7fc18d934a30 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<GstRTSPMediaFactoryURI> child 'dynpay0' changed state to 2(READY) successfully
0:01:32.851444000 9391 0x7fc18d934a30 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<GstRTSPMediaFactoryURI> completed state change to READY
0:01:32.851452000 9391 0x7fc18d934a30 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<GstRTSPMediaFactoryURI> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:01:32.851462000 9391 0x7fc18d934a30 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<media-pipeline> child 'GstRTSPMediaFactoryURI' changed state to 2(READY) successfully
0:01:32.851475000 9391 0x7fc18d934a30 INFO GST_STATES gstelement.c:2782:gst_element_continue_state:<media-pipeline> committing state from NULL to READY, pending PAUSED, next PAUSED
0:01:32.851483000 9391 0x7fc18d934a30 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<media-pipeline> notifying about state-changed NULL to READY (PAUSED pending)
0:01:32.851493000 9391 0x7fc18d934a30 INFO GST_STATES gstelement.c:2789:gst_element_continue_state:<media-pipeline> continue state change READY to PAUSED, final PAUSED
0:01:32.851514000 9391 0x7fc18d934a30 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<rtpbin0> current READY pending VOID_PENDING, desired next PAUSED
0:01:32.851525000 9391 0x7fc18d934a30 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<rtpbin0> completed state change to PAUSED
0:01:32.851533000 9391 0x7fc18d934a30 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<rtpbin0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:01:32.851543000 9391 0x7fc18d934a30 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<media-pipeline> child 'rtpbin0' changed state to 3(PAUSED) successfully
0:01:32.851553000 9391 0x7fc18d934a30 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<GstRTSPMediaFactoryURI> current READY pending VOID_PENDING, desired next PAUSED
0:01:32.851563000 9391 0x7fc18d934a30 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<dynpay0> current READY pending VOID_PENDING, desired next PAUSED
0:01:32.851574000 9391 0x7fc18d934a30 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<uribin> current READY pending VOID_PENDING, desired next PAUSED
0:01:32.854371000 9391 0x7fc18d934a30 INFO GST_PLUGIN_LOADING gstplugin.c:984:_priv_gst_plugin_load_file_for_registry: plugin "/Library/Frameworks/GStreamer.framework/Versions/1.0/lib/gstreamer-1.0/libgstcoreelements.dylib" loaded
0:01:32.854449000 9391 0x7fc18d934a30 INFO GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "filesrc"
0:01:32.854502000 9391 0x7fc18d934a30 INFO GST_ELEMENT_PADS gstelement.c:760:gst_element_add_pad:<GstBaseSrc@0x7fc17e01e1c0> adding pad 'src'
0:01:32.854529000 9391 0x7fc18d934a30 INFO filesrc gstfilesrc.c:237:gst_file_src_set_location: filename : /Users/chris/Qt-6.2/6.2.2/Src/qtmultimedia/tests/auto/integration/qmediaplayerbackend/testdata/test.wav
0:01:32.854538000 9391 0x7fc18d934a30 INFO filesrc gstfilesrc.c:238:gst_file_src_set_location: uri : file:///Users/chris/Qt-6.2/6.2.2/Src/qtmultimedia/tests/auto/integration/qmediaplayerbackend/testdata/test.wav
0:01:32.854564000 9391 0x7fc18d934a30 INFO filesrc gstfilesrc.c:465:gst_file_src_start:<source> opening file /Users/chris/Qt-6.2/6.2.2/Src/qtmultimedia/tests/auto/integration/qmediaplayerbackend/testdata/test.wav
0:01:32.856343000 9391 0x7fc18d934a30 WARN basesrc gstbasesrc.c:3688:gst_base_src_start_complete:<source> pad not activated yet
0:01:32.856409000 9391 0x7fc18d934a30 INFO GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "decodebin"
0:01:32.856534000 9391 0x7fc18d934a30 INFO GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "typefind"
0:01:32.856568000 9391 0x7fc18d934a30 INFO GST_ELEMENT_PADS gstelement.c:760:gst_element_add_pad:<GstTypeFindElement@0x7fc18f822080> adding pad 'sink'
0:01:32.856595000 9391 0x7fc18d934a30 INFO GST_ELEMENT_PADS gstelement.c:760:gst_element_add_pad:<GstTypeFindElement@0x7fc18f822080> adding pad 'src'
0:01:32.856631000 9391 0x7fc18d934a30 INFO GST_ELEMENT_PADS gstelement.c:1017:gst_element_get_static_pad: found pad typefind:sink
0:01:32.856688000 9391 0x7fc18d934a30 INFO GST_PADS gstpad.c:2383:gst_pad_link_prepare: trying to link sink:proxypad0 and typefind:sink
0:01:32.856701000 9391 0x7fc18d934a30 INFO GST_PADS gstpad.c:2591:gst_pad_link_full: linked sink:proxypad0 and typefind:sink, successful
0:01:32.856711000 9391 0x7fc18d934a30 INFO GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:01:32.856734000 9391 0x7fc18d934a30 INFO GST_ELEMENT_PADS gstelement.c:760:gst_element_add_pad:<GstDecodeBin@0x7fc18f81e110> adding pad 'sink'
0:01:32.856803000 9391 0x7fc18d934a30 INFO GST_ELEMENT_PADS gstutils.c:1819:gst_element_link_pads_full: trying to link element source:(any) to element decodebin0:sink
0:01:32.856814000 9391 0x7fc18d934a30 INFO GST_ELEMENT_PADS gstelement.c:1017:gst_element_get_static_pad: found pad decodebin0:sink
0:01:32.856825000 9391 0x7fc18d934a30 INFO GST_PADS gstutils.c:1633:prepare_link_maybe_ghosting: source and decodebin0 in same bin, no need for ghost pads
0:01:32.856837000 9391 0x7fc18d934a30 INFO GST_PADS gstpad.c:2383:gst_pad_link_prepare: trying to link source:src and decodebin0:sink
0:01:32.856861000 9391 0x7fc18d934a30 INFO GST_PADS gstpad.c:2591:gst_pad_link_full: linked source:src and decodebin0:sink, successful
0:01:32.856869000 9391 0x7fc18d934a30 INFO GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:01:32.856878000 9391 0x7fc18d934a30 INFO GST_EVENT gstpad.c:5947:gst_pad_send_event_unchecked:<source:src> Received event on flushing pad. Discarding
0:01:32.856900000 9391 0x7fc18d934a30 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<typefind> current NULL pending VOID_PENDING, desired next READY
0:01:32.856912000 9391 0x7fc18d934a30 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<typefind> completed state change to READY
0:01:32.856921000 9391 0x7fc18d934a30 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<typefind> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:01:32.856934000 9391 0x7fc18d934a30 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<decodebin0> child 'typefind' changed state to 2(READY) successfully
0:01:32.856946000 9391 0x7fc18d934a30 INFO GST_STATES gstelement.c:2782:gst_element_continue_state:<decodebin0> committing state from NULL to READY, pending PAUSED, next PAUSED
0:01:32.856954000 9391 0x7fc18d934a30 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<decodebin0> notifying about state-changed NULL to READY (PAUSED pending)
0:01:32.856965000 9391 0x7fc18d934a30 INFO GST_STATES gstelement.c:2789:gst_element_continue_state:<decodebin0> continue state change READY to PAUSED, final PAUSED
0:01:32.856984000 9391 0x7fc18d934a30 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<typefind> current READY pending VOID_PENDING, desired next PAUSED
0:01:32.856999000 9391 0x7fc18d934a30 INFO filesrc gstfilesrc.c:465:gst_file_src_start:<source> opening file /Users/chris/Qt-6.2/6.2.2/Src/qtmultimedia/tests/auto/integration/qmediaplayerbackend/testdata/test.wav
0:01:32.857047000 9391 0x7fc18d934a30 WARN basesrc gstbasesrc.c:3688:gst_base_src_start_complete:<source> pad not activated yet
0:01:32.857068000 9391 0x7fc18d934a30 INFO filesrc gstfilesrc.c:465:gst_file_src_start:<source> opening file /Users/chris/Qt-6.2/6.2.2/Src/qtmultimedia/tests/auto/integration/qmediaplayerbackend/testdata/test.wav
0:01:32.857111000 9391 0x7fc18d934a30 INFO task gsttask.c:516:gst_task_set_lock: setting stream lock 0x7fc17e022470 on task 0x7fc18f820170
0:01:32.857121000 9391 0x7fc18d934a30 INFO GST_PADS gstpad.c:6291:gst_pad_start_task:<typefind:sink> created task 0x7fc18f820170
0:01:32.857156000 9391 0x7fc18d934a30 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<typefind> completed state change to PAUSED
0:01:32.857167000 9391 0x7fc18d934a30 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<typefind> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:01:32.857182000 9391 0x7fc18d934a30 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<decodebin0> child 'typefind' changed state to 3(PAUSED) successfully
0:01:32.857196000 9391 0x7fc18d934a30 INFO GST_STATES gstelement.c:2782:gst_element_continue_state:<source> committing state from NULL to READY, pending PAUSED, next PAUSED
0:01:32.857205000 9391 0x7fc18d934a30 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<source> notifying about state-changed NULL to READY (PAUSED pending)
0:01:32.857211000 9391 0x7fc17e012000 INFO GST_ELEMENT_PADS gstelement.c:1017:gst_element_get_static_pad: found pad typefind:sink
0:01:32.857217000 9391 0x7fc18d934a30 INFO GST_STATES gstelement.c:2789:gst_element_continue_state:<source> continue state change READY to PAUSED, final PAUSED
0:01:32.857244000 9391 0x7fc18d934a30 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<source> completed state change to PAUSED
0:01:32.857253000 9391 0x7fc18d934a30 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<source> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:01:32.857264000 9391 0x7fc18d934a30 INFO GST_STATES gstbin.c:2937:gst_bin_change_state_func:<dynpay0> child 'uribin' is changing state asynchronously to PAUSED
0:01:32.857275000 9391 0x7fc18d934a30 INFO GST_STATES gstbin.c:2937:gst_bin_change_state_func:<GstRTSPMediaFactoryURI> child 'dynpay0' is changing state asynchronously to PAUSED
0:01:32.857285000 9391 0x7fc18d934a30 INFO GST_STATES gstbin.c:2937:gst_bin_change_state_func:<media-pipeline> child 'GstRTSPMediaFactoryURI' is changing state asynchronously to PAUSED
0:01:32.857295000 9391 0x7fc18d934a30 INFO pipeline gstpipeline.c:534:gst_pipeline_change_state:<media-pipeline> pipeline is not live
0:01:32.857304000 9391 0x7fc18d934a30 INFO rtspmedia rtsp-media.c:3551:start_preroll: ASYNC state change for media 0x7fc17e0181d0
0:01:32.859209000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for video/x-msvideo
0:01:32.859242000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/qcelp
0:01:32.859272000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for video/x-cdxa
0:01:32.859288000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/riff-midi
0:01:32.859306000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/x-wav
0:01:32.859322000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for image/webp
0:01:32.859338000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for video/x-ms-asf
0:01:32.859353000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for video/x-vcd
0:01:32.859369000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/x-imelody
0:01:32.859389000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/x-scc
0:01:32.859403000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/vnd.rn-realmedia
0:01:32.859434000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/x-pn-realaudio
0:01:32.859449000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for video/x-flv
0:01:32.859463000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/x-nist
0:01:32.859477000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/x-voc
0:01:32.859494000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/x-w64
0:01:32.859508000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/x-rf64
0:01:32.859523000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for image/gif
0:01:32.859537000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for image/png
0:01:32.859551000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for video/x-mve
0:01:32.859564000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/x-amr-nb-sh
0:01:32.859578000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/x-amr-wb-sh
0:01:32.859592000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/x-sid
0:01:32.859615000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for image/x-xcf
0:01:32.859628000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for video/x-mng
0:01:32.859641000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for image/x-jng
0:01:32.859655000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for image/x-xpixmap
0:01:32.859669000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for image/x-sun-raster
0:01:32.859682000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/x-bzip
0:01:32.859698000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/x-gzip
0:01:32.859711000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/zip
0:01:32.859724000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/x-compress
0:01:32.859737000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/x-executable
0:01:32.859750000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/x-spc
0:01:32.859766000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/x-caf
0:01:32.859780000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/x-rar
0:01:32.859796000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/x-nsf
0:01:32.859809000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/x-gym
0:01:32.859822000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/x-ay
0:01:32.859835000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/x-gbs
0:01:32.859852000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/x-vgm
0:01:32.859866000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/x-sap
0:01:32.859879000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for video/x-ivf
0:01:32.859892000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/x-kss
0:01:32.859906000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/pdf
0:01:32.859931000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/msword
0:01:32.859945000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/octet-stream
0:01:32.859958000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for image/vnd.adobe.photoshop
0:01:32.859971000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/x-xi
0:01:32.859984000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/x-tap-dmp
0:01:32.860007000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/x-musepack
0:01:32.860023000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/x-au
0:01:32.860036000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/x-mcc
0:01:32.860050000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/midi
0:01:32.860063000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/mobile-xmf
0:01:32.860077000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for video/x-fli
0:01:32.860091000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/x-id3v2
0:01:32.860104000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/x-id3v1
0:01:32.860118000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/x-apetag
0:01:32.860133000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/x-ttafile
0:01:32.860148000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/x-mod
0:01:32.860171000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/mpeg
0:01:32.860187000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/x-ac3
0:01:32.860203000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/x-dts
0:01:32.860219000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/x-gsm
0:01:32.860235000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for video/mpeg-sys
0:01:32.860262000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for video/mpegts
0:01:32.860280000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/ogg
0:01:32.860296000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for video/mpeg-elementary
0:01:32.860309000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for video/mpeg4
0:01:32.860328000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for video/x-h263
0:01:32.860363000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for video/x-h264
0:01:32.860397000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for video/x-h265
0:01:32.860412000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for video/x-nuv
0:01:32.860427000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/x-m4a
0:01:32.860440000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/x-3gp
0:01:32.860457000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for video/quicktime
0:01:32.860471000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for image/x-quicktime
0:01:32.860487000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for image/jp2
0:01:32.860501000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for image/x-jpc
0:01:32.860514000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for video/mj2
0:01:32.860527000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for text/html
0:01:32.860542000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/x-shockwave-flash
0:01:32.860558000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/xges
0:01:32.860571000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/vnd.apple-xmeml+xml
0:01:32.860585000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/vnd.apple-fcp+xml
0:01:32.860600000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/vnd.pixar.opentimelineio+json
0:01:32.860622000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/dash+xml
0:01:32.860636000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/vnd.ms-sstr+xml
0:01:32.860649000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for text/plain
0:01:32.860663000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for text/utf-16
0:01:32.860676000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for text/utf-32
0:01:32.860691000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for text/uri-list
0:01:32.860705000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/itc
0:01:32.860720000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/x-hls
0:01:32.860733000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/sdp
0:01:32.860748000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/smil
0:01:32.860761000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/ttml+xml
0:01:32.860775000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/xml
0:01:32.860791000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/x-aiff
0:01:32.860805000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/x-svx
0:01:32.860818000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/x-paris
0:01:32.860834000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/x-sds
0:01:32.860847000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/x-ircam
0:01:32.860863000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/x-shorten
0:01:32.860877000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/x-ape
0:01:32.860891000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for image/jpeg
0:01:32.860906000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for image/bmp
0:01:32.860921000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for image/tiff
0:01:32.860944000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for image/x-exr
0:01:32.860960000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for image/x-portable-pixmap
0:01:32.860976000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for video/x-matroska
0:01:32.860990000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/mxf
0:01:32.861004000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for video/x-dv
0:01:32.861017000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/iLBC-sh
0:01:32.861031000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/x-sbc
0:01:32.861042000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for subtitle/x-kate
0:01:32.861056000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/x-subtitle-vtt
0:01:32.861071000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/x-flac
0:01:32.861086000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/x-vorbis
0:01:32.861101000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for video/x-theora
0:01:32.861115000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/x-ogm-video
0:01:32.861128000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/x-ogm-audio
0:01:32.861143000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/x-ogm-text
0:01:32.861185000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/x-speex
0:01:32.861215000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/x-celt
0:01:32.861229000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/x-ogg-skeleton
0:01:32.861281000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for text/x-cmml
0:01:32.861318000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/aac
0:01:32.861335000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/x-wavpack
0:01:32.861358000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/x-wavpack-correction
0:01:32.861372000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/postscript
0:01:32.861386000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for image/svg+xml
0:01:32.861400000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/x-tar
0:01:32.861415000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/x-ar
0:01:32.861429000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/x-ms-dos-executable
0:01:32.861445000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for video/x-dirac
0:01:32.861458000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for multipart/x-mixed-replace
0:01:32.861471000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/x-mmsh
0:01:32.861506000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for video/vivo
0:01:32.861518000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for image/vnd.wap.wbmp
0:01:32.861533000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/x-yuv4mpeg
0:01:32.861544000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for image/x-icon
0:01:32.861556000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for image/x-degas
0:01:32.861594000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/octet-stream
0:01:32.861636000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for application/x-ssa
0:01:32.861650000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for video/x-pva
0:01:32.861664000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/audible
0:01:32.861699000 9391 0x7fc17e012000 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for audio/x-tap-tap
0:01:32.861715000 9391 0x7fc17e012000 INFO GST_PLUGIN_LOADING gstplugin.c:984:_priv_gst_plugin_load_file_for_registry: plugin "/Library/Frameworks/GStreamer.framework/Versions/1.0/lib/gstreamer-1.0/libgsttypefindfunctions.dylib" loaded
0:01:32.862147000 9391 0x7fc17e012000 INFO GST_EVENT gstevent.c:892:gst_event_new_caps: creating caps event audio/x-wav
0:01:32.862227000 9391 0x7fc17e012000 INFO GST_ELEMENT_PADS gstelement.c:1017:gst_element_get_static_pad: found pad typefind:src
0:01:32.862237000 9391 0x7fc17e012000 INFO GST_ELEMENT_PADS gstelement.c:1017:gst_element_get_static_pad: found pad typefind:sink
0:01:32.862324000 9391 0x7fc17e012000 INFO GST_PADS gstpad.c:2383:gst_pad_link_prepare: trying to link typefind:src and decodepad0:proxypad1
0:01:32.862335000 9391 0x7fc17e012000 INFO GST_PADS gstpad.c:2591:gst_pad_link_full: linked typefind:src and decodepad0:proxypad1, successful
0:01:32.862342000 9391 0x7fc17e012000 INFO GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:01:32.864640000 9391 0x7fc17e012000 INFO GST_ELEMENT_PADS gstpad.c:2139:gst_pad_unlink: unlinking typefind:src(0x7fc17e022650) and decodepad0:proxypad1(0x7fc17e0153c0)
0:01:32.864659000 9391 0x7fc17e012000 INFO GST_ELEMENT_PADS gstpad.c:2193:gst_pad_unlink: unlinked typefind:src and decodepad0:proxypad1
0:01:32.864672000 9391 0x7fc17e012000 INFO GST_PADS gstpad.c:2383:gst_pad_link_prepare: trying to link typefind:src and decodepad0:proxypad1
0:01:32.864681000 9391 0x7fc17e012000 INFO GST_PADS gstpad.c:2591:gst_pad_link_full: linked typefind:src and decodepad0:proxypad1, successful
0:01:32.864687000 9391 0x7fc17e012000 INFO GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:01:32.864725000 9391 0x7fc17e012000 INFO GST_ELEMENT_PADS gstpad.c:2139:gst_pad_unlink: unlinking typefind:src(0x7fc17e022650) and decodepad0:proxypad1(0x7fc17e0153c0)
0:01:32.864736000 9391 0x7fc17e012000 INFO GST_ELEMENT_PADS gstpad.c:2193:gst_pad_unlink: unlinked typefind:src and decodepad0:proxypad1
0:01:32.867380000 9391 0x7fc17e012000 INFO GST_PLUGIN_LOADING gstplugin.c:984:_priv_gst_plugin_load_file_for_registry: plugin "/Library/Frameworks/GStreamer.framework/Versions/1.0/lib/gstreamer-1.0/libgstwavparse.dylib" loaded
0:01:32.867398000 9391 0x7fc17e012000 INFO GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "wavparse"
0:01:32.867646000 9391 0x7fc17e012000 INFO GST_ELEMENT_PADS gstelement.c:760:gst_element_add_pad:<GstWavParse@0x7fc17e0155d0> adding pad 'sink'
0:01:32.867688000 9391 0x7fc17e012000 INFO GST_ELEMENT_PADS gstelement.c:760:gst_element_add_pad:<GstWavParse@0x7fc17e0155d0> adding pad 'src'
0:01:32.867720000 9391 0x7fc17e012000 INFO GST_PADS gstpad.c:2383:gst_pad_link_prepare: trying to link typefind:src and wavparse0:sink
0:01:32.867732000 9391 0x7fc17e012000 INFO GST_PADS gstpad.c:2591:gst_pad_link_full: linked typefind:src and wavparse0:sink, successful
0:01:32.867739000 9391 0x7fc17e012000 INFO GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:01:32.867756000 9391 0x7fc17e012000 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<wavparse0> completed state change to READY
0:01:32.867765000 9391 0x7fc17e012000 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<wavparse0> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:01:32.867806000 9391 0x7fc17e012000 INFO GST_ELEMENT_PADS gstelement.c:1017:gst_element_get_static_pad: found pad wavparse0:src
0:01:32.867865000 9391 0x7fc17e012000 INFO task gsttask.c:516:gst_task_set_lock: setting stream lock 0x7fc17e022b60 on task 0x7fc18f820710
0:01:32.867877000 9391 0x7fc17e012000 INFO GST_PADS gstpad.c:6291:gst_pad_start_task:<wavparse0:sink> created task 0x7fc18f820710
0:01:32.867910000 9391 0x7fc17e012000 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<wavparse0> completed state change to PAUSED
0:01:32.867922000 9391 0x7fc17e012000 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<wavparse0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:01:32.868020000 9391 0x7fc17e012000 INFO typefind gsttypefindelement.c:184:gst_type_find_element_have_type:<typefind> found caps audio/x-wav, probability=100
0:01:32.868026000 9391 0x7fc17d828cf0 INFO wavparse gstwavparse.c:2230:gst_wavparse_loop:<wavparse0> GST_WAVPARSE_START
0:01:32.868032000 9391 0x7fc17e012000 INFO task gsttask.c:368:gst_task_func:<typefind:sink> Task going to paused
0:01:32.868087000 9391 0x7fc17d828cf0 INFO GST_ELEMENT_PADS gstelement.c:1017:gst_element_get_static_pad: found pad wavparse0:sink
0:01:32.868106000 9391 0x7fc17d828cf0 stream-id, consider implementing a deterministic way of creating a stream-id
0:01:32.868129000 9391 0x7fc17d828cf0 INFO wavparse gstwavparse.c:2245:gst_wavparse_loop:<wavparse0> GST_WAVPARSE_HEADER
0:01:32.868156000 9391 0x7fc17d828cf0 INFO riff riff-read.c:514:gst_riff_parse_strf_auds:<wavparse0> strf tag found in context auds:
0:01:32.868166000 9391 0x7fc17d828cf0 INFO riff riff-read.c:515:gst_riff_parse_strf_auds:<wavparse0> format 1
0:01:32.868173000 9391 0x7fc17d828cf0 INFO riff riff-read.c:516:gst_riff_parse_strf_auds:<wavparse0> channels 1
0:01:32.868180000 9391 0x7fc17d828cf0 INFO riff riff-read.c:517:gst_riff_parse_strf_auds:<wavparse0> rate 44100
0:01:32.868186000 9391 0x7fc17d828cf0 INFO riff riff-read.c:518:gst_riff_parse_strf_auds:<wavparse0> av_bps 88200
0:01:32.868192000 9391 0x7fc17d828cf0 INFO riff riff-read.c:519:gst_riff_parse_strf_auds:<wavparse0> blockalign 2
0:01:32.868198000 9391 0x7fc17d828cf0 INFO riff riff-read.c:520:gst_riff_parse_strf_auds:<wavparse0> bits/sample 16
0:01:32.868268000 9391 0x7fc17d828cf0 INFO wavparse gstwavparse.c:1305:gst_wavparse_stream_headers:<wavparse0> Got TAG: data, offset 36, size 88188
0:01:32.868282000 9391 0x7fc17d828cf0 INFO wavparse gstwavparse.c:730:gst_wavparse_calculate_duration:<wavparse0> Got datasize 88188
0:01:32.868293000 9391 0x7fc17d828cf0 INFO wavparse gstwavparse.c:735:gst_wavparse_calculate_duration:<wavparse0> Got duration (bps) 0:00:00.999863946
0:01:32.868338000 9391 0x7fc17d828cf0 INFO GST_EVENT gstevent.c:973:gst_event_new_segment: creating segment event time segment start=0:00:00.000000000, offset=0:00:00.000000000, stop=99:99:99.999999999, rate=1.000000, applied_rate=1.000000, flags=0x00, time=0:00:00.000000000, base=0:00:00.000000000, position 0:00:00.000000000, duration 0:00:00.999863946
0:01:32.868364000 9391 0x7fc17d828cf0 INFO wavparse gstwavparse.c:2250:gst_wavparse_loop:<wavparse0> GST_WAVPARSE_DATA
0:01:32.887488000 9391 0x7fc17d828cf0 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for avtype_avs
0:01:32.887592000 9391 0x7fc17d828cf0 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for avtype_bfstm
0:01:32.887626000 9391 0x7fc17d828cf0 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for avtype_brstm
0:01:32.887662000 9391 0x7fc17d828cf0 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for avtype_daud
0:01:32.887692000 9391 0x7fc17d828cf0 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for avtype_dsf
0:01:32.887720000 9391 0x7fc17d828cf0 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for avtype_ea
0:01:32.887750000 9391 0x7fc17d828cf0 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for avtype_4xm
0:01:32.887787000 9391 0x7fc17d828cf0 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for avtype_gxf
0:01:32.887820000 9391 0x7fc17d828cf0 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for avtype_idcin
0:01:32.887848000 9391 0x7fc17d828cf0 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for avtype_iff
0:01:32.887878000 9391 0x7fc17d828cf0 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for avtype_ipmovie
0:01:32.887920000 9391 0x7fc17d828cf0 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for avtype_mm
0:01:32.887945000 9391 0x7fc17d828cf0 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for avtype_mmf
0:01:32.887999000 9391 0x7fc17d828cf0 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for avtype_nsv
0:01:32.888025000 9391 0x7fc17d828cf0 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for avtype_nut
0:01:32.888076000 9391 0x7fc17d828cf0 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for avtype_film_cpk
0:01:32.888106000 9391 0x7fc17d828cf0 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for avtype_smk
0:01:32.888133000 9391 0x7fc17d828cf0 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for avtype_sol
0:01:32.888162000 9391 0x7fc17d828cf0 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for avtype_psxstr
0:01:32.888214000 9391 0x7fc17d828cf0 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for avtype_vmd
0:01:32.888256000 9391 0x7fc17d828cf0 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for avtype_wc3movie
0:01:32.888286000 9391 0x7fc17d828cf0 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for avtype_wsaud
0:01:32.888315000 9391 0x7fc17d828cf0 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for avtype_wsvqa
0:01:32.888342000 9391 0x7fc17d828cf0 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for avtype_xwma
0:01:32.888370000 9391 0x7fc17d828cf0 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for avtype_yuv4mpegpipe
0:01:32.888726000 9391 0x7fc17d828cf0 INFO GST_PLUGIN_LOADING gstplugin.c:984:_priv_gst_plugin_load_file_for_registry: plugin "/Library/Frameworks/GStreamer.framework/Versions/1.0/lib/gstreamer-1.0/libgstlibav.dylib" loaded
0:01:32.891385000 9391 0x7fc17d828cf0 INFO GST_TYPEFIND gsttypefind.c:72:gst_type_find_register: registering typefind function for subparse_typefind
0:01:32.891493000 9391 0x7fc17d828cf0 INFO GST_PLUGIN_LOADING gstplugin.c:984:_priv_gst_plugin_load_file_for_registry: plugin "/Library/Frameworks/GStreamer.framework/Versions/1.0/lib/gstreamer-1.0/libgstsubparse.dylib" loaded
0:01:32.891628000 9391 0x7fc17d828cf0 INFO GST_EVENT gstevent.c:892:gst_event_new_caps: creating caps event audio/x-raw, format=(string)S16LE, layout=(string)interleaved, channels=(int)1, rate=(int)44100
0:01:32.891671000 9391 0x7fc17d828cf0 INFO GST_PADS gstpad.c:2383:gst_pad_link_prepare: trying to link wavparse0:src and decodepad0:proxypad1
0:01:32.891685000 9391 0x7fc17d828cf0 INFO GST_PADS gstpad.c:2591:gst_pad_link_full: linked wavparse0:src and decodepad0:proxypad1, successful
0:01:32.891693000 9391 0x7fc17d828cf0 INFO GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:01:32.892054000 9391 0x7fc17d828cf0 INFO GST_ELEMENT_PADS gstelement.c:760:gst_element_add_pad:<decodebin0> adding pad 'src_0'
0:01:32.892114000 9391 0x7fc17d828cf0 INFO GST_PADS gstpad.c:2383:gst_pad_link_prepare: trying to link decodebin0:src_0 and src_0:proxypad2
0:01:32.892128000 9391 0x7fc17d828cf0 INFO GST_PADS gstpad.c:2591:gst_pad_link_full: linked decodebin0:src_0 and src_0:proxypad2, successful
0:01:32.892136000 9391 0x7fc17d828cf0 INFO GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:01:32.892165000 9391 0x7fc17d828cf0 INFO GST_ELEMENT_PADS gstelement.c:760:gst_element_add_pad:<uribin> adding pad 'src_0'
0:01:32.893684000 9391 0x7fc17d828cf0 INFO GST_PLUGIN_LOADING gstplugin.c:984:_priv_gst_plugin_load_file_for_registry: plugin "/Library/Frameworks/GStreamer.framework/Versions/1.0/lib/gstreamer-1.0/libgstaudioconvert.dylib" loaded
0:01:32.893705000 9391 0x7fc17d828cf0 INFO GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "audioconvert"
0:01:32.893829000 9391 0x7fc17d828cf0 INFO GST_ELEMENT_PADS gstelement.c:760:gst_element_add_pad:<GstBaseTransform@0x7fc17e02a0d0> adding pad 'sink'
0:01:32.893866000 9391 0x7fc17d828cf0 INFO GST_ELEMENT_PADS gstelement.c:760:gst_element_add_pad:<GstBaseTransform@0x7fc17e02a0d0> adding pad 'src'
0:01:32.893903000 9391 0x7fc17d828cf0 INFO GST_STATES gstelement.c:2782:gst_element_continue_state:<audioconvert0> committing state from NULL to READY, pending PLAYING, next PAUSED
0:01:32.893914000 9391 0x7fc17d828cf0 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<audioconvert0> notifying about state-changed NULL to READY (PLAYING pending)
0:01:32.893936000 9391 0x7fc17d828cf0 INFO GST_STATES gstelement.c:2789:gst_element_continue_state:<audioconvert0> continue state change READY to PAUSED, final PLAYING
0:01:32.893954000 9391 0x7fc17d828cf0 INFO GST_STATES gstelement.c:2782:gst_element_continue_state:<audioconvert0> committing state from READY to PAUSED, pending PLAYING, next PLAYING
0:01:32.893963000 9391 0x7fc17d828cf0 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<audioconvert0> notifying about state-changed READY to PAUSED (PLAYING pending)
0:01:32.893974000 9391 0x7fc17d828cf0 INFO GST_STATES gstelement.c:2789:gst_element_continue_state:<audioconvert0> continue state change PAUSED to PLAYING, final PLAYING
0:01:32.893982000 9391 0x7fc17d828cf0 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<audioconvert0> completed state change to PLAYING
0:01:32.893990000 9391 0x7fc17d828cf0 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<audioconvert0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:01:32.894009000 9391 0x7fc17d828cf0 INFO GST_ELEMENT_PADS gstelement.c:1017:gst_element_get_static_pad: found pad audioconvert0:sink
0:01:32.894023000 9391 0x7fc17d828cf0 INFO GST_PADS gstpad.c:2383:gst_pad_link_prepare: trying to link uribin:src_0 and audioconvert0:sink
0:01:32.894048000 9391 0x7fc17d828cf0 INFO GST_PADS gstpad.c:4357:gst_pad_peer_query:<audioconvert0:src> pad has no peer
0:01:32.894083000 9391 0x7fc17d828cf0 INFO structure gststructure.c:2918:gst_structure_get_valist: Expected field 'channel-mask' in structure: audio/x-raw, rate=(int)[ 1, 2147483647 ], channels=(int)[ 1, 2147483647 ];
0:01:32.894132000 9391 0x7fc17d828cf0 INFO GST_PADS gstpad.c:2591:gst_pad_link_full: linked uribin:src_0 and audioconvert0:sink, successful
0:01:32.894141000 9391 0x7fc17d828cf0 INFO GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:01:32.894162000 9391 0x7fc17d828cf0 INFO GST_ELEMENT_PADS gstelement.c:1017:gst_element_get_static_pad: found pad audioconvert0:src
0:01:32.894188000 9391 0x7fc17d828cf0 INFO structure gststructure.c:2918:gst_structure_get_valist: Expected field 'channel-mask' in structure: audio/x-raw, channels=(int)1, rate=(int)44100;
0:01:32.896333000 9391 0x7fc17d828cf0 INFO GST_PLUGIN_LOADING gstplugin.c:984:_priv_gst_plugin_load_file_for_registry: plugin "/Library/Frameworks/GStreamer.framework/Versions/1.0/lib/gstreamer-1.0/libgstrtp.dylib" loaded
0:01:32.896446000 9391 0x7fc17d828cf0 INFO GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "rtpL16pay"
0:01:32.896475000 9391 0x7fc17d828cf0 INFO GST_ELEMENT_PADS gstelement.c:760:gst_element_add_pad:<GstRTPBasePayload@0x7fc17e02a710> adding pad 'src'
0:01:32.896495000 9391 0x7fc17d828cf0 INFO GST_ELEMENT_PADS gstelement.c:760:gst_element_add_pad:<GstRTPBasePayload@0x7fc17e02a710> adding pad 'sink'
0:01:32.896533000 9391 0x7fc17d828cf0 INFO GST_STATES gstelement.c:2782:gst_element_continue_state:<pay_src_0> committing state from NULL to READY, pending PLAYING, next PAUSED
0:01:32.896544000 9391 0x7fc17d828cf0 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<pay_src_0> notifying about state-changed NULL to READY (PLAYING pending)
0:01:32.896563000 9391 0x7fc17d828cf0 INFO GST_STATES gstelement.c:2789:gst_element_continue_state:<pay_src_0> continue state change READY to PAUSED, final PLAYING
0:01:32.896582000 9391 0x7fc17d828cf0 INFO GST_STATES gstelement.c:2782:gst_element_continue_state:<pay_src_0> committing state from READY to PAUSED, pending PLAYING, next PLAYING
0:01:32.896591000 9391 0x7fc17d828cf0 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<pay_src_0> notifying about state-changed READY to PAUSED (PLAYING pending)
0:01:32.896605000 9391 0x7fc17d828cf0 INFO GST_STATES gstelement.c:2789:gst_element_continue_state:<pay_src_0> continue state change PAUSED to PLAYING, final PLAYING
0:01:32.896613000 9391 0x7fc17d828cf0 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<pay_src_0> completed state change to PLAYING
0:01:32.896621000 9391 0x7fc17d828cf0 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<pay_src_0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:01:32.896634000 9391 0x7fc17d828cf0 INFO GST_ELEMENT_PADS gstelement.c:1017:gst_element_get_static_pad: found pad pay_src_0:sink
0:01:32.896647000 9391 0x7fc17d828cf0 INFO GST_PADS gstpad.c:2383:gst_pad_link_prepare: trying to link audioconvert0:src and pay_src_0:sink
0:01:32.896675000 9391 0x7fc17d828cf0 INFO structure gststructure.c:2918:gst_structure_get_valist: Expected field 'channel-mask' in structure: audio/x-raw, channels=(int)1, rate=(int)44100;
0:01:32.896707000 9391 0x7fc17d828cf0 INFO GST_PADS gstpad.c:2591:gst_pad_link_full: linked audioconvert0:src and pay_src_0:sink, successful
0:01:32.896715000 9391 0x7fc17d828cf0 INFO GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:01:32.896736000 9391 0x7fc17d828cf0 INFO GST_ELEMENT_PADS gstelement.c:1017:gst_element_get_static_pad: found pad pay_src_0:src
0:01:32.896762000 9391 0x7fc17d828cf0 INFO GST_PADS gstpad.c:2383:gst_pad_link_prepare: trying to link pay_src_0:src and src_0:proxypad3
0:01:32.896772000 9391 0x7fc17d828cf0 INFO GST_PADS gstpad.c:2591:gst_pad_link_full: linked pay_src_0:src and src_0:proxypad3, successful
0:01:32.896779000 9391 0x7fc17d828cf0 INFO GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:01:32.896802000 9391 0x7fc17d828cf0 INFO GST_ELEMENT_PADS gstelement.c:760:gst_element_add_pad:<dynpay0> adding pad 'src_0'
0:01:32.896815000 9391 0x7fc17d828cf0 INFO GST_PARENTAGE gstbin.c:4378:gst_bin_get_by_name: [dynpay0]: looking up child element pay
0:01:32.896831000 9391 0x7fc17d828cf0 INFO GST_PARENTAGE gstbin.c:4378:gst_bin_get_by_name: [dynpay0]: looking up child element pay_src_0
0:01:32.896858000 9391 0x7fc17d828cf0 INFO GST_PADS gstpad.c:2383:gst_pad_link_prepare: trying to link dynpay0:src_0 and src_0:proxypad4
0:01:32.896868000 9391 0x7fc17d828cf0 INFO GST_PADS gstpad.c:2591:gst_pad_link_full: linked dynpay0:src_0 and src_0:proxypad4, successful
0:01:32.896874000 9391 0x7fc17d828cf0 INFO GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:01:32.896896000 9391 0x7fc17d828cf0 INFO GST_ELEMENT_PADS gstelement.c:760:gst_element_add_pad:<GstRTSPMediaFactoryURI> adding pad 'src_0'
0:01:32.896951000 9391 0x7fc17d828cf0 INFO rtspmedia rtsp-media.c:3463:pad_added_cb: pad added dynpay0:src_0, stream 0x7fc17e034380
0:01:32.896962000 9391 0x7fc17d828cf0 INFO rtspstream rtsp-stream.c:3963:gst_rtsp_stream_join_bin: stream 0x7fc17e034380 joining bin as session 0
0:01:32.896973000 9391 0x7fc17d828cf0 INFO GST_ELEMENT_PADS gstelement.c:1014:gst_element_get_static_pad: no such pad 'send_rtp_sink_0' in element "rtpbin0"
0:01:32.896984000 9391 0x7fc17d828cf0 INFO GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "rtpsession"
0:01:32.897268000 9391 0x7fc17d828cf0 INFO GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "rtpssrcdemux"
0:01:32.897337000 9391 0x7fc17d828cf0 INFO GST_ELEMENT_PADS gstelement.c:760:gst_element_add_pad:<GstRtpSsrcDemux@0x7fc18e00e6d0> adding pad 'sink'
0:01:32.897359000 9391 0x7fc17d828cf0 INFO GST_ELEMENT_PADS gstelement.c:760:gst_element_add_pad:<GstRtpSsrcDemux@0x7fc18e00e6d0> adding pad 'rtcp_sink'
0:01:32.897372000 9391 0x7fc17d828cf0 INFO GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "rtpstorage"
0:01:32.897425000 9391 0x7fc17d828cf0 INFO GST_ELEMENT_PADS gstelement.c:760:gst_element_add_pad:<GstRtpStorage@0x7fc18f820d80> adding pad 'src'
0:01:32.897437000 9391 0x7fc17d828cf0 INFO GST_ELEMENT_PADS gstelement.c:760:gst_element_add_pad:<GstRtpStorage@0x7fc18f820d80> adding pad 'sink'
0:01:32.897509000 9391 0x7fc17d828cf0 INFO GST_STATES gstelement.c:2782:gst_element_continue_state:<rtpssrcdemux0> committing state from NULL to READY, pending PAUSED, next PAUSED
0:01:32.897522000 9391 0x7fc17d828cf0 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<rtpssrcdemux0> notifying about state-changed NULL to READY (PAUSED pending)
0:01:32.897540000 9391 0x7fc17d828cf0 INFO GST_STATES gstelement.c:2789:gst_element_continue_state:<rtpssrcdemux0> continue state change READY to PAUSED, final PAUSED
0:01:32.897552000 9391 0x7fc17d828cf0 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<rtpssrcdemux0> completed state change to PAUSED
0:01:32.897561000 9391 0x7fc17d828cf0 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<rtpssrcdemux0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:01:32.897579000 9391 0x7fc17d828cf0 INFO GST_STATES gstelement.c:2782:gst_element_continue_state:<rtpsession0> committing state from NULL to READY, pending PAUSED, next PAUSED
0:01:32.897588000 9391 0x7fc17d828cf0 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<rtpsession0> notifying about state-changed NULL to READY (PAUSED pending)
0:01:32.897598000 9391 0x7fc17d828cf0 INFO GST_STATES gstelement.c:2789:gst_element_continue_state:<rtpsession0> continue state change READY to PAUSED, final PAUSED
0:01:32.897607000 9391 0x7fc17d828cf0 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<rtpsession0> completed state change to PAUSED
0:01:32.897615000 9391 0x7fc17d828cf0 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<rtpsession0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:01:32.897626000 9391 0x7fc17d828cf0 INFO GST_STATES gstelement.c:2782:gst_element_continue_state:<rtpstorage0> committing state from NULL to READY, pending PAUSED, next PAUSED
0:01:32.897634000 9391 0x7fc17d828cf0 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<rtpstorage0> notifying about state-changed NULL to READY (PAUSED pending)
0:01:32.897644000 9391 0x7fc17d828cf0 INFO GST_STATES gstelement.c:2789:gst_element_continue_state:<rtpstorage0> continue state change READY to PAUSED, final PAUSED
0:01:32.897653000 9391 0x7fc17d828cf0 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<rtpstorage0> completed state change to PAUSED
0:01:32.897661000 9391 0x7fc17d828cf0 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<rtpstorage0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:01:32.897677000 9391 0x7fc17d828cf0 INFO GST_ELEMENT_PADS gstelement.c:1014:gst_element_get_static_pad: no such pad 'send_rtp_sink' in element "rtpsession0"
0:01:32.897701000 9391 0x7fc17d828cf0 INFO GST_ELEMENT_PADS gstelement.c:760:gst_element_add_pad:<rtpsession0> adding pad 'send_rtp_sink'
0:01:32.897722000 9391 0x7fc17d828cf0 INFO GST_ELEMENT_PADS gstelement.c:760:gst_element_add_pad:<rtpsession0> adding pad 'send_rtp_src'
0:01:32.897735000 9391 0x7fc17d828cf0 INFO GST_ELEMENT_PADS gstelement.c:1017:gst_element_get_static_pad: found pad rtpsession0:send_rtp_src
0:01:32.897764000 9391 0x7fc17d828cf0 INFO GST_PADS gstpad.c:2383:gst_pad_link_prepare: trying to link rtpsession0:send_rtp_src and send_rtp_src_0:proxypad5
0:01:32.897775000 9391 0x7fc17d828cf0 INFO GST_PADS gstpad.c:2591:gst_pad_link_full: linked rtpsession0:send_rtp_src and send_rtp_src_0:proxypad5, successful
0:01:32.897782000 9391 0x7fc17d828cf0 INFO GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:01:32.897800000 9391 0x7fc17d828cf0 INFO GST_ELEMENT_PADS gstelement.c:760:gst_element_add_pad:<rtpbin0> adding pad 'send_rtp_src_0'
0:01:32.897823000 9391 0x7fc17d828cf0 INFO GST_PADS gstpad.c:2383:gst_pad_link_prepare: trying to link send_rtp_sink_0:proxypad6 and rtpsession0:send_rtp_sink
0:01:32.897833000 9391 0x7fc17d828cf0 INFO GST_PADS gstpad.c:2591:gst_pad_link_full: linked send_rtp_sink_0:proxypad6 and rtpsession0:send_rtp_sink, successful
0:01:32.897840000 9391 0x7fc17d828cf0 INFO GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:01:32.897851000 9391 0x7fc17d828cf0 INFO GST_ELEMENT_PADS gstelement.c:760:gst_element_add_pad:<rtpbin0> adding pad 'send_rtp_sink_0'
0:01:32.897863000 9391 0x7fc17d828cf0 INFO GST_PADS gstpad.c:2383:gst_pad_link_prepare: trying to link GstRTSPMediaFactoryURI:src_0 and rtpbin0:send_rtp_sink_0
0:01:32.897886000 9391 0x7fc17d828cf0 INFO GST_PADS gstpad.c:2591:gst_pad_link_full: linked GstRTSPMediaFactoryURI:src_0 and rtpbin0:send_rtp_sink_0, successful
0:01:32.897894000 9391 0x7fc17d828cf0 INFO GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:01:32.897916000 9391 0x7fc17d828cf0 INFO GST_ELEMENT_PADS gstelement.c:1017:gst_element_get_static_pad: found pad rtpbin0:send_rtp_src_0
0:01:32.897926000 9391 0x7fc17d828cf0 INFO GST_ELEMENT_PADS gstelement.c:1014:gst_element_get_static_pad: no such pad 'send_rtcp_src_0' in element "rtpbin0"
0:01:32.897936000 9391 0x7fc17d828cf0 INFO GST_ELEMENT_PADS gstelement.c:1014:gst_element_get_static_pad: no such pad 'send_rtcp_src' in element "rtpsession0"
0:01:32.897958000 9391 0x7fc17d828cf0 INFO GST_ELEMENT_PADS gstelement.c:760:gst_element_add_pad:<rtpsession0> adding pad 'send_rtcp_src'
0:01:32.897984000 9391 0x7fc17d828cf0 INFO GST_PADS gstpad.c:2383:gst_pad_link_prepare: trying to link rtpsession0:send_rtcp_src and send_rtcp_src_0:proxypad7
0:01:32.897994000 9391 0x7fc17d828cf0 INFO GST_PADS gstpad.c:2591:gst_pad_link_full: linked rtpsession0:send_rtcp_src and send_rtcp_src_0:proxypad7, successful
0:01:32.898000000 9391 0x7fc17d828cf0 INFO GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:01:32.898013000 9391 0x7fc17d828cf0 INFO GST_ELEMENT_PADS gstelement.c:760:gst_element_add_pad:<rtpbin0> adding pad 'send_rtcp_src_0'
0:01:32.898024000 9391 0x7fc17d828cf0 INFO GST_ELEMENT_PADS gstelement.c:1014:gst_element_get_static_pad: no such pad 'recv_rtcp_sink_0' in element "rtpbin0"
0:01:32.898033000 9391 0x7fc17d828cf0 INFO GST_ELEMENT_PADS gstelement.c:1014:gst_element_get_static_pad: no such pad 'recv_rtcp_sink' in element "rtpsession0"
0:01:32.898053000 9391 0x7fc17d828cf0 INFO GST_ELEMENT_PADS gstelement.c:760:gst_element_add_pad:<rtpsession0> adding pad 'recv_rtcp_sink'
0:01:32.898073000 9391 0x7fc17d828cf0 INFO GST_ELEMENT_PADS gstelement.c:760:gst_element_add_pad:<rtpsession0> adding pad 'sync_src'
0:01:32.898085000 9391 0x7fc17d828cf0 INFO GST_ELEMENT_PADS gstelement.c:1017:gst_element_get_static_pad: found pad rtpsession0:sync_src
0:01:32.898093000 9391 0x7fc17d828cf0 INFO GST_ELEMENT_PADS gstelement.c:1017:gst_element_get_static_pad: found pad rtpssrcdemux0:rtcp_sink
0:01:32.898106000 9391 0x7fc17d828cf0 INFO GST_PADS gstpad.c:2383:gst_pad_link_prepare: trying to link rtpsession0:sync_src and rtpssrcdemux0:rtcp_sink
0:01:32.898116000 9391 0x7fc17d828cf0 INFO GST_PADS gstpad.c:2591:gst_pad_link_full: linked rtpsession0:sync_src and rtpssrcdemux0:rtcp_sink, successful
0:01:32.898123000 9391 0x7fc17d828cf0 INFO GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:01:32.898146000 9391 0x7fc17d828cf0 INFO GST_PADS gstpad.c:2383:gst_pad_link_prepare: trying to link recv_rtcp_sink_0:proxypad8 and rtpsession0:recv_rtcp_sink
0:01:32.898156000 9391 0x7fc17d828cf0 INFO GST_PADS gstpad.c:2591:gst_pad_link_full: linked recv_rtcp_sink_0:proxypad8 and rtpsession0:recv_rtcp_sink, successful
0:01:32.898163000 9391 0x7fc17d828cf0 INFO GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:01:32.898174000 9391 0x7fc17d828cf0 INFO GST_ELEMENT_PADS gstelement.c:760:gst_element_add_pad:<rtpbin0> adding pad 'recv_rtcp_sink_0'
0:01:32.898208000 9391 0x7fc17d828cf0 INFO decodebin gstdecodebin2.c:4809:gst_decode_bin_expose:<decodebin0:src_0> added new decoded pad
0:01:32.898221000 9391 0x7fc17d828cf0 INFO rtspmedia rtsp-media.c:3519:no_more_pads_cb:<dynpay0> no more pads
0:01:32.898238000 9391 0x7fc17d828cf0 INFO GST_STATES gstbin.c:3401:bin_handle_async_done:<decodebin0> committing state from READY to PAUSED, old pending PAUSED
0:01:32.898246000 9391 0x7fc17d828cf0 INFO GST_STATES gstbin.c:3421:bin_handle_async_done:<decodebin0> completed state change, pending VOID
0:01:32.898255000 9391 0x7fc17d828cf0 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<decodebin0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:01:32.898271000 9391 0x7fc17d828cf0 INFO GST_STATES gstbin.c:3401:bin_handle_async_done:<uribin> committing state from READY to PAUSED, old pending PAUSED
0:01:32.898279000 9391 0x7fc17d828cf0 INFO GST_STATES gstbin.c:3421:bin_handle_async_done:<uribin> completed state change, pending VOID
0:01:32.898287000 9391 0x7fc17d828cf0 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<uribin> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:01:32.898301000 9391 0x7fc17d828cf0 INFO GST_STATES gstbin.c:3401:bin_handle_async_done:<dynpay0> committing state from READY to PAUSED, old pending PAUSED
0:01:32.898309000 9391 0x7fc17d828cf0 INFO GST_STATES gstbin.c:3421:bin_handle_async_done:<dynpay0> completed state change, pending VOID
0:01:32.898316000 9391 0x7fc17d828cf0 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<dynpay0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:01:32.898330000 9391 0x7fc17d828cf0 INFO GST_STATES gstbin.c:3401:bin_handle_async_done:<GstRTSPMediaFactoryURI> committing state from READY to PAUSED, old pending PAUSED
0:01:32.898338000 9391 0x7fc17d828cf0 INFO GST_STATES gstbin.c:3421:bin_handle_async_done:<GstRTSPMediaFactoryURI> completed state change, pending VOID
0:01:32.898346000 9391 0x7fc17d828cf0 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<GstRTSPMediaFactoryURI> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:01:32.898361000 9391 0x7fc17d828cf0 INFO GST_STATES gstbin.c:3401:bin_handle_async_done:<media-pipeline> committing state from READY to PAUSED, old pending PAUSED
0:01:32.898369000 9391 0x7fc17d828cf0 INFO GST_STATES gstbin.c:3421:bin_handle_async_done:<media-pipeline> completed state change, pending VOID
0:01:32.898377000 9391 0x7fc17d828cf0 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<media-pipeline> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:01:32.898431000 9391 0x7fc17d828cf0 INFO structure gststructure.c:2918:gst_structure_get_valist: Expected field 'channel-mask' in structure: audio/x-raw, channels=(int)1, rate=(int)44100;
0:01:32.898469000 9391 0x7fc17d828cf0 INFO structure gststructure.c:2918:gst_structure_get_valist: Expected field 'channel-mask' in structure: audio/x-raw, channels=(int)1, rate=(int)44100;
0:01:32.898498000 9391 0x7fc17d828cf0 INFO structure gststructure.c:2918:gst_structure_get_valist: Expected field 'channel-mask' in structure: audio/x-raw, channels=(int)1, rate=(int)44100;
0:01:32.898528000 9391 0x7fc17d828cf0 INFO structure gststructure.c:2918:gst_structure_get_valist: Expected field 'channel-mask' in structure: audio/x-raw, channels=(int)1, rate=(int)44100;
0:01:32.898683000 9391 0x7fc17d828cf0 INFO structure gststructure.c:2918:gst_structure_get_valist: Expected field 'channel-mask' in structure: audio/x-raw, format=(string)S16LE, layout=(string)interleaved, channels=(int)1, rate=(int)44100;
0:01:32.898714000 9391 0x7fc17d828cf0 INFO structure gststructure.c:2918:gst_structure_get_valist: Expected field 'channel-mask' in structure: audio/x-raw, rate=(int)44100, format=(string)S16BE, channels=(int)1, layout=(string)interleaved;
0:01:32.898739000 9391 0x7fc17d828cf0 INFO audio-converter audio-converter.c:1358:gst_audio_converter_new: unitsizes: 2 -> 2
0:01:32.898748000 9391 0x7fc17d828cf0 INFO audio-converter audio-converter.c:729:chain_unpack: unpack format S16LE to S32LE
0:01:32.898775000 9391 0x7fc17d828cf0 INFO audio-converter audio-converter.c:871:chain_mix: mix format S32LE, passthrough 1, in_channels 1, out_channels 1
0:01:32.898786000 9391 0x7fc17d828cf0 INFO audio-converter audio-converter.c:963:chain_quantize: depth in 32, out 16
0:01:32.898793000 9391 0x7fc17d828cf0 INFO audio-converter audio-converter.c:977:chain_quantize: using dither 2 and noise shaping 0
0:01:32.898800000 9391 0x7fc17d828cf0 INFO audio-converter audio-converter.c:987:chain_quantize: quantize to 16 bits, dither 2, ns 0
0:01:32.898811000 9391 0x7fc17d828cf0 INFO audio-converter audio-converter.c:1033:chain_pack: pack format S32LE to S16BE
0:01:32.898817000 9391 0x7fc17d828cf0 INFO audio-converter audio-converter.c:1401:gst_audio_converter_new: no resampler, passthrough mixing -> only endian conversion
0:01:32.898832000 9391 0x7fc17d828cf0 INFO GST_EVENT gstevent.c:892:gst_event_new_caps: creating caps event audio/x-raw, rate=(int)44100, format=(string)S16BE, channels=(int)1, layout=(string)interleaved
0:01:32.898900000 9391 0x7fc17d828cf0 INFO structure gststructure.c:2918:gst_structure_get_valist: Expected field 'channel-mask' in structure: audio/x-raw, rate=(int)44100, format=(string)S16BE, channels=(int)1, layout=(string)interleaved;
0:01:32.899034000 9391 0x7fc17d828cf0 INFO GST_EVENT gstevent.c:892:gst_event_new_caps: creating caps event application/x-rtp, media=(string)audio, clock-rate=(int)44100, encoding-name=(string)L16, encoding-params=(string)1, channels=(int)1, payload=(int)96, ssrc=(uint)1087690860, timestamp-offset=(uint)2116688505, seqnum-offset=(uint)5507
0:01:32.899056000 9391 0x7fc17d828cf0 INFO GST_PADS gstpad.c:4357:gst_pad_peer_query:<rtpbin0:send_rtp_src_0> pad has no peer
0:01:32.899078000 9391 0x7fc17d828cf0 INFO GST_PADS gstpad.c:4357:gst_pad_peer_query:<rtpbin0:send_rtp_src_0> pad has no peer
0:01:32.899096000 9391 0x7fc17d828cf0 INFO GST_PADS gstpad.c:4357:gst_pad_peer_query:<rtpbin0:send_rtp_src_0> pad has no peer
0:01:32.899112000 9391 0x7fc17d828cf0 INFO GST_PADS gstpad.c:4357:gst_pad_peer_query:<rtpbin0:send_rtp_src_0> pad has no peer
0:01:32.899164000 9391 0x7fc17d828cf0 INFO rtspstream rtsp-stream.c:2512:on_new_sender_ssrc: 0x7fc17e034380: new sender source 0x7fc17e03a3e0
0:01:32.899218000 9391 0x7fc17d828cf0 INFO rtspstream rtsp-stream.c:2346:dump_structure: structure: application/x-rtp-source-stats, ssrc=(uint)1087690860, internal=(boolean)true, validated=(boolean)true, received-bye=(boolean)false, is-csrc=(boolean)false, is-sender=(boolean)false, seqnum-base=(int)5507, clock-rate=(int)44100, octets-sent=(guint64)0, packets-sent=(guint64)0, octets-received=(guint64)0, packets-received=(guint64)0, bytes-received=(guint64)0, bitrate=(guint64)0, packets-lost=(int)0, jitter=(uint)0, sent-pli-count=(uint)0, recv-pli-count=(uint)0, sent-fir-count=(uint)0, recv-fir-count=(uint)0, sent-nack-count=(uint)0, recv-nack-count=(uint)0, recv-packet-rate=(uint)0, have-sr=(boolean)false, sr-ntptime=(guint64)0, sr-rtptime=(uint)0, sr-octet-count=(uint)0, sr-packet-count=(uint)0;
0:01:32.899245000 9391 0x7fc17d828cf0 INFO GST_PADS gstpad.c:4357:gst_pad_peer_query:<rtpbin0:send_rtp_src_0> pad has no peer
0:01:32.899268000 9391 0x7fc17d828cf0 INFO rtspstream rtsp-stream.c:2329:caps_notify: stream 0x7fc17e034380 received caps 0x7fc18d932a30, application/x-rtp, media=(string)audio, clock-rate=(int)44100, encoding-name=(string)L16, encoding-params=(string)1, channels=(int)1, payload=(int)96, ssrc=(uint)1087690860, timestamp-offset=(uint)2116688505, seqnum-offset=(uint)5507
0:01:32.899511000 9391 0x7fc18d934a30 INFO rtspmedia rtsp-media.c:873:collect_media_stats: collect media stats
0:01:32.899529000 9391 0x7fc18d934a30 INFO rtspmedia rtsp-media.c:691:do_query_position:<GstRTSPStream@0x7fc17e034380> media position: 0:00:00.000000000
0:01:32.899544000 9391 0x7fc18d934a30 INFO rtspmedia rtsp-media.c:909:collect_media_stats: stats: position 0:00:00.000000000, stop 0:00:00.999863946
0:01:32.899592000 9391 0x7fc18d942140 INFO rtspmedia rtsp-media.c:3910:gst_rtsp_media_prepare: object 0x7fc17e0181d0 is prerolled
0:01:32.899644000 9391 0x7fc18d942140 INFO rtspmedia rtsp-media.c:873:collect_media_stats: collect media stats
0:01:32.899664000 9391 0x7fc18d942140 INFO rtspmedia rtsp-media.c:691:do_query_position:<GstRTSPStream@0x7fc17e034380> media position: 0:00:00.000000000
0:01:32.899678000 9391 0x7fc18d942140 INFO rtspmedia rtsp-media.c:909:collect_media_stats: stats: position 0:00:00.000000000, stop 0:00:00.999863946
0:01:32.899780000 9391 0x7fc18d942140 INFO default gstmikey.c:2358:gst_mikey_message_new_from_caps: No srtp key
0:01:32.899820000 9391 0x7fc18d942140 FIXME rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
0:01:32.899844000 9391 0x7fc18d942140 INFO rtspclient rtsp-client.c:3330:handle_describe_request: adding content-base: rtsp://localhost:8554/test/
0:01:32.904209000 9391 0x7fc18d942140 INFO rtspclient rtsp-client.c:3981:handle_request: client 0x7fc18e00e190: received a request SETUP rtsp://localhost:8554/test/stream=0 1.0
0:01:32.904247000 9391 0x7fc18d942140 INFO rtspmountpoints rtsp-mount-points.c:305:gst_rtsp_mount_points_match: found media factory 0x7fc18d9590e0 for path /test/stream=0
0:01:32.904257000 9391 0x7fc18d942140 INFO rtspclient rtsp-client.c:1032:find_media: reusing cached media 0x7fc17e0181d0 for path /test
0:01:32.904265000 9391 0x7fc18d942140 FIXME rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
0:01:32.904273000 9391 0x7fc18d942140 WARN rtspmedia rtsp-media.c:4623:gst_rtsp_media_suspend: media 0x7fc17e0181d0 was not prepared
0:01:32.904316000 9391 0x7fc18d942140 INFO rtspsession rtsp-session.c:157:gst_rtsp_session_init: init session 0x7fc18f027900
0:01:32.904334000 9391 0x7fc18d942140 INFO rtspclient rtsp-client.c:669:client_watch_session: watching session 0x7fc18f027900
0:01:32.904375000 9391 0x7fc18d942140 INFO rtspclient rtsp-client.c:2339:parse_transport: found valid transport RTP/AVP;unicast;client_port=53000-53001
0:01:32.904397000 9391 0x7fc18d942140 INFO rtspsession rtsp-session.c:281:gst_rtsp_session_manage_media: manage new media 0x7fc17e0181d0 in session 0x7fc18f01d870
0:01:32.911668000 9391 0x7fc18d942140 INFO rtspclient rtsp-client.c:3981:handle_request: client 0x7fc18e00e190: received a request PLAY rtsp://localhost:8554/test/ 1.0
0:01:32.911703000 9391 0x7fc18d942140 INFO GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "funnel"
0:01:32.911770000 9391 0x7fc18d942140 INFO GST_ELEMENT_PADS gstelement.c:760:gst_element_add_pad:<GstFunnel@0x7fc17e024120> adding pad 'src'
0:01:32.911796000 9391 0x7fc18d942140 INFO GST_ELEMENT_PADS gstelement.c:1017:gst_element_get_static_pad: found pad funnel0:src
0:01:32.911811000 9391 0x7fc18d942140 INFO GST_PADS gstpad.c:2383:gst_pad_link_prepare: trying to link funnel0:src and rtpbin0:recv_rtcp_sink_0
0:01:32.911832000 9391 0x7fc18d942140 INFO GST_PADS gstpad.c:2591:gst_pad_link_full: linked funnel0:src and rtpbin0:recv_rtcp_sink_0, successful
0:01:32.911840000 9391 0x7fc18d942140 INFO GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:01:32.911849000 9391 0x7fc18d942140 INFO GST_EVENT gstpad.c:5947:gst_pad_send_event_unchecked:<funnel0:src> Received event on flushing pad. Discarding
0:01:32.913257000 9391 0x7fc18d942140 INFO GST_PLUGIN_LOADING gstplugin.c:984:_priv_gst_plugin_load_file_for_registry: plugin "/Library/Frameworks/GStreamer.framework/Versions/1.0/lib/gstreamer-1.0/libgstudp.dylib" loaded
0:01:32.913276000 9391 0x7fc18d942140 INFO GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "udpsrc"
0:01:32.913379000 9391 0x7fc18d942140 INFO GST_ELEMENT_PADS gstelement.c:760:gst_element_add_pad:<GstBaseSrc@0x7fc18e016ae0> adding pad 'src'
0:01:32.913428000 9391 0x7fc18d942140 INFO udpsrc gstudpsrc.c:1651:gst_udpsrc_open:<udpsrc0> have udp buffer of 786896 bytes
0:01:32.913453000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<udpsrc0> completed state change to READY
0:01:32.913465000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<udpsrc0> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:01:32.913479000 9391 0x7fc18d942140 INFO GST_ELEMENT_PADS gstelement.c:1017:gst_element_get_static_pad: found pad udpsrc0:src
0:01:32.913495000 9391 0x7fc18d942140 INFO GST_ELEMENT_PADS gstelement.c:1017:gst_element_get_static_pad: found pad udpsrc0:src
0:01:32.913526000 9391 0x7fc18d942140 INFO basesrc gstbasesrc.c:1430:gst_base_src_do_seek:<udpsrc0> seeking: time segment start=0:00:00.000000000, offset=0:00:00.000000000, stop=99:99:99.999999999, rate=1.000000, applied_rate=1.000000, flags=0x00, time=0:00:00.000000000, base=0:00:00.000000000, position 0:00:00.000000000, duration 99:99:99.999999999
0:01:32.913543000 9391 0x7fc18d942140 INFO task gsttask.c:516:gst_task_set_lock: setting stream lock 0x7fc17e045e20 on task 0x7fc17e024290
0:01:32.913552000 9391 0x7fc18d942140 INFO GST_PADS gstpad.c:6291:gst_pad_start_task:<udpsrc0:src> created task 0x7fc17e024290
0:01:32.913590000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2782:gst_element_continue_state:<udpsrc0> committing state from READY to PAUSED, pending PLAYING, next PLAYING
0:01:32.913601000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<udpsrc0> notifying about state-changed READY to PAUSED (PLAYING pending)
0:01:32.913626000 9391 0x7fc17e00eb20 INFO GST_ELEMENT_PADS gstelement.c:1014:gst_element_get_static_pad: no such pad 'sink' in element "udpsrc0"
0:01:32.913639000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2789:gst_element_continue_state:<udpsrc0> continue state change PAUSED to PLAYING, final PLAYING
0:01:32.913663000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<udpsrc0> completed state change to PLAYING
0:01:32.913677000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<udpsrc0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:01:32.913683000 9391 0x7fc17e00eb20 INFO GST_PADS gstpad.c:4357:gst_pad_peer_query:<udpsrc0:src> pad has no peer
0:01:32.913705000 9391 0x7fc17e00eb20 INFO GST_EVENT gstevent.c:892:gst_event_new_caps: creating caps event application/x-rtcp
0:01:32.913713000 9391 0x7fc18d942140 INFO GST_ELEMENT_PADS gstelement.c:760:gst_element_add_pad:<funnel0> adding pad 'funnelpad0'
0:01:32.913721000 9391 0x7fc17e00eb20 INFO GST_PADS gstpad.c:4357:gst_pad_peer_query:<udpsrc0:src> pad has no peer
0:01:32.913761000 9391 0x7fc18d942140 INFO GST_PADS gstpad.c:2383:gst_pad_link_prepare: trying to link udpsrc0:src and funnel0:funnelpad0
0:01:32.913796000 9391 0x7fc18d942140 INFO GST_PADS gstpad.c:2591:gst_pad_link_full: linked udpsrc0:src and funnel0:funnelpad0, successful
0:01:32.913806000 9391 0x7fc18d942140 INFO GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:01:32.913825000 9391 0x7fc18d942140 INFO GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "udpsrc"
0:01:32.913847000 9391 0x7fc18d942140 INFO GST_ELEMENT_PADS gstelement.c:760:gst_element_add_pad:<GstBaseSrc@0x7fc18d8082e0> adding pad 'src'
0:01:32.913893000 9391 0x7fc18d942140 INFO udpsrc gstudpsrc.c:1651:gst_udpsrc_open:<udpsrc1> have udp buffer of 786896 bytes
0:01:32.913917000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<udpsrc1> completed state change to READY
0:01:32.913929000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<udpsrc1> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:01:32.913943000 9391 0x7fc18d942140 INFO GST_ELEMENT_PADS gstelement.c:1017:gst_element_get_static_pad: found pad udpsrc1:src
0:01:32.913959000 9391 0x7fc18d942140 INFO GST_ELEMENT_PADS gstelement.c:1017:gst_element_get_static_pad: found pad udpsrc1:src
0:01:32.913985000 9391 0x7fc18d942140 INFO basesrc gstbasesrc.c:1430:gst_base_src_do_seek:<udpsrc1> seeking: time segment start=0:00:00.000000000, offset=0:00:00.000000000, stop=99:99:99.999999999, rate=1.000000, applied_rate=1.000000, flags=0x00, time=0:00:00.000000000, base=0:00:00.000000000, position 0:00:00.000000000, duration 99:99:99.999999999
0:01:32.913999000 9391 0x7fc18d942140 INFO task gsttask.c:516:gst_task_set_lock: setting stream lock 0x7fc18e0203f0 on task 0x7fc17e0243b0
0:01:32.914008000 9391 0x7fc18d942140 INFO GST_PADS gstpad.c:6291:gst_pad_start_task:<udpsrc1:src> created task 0x7fc17e0243b0
0:01:32.914044000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2782:gst_element_continue_state:<udpsrc1> committing state from READY to PAUSED, pending PLAYING, next PLAYING
0:01:32.914057000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<udpsrc1> notifying about state-changed READY to PAUSED (PLAYING pending)
0:01:32.914066000 9391 0x7fc18d92e850 INFO GST_ELEMENT_PADS gstelement.c:1014:gst_element_get_static_pad: no such pad 'sink' in element "udpsrc1"
0:01:32.914091000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2789:gst_element_continue_state:<udpsrc1> continue state change PAUSED to PLAYING, final PLAYING
0:01:32.914097000 9391 0x7fc18d92e850 INFO GST_PADS gstpad.c:4357:gst_pad_peer_query:<udpsrc1:src> pad has no peer
0:01:32.914114000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<udpsrc1> completed state change to PLAYING
0:01:32.914115000 9391 0x7fc18d92e850 INFO GST_EVENT gstevent.c:892:gst_event_new_caps: creating caps event application/x-rtcp
0:01:32.914126000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<udpsrc1> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:01:32.914141000 9391 0x7fc18d92e850 INFO GST_PADS gstpad.c:4357:gst_pad_peer_query:<udpsrc1:src> pad has no peer
0:01:32.914160000 9391 0x7fc18d942140 INFO GST_ELEMENT_PADS gstelement.c:760:gst_element_add_pad:<funnel0> adding pad 'funnelpad1'
0:01:32.914176000 9391 0x7fc18d942140 INFO GST_PADS gstpad.c:2383:gst_pad_link_prepare: trying to link udpsrc1:src and funnel0:funnelpad1
0:01:32.914197000 9391 0x7fc18d942140 INFO GST_PADS gstpad.c:2591:gst_pad_link_full: linked udpsrc1:src and funnel0:funnelpad1, successful
0:01:32.914205000 9391 0x7fc18d942140 INFO GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:01:32.914221000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2782:gst_element_continue_state:<funnel0> committing state from NULL to READY, pending PAUSED, next PAUSED
0:01:32.914231000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<funnel0> notifying about state-changed NULL to READY (PAUSED pending)
0:01:32.914244000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2789:gst_element_continue_state:<funnel0> continue state change READY to PAUSED, final PAUSED
0:01:32.914255000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<funnel0> completed state change to PAUSED
0:01:32.914263000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<funnel0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:01:32.914282000 9391 0x7fc18d942140 INFO GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "tee"
0:01:32.914342000 9391 0x7fc18d942140 INFO GST_ELEMENT_PADS gstelement.c:760:gst_element_add_pad:<GstTee@0x7fc18d959a80> adding pad 'sink'
0:01:32.914383000 9391 0x7fc18d942140 INFO GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "multiudpsink"
0:01:32.914492000 9391 0x7fc18d942140 INFO GST_ELEMENT_PADS gstelement.c:760:gst_element_add_pad:<GstBaseSink@0x7fc18d81aa90> adding pad 'sink'
0:01:32.914544000 9391 0x7fc18d942140 INFO GST_ELEMENT_PADS gstelement.c:760:gst_element_add_pad:<tee0> adding pad 'src_0'
0:01:32.914559000 9391 0x7fc18d942140 INFO GST_ELEMENT_PADS gstelement.c:1017:gst_element_get_static_pad: found pad multiudpsink0:sink
0:01:32.914572000 9391 0x7fc18d942140 INFO GST_PADS gstpad.c:2383:gst_pad_link_prepare: trying to link tee0:src_0 and multiudpsink0:sink
0:01:32.914585000 9391 0x7fc18d942140 INFO GST_PADS gstpad.c:4357:gst_pad_peer_query:<tee0:sink> pad has no peer
0:01:32.914603000 9391 0x7fc18d942140 INFO GST_PADS gstpad.c:2591:gst_pad_link_full: linked tee0:src_0 and multiudpsink0:sink, successful
0:01:32.914610000 9391 0x7fc18d942140 INFO GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:01:32.914618000 9391 0x7fc18d942140 INFO GST_EVENT gstpad.c:5947:gst_pad_send_event_unchecked:<tee0:src_0> Received event on flushing pad. Discarding
0:01:32.914646000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2782:gst_element_continue_state:<multiudpsink0> committing state from NULL to READY, pending PAUSED, next PAUSED
0:01:32.914656000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<multiudpsink0> notifying about state-changed NULL to READY (PAUSED pending)
0:01:32.914671000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2789:gst_element_continue_state:<multiudpsink0> continue state change READY to PAUSED, final PAUSED
0:01:32.914685000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<media-pipeline> notifying about state-changed PAUSED to PAUSED (PAUSED pending)
0:01:32.914708000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2782:gst_element_continue_state:<tee0> committing state from NULL to READY, pending PAUSED, next PAUSED
0:01:32.914718000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<tee0> notifying about state-changed NULL to READY (PAUSED pending)
0:01:32.914730000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2789:gst_element_continue_state:<tee0> continue state change READY to PAUSED, final PAUSED
0:01:32.914741000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<tee0> completed state change to PAUSED
0:01:32.914749000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<tee0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:01:32.914759000 9391 0x7fc18d942140 INFO GST_ELEMENT_PADS gstelement.c:1017:gst_element_get_static_pad: found pad tee0:sink
0:01:32.914770000 9391 0x7fc18d942140 INFO GST_PADS gstpad.c:2383:gst_pad_link_prepare: trying to link rtpbin0:send_rtp_src_0 and tee0:sink
0:01:32.914810000 9391 0x7fc18d942140 INFO GST_PADS gstpad.c:2591:gst_pad_link_full: linked rtpbin0:send_rtp_src_0 and tee0:sink, successful
0:01:32.914819000 9391 0x7fc18d942140 INFO GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:01:32.914848000 9391 0x7fc18d942140 INFO GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "tee"
0:01:32.914875000 9391 0x7fc18d942140 INFO GST_ELEMENT_PADS gstelement.c:760:gst_element_add_pad:<GstTee@0x7fc18d959bd0> adding pad 'sink'
0:01:32.914896000 9391 0x7fc18d942140 INFO GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "multiudpsink"
0:01:32.914913000 9391 0x7fc18d942140 INFO GST_ELEMENT_PADS gstelement.c:760:gst_element_add_pad:<GstBaseSink@0x7fc18d8b4a90> adding pad 'sink'
0:01:32.914957000 9391 0x7fc18d942140 INFO GST_ELEMENT_PADS gstelement.c:760:gst_element_add_pad:<tee1> adding pad 'src_0'
0:01:32.914969000 9391 0x7fc18d942140 INFO GST_ELEMENT_PADS gstelement.c:1017:gst_element_get_static_pad: found pad multiudpsink1:sink
0:01:32.914980000 9391 0x7fc18d942140 INFO GST_PADS gstpad.c:2383:gst_pad_link_prepare: trying to link tee1:src_0 and multiudpsink1:sink
0:01:32.914992000 9391 0x7fc18d942140 INFO GST_PADS gstpad.c:4357:gst_pad_peer_query:<tee1:sink> pad has no peer
0:01:32.915005000 9391 0x7fc18d942140 INFO GST_PADS gstpad.c:2591:gst_pad_link_full: linked tee1:src_0 and multiudpsink1:sink, successful
0:01:32.915012000 9391 0x7fc18d942140 INFO GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:01:32.915020000 9391 0x7fc18d942140 INFO GST_EVENT gstpad.c:5947:gst_pad_send_event_unchecked:<tee1:src_0> Received event on flushing pad. Discarding
0:01:32.915039000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2782:gst_element_continue_state:<multiudpsink1> committing state from NULL to READY, pending PAUSED, next PAUSED
0:01:32.915049000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<multiudpsink1> notifying about state-changed NULL to READY (PAUSED pending)
0:01:32.915059000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2789:gst_element_continue_state:<multiudpsink1> continue state change READY to PAUSED, final PAUSED
0:01:32.915073000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<multiudpsink1> completed state change to PAUSED
0:01:32.915081000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<multiudpsink1> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:01:32.915092000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2782:gst_element_continue_state:<tee1> committing state from NULL to READY, pending PAUSED, next PAUSED
0:01:32.915101000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<tee1> notifying about state-changed NULL to READY (PAUSED pending)
0:01:32.915111000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2789:gst_element_continue_state:<tee1> continue state change READY to PAUSED, final PAUSED
0:01:32.915120000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<tee1> completed state change to PAUSED
0:01:32.915128000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<tee1> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:01:32.915138000 9391 0x7fc18d942140 INFO GST_ELEMENT_PADS gstelement.c:1017:gst_element_get_static_pad: found pad tee1:sink
0:01:32.915149000 9391 0x7fc18d942140 INFO GST_PADS gstpad.c:2383:gst_pad_link_prepare: trying to link rtpbin0:send_rtcp_src_0 and tee1:sink
0:01:32.915166000 9391 0x7fc18d942140 INFO GST_PADS gstpad.c:2591:gst_pad_link_full: linked rtpbin0:send_rtcp_src_0 and tee1:sink, successful
0:01:32.915173000 9391 0x7fc18d942140 INFO GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:01:32.915190000 9391 0x7fc18d942140 INFO rtspstream rtsp-stream.c:4763:update_transport: adding 127.0.0.1:53000-53001
0:01:32.915291000 9391 0x7fc18d934a30 INFO GST_EVENT gstevent.c:1531:gst_event_new_latency: creating latency event 0:00:00.000000000
0:01:32.915329000 9391 0x7fc18d934a30 INFO bin gstbin.c:2760:gst_bin_do_latency_func:<media-pipeline> configured latency of 0:00:00.000000000
0:01:32.915377000 9391 0x7fc18d942140 INFO rtspmedia rtsp-media.c:2904:gst_rtsp_media_seek_trickmode: got 0:00:00.000000000 - 0:00:00.999863944
0:01:32.915391000 9391 0x7fc18d942140 INFO rtspmedia rtsp-media.c:2906:gst_rtsp_media_seek_trickmode: current 0:00:00.000000000 - 0:00:00.999863946
0:01:32.915402000 9391 0x7fc18d942140 INFO rtspmedia rtsp-media.c:691:do_query_position:<GstRTSPStream@0x7fc17e034380> media position: 0:00:00.000000000
0:01:32.915410000 9391 0x7fc18d942140 INFO rtspmedia rtsp-media.c:2913:gst_rtsp_media_seek_trickmode: current media position 0:00:00.000000000
0:01:32.915419000 9391 0x7fc18d942140 INFO rtspmedia rtsp-media.c:2928:gst_rtsp_media_seek_trickmode: seeking to 0:00:00.000000000 - 0:00:00.999863944
0:01:32.915431000 9391 0x7fc18d942140 INFO GST_EVENT gstevent.c:1368:gst_event_new_seek: creating seek rate 1.000000, format TIME, flags 5, start_type 1, start 0:00:00.000000000, stop_type 1, stop 0:00:00.999863944
0:01:32.915519000 9391 0x7fc17d828cf0 INFO wavparse gstwavparse.c:2213:gst_wavparse_stream_data:<wavparse0> Error pushing on srcpad wavparse0:src, reason flushing, is linked? = 1
0:01:32.915542000 9391 0x7fc17d828cf0 INFO task gsttask.c:368:gst_task_func:<wavparse0:sink> Task going to paused
0:01:32.915557000 9391 0x7fc18d942140 INFO default gstsegment.c:385:gst_segment_do_seek: segment updated: time segment start=0:00:00.000000000, offset=0:00:00.000000000, stop=0:00:00.999863944, rate=1.000000, applied_rate=1.000000, flags=0x01, time=0:00:00.000000000, base=0:00:00.000000000, position 0:00:00.000000000, duration 0:00:00.999863946
0:01:32.915578000 9391 0x7fc18d942140 INFO GST_EVENT gstevent.c:582:gst_event_new_flush_stop: creating flush stop 1
0:01:32.915625000 9391 0x7fc18d942140 INFO GST_EVENT gstevent.c:973:gst_event_new_segment: creating segment event time segment start=0:00:00.000000000, offset=0:00:00.000000000, stop=0:00:00.999863944, rate=1.000000, applied_rate=1.000000, flags=0x01, time=0:00:00.000000000, base=0:00:00.000000000, position 0:00:00.000000000, duration 0:00:00.999863946
0:01:32.915642000 9391 0x7fc17d828cf0 INFO task gsttask.c:370:gst_task_func:<wavparse0:sink> Task resume from paused
0:01:32.915649000 9391 0x7fc18d942140 INFO rtspmedia rtsp-media.c:3014:gst_rtsp_media_seek_trickmode: done seeking 1
0:01:32.915699000 9391 0x7fc18d934a30 INFO rtspmedia rtsp-media.c:3353:default_handle_message: 0x7fc17e0181d0: got message type 134217728 (reset-time)
0:01:32.915729000 9391 0x7fc17d828cf0 INFO structure gststructure.c:2918:gst_structure_get_valist: Expected field 'channel-mask' in structure: audio/x-raw, channels=(int)1, rate=(int)44100;
0:01:32.916132000 9391 0x7fc17d828cf0 INFO GST_EVENT gstevent.c:892:gst_event_new_caps: creating caps event application/x-rtp, media=(string)audio, clock-rate=(int)44100, encoding-name=(string)L16, encoding-params=(string)1, channels=(int)1, payload=(int)96, seqnum-offset=(uint)5507, timestamp-offset=(uint)2116688505, ssrc=(uint)1087690860
0:01:32.916214000 9391 0x7fc17d828cf0 INFO rtspstream rtsp-stream.c:2329:caps_notify: stream 0x7fc17e034380 received caps 0x7fc18d9298a0, application/x-rtp, media=(string)audio, clock-rate=(int)44100, encoding-name=(string)L16, encoding-params=(string)1, channels=(int)1, payload=(int)96, seqnum-offset=(uint)5507, timestamp-offset=(uint)2116688505, ssrc=(uint)1087690860
0:01:32.916287000 9391 0x7fc18d934a30 INFO rtspmedia rtsp-media.c:3353:default_handle_message: 0x7fc17e0181d0: got message type 16 (tag)
0:01:32.916309000 9391 0x7fc18d934a30 INFO rtspmedia rtsp-media.c:873:collect_media_stats: collect media stats
0:01:32.916322000 9391 0x7fc18d934a30 INFO rtspmedia rtsp-media.c:691:do_query_position:<GstRTSPStream@0x7fc17e034380> media position: 0:00:00.000000000
0:01:32.916353000 9391 0x7fc18d934a30 INFO rtspmedia rtsp-media.c:909:collect_media_stats: stats: position 0:00:00.000000000, stop 0:00:00.999863944
0:01:32.916391000 9391 0x7fc18d942140 INFO rtspmedia rtsp-media.c:3025:gst_rtsp_media_seek_trickmode: prerolled again
0:01:32.916423000 9391 0x7fc18d942140 INFO rtspmedia rtsp-media.c:873:collect_media_stats: collect media stats
0:01:32.916437000 9391 0x7fc18d942140 INFO rtspmedia rtsp-media.c:691:do_query_position:<GstRTSPStream@0x7fc17e034380> media position: 0:00:00.000000000
0:01:32.916466000 9391 0x7fc18d942140 INFO rtspmedia rtsp-media.c:909:collect_media_stats: stats: position 0:00:00.000000000, stop 0:00:00.999863944
0:01:32.916539000 9391 0x7fc18d942140 INFO rtspmedia rtsp-media.c:4855:gst_rtsp_media_set_state: going to state PLAYING media 0x7fc17e0181d0, target state PAUSED
0:01:32.916557000 9391 0x7fc18d942140 INFO rtspmedia rtsp-media.c:4911:gst_rtsp_media_set_state: state 4 active 1 media 0x7fc17e0181d0 do_state 1
0:01:32.916566000 9391 0x7fc18d942140 INFO rtspmedia rtsp-media.c:4763:media_set_pipeline_state_locked: state PLAYING media 0x7fc17e0181d0
0:01:32.916573000 9391 0x7fc18d942140 INFO rtspmedia rtsp-media.c:3152:set_target_state: set target state to PLAYING for media 0x7fc17e0181d0
0:01:32.916581000 9391 0x7fc18d942140 INFO rtspmedia rtsp-media.c:3139:set_state: set state to PLAYING for media 0x7fc17e0181d0
0:01:32.916591000 9391 0x7fc17d828cf0 INFO GST_STATES gstbin.c:3401:bin_handle_async_done:<media-pipeline> committing state from PAUSED to PAUSED, old pending PAUSED
0:01:32.916608000 9391 0x7fc17d828cf0 INFO GST_STATES gstbin.c:3421:bin_handle_async_done:<media-pipeline> completed state change, pending VOID
0:01:32.916621000 9391 0x7fc17d828cf0 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<media-pipeline> notifying about state-changed PAUSED to PAUSED (VOID_PENDING pending)
0:01:32.916703000 9391 0x7fc18d942140 INFO GST_EVENT gstevent.c:1531:gst_event_new_latency: creating latency event 0:00:00.000000000
0:01:32.916750000 9391 0x7fc18d942140 INFO bin gstbin.c:2760:gst_bin_do_latency_func:<media-pipeline> configured latency of 0:00:00.000000000
0:01:32.916775000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<multiudpsink1> current PAUSED pending VOID_PENDING, desired next PLAYING
0:01:32.916787000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<multiudpsink1> completed state change to PLAYING
0:01:32.916812000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<multiudpsink1> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:01:32.916825000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<media-pipeline> child 'multiudpsink1' changed state to 4(PLAYING) successfully
0:01:32.916837000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<multiudpsink0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:01:32.916848000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<multiudpsink0> completed state change to PLAYING
0:01:32.916858000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<multiudpsink0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:01:32.916908000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<media-pipeline> child 'multiudpsink0' changed state to 4(PLAYING) successfully
0:01:32.916930000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<tee1> current PAUSED pending VOID_PENDING, desired next PLAYING
0:01:32.916944000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<tee1> completed state change to PLAYING
0:01:32.916953000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<tee1> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:01:32.916966000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<media-pipeline> child 'tee1' changed state to 4(PLAYING) successfully
0:01:32.916977000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<tee0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:01:32.916985000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<tee0> completed state change to PLAYING
0:01:32.916993000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<tee0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:01:32.917003000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<media-pipeline> child 'tee0' changed state to 4(PLAYING) successfully
0:01:32.917015000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<rtpbin0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:01:32.917030000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<rtpstorage0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:01:32.917039000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<rtpstorage0> completed state change to PLAYING
0:01:32.917047000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<rtpstorage0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:01:32.917058000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<rtpbin0> child 'rtpstorage0' changed state to 4(PLAYING) successfully
0:01:32.917070000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<rtpssrcdemux0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:01:32.917078000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<rtpssrcdemux0> completed state change to PLAYING
0:01:32.917087000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<rtpssrcdemux0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:01:32.917097000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<rtpbin0> child 'rtpssrcdemux0' changed state to 4(PLAYING) successfully
0:01:32.917107000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<rtpsession0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:01:32.917129000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<rtpsession0> completed state change to PLAYING
0:01:32.917139000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<rtpsession0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:01:32.917151000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<rtpbin0> child 'rtpsession0' changed state to 4(PLAYING) successfully
0:01:32.917160000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<rtpbin0> completed state change to PLAYING
0:01:32.917168000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<rtpbin0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:01:32.917179000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<media-pipeline> child 'rtpbin0' changed state to 4(PLAYING) successfully
0:01:32.917189000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<GstRTSPMediaFactoryURI> current PAUSED pending VOID_PENDING, desired next PLAYING
0:01:32.917201000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<dynpay0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:01:32.917217000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<pay_src_0> current PLAYING pending VOID_PENDING, desired next PLAYING
0:01:32.917225000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2600:gst_bin_element_set_state:<pay_src_0> skipping transition from PLAYING to PLAYING
0:01:32.917233000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<dynpay0> child 'pay_src_0' changed state to 4(PLAYING) successfully
0:01:32.917243000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<audioconvert0> current PLAYING pending VOID_PENDING, desired next PLAYING
0:01:32.917251000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2600:gst_bin_element_set_state:<audioconvert0> skipping transition from PLAYING to PLAYING
0:01:32.917259000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<dynpay0> child 'audioconvert0' changed state to 4(PLAYING) successfully
0:01:32.917273000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<uribin> current PAUSED pending VOID_PENDING, desired next PLAYING
0:01:32.917312000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<decodebin0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:01:32.917339000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<wavparse0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:01:32.917349000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<wavparse0> completed state change to PLAYING
0:01:32.917358000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<wavparse0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:01:32.917373000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<decodebin0> child 'wavparse0' changed state to 4(PLAYING) successfully
0:01:32.917384000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<typefind> current PAUSED pending VOID_PENDING, desired next PLAYING
0:01:32.917393000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<typefind> completed state change to PLAYING
0:01:32.917401000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<typefind> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:01:32.917413000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<decodebin0> child 'typefind' changed state to 4(PLAYING) successfully
0:01:32.917422000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<decodebin0> completed state change to PLAYING
0:01:32.917431000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<decodebin0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:01:32.917442000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<uribin> child 'decodebin0' changed state to 4(PLAYING) successfully
0:01:32.917452000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<source> current PAUSED pending VOID_PENDING, desired next PLAYING
0:01:32.917460000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<source> completed state change to PLAYING
0:01:32.917468000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<source> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:01:32.917478000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<uribin> child 'source' changed state to 4(PLAYING) successfully
0:01:32.917487000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<uribin> completed state change to PLAYING
0:01:32.917495000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<uribin> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:01:32.917505000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<dynpay0> child 'uribin' changed state to 4(PLAYING) successfully
0:01:32.917516000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<dynpay0> completed state change to PLAYING
0:01:32.917524000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<dynpay0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:01:32.917537000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<GstRTSPMediaFactoryURI> child 'dynpay0' changed state to 4(PLAYING) successfully
0:01:32.917546000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<GstRTSPMediaFactoryURI> completed state change to PLAYING
0:01:32.917555000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<GstRTSPMediaFactoryURI> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:01:32.917565000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<media-pipeline> child 'GstRTSPMediaFactoryURI' changed state to 4(PLAYING) successfully
0:01:32.917577000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<funnel0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:01:32.917585000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<funnel0> completed state change to PLAYING
0:01:32.917592000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<funnel0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:01:32.917602000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<media-pipeline> child 'funnel0' changed state to 4(PLAYING) successfully
0:01:32.917612000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<media-pipeline> child 'udpsrc0' changed state to 4(PLAYING) successfully
0:01:32.917620000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<media-pipeline> child 'udpsrc1' changed state to 4(PLAYING) successfully
0:01:32.917630000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<media-pipeline> completed state change to PLAYING
0:01:32.917638000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<media-pipeline> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:01:32.917647000 9391 0x7fc18d942140 INFO rtspmedia rtsp-media.c:873:collect_media_stats: collect media stats
0:01:32.917668000 9391 0x7fc18d942140 INFO rtspmedia rtsp-media.c:691:do_query_position:<GstRTSPStream@0x7fc17e034380> media position: 0:00:00.001036976
0:01:32.917696000 9391 0x7fc18d942140 INFO rtspmedia rtsp-media.c:909:collect_media_stats: stats: position 0:00:00.001036976, stop 0:00:00.999863944
0:01:32.917812000 9391 0x7fc18d934a30 INFO GST_EVENT gstevent.c:1531:gst_event_new_latency: creating latency event 0:00:00.000000000
0:01:32.917858000 9391 0x7fc18d934a30 INFO bin gstbin.c:2760:gst_bin_do_latency_func:<media-pipeline> configured latency of 0:00:00.000000000
0:01:32.917879000 9391 0x7fc18d934a30 INFO rtspmedia rtsp-media.c:3353:default_handle_message: 0x7fc17e0181d0: got message type 2048 (new-clock)
0:01:33.917202000 9391 0x7fc17d828cf0 INFO task gsttask.c:368:gst_task_func:<wavparse0:sink> Task going to paused
0:01:33.917495000 9391 0x7fc18d92da80 INFO GST_EVENT gstevent.c:892:gst_event_new_caps: creating caps event application/x-rtcp
0:01:33.917683000 9391 0x7fc18d92da80 INFO GST_EVENT gstevent.c:973:gst_event_new_segment: creating segment event time segment start=0:00:00.000000000, offset=0:00:00.000000000, stop=99:99:99.999999999, rate=1.000000, applied_rate=1.000000, flags=0x00, time=0:00:00.000000000, base=0:00:00.000000000, position 0:00:00.000000000, duration 99:99:99.999999999
0:01:33.917975000 9391 0x7fc18d92da80 INFO rtspstream rtsp-stream.c:2346:dump_structure: structure: application/x-rtp-source-stats, ssrc=(uint)1087690860, internal=(boolean)true, validated=(boolean)true, received-bye=(boolean)true, is-csrc=(boolean)false, is-sender=(boolean)true, seqnum-base=(int)5507, clock-rate=(int)44100, octets-sent=(guint64)89576, packets-sent=(guint64)66, octets-received=(guint64)89576, packets-received=(guint64)66, bytes-received=(guint64)92216, bitrate=(guint64)0, packets-lost=(int)-66, jitter=(uint)0, sent-pli-count=(uint)0, recv-pli-count=(uint)0, sent-fir-count=(uint)0, recv-fir-count=(uint)0, sent-nack-count=(uint)0, recv-nack-count=(uint)0, recv-packet-rate=(uint)0, have-sr=(boolean)true, sr-ntptime=(guint64)16558909707540192011, sr-rtptime=(uint)2116732632, sr-octet-count=(uint)89576, sr-packet-count=(uint)66;
0:01:33.917995000 9391 0x7fc18d934a30 INFO GST_EVENT gstevent.c:1531:gst_event_new_latency: creating latency event 0:00:00.000000000
0:01:33.918026000 9391 0x7fc18d92da80 INFO rtspstream rtsp-stream.c:2501:on_timeout: 0x7fc17e034380: source 0x7fc17e03a3e0 timeout
0:01:33.918184000 9391 0x7fc18d934a30 INFO bin gstbin.c:2760:gst_bin_do_latency_func:<media-pipeline> configured latency of 0:00:00.000000000
0:01:33.918236000 9391 0x7fc18d934a30 INFO rtspmedia rtsp-media.c:3344:default_handle_message: 0x7fc17e0181d0: got EOS
0:01:35.759383000 9391 0x7fc18d942140 INFO rtspclient rtsp-client.c:3981:handle_request: client 0x7fc18e00e190: received a request PAUSE rtsp://localhost:8554/test/ 1.0
0:01:35.759430000 9391 0x7fc18d942140 INFO rtspmedia rtsp-media.c:4855:gst_rtsp_media_set_state: going to state PAUSED media 0x7fc17e0181d0, target state PLAYING
0:01:35.759446000 9391 0x7fc18d942140 INFO rtspstream rtsp-stream.c:4767:update_transport: removing 127.0.0.1:53000-53001
0:01:35.759478000 9391 0x7fc18d942140 INFO rtspmedia rtsp-media.c:4911:gst_rtsp_media_set_state: state 3 active 0 media 0x7fc17e0181d0 do_state 1
0:01:35.759489000 9391 0x7fc18d942140 INFO rtspmedia rtsp-media.c:4763:media_set_pipeline_state_locked: state PAUSED media 0x7fc17e0181d0
0:01:35.759498000 9391 0x7fc18d942140 INFO rtspmedia rtsp-media.c:3152:set_target_state: set target state to PAUSED for media 0x7fc17e0181d0
0:01:35.759507000 9391 0x7fc18d942140 INFO rtspmedia rtsp-media.c:3139:set_state: set state to PAUSED for media 0x7fc17e0181d0
0:01:35.759544000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<multiudpsink1> current PLAYING pending VOID_PENDING, desired next PAUSED
0:01:35.759578000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<multiudpsink1> completed state change to PAUSED
0:01:35.759592000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<multiudpsink1> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:01:35.759615000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<media-pipeline> child 'multiudpsink1' changed state to 3(PAUSED) successfully
0:01:35.759630000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<multiudpsink0> current PLAYING pending VOID_PENDING, desired next PAUSED
0:01:35.759655000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<multiudpsink0> completed state change to PAUSED
0:01:35.759666000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<multiudpsink0> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:01:35.759682000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<media-pipeline> child 'multiudpsink0' changed state to 3(PAUSED) successfully
0:01:35.759700000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<tee1> current PLAYING pending VOID_PENDING, desired next PAUSED
0:01:35.759711000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<tee1> completed state change to PAUSED
0:01:35.759721000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<tee1> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:01:35.759734000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<media-pipeline> child 'tee1' changed state to 3(PAUSED) successfully
0:01:35.759747000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<tee0> current PLAYING pending VOID_PENDING, desired next PAUSED
0:01:35.759756000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<tee0> completed state change to PAUSED
0:01:35.759766000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<tee0> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:01:35.759778000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<media-pipeline> child 'tee0' changed state to 3(PAUSED) successfully
0:01:35.759792000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<rtpbin0> current PLAYING pending VOID_PENDING, desired next PAUSED
0:01:35.759812000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<rtpstorage0> current PLAYING pending VOID_PENDING, desired next PAUSED
0:01:35.759822000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<rtpstorage0> completed state change to PAUSED
0:01:35.759833000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<rtpstorage0> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:01:35.759848000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<rtpbin0> child 'rtpstorage0' changed state to 3(PAUSED) successfully
0:01:35.759862000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<rtpssrcdemux0> current PLAYING pending VOID_PENDING, desired next PAUSED
0:01:35.759872000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<rtpssrcdemux0> completed state change to PAUSED
0:01:35.759882000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<rtpssrcdemux0> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:01:35.759895000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<rtpbin0> child 'rtpssrcdemux0' changed state to 3(PAUSED) successfully
0:01:35.759908000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<rtpsession0> current PLAYING pending VOID_PENDING, desired next PAUSED
0:01:35.759925000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<rtpsession0> completed state change to PAUSED
0:01:35.759936000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<rtpsession0> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:01:35.759952000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<rtpbin0> child 'rtpsession0' changed state to 3(PAUSED) successfully
0:01:35.759964000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<rtpbin0> completed state change to PAUSED
0:01:35.759974000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<rtpbin0> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:01:35.759993000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<media-pipeline> child 'rtpbin0' changed state to 3(PAUSED) successfully
0:01:35.760009000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<GstRTSPMediaFactoryURI> current PLAYING pending VOID_PENDING, desired next PAUSED
0:01:35.760024000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<dynpay0> current PLAYING pending VOID_PENDING, desired next PAUSED
0:01:35.760042000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<pay_src_0> current PLAYING pending VOID_PENDING, desired next PAUSED
0:01:35.760053000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<pay_src_0> completed state change to PAUSED
0:01:35.760063000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<pay_src_0> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:01:35.760078000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<dynpay0> child 'pay_src_0' changed state to 3(PAUSED) successfully
0:01:35.760090000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<audioconvert0> current PLAYING pending VOID_PENDING, desired next PAUSED
0:01:35.760102000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<audioconvert0> completed state change to PAUSED
0:01:35.760112000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<audioconvert0> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:01:35.760126000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<dynpay0> child 'audioconvert0' changed state to 3(PAUSED) successfully
0:01:35.760138000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<uribin> current PLAYING pending VOID_PENDING, desired next PAUSED
0:01:35.760155000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<decodebin0> current PLAYING pending VOID_PENDING, desired next PAUSED
0:01:35.760172000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<wavparse0> current PLAYING pending VOID_PENDING, desired next PAUSED
0:01:35.760182000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<wavparse0> completed state change to PAUSED
0:01:35.760192000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<wavparse0> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:01:35.760207000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<decodebin0> child 'wavparse0' changed state to 3(PAUSED) successfully
0:01:35.760220000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<typefind> current PLAYING pending VOID_PENDING, desired next PAUSED
0:01:35.760230000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<typefind> completed state change to PAUSED
0:01:35.760240000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<typefind> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:01:35.760254000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<decodebin0> child 'typefind' changed state to 3(PAUSED) successfully
0:01:35.760265000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<decodebin0> completed state change to PAUSED
0:01:35.760276000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<decodebin0> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:01:35.760290000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<uribin> child 'decodebin0' changed state to 3(PAUSED) successfully
0:01:35.760302000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<source> current PLAYING pending VOID_PENDING, desired next PAUSED
0:01:35.760312000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<source> completed state change to PAUSED
0:01:35.760322000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<source> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:01:35.760336000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<uribin> child 'source' changed state to 3(PAUSED) successfully
0:01:35.760347000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<uribin> completed state change to PAUSED
0:01:35.760357000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<uribin> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:01:35.760370000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<dynpay0> child 'uribin' changed state to 3(PAUSED) successfully
0:01:35.760381000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<dynpay0> completed state change to PAUSED
0:01:35.760391000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<dynpay0> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:01:35.760404000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<GstRTSPMediaFactoryURI> child 'dynpay0' changed state to 3(PAUSED) successfully
0:01:35.760415000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<GstRTSPMediaFactoryURI> completed state change to PAUSED
0:01:35.760426000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<GstRTSPMediaFactoryURI> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:01:35.760438000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<media-pipeline> child 'GstRTSPMediaFactoryURI' changed state to 3(PAUSED) successfully
0:01:35.760452000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<funnel0> current PLAYING pending VOID_PENDING, desired next PAUSED
0:01:35.760462000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<funnel0> completed state change to PAUSED
0:01:35.760472000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<funnel0> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:01:35.760484000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<media-pipeline> child 'funnel0' changed state to 3(PAUSED) successfully
0:01:35.760496000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<media-pipeline> child 'udpsrc0' changed state to 3(PAUSED) successfully
0:01:35.760508000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<media-pipeline> child 'udpsrc1' changed state to 3(PAUSED) successfully
0:01:35.760520000 9391 0x7fc18d942140 INFO pipeline gstpipeline.c:534:gst_pipeline_change_state:<media-pipeline> pipeline is not live
0:01:35.760531000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<media-pipeline> completed state change to PAUSED
0:01:35.760542000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<media-pipeline> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:01:35.760553000 9391 0x7fc18d942140 FIXME rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
0:01:35.786624000 9391 0x7fc18d942140 INFO rtspclient rtsp-client.c:3981:handle_request: client 0x7fc18e00e190: received a request TEARDOWN rtsp://localhost:8554/test/ 1.0
0:01:35.786670000 9391 0x7fc18d942140 INFO rtspmedia rtsp-media.c:4855:gst_rtsp_media_set_state: going to state NULL media 0x7fc17e0181d0, target state PAUSED
0:01:35.786683000 9391 0x7fc18d942140 INFO rtspmedia rtsp-media.c:4911:gst_rtsp_media_set_state: state 1 active 0 media 0x7fc17e0181d0 do_state 1
0:01:35.786691000 9391 0x7fc18d942140 INFO rtspmedia rtsp-media.c:4107:gst_rtsp_media_unprepare: unprepare media 0x7fc17e0181d0
0:01:35.786699000 9391 0x7fc18d942140 INFO rtspmedia rtsp-media.c:3152:set_target_state: set target state to NULL for media 0x7fc17e0181d0
0:01:35.786707000 9391 0x7fc18d942140 INFO rtspmedia rtsp-media.c:3139:set_state: set state to NULL for media 0x7fc17e0181d0
0:01:35.786742000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<multiudpsink1> current PAUSED pending VOID_PENDING, desired next READY
0:01:35.786783000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<multiudpsink1> completed state change to READY
0:01:35.786795000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<multiudpsink1> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:01:35.786815000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<media-pipeline> child 'multiudpsink1' changed state to 2(READY) successfully
0:01:35.786827000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<multiudpsink0> current PAUSED pending VOID_PENDING, desired next READY
0:01:35.786863000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<multiudpsink0> completed state change to READY
0:01:35.786873000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<multiudpsink0> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:01:35.786887000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<media-pipeline> child 'multiudpsink0' changed state to 2(READY) successfully
0:01:35.786898000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<tee1> current PAUSED pending VOID_PENDING, desired next READY
0:01:35.786914000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<tee1> completed state change to READY
0:01:35.786922000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<tee1> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:01:35.786933000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<media-pipeline> child 'tee1' changed state to 2(READY) successfully
0:01:35.786944000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<tee0> current PAUSED pending VOID_PENDING, desired next READY
0:01:35.786960000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<tee0> completed state change to READY
0:01:35.786969000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<tee0> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:01:35.786979000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<media-pipeline> child 'tee0' changed state to 2(READY) successfully
0:01:35.786990000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<rtpbin0> current PAUSED pending VOID_PENDING, desired next READY
0:01:35.787008000 9391 0x7fc18d942140 INFO rtspstream rtsp-stream.c:2329:caps_notify: stream 0x7fc17e034380 received caps 0x0, (NULL)
0:01:35.787032000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<rtpstorage0> current PAUSED pending VOID_PENDING, desired next READY
0:01:35.787044000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<rtpstorage0> completed state change to READY
0:01:35.787052000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<rtpstorage0> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:01:35.787063000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<rtpbin0> child 'rtpstorage0' changed state to 2(READY) successfully
0:01:35.787073000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<rtpssrcdemux0> current PAUSED pending VOID_PENDING, desired next READY
0:01:35.787085000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<rtpssrcdemux0> completed state change to READY
0:01:35.787094000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<rtpssrcdemux0> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:01:35.787104000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<rtpbin0> child 'rtpssrcdemux0' changed state to 2(READY) successfully
0:01:35.787115000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<rtpsession0> current PAUSED pending VOID_PENDING, desired next READY
0:01:35.787165000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<rtpsession0> completed state change to READY
0:01:35.787177000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<rtpsession0> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:01:35.787189000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<rtpbin0> child 'rtpsession0' changed state to 2(READY) successfully
0:01:35.787207000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<rtpbin0> completed state change to READY
0:01:35.787216000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<rtpbin0> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:01:35.787228000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<media-pipeline> child 'rtpbin0' changed state to 2(READY) successfully
0:01:35.787239000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<GstRTSPMediaFactoryURI> current PAUSED pending VOID_PENDING, desired next READY
0:01:35.787256000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<dynpay0> current PAUSED pending VOID_PENDING, desired next READY
0:01:35.787278000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<pay_src_0> current PAUSED pending VOID_PENDING, desired next READY
0:01:35.787298000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<pay_src_0> completed state change to READY
0:01:35.787307000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<pay_src_0> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:01:35.787318000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<dynpay0> child 'pay_src_0' changed state to 2(READY) successfully
0:01:35.787329000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<audioconvert0> current PAUSED pending VOID_PENDING, desired next READY
0:01:35.787345000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<audioconvert0> completed state change to READY
0:01:35.787354000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<audioconvert0> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:01:35.787365000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<dynpay0> child 'audioconvert0' changed state to 2(READY) successfully
0:01:35.787374000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<uribin> current PAUSED pending VOID_PENDING, desired next READY
0:01:35.787396000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<decodebin0> current PAUSED pending VOID_PENDING, desired next READY
0:01:35.787422000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<wavparse0> current PAUSED pending VOID_PENDING, desired next READY
0:01:35.787465000 9391 0x7fc17e012000 INFO task gsttask.c:370:gst_task_func:<typefind:sink> Task resume from paused
0:01:35.787496000 9391 0x7fc17d828cf0 INFO task gsttask.c:370:gst_task_func:<wavparse0:sink> Task resume from paused
0:01:35.787516000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<wavparse0> completed state change to READY
0:01:35.787527000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<wavparse0> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:01:35.787539000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<decodebin0> child 'wavparse0' changed state to 2(READY) successfully
0:01:35.787549000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<typefind> current PAUSED pending VOID_PENDING, desired next READY
0:01:35.787561000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<typefind> completed state change to READY
0:01:35.787569000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<typefind> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:01:35.787581000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<decodebin0> child 'typefind' changed state to 2(READY) successfully
0:01:35.787594000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<wavparse0> completed state change to NULL
0:01:35.787603000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<wavparse0> notifying about state-changed READY to NULL (VOID_PENDING pending)
0:01:35.787618000 9391 0x7fc18d942140 INFO GST_ELEMENT_PADS gstpad.c:2139:gst_pad_unlink: unlinking typefind:src(0x7fc17e022650) and wavparse0:sink(0x7fc17e022af0)
0:01:35.787632000 9391 0x7fc18d942140 INFO GST_ELEMENT_PADS gstpad.c:2193:gst_pad_unlink: unlinked typefind:src and wavparse0:sink
0:01:35.787645000 9391 0x7fc18d942140 INFO GST_ELEMENT_PADS gstpad.c:2139:gst_pad_unlink: unlinking wavparse0:src(0x7fc17e022d40) and src_0:proxypad1(0x7fc17e0153c0)
0:01:35.787655000 9391 0x7fc18d942140 INFO GST_ELEMENT_PADS gstpad.c:2193:gst_pad_unlink: unlinked wavparse0:src and src_0:proxypad1
0:01:35.787665000 9391 0x7fc18d942140 INFO GST_PARENTAGE gstbin.c:1804:gst_bin_remove_func:<decodebin0> removed child "wavparse0"
0:01:35.787681000 9391 0x7fc18d942140 INFO GST_ELEMENT_PADS gstelement.c:876:gst_element_remove_pad:<decodebin0> removing pad 'src_0'
0:01:35.787691000 9391 0x7fc18d942140 INFO GST_ELEMENT_PADS gstpad.c:2139:gst_pad_unlink: unlinking decodebin0:src_0(0x7fc18f81eb80) and src_0:proxypad2(0x7fc17e015d40)
0:01:35.787701000 9391 0x7fc18d942140 INFO GST_ELEMENT_PADS gstpad.c:2193:gst_pad_unlink: unlinked decodebin0:src_0 and src_0:proxypad2
0:01:35.787714000 9391 0x7fc18d942140 INFO GST_ELEMENT_PADS gstelement.c:876:gst_element_remove_pad:<uribin> removing pad 'src_0'
0:01:35.787723000 9391 0x7fc18d942140 INFO GST_ELEMENT_PADS gstpad.c:2139:gst_pad_unlink: unlinking uribin:src_0(0x7fc18f824a10) and audioconvert0:sink(0x7fc17e023430)
0:01:35.787734000 9391 0x7fc18d942140 INFO GST_ELEMENT_PADS gstpad.c:2193:gst_pad_unlink: unlinked uribin:src_0 and audioconvert0:sink
0:01:35.787776000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<wavparse0> completed state change to NULL
0:01:35.787786000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<decodebin0> completed state change to READY
0:01:35.787795000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<decodebin0> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:01:35.787806000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<uribin> child 'decodebin0' changed state to 2(READY) successfully
0:01:35.787820000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<decodebin0> current READY pending VOID_PENDING, desired next READY
0:01:35.787833000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<typefind> current READY pending VOID_PENDING, desired next READY
0:01:35.787841000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2600:gst_bin_element_set_state:<typefind> skipping transition from READY to READY
0:01:35.787850000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<decodebin0> child 'typefind' changed state to 2(READY) successfully
0:01:35.787858000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<decodebin0> completed state change to READY
0:01:35.787867000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<uribin> child 'decodebin0' changed state to 2(READY) successfully
0:01:35.787876000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<source> current PAUSED pending VOID_PENDING, desired next READY
0:01:35.787885000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<source> completed state change to READY
0:01:35.787893000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<source> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:01:35.787904000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<uribin> child 'source' changed state to 2(READY) successfully
0:01:35.787919000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<typefind> current READY pending VOID_PENDING, desired next READY
0:01:35.787928000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2600:gst_bin_element_set_state:<typefind> skipping transition from READY to READY
0:01:35.787936000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<decodebin0> child 'typefind' changed state to 2(READY) successfully
0:01:35.787944000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<decodebin0> completed state change to READY
0:01:35.787957000 9391 0x7fc18d942140 INFO GST_ELEMENT_PADS gstpad.c:2139:gst_pad_unlink: unlinking source:src(0x7fc17e0221b0) and decodebin0:sink(0x7fc18f824050)
0:01:35.787968000 9391 0x7fc18d942140 INFO GST_ELEMENT_PADS gstpad.c:2193:gst_pad_unlink: unlinked source:src and decodebin0:sink
0:01:35.787979000 9391 0x7fc18d942140 INFO GST_PARENTAGE gstbin.c:1804:gst_bin_remove_func:<uribin> removed child "decodebin0"
0:01:35.788011000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<source> completed state change to NULL
0:01:35.788022000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<source> notifying about state-changed READY to NULL (VOID_PENDING pending)
0:01:35.788034000 9391 0x7fc18d942140 INFO GST_PARENTAGE gstbin.c:1804:gst_bin_remove_func:<uribin> removed child "source"
0:01:35.788049000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<uribin> completed state change to READY
0:01:35.788058000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<uribin> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:01:35.788069000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<dynpay0> child 'uribin' changed state to 2(READY) successfully
0:01:35.788082000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<pay_src_0> current READY pending VOID_PENDING, desired next READY
0:01:35.788089000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2600:gst_bin_element_set_state:<pay_src_0> skipping transition from READY to READY
0:01:35.788097000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<dynpay0> child 'pay_src_0' changed state to 2(READY) successfully
0:01:35.788107000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<audioconvert0> current READY pending VOID_PENDING, desired next READY
0:01:35.788114000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2600:gst_bin_element_set_state:<audioconvert0> skipping transition from READY to READY
0:01:35.788122000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<dynpay0> child 'audioconvert0' changed state to 2(READY) successfully
0:01:35.788131000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<uribin> current READY pending VOID_PENDING, desired next READY
0:01:35.788141000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<uribin> completed state change to READY
0:01:35.788150000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<dynpay0> child 'uribin' changed state to 2(READY) successfully
0:01:35.788159000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<dynpay0> completed state change to READY
0:01:35.788167000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<dynpay0> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:01:35.788178000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<GstRTSPMediaFactoryURI> child 'dynpay0' changed state to 2(READY) successfully
0:01:35.788188000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<GstRTSPMediaFactoryURI> completed state change to READY
0:01:35.788197000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<GstRTSPMediaFactoryURI> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:01:35.788207000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<media-pipeline> child 'GstRTSPMediaFactoryURI' changed state to 2(READY) successfully
0:01:35.788218000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<funnel0> current PAUSED pending VOID_PENDING, desired next READY
0:01:35.788232000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<funnel0> completed state change to READY
0:01:35.788241000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<funnel0> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:01:35.788252000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<media-pipeline> child 'funnel0' changed state to 2(READY) successfully
0:01:35.788261000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<media-pipeline> child 'udpsrc0' changed state to 2(READY) successfully
0:01:35.788270000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<media-pipeline> child 'udpsrc1' changed state to 2(READY) successfully
0:01:35.788286000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2782:gst_element_continue_state:<media-pipeline> committing state from PAUSED to READY, pending NULL, next NULL
0:01:35.788296000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<media-pipeline> notifying about state-changed PAUSED to READY (NULL pending)
0:01:35.788306000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2789:gst_element_continue_state:<media-pipeline> continue state change READY to NULL, final NULL
0:01:35.788325000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<multiudpsink1> current READY pending VOID_PENDING, desired next NULL
0:01:35.788336000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<multiudpsink1> completed state change to NULL
0:01:35.788345000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<multiudpsink1> notifying about state-changed READY to NULL (VOID_PENDING pending)
0:01:35.788354000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<media-pipeline> child 'multiudpsink1' changed state to 1(NULL) successfully
0:01:35.788364000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<multiudpsink0> current READY pending VOID_PENDING, desired next NULL
0:01:35.788374000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<multiudpsink0> completed state change to NULL
0:01:35.788382000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<multiudpsink0> notifying about state-changed READY to NULL (VOID_PENDING pending)
0:01:35.788391000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<media-pipeline> child 'multiudpsink0' changed state to 1(NULL) successfully
0:01:35.788401000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<tee1> current READY pending VOID_PENDING, desired next NULL
0:01:35.788411000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<tee1> completed state change to NULL
0:01:35.788418000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<tee1> notifying about state-changed READY to NULL (VOID_PENDING pending)
0:01:35.788428000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<media-pipeline> child 'tee1' changed state to 1(NULL) successfully
0:01:35.788454000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<tee0> current READY pending VOID_PENDING, desired next NULL
0:01:35.788464000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<tee0> completed state change to NULL
0:01:35.788473000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<tee0> notifying about state-changed READY to NULL (VOID_PENDING pending)
0:01:35.788482000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<media-pipeline> child 'tee0' changed state to 1(NULL) successfully
0:01:35.788493000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<rtpbin0> current READY pending VOID_PENDING, desired next NULL
0:01:35.788509000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<rtpstorage0> current READY pending VOID_PENDING, desired next NULL
0:01:35.788518000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<rtpstorage0> completed state change to NULL
0:01:35.788526000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<rtpstorage0> notifying about state-changed READY to NULL (VOID_PENDING pending)
0:01:35.788537000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<rtpbin0> child 'rtpstorage0' changed state to 1(NULL) successfully
0:01:35.788547000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<rtpssrcdemux0> current READY pending VOID_PENDING, desired next NULL
0:01:35.788557000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<rtpssrcdemux0> completed state change to NULL
0:01:35.788565000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<rtpssrcdemux0> notifying about state-changed READY to NULL (VOID_PENDING pending)
0:01:35.788575000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<rtpbin0> child 'rtpssrcdemux0' changed state to 1(NULL) successfully
0:01:35.788585000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<rtpsession0> current READY pending VOID_PENDING, desired next NULL
0:01:35.788596000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<rtpsession0> completed state change to NULL
0:01:35.788603000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<rtpsession0> notifying about state-changed READY to NULL (VOID_PENDING pending)
0:01:35.788613000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<rtpbin0> child 'rtpsession0' changed state to 1(NULL) successfully
0:01:35.788624000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2807:gst_element_continue_state:<rtpbin0> completed state change to NULL
0:01:35.788633000 9391 0x7fc18d942140 INFO GST_STATES gstelement.c:2710:_priv_gst_element_state_changed:<rtpbin0> notifying about state-changed READY to NULL (VOID_PENDING pending)
0:01:35.788644000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2931:gst_bin_change_state_func:<media-pipeline> child 'rtpbin0' changed state to 1(NULL) successfully
0:01:35.788654000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<GstRTSPMediaFactoryURI> current READY pending VOID_PENDING, desired next NULL
0:01:35.788666000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<dynpay0> current READY pending VOID_PENDING, desired next NULL
0:01:35.788678000 9391 0x7fc18d942140 INFO GST_STATES gstbin.c:2483:gst_bin_element_set_state:<pay_src_0> current READY pending VOID_PENDING, desired next NULL