      GST_DEBUG gstsystemclock.c:148:???:[00m test_single_shot started 
      GST_CLOCK gstsystemclock.c:266:gst_system_clock_obtain():[00m creating new static system clock
      GST_REFCOUNTING gstobject.c:270:gst_object_init(struct _GstObject *):<GstObject@0x124b8010>[00m 0x124b8010 new
      GST_REFCOUNTING gstobject.c:370:gst_object_ref_sink(void *):<GstSystemClock>[00m unsetting floating flag
      GST_REFCOUNTING gstobject.c:306:gst_object_ref(void *):<GstSystemClock>[00m 0x124b8010 ref 1->2
      GST_CLOCK gstclock.c:907:gst_clock_get_internal_time(struct _GstClock *):<GstSystemClock>[00m internal time 350550:39:20.255016825
      GST_CLOCK gstclock.c:956:gst_clock_get_time(struct _GstClock *):<GstSystemClock>[00m adjusted time 350550:39:20.255016825
      GST_DEBUG gstsystemclock.c:88:???:[00m Clock info: time 350550:39:20.255016825
      GST_CLOCK gstclock.c:907:gst_clock_get_internal_time(struct _GstClock *):<GstSystemClock>[00m internal time 350550:39:20.255016825
      GST_CLOCK gstclock.c:956:gst_clock_get_time(struct _GstClock *):<GstSystemClock>[00m adjusted time 350550:39:20.255016825
      GST_CLOCK gstclock.c:200:gst_clock_entry_new(struct _GstClock *, unsigned long long, unsigned long long, @enum$3957gstclock_c):<GstSystemClock>[00m created entry 0x12482050, time 350550:39:20.455016825
      GST_DEBUG gstsystemclock.c:159:???:[00m waiting one time unit
      GST_CLOCK gstclock.c:443:gst_clock_id_wait(void *, long long *):<GstSystemClock>[00m waiting on clock entry 0x12482050
      GST_CLOCK gstsystemclock.c:554:gst_system_clock_id_wait_jitter_unlocked(struct _GstClock *, struct _GstClockEntry *, long long *, int):[00m entry 0x12482050 time 350550:39:20.455016825 now 350550:39:20.255031825 real 350550:39:20.255031825 diff (time-now) 199985000
      GST_CLOCK gstsystemclock.c:607:gst_system_clock_id_wait_jitter_unlocked(struct _GstClock *, struct _GstClockEntry *, long long *, int):[00m entry 0x12482050 unlocked after timeout
      GST_CLOCK gstsystemclock.c:636:gst_system_clock_id_wait_jitter_unlocked(struct _GstClock *, struct _GstClockEntry *, long long *, int):[00m entry 0x12482050 restart, diff 199776875
      GST_CLOCK gstsystemclock.c:607:gst_system_clock_id_wait_jitter_unlocked(struct _GstClock *, struct _GstClockEntry *, long long *, int):[00m entry 0x12482050 unlocked after timeout
      GST_CLOCK gstsystemclock.c:636:gst_system_clock_id_wait_jitter_unlocked(struct _GstClock *, struct _GstClockEntry *, long long *, int):[00m entry 0x12482050 restart, diff 199573750
      GST_CLOCK gstsystemclock.c:607:gst_system_clock_id_wait_jitter_unlocked(struct _GstClock *, struct _GstClockEntry *, long long *, int):[00m entry 0x12482050 unlocked after timeout
      GST_CLOCK gstsystemclock.c:636:gst_system_clock_id_wait_jitter_unlocked(struct _GstClock *, struct _GstClockEntry *, long long *, int):[00m entry 0x12482050 restart, diff 199370625
      GST_CLOCK gstsystemclock.c:607:gst_system_clock_id_wait_jitter_unlocked(struct _GstClock *, struct _GstClockEntry *, long long *, int):[00m entry 0x12482050 unlocked after timeout
      GST_CLOCK gstsystemclock.c:621:gst_system_clock_id_wait_jitter_unlocked(struct _GstClock *, struct _GstClockEntry *, long long *, int):[00m entry 0x12482050 finished, diff -799832500
      GST_CLOCK gstclock.c:468:gst_clock_id_wait(void *, long long *):<GstSystemClock>[00m done waiting entry 0x12482050, res: 0
      GST_CLOCK gstclock.c:907:gst_clock_get_internal_time(struct _GstClock *):<GstSystemClock>[00m internal time 350550:39:21.254849325
      GST_CLOCK gstclock.c:956:gst_clock_get_time(struct _GstClock *):<GstSystemClock>[00m adjusted time 350550:39:21.254849325
      GST_DEBUG gstsystemclock.c:88:???:[00m Clock info: time 350550:39:21.254849325
      GST_CLOCK gstclock.c:907:gst_clock_get_internal_time(struct _GstClock *):<GstSystemClock>[00m internal time 350550:39:21.254849325
      GST_CLOCK gstclock.c:956:gst_clock_get_time(struct _GstClock *):<GstSystemClock>[00m adjusted time 350550:39:21.254849325
      GST_DEBUG gstsystemclock.c:167:???:[00m waiting in the past
      GST_CLOCK gstclock.c:443:gst_clock_id_wait(void *, long long *):<GstSystemClock>[00m waiting on clock entry 0x12482050
      GST_CLOCK gstsystemclock.c:554:gst_system_clock_id_wait_jitter_unlocked(struct _GstClock *, struct _GstClockEntry *, long long *, int):[00m entry 0x12482050 time 350550:39:20.455016825 now 350550:39:21.254849325 real 350550:39:21.254849325 diff (time-now) -799832500
      GST_CLOCK gstclock.c:468:gst_clock_id_wait(void *, long long *):<GstSystemClock>[00m done waiting entry 0x12482050, res: 1
      GST_CLOCK gstclock.c:907:gst_clock_get_internal_time(struct _GstClock *):<GstSystemClock>[00m internal time 350550:39:21.254849325
      GST_CLOCK gstclock.c:956:gst_clock_get_time(struct _GstClock *):<GstSystemClock>[00m adjusted time 350550:39:21.254849325
      GST_DEBUG gstsystemclock.c:88:???:[00m Clock info: time 350550:39:21.254849325
      GST_CLOCK gstclock.c:243:_gst_clock_id_free(void *):[00m freed entry 0x12482050
      GST_CLOCK gstclock.c:200:gst_clock_entry_new(struct _GstClock *, unsigned long long, unsigned long long, @enum$3957gstclock_c):<GstSystemClock>[00m created entry 0x12482078, time 350550:39:20.655016825
      GST_DEBUG gstsystemclock.c:175:???:[00m waiting one second async id 0x12482078
      GST_CLOCK gstsystemclock.c:715:gst_system_clock_id_wait_async(struct _GstClock *, struct _GstClockEntry *):[00m adding async entry 0x12482078
      GST_CLOCK gstsystemclock.c:346:gst_system_clock_async_thread(struct _GstClock *):[00m enter system clock thread
      GST_CLOCK gstsystemclock.c:358:gst_system_clock_async_thread(struct _GstClock *):[00m no clock entries, waiting..
      GST_CLOCK gstsystemclock.c:740:gst_system_clock_id_wait_async(struct _GstClock *, struct _GstClockEntry *):[00m async entry added to head
      GST_CLOCK gstsystemclock.c:744:gst_system_clock_id_wait_async(struct _GstClock *, struct _GstClockEntry *):[00m first entry, sending signal
      GST_CLOCK gstsystemclock.c:361:gst_system_clock_async_thread(struct _GstClock *):[00m got signal
      GST_CLOCK gstsystemclock.c:554:gst_system_clock_id_wait_jitter_unlocked(struct _GstClock *, struct _GstClockEntry *, long long *, int):[00m entry 0x12482078 time 350550:39:20.655016825 now 350550:39:21.254854325 real 350550:39:21.254854325 diff (time-now) -599837500
      GST_CLOCK gstsystemclock.c:400:gst_system_clock_async_thread(struct _GstClock *):[00m async entry 0x12482078 timed out
      GST_DEBUG gstsystemclock.c:95:???:[00m unlocked async id 0x12482078
      GST_CLOCK gstsystemclock.c:418:gst_system_clock_async_thread(struct _GstClock *):[00m moving to next entry
      GST_CLOCK gstsystemclock.c:358:gst_system_clock_async_thread(struct _GstClock *):[00m no clock entries, waiting..
      GST_CLOCK gstsystemclock.c:790:gst_system_clock_id_unschedule(struct _GstClock *, struct _GstClockEntry *):[00m unscheduling entry 0x12482078
      GST_CLOCK gstclock.c:243:_gst_clock_id_free(void *):[00m freed entry 0x12482078
      GST_CLOCK gstclock.c:200:gst_clock_entry_new(struct _GstClock *, unsigned long long, unsigned long long, @enum$3957gstclock_c):<GstSystemClock>[00m created entry 0x124820a0, time 350550:39:21.255016825
      GST_DEBUG gstsystemclock.c:183:???:[00m waiting one second async, with cancel on id 0x124820a0
      GST_CLOCK gstsystemclock.c:715:gst_system_clock_id_wait_async(struct _GstClock *, struct _GstClockEntry *):[00m adding async entry 0x124820a0
      GST_CLOCK gstsystemclock.c:740:gst_system_clock_id_wait_async(struct _GstClock *, struct _GstClockEntry *):[00m async entry added to head
      GST_CLOCK gstsystemclock.c:744:gst_system_clock_id_wait_async(struct _GstClock *, struct _GstClockEntry *):[00m first entry, sending signal
      GST_CLOCK gstsystemclock.c:361:gst_system_clock_async_thread(struct _GstClock *):[00m got signal
      GST_CLOCK gstsystemclock.c:554:gst_system_clock_id_wait_jitter_unlocked(struct _GstClock *, struct _GstClockEntry *, long long *, int):[00m entry 0x124820a0 time 350550:39:21.255016825 now 350550:39:22.254864950 real 350550:39:22.254864950 diff (time-now) -999848125
      GST_CLOCK gstsystemclock.c:400:gst_system_clock_async_thread(struct _GstClock *):[00m async entry 0x124820a0 timed out
         GST_DEBUG gstsystemclock.c:103:???:[00m unlocked unscheduled async id 0x124820a0, this is wrong
