Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

Percentage of cross side lick intervals under 100 ms in Box A mouse 741210 exceeded 10% #1096

Open
alexpiet opened this issue Nov 26, 2024 · 0 comments
Open

Percentage of cross side lick intervals under 100 ms in Box A mouse 741210 exceeded 10% #1096

alexpiet opened this issue Nov 26, 2024 · 0 comments
Labels
low priority - auto-generated autogenerated issues that have only occurred once

Comments

@alexpiet
Copy link
Collaborator

This issue was automatically generated from GUI logs.

file: MyFunctions.py
function: calculate_inter_lick_intervals
line 957
logfile: W10DT713669/W10DT713669-A_gui_log_2024-11-25_11-25-07.txt
boxes: ephys1
count: 7
text: Percentage of cross side lick intervals under 100 ms in Box A mouse 741210 exceeded 10%

GUI Log snippet (click for full log)

02:07:26 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525387.122912, '127.0.0.1', 4003]
02:07:26 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525387.303584, '127.0.0.1', 4003]
02:07:26 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=303608 fps=501 q=24.0 size= 3729688kB time=00:10:07.21 bitrate=50317.5kbits/s speed=   1x
02:07:26 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=30358 fps= 50 q=29.0 size=  376320kB time=00:01:00.71 bitrate=50774.3kbits/s speed= 0.1x
02:07:26 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=30356 fps= 50 q=20.0 size=  375040kB time=00:01:00.71 bitrate=50604.9kbits/s speed= 0.1x
02:07:26 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525387.467456, '127.0.0.1', 4003]
02:07:26 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 525387.469408, '127.0.0.1', 4003]
02:07:26 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=30383 fps= 50 q=27.0 size=  376320kB time=00:01:00.76 bitrate=50732.5kbits/s speed= 0.1x
02:07:26 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=303862 fps=501 q=24.0 size= 3732248kB time=00:10:07.72 bitrate=50310.0kbits/s speed=   1x
02:07:26 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=30382 fps= 50 q=21.0 size=  375296kB time=00:01:00.76 bitrate=50596.2kbits/s speed= 0.1x
02:07:27 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=304118 fps=501 q=24.0 size= 3734808kB time=00:10:08.23 bitrate=50302.3kbits/s speed=   1x
02:07:27 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=30409 fps= 50 q=24.0 size=  376576kB time=00:01:00.81 bitrate=50723.6kbits/s speed= 0.1x
02:07:27 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=30407 fps= 50 q=21.0 size=  375552kB time=00:01:00.81 bitrate=50589.0kbits/s speed= 0.1x
02:07:27 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525388.442912, '127.0.0.1', 4003]
02:07:27 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525388.567488, '127.0.0.1', 4003]
02:07:27 PM:INFO:MyFunctions:MyFunctions.py:calculate_inter_lick_intervals:line 913:Percentage of left lick intervals under 100 ms is 0.00%.
02:07:27 PM:INFO:MyFunctions:MyFunctions.py:calculate_inter_lick_intervals:line 919:Percentage of right lick intervals under 100 ms is 0.00%.
02:07:27 PM:INFO:MyFunctions:MyFunctions.py:calculate_inter_lick_intervals:line 926:Percentage of right and left lick intervals under 100 ms is 0.00%.
02:07:27 PM:INFO:MyFunctions:MyFunctions.py:calculate_inter_lick_intervals:line 951:Percentage of cross side lick intervals under 100 ms is 15.38%.
02:07:27 PM:ERROR:MyFunctions:MyFunctions.py:calculate_inter_lick_intervals:line 957:Percentage of cross side lick intervals under 100 ms in Box A mouse 741210 exceeded 10%
Full GUI log (first 200 and last 200 lines) 
11:25:07 AM:INFO:Foraging:Foraging.py:start_gui_log_file:line 4661:Starting logfile!
11:25:07 AM:INFO:Foraging:Foraging.py:log_git_hash:line 4672:Python version: 3.11.9 | packaged by Anaconda, Inc. | (main, Apr 19 2024, 16:40:41) [MSC v.1916 64 bit (AMD64)]
11:25:07 AM:INFO:Foraging:Foraging.py:log_git_hash:line 4689:Current git commit branch, hash: main, 1a7c9d3d1aa10284caed44ba9b223c6bfbb8ff5f
11:25:07 AM:INFO:Foraging:Foraging.py:log_git_hash:line 4693:Current foraging_gui version: 1.6.10
11:25:07 AM:WARNING:Foraging:Foraging.py:log_git_hash:line 4703:local repository is clean
11:25:07 AM:INFO:Foraging:Foraging.py:<module>:line 4804:Setting QApplication attributes
11:25:07 AM:INFO:Foraging:Foraging.py:<module>:line 4812:Starting QApplication and Window
11:25:07 AM:INFO:Foraging:Foraging.py:__init__:line 69:Creating Window
11:25:07 AM:INFO:Foraging:Foraging.py:_GetSettings:line 1174:Loaded settings_box file
11:25:07 AM:INFO:Foraging:Foraging.py:_GetSettings:line 1202:Loaded settings file
11:25:07 AM:WARNING:Foraging:Foraging.py:_GetSettings:line 1212:Missing setting (FIP_workflow_path), using default: 
11:25:07 AM:WARNING:Foraging:Foraging.py:_GetSettings:line 1212:Missing setting (FIP_settings), using default: C:\Users\svc_aind_ephys\Documents\FIPSettings
11:25:07 AM:WARNING:Foraging:Foraging.py:_GetSettings:line 1212:Missing setting (bonsai_config_path), using default: C:\Users\svc_aind_ephys\Documents\GitHub\dynamic-foraging-task\bonsai\Bonsai.config
11:25:07 AM:WARNING:Foraging:Foraging.py:_GetSettings:line 1212:Missing setting (metadata_dialog_folder), using default: C:\Users\svc_aind_ephys\Documents\ForagingSettings\metadata_dialog\
11:25:07 AM:WARNING:Foraging:Foraging.py:_GetSettings:line 1212:Missing setting (rig_metadata_folder), using default: C:\Users\svc_aind_ephys\Documents\ForagingSettings\rig_metadata\
11:25:07 AM:WARNING:Foraging:Foraging.py:_GetSettings:line 1212:Missing setting (project_info_file), using default: C:\Users\svc_aind_ephys\Documents\ForagingSettings\Project Name and Funding Source v2.csv
11:25:07 AM:WARNING:Foraging:Foraging.py:_GetSettings:line 1212:Missing setting (schedule_path), using default: Z:\dynamic_foraging\DynamicForagingSchedule.csv
11:25:07 AM:WARNING:Foraging:Foraging.py:_GetSettings:line 1212:Missing setting (go_cue_decibel_box1), using default: 60
11:25:07 AM:WARNING:Foraging:Foraging.py:_GetSettings:line 1212:Missing setting (go_cue_decibel_box2), using default: 60
11:25:07 AM:WARNING:Foraging:Foraging.py:_GetSettings:line 1212:Missing setting (go_cue_decibel_box3), using default: 60
11:25:07 AM:WARNING:Foraging:Foraging.py:_GetSettings:line 1212:Missing setting (go_cue_decibel_box4), using default: 60
11:25:07 AM:WARNING:Foraging:Foraging.py:_GetSettings:line 1212:Missing setting (lick_spout_distance_box1), using default: 5000
11:25:07 AM:WARNING:Foraging:Foraging.py:_GetSettings:line 1212:Missing setting (lick_spout_distance_box2), using default: 5000
11:25:07 AM:WARNING:Foraging:Foraging.py:_GetSettings:line 1212:Missing setting (lick_spout_distance_box3), using default: 5000
11:25:07 AM:WARNING:Foraging:Foraging.py:_GetSettings:line 1212:Missing setting (lick_spout_distance_box4), using default: 5000
11:25:07 AM:WARNING:Foraging:Foraging.py:_GetSettings:line 1212:Missing setting (name_mapper_file), using default: C:\Users\svc_aind_ephys\Documents\ForagingSettings\name_mapper.json
11:25:07 AM:WARNING:Foraging:Foraging.py:_GetSettings:line 1212:Missing setting (save_each_trial), using default: True
11:25:07 AM:WARNING:Foraging:Foraging.py:_GetSettings:line 1212:Missing setting (AutomaticUpload), using default: True
11:25:07 AM:WARNING:Foraging:Foraging.py:_GetSettings:line 1212:Missing setting (manifest_flag_dir), using default: C:\Users\svc_aind_ephys\Documents\aind_watchdog_service\manifest
11:25:07 AM:WARNING:Foraging:Foraging.py:_GetSettings:line 1212:Missing setting (auto_engage), using default: True
11:25:07 AM:WARNING:Foraging:Foraging.py:_GetSettings:line 1212:Missing setting (clear_figure_after_save), using default: True
11:25:07 AM:WARNING:Foraging:Foraging.py:_GetSettings:line 1212:Missing setting (add_default_project_name), using default: True
11:25:07 AM:INFO:Foraging:Foraging.py:_LoadSchedule:line 1100:Loaded behavior schedule
11:25:07 AM:INFO:Foraging:Foraging.py:_GetWaterCalibration:line 1064:Loaded Water Calibration
11:25:07 AM:INFO:Foraging:Foraging.py:_LoadRigJson:line 1544:Skipping rig metadata creation because create_rig_metadata=False
11:25:07 AM:INFO:Foraging:Foraging.py:_LoadUI:line 275:Using ForagingGUI.ui interface
11:25:07 AM:INFO:Foraging:Foraging.py:__init__:line 119:Setting Window title: 323_EPHYS1
11:25:07 AM:INFO:Foraging:Foraging.py:_InitializeBonsai:line 1371:Trying to connect to already running Bonsai
11:25:07 AM:INFO:Foraging:Foraging.py:_ConnectOSC:line 1418:connecting to GUI and Bonsai through OSC
11:25:08 AM:INFO:Foraging:Foraging.py:_InitializeBonsai:line 1375:Could not connect: timed out
11:25:08 AM:INFO:Foraging:Foraging.py:_InitializeBonsai:line 1384:Starting Bonsai
11:25:08 AM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4776:BONSAI logging starting
11:25:09 AM:INFO:Foraging:Foraging.py:_ConnectOSC:line 1418:connecting to GUI and Bonsai through OSC
11:25:10 AM:INFO:Foraging:Foraging.py:_InitializeBonsai:line 1398:Could not connect, total waiting time 0.5 seconds: timed out
11:25:10 AM:INFO:Foraging:Foraging.py:_ConnectOSC:line 1418:connecting to GUI and Bonsai through OSC
11:25:11 AM:INFO:Foraging:Foraging.py:_InitializeBonsai:line 1398:Could not connect, total waiting time 1.0 seconds: timed out
11:25:12 AM:INFO:Foraging:Foraging.py:_ConnectOSC:line 1418:connecting to GUI and Bonsai through OSC
11:25:13 AM:INFO:Foraging:Foraging.py:_InitializeBonsai:line 1398:Could not connect, total waiting time 1.5 seconds: timed out
11:25:13 AM:INFO:Foraging:Foraging.py:_ConnectOSC:line 1418:connecting to GUI and Bonsai through OSC
11:25:14 AM:INFO:Foraging:Foraging.py:_InitializeBonsai:line 1401:Connected to Bonsai after 2.0 seconds
11:25:14 AM:INFO:Foraging:Foraging.py:_InitializeBonsai:line 1402:Bonsai started successfully
11:25:14 AM:INFO:Foraging:Foraging.py:_ConnectSlims:line 1281:Attempting to connect to Slims
11:25:15 AM:INFO:Foraging:Foraging.py:_ConnectSlims:line 1298:Successfully connected to Slims
11:25:15 AM:WARNING:Dialogs:Dialogs.py:_LoadCalibrationParameters:line 480:could not find water calibration parameters: C:\Users\svc_aind_ephys\Documents\ForagingSettings\WaterCalibrationPar_1.json
11:25:15 AM:INFO:Foraging:Foraging.py:keyPressEvent:line 1863:processing parameter changes
11:25:15 AM:INFO:Foraging:Foraging.py:_InitializeMotorStage:line 875:Scanning for newscale stages
11:25:15 AM:INFO:Foraging:Foraging.py:_InitializeMotorStage:line 890:found 3 newscale stages
11:25:15 AM:INFO:Foraging:Foraging.py:_InitializeMotorStage:line 914:Found the newscale stage from the settings file
11:25:15 AM:ERROR:Foraging:Foraging.py:_connect_stage:line 938:Traceback (most recent call last):
  File "C:\Users\svc_aind_ephys\Documents\GitHub\dynamic-foraging-task\src\foraging_gui\Foraging.py", line 933, in _connect_stage
    instance.io.open()
  File "C:\Users\svc_aind_ephys\.conda\envs\Foraging\Lib\site-packages\newscale\usbxpress.py", line 356, in open
    self._dll.SI_Open(ctypes.c_ulong(self.device_num), ctypes.byref(handle))
  File "C:\Users\svc_aind_ephys\.conda\envs\Foraging\Lib\site-packages\newscale\usbxpress.py", line 61, in usbxpress_errcheck
    raise USBXpressException("USBXpress runtime error: %r" % \
newscale.usbxpress.USBXpressException: <exception str() failed>

11:25:15 AM:INFO:Foraging:Foraging.py:_load_most_recent_rig_json:line 1534:Found existing rig.json: rig_323_EPHYS1_2024-06-17.json
11:25:15 AM:INFO:Foraging:Foraging.py:keyPressEvent:line 1863:processing parameter changes
11:25:15 AM:INFO:Foraging:Foraging.py:keyPressEvent:line 1922:Changing parameter: RightValue, 0.03 -> 0.036
11:25:15 AM:INFO:Foraging:Foraging.py:keyPressEvent:line 1922:Changing parameter: LeftValue, 0.03 -> 0.04
11:25:15 AM:INFO:Foraging:Foraging.py:keyPressEvent:line 1922:Changing parameter: RightValue, 0.03 -> 0.036
11:25:15 AM:INFO:Foraging:Foraging.py:keyPressEvent:line 1922:Changing parameter: LeftValue, 0.03 -> 0.04
11:25:15 AM:INFO:Foraging:Foraging.py:keyPressEvent:line 1922:Changing parameter: GiveWaterL, 0.03 -> 0.04
11:25:15 AM:INFO:Foraging:Foraging.py:keyPressEvent:line 1922:Changing parameter: GiveWaterR, 0.03 -> 0.036
11:25:15 AM:INFO:Foraging:Foraging.py:keyPressEvent:line 1863:processing parameter changes
11:25:15 AM:INFO:Foraging:Foraging.py:keyPressEvent:line 1863:processing parameter changes
11:25:15 AM:INFO:Foraging:Foraging.py:keyPressEvent:line 1863:processing parameter changes
11:25:15 AM:INFO:Foraging:Foraging.py:keyPressEvent:line 1863:processing parameter changes
11:25:16 AM:INFO:Foraging:Foraging.py:__init__:line 219:Start up complete
11:25:17 AM:INFO:aws_util:aws_util.py:import_df_from_s3:line 115:Dataframe imported from s3://aind-behavior-data/foraging_nwb_bonsai_processed/df_sessions.pkl, len(df) = 5669
11:25:17 AM:INFO:aws_util:aws_util.py:import_df_from_s3:line 115:Dataframe imported from s3://aind-behavior-data/foraging_auto_training/df_manager_447_demo.pkl, len(df) = 4210
11:25:18 AM:INFO:aws_util:aws_util.py:download_dir_from_s3:line 140:134 objects downloaded from s3://aind-behavior-data/foraging_auto_training/saved_curriculums/ to C:\Users\svc_aind_ephys/capsule/scratch/saved_curriculums/
11:25:18 AM:INFO:curriculum_manager:curriculum_manager.py:download_curriculums:line 162:Found 29 curriculums in C:\Users\svc_aind_ephys/capsule/scratch/saved_curriculums/
11:25:18 AM:INFO:aws_util:aws_util.py:download_dir_from_s3:line 140:134 objects downloaded from s3://aind-behavior-data/foraging_auto_training/saved_curriculums/ to C:\Users\svc_aind_ephys/.aind_auto_train/curriculum_manager/
11:25:18 AM:INFO:curriculum_manager:curriculum_manager.py:download_curriculums:line 162:Found 29 curriculums in C:\Users\svc_aind_ephys/.aind_auto_train/curriculum_manager/
11:25:18 AM:INFO:Dialogs:Dialogs.py:update_auto_train_fields:line 2273:No entry found in df_training_manager for subject_id: 0
11:25:18 AM:INFO:Dialogs:Dialogs.py:_override_curriculum_clicked:line 2621:Override stage clicked: state=2
11:25:18 AM:INFO:Dialogs:Dialogs.py:_update_stage_to_apply:line 2647:Current stage to apply: unknown training stage @unknown curriculum
11:25:27 AM:INFO:Dialogs:Dialogs.py:_start_preview:line 1213:Camera is on
11:25:38 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/ManualLeftWaterStartTime', 515680.017984, '127.0.0.1', 4003]
11:25:38 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftRewardDeliveryTimeHarp', 515680.021504, '127.0.0.1', 4003]
11:25:45 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/ManualLeftWaterStartTime', 515686.244, '127.0.0.1', 4003]
11:25:45 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftRewardDeliveryTimeHarp', 515686.246496, '127.0.0.1', 4003]
11:25:46 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/ManualRightWaterStartTime', 515687.705984, '127.0.0.1', 4003]
11:25:46 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightRewardDeliveryTimeHarp', 515687.70848, '127.0.0.1', 4003]
11:25:55 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/ManualRightWaterStartTime', 515696.966976, '127.0.0.1', 4003]
11:25:55 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightRewardDeliveryTimeHarp', 515696.96848, '127.0.0.1', 4003]
11:25:57 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/ManualLeftWaterStartTime', 515698.612, '127.0.0.1', 4003]
11:25:57 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftRewardDeliveryTimeHarp', 515698.615488, '127.0.0.1', 4003]
11:26:00 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/ManualLeftWaterStartTime', 515701.948992, '127.0.0.1', 4003]
11:26:00 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftRewardDeliveryTimeHarp', 515701.950496, '127.0.0.1', 4003]
11:26:01 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/ManualRightWaterStartTime', 515703.033984, '127.0.0.1', 4003]
11:26:01 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightRewardDeliveryTimeHarp', 515703.03648, '127.0.0.1', 4003]
11:26:02 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 515703.327264, '127.0.0.1', 4003]
11:26:05 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/ManualRightWaterStartTime', 515706.529984, '127.0.0.1', 4003]
11:26:05 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightRewardDeliveryTimeHarp', 515706.53248, '127.0.0.1', 4003]
11:26:09 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 515710.527456, '127.0.0.1', 4003]
11:27:17 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 515779.075744, '127.0.0.1', 4003]
11:27:18 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 515779.657792, '127.0.0.1', 4003]
11:27:18 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 515779.766112, '127.0.0.1', 4003]
11:27:19 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 515781.050528, '127.0.0.1', 4003]
11:27:19 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 515781.151872, '127.0.0.1', 4003]
11:27:20 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 515781.272032, '127.0.0.1', 4003]
11:27:20 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 515781.397344, '127.0.0.1', 4003]
11:27:20 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 515781.51904, '127.0.0.1', 4003]
11:27:20 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 515781.631232, '127.0.0.1', 4003]
11:27:20 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 515781.788, '127.0.0.1', 4003]
11:27:20 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 515781.913824, '127.0.0.1', 4003]
11:27:20 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 515782.006272, '127.0.0.1', 4003]
11:27:20 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 515782.03152, '127.0.0.1', 4003]
11:27:21 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 515782.335456, '127.0.0.1', 4003]
11:27:21 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 515782.483904, '127.0.0.1', 4003]
11:27:21 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 515782.650176, '127.0.0.1', 4003]
11:27:21 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515782.820384, '127.0.0.1', 4003]
11:27:21 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 515782.86784, '127.0.0.1', 4003]
11:27:21 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 515782.936928, '127.0.0.1', 4003]
11:27:21 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515783.045536, '127.0.0.1', 4003]
11:27:21 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515783.160544, '127.0.0.1', 4003]
11:27:22 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515783.277312, '127.0.0.1', 4003]
11:27:22 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515783.413376, '127.0.0.1', 4003]
11:27:22 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515783.561312, '127.0.0.1', 4003]
11:27:22 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 515784.184832, '127.0.0.1', 4003]
11:27:24 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 515785.999808, '127.0.0.1', 4003]
11:27:25 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515786.290752, '127.0.0.1', 4003]
11:27:25 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515786.485344, '127.0.0.1', 4003]
11:27:25 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515786.633952, '127.0.0.1', 4003]
11:27:25 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515786.774176, '127.0.0.1', 4003]
11:27:25 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515786.926752, '127.0.0.1', 4003]
11:27:25 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515787.077184, '127.0.0.1', 4003]
11:27:26 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515787.220672, '127.0.0.1', 4003]
11:27:26 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 515787.394688, '127.0.0.1', 4003]
11:27:26 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 515787.537312, '127.0.0.1', 4003]
11:27:26 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 515787.712384, '127.0.0.1', 4003]
11:27:26 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515788.050944, '127.0.0.1', 4003]
11:27:27 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 515788.4296, '127.0.0.1', 4003]
11:27:27 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 515788.827616, '127.0.0.1', 4003]
11:27:27 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515789.201088, '127.0.0.1', 4003]
11:27:28 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515789.377472, '127.0.0.1', 4003]
11:27:29 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515790.726624, '127.0.0.1', 4003]
11:27:29 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515790.867712, '127.0.0.1', 4003]
11:27:30 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515791.217856, '127.0.0.1', 4003]
11:27:30 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515791.366688, '127.0.0.1', 4003]
11:27:30 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 515791.390016, '127.0.0.1', 4003]
11:27:30 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515791.507808, '127.0.0.1', 4003]
11:27:30 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515791.675744, '127.0.0.1', 4003]
11:27:30 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515791.818144, '127.0.0.1', 4003]
11:27:31 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515792.255808, '127.0.0.1', 4003]
11:27:31 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515792.384928, '127.0.0.1', 4003]
11:27:31 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515793.15344, '127.0.0.1', 4003]
11:27:32 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515793.305536, '127.0.0.1', 4003]
11:27:32 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515793.453696, '127.0.0.1', 4003]
11:27:32 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515793.585888, '127.0.0.1', 4003]
11:27:32 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515793.764672, '127.0.0.1', 4003]
11:27:32 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515793.930048, '127.0.0.1', 4003]
11:27:33 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515794.768896, '127.0.0.1', 4003]
11:27:33 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515794.920864, '127.0.0.1', 4003]
11:27:33 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515794.943808, '127.0.0.1', 4003]
11:27:33 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 515795.148608, '127.0.0.1', 4003]
11:27:34 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 515795.341312, '127.0.0.1', 4003]
11:27:34 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 515795.507648, '127.0.0.1', 4003]
11:27:34 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 515795.645536, '127.0.0.1', 4003]
11:27:34 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 515795.784768, '127.0.0.1', 4003]
11:27:37 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515798.90512, '127.0.0.1', 4003]
11:27:37 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515799.077696, '127.0.0.1', 4003]
11:27:38 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515799.249088, '127.0.0.1', 4003]
11:27:38 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 515799.254944, '127.0.0.1', 4003]
11:27:40 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515801.714912, '127.0.0.1', 4003]
11:27:40 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 515801.719616, '127.0.0.1', 4003]
11:27:40 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515801.856128, '127.0.0.1', 4003]
11:27:42 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515803.428256, '127.0.0.1', 4003]
11:27:42 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515803.570688, '127.0.0.1', 4003]
11:27:42 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 515803.580416, '127.0.0.1', 4003]
11:27:43 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 515805.0424, '127.0.0.1', 4003]
11:27:44 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 515805.268096, '127.0.0.1', 4003]
11:27:44 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 515805.490112, '127.0.0.1', 4003]
11:27:44 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 515805.516, '127.0.0.1', 4003]
11:27:46 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 515807.75728, '127.0.0.1', 4003]
11:27:47 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515808.466208, '127.0.0.1', 4003]
11:28:01 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515822.44288, '127.0.0.1', 4003]
11:28:17 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515838.910272, '127.0.0.1', 4003]
11:28:17 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 515838.927712, '127.0.0.1', 4003]
11:28:17 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515839.039872, '127.0.0.1', 4003]
11:28:30 AM:INFO:Foraging:Foraging.py:_StopCurrentSession:line 3581:Stopping current trials
11:28:33 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515854.363616, '127.0.0.1', 4003]
11:28:33 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515854.498784, '127.0.0.1', 4003]
11:28:33 AM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 515854.666368, '127.0.0.1', 4003]
11:28:35 AM:INFO:Foraging:Foraging.py:_Open:line 2948:Quick load success: D:\dynamic-foraging\323_EPHYS1\728568\behavior_728568_2024-11-21_13-53-52\behavior\728568_2024-11-21_13-53-52.json
11:28:35 AM:INFO:Foraging:Foraging.py:_NewSession:line 3491:New Session pressed
11:28:35 AM:INFO:Foraging:Foraging.py:_NewSession:line 3523:Resetting session run flag
_____SKIPPING_____
02:07:12 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=29686 fps= 50 q=20.0 size=  366848kB time=00:00:59.37 bitrate=50616.8kbits/s speed= 0.1x
02:07:12 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525374.039776, '127.0.0.1', 4003]
02:07:13 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525374.205856, '127.0.0.1', 4003]
02:07:13 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525374.400512, '127.0.0.1', 4003]
02:07:13 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 525374.433152, '127.0.0.1', 4003]
02:07:13 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=297160 fps=501 q=25.0 size= 3649560kB time=00:09:54.32 bitrate=50304.9kbits/s speed=   1x
02:07:13 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=29713 fps= 50 q=28.0 size=  368128kB time=00:00:59.42 bitrate=50747.2kbits/s speed= 0.1x
02:07:13 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=29711 fps= 50 q=20.0 size=  367104kB time=00:00:59.42 bitrate=50609.5kbits/s speed= 0.1x
02:07:13 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=297415 fps=501 q=24.0 size= 3652376kB time=00:09:54.83 bitrate=50300.5kbits/s speed=   1x
02:07:13 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=29737 fps= 50 q=20.0 size=  367360kB time=00:00:59.47 bitrate=50600.5kbits/s speed= 0.1x
02:07:13 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=29739 fps= 50 q=26.0 size=  368384kB time=00:00:59.47 bitrate=50738.1kbits/s speed= 0.1x
02:07:14 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=297678 fps=501 q=24.0 size= 3655192kB time=00:09:55.35 bitrate=50294.8kbits/s speed=   1x
02:07:14 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=29763 fps= 50 q=20.0 size=  367616kB time=00:00:59.52 bitrate=50591.5kbits/s speed= 0.1x
02:07:14 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=29765 fps= 50 q=27.0 size=  368640kB time=00:00:59.53 bitrate=50729.0kbits/s speed= 0.1x
02:07:14 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/DelayStartTime', 525375.533984, '127.0.0.1', 4002]
02:07:14 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/BehaviorEvent', 525375.535488, '127.0.0.1', 4002]
02:07:14 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525375.626048, '127.0.0.1', 4003]
02:07:14 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=297933 fps=501 q=23.0 size= 3658520kB time=00:09:55.86 bitrate=50297.5kbits/s speed=   1x
02:07:14 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=29791 fps= 50 q=28.0 size=  369152kB time=00:00:59.58 bitrate=50755.1kbits/s speed= 0.1x
02:07:14 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=29789 fps= 50 q=21.0 size=  368128kB time=00:00:59.57 bitrate=50617.8kbits/s speed= 0.1x
02:07:15 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=298188 fps=501 q=23.0 size= 3661080kB time=00:09:56.37 bitrate=50289.7kbits/s speed=   1x
02:07:15 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=29814 fps= 50 q=21.0 size=  368384kB time=00:00:59.62 bitrate=50610.5kbits/s speed= 0.1x
02:07:15 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=29816 fps= 50 q=25.0 size=  369152kB time=00:00:59.63 bitrate=50712.6kbits/s speed= 0.1x
02:07:15 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/DelayStartTime', 525376.733984, '127.0.0.1', 4002]
02:07:15 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/BehaviorEvent', 525376.735488, '127.0.0.1', 4002]
02:07:15 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=298450 fps=501 q=24.0 size= 3663896kB time=00:09:56.90 bitrate=50284.2kbits/s speed=   1x
02:07:15 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=29842 fps= 50 q=23.0 size=  369408kB time=00:00:59.68 bitrate=50703.5kbits/s speed= 0.1x
02:07:15 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=29840 fps= 50 q=20.0 size=  368640kB time=00:00:59.68 bitrate=50601.5kbits/s speed= 0.1x
02:07:16 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=298704 fps=501 q=24.0 size= 3666968kB time=00:09:57.40 bitrate=50283.6kbits/s speed=   1x
02:07:16 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=29868 fps= 50 q=28.0 size=  369664kB time=00:00:59.73 bitrate=50694.5kbits/s speed= 0.1x
02:07:16 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=29866 fps= 50 q=21.0 size=  368896kB time=00:00:59.73 bitrate=50592.6kbits/s speed= 0.1x
02:07:16 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/GoCueTime', 525377.716, '127.0.0.1', 4002]
02:07:16 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/BehaviorEvent', 525377.719488, '127.0.0.1', 4002]
02:07:16 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/GoCueTimeSoundCard', 525377.719168, 4, '127.0.0.1']
02:07:16 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525377.895232, '127.0.0.1', 4003]
02:07:16 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 525377.89568, '127.0.0.1', 4003]
02:07:16 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RewardOutcomeTime', 525377.894976, '127.0.0.1', 4002]
02:07:16 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightRewardDeliveryTime', 525377.894976, '127.0.0.1', 4003]
02:07:16 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RewardOutcome', 'RewardRight', '127.0.0.1', 4002]
02:07:16 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/EarnedRightWaterStartTime', 525377.894976, '127.0.0.1', 4003]
02:07:16 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightRewardDeliveryTimeHarp', 525377.89648, '127.0.0.1', 4003]
02:07:16 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525378.030944, '127.0.0.1', 4003]
02:07:17 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=298967 fps=501 q=24.0 size= 3670808kB time=00:09:57.93 bitrate=50291.9kbits/s speed=   1x
02:07:17 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=29892 fps= 50 q=21.0 size=  369152kB time=00:00:59.78 bitrate=50583.7kbits/s speed= 0.1x
02:07:17 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=29894 fps= 50 q=29.0 size=  370176kB time=00:00:59.78 bitrate=50720.6kbits/s speed= 0.1x
02:07:17 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525378.179168, '127.0.0.1', 4003]
02:07:17 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525378.32208, '127.0.0.1', 4003]
02:07:17 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525378.456416, '127.0.0.1', 4003]
02:07:17 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525378.58736, '127.0.0.1', 4003]
02:07:17 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=299221 fps=501 q=25.0 size= 3674392kB time=00:09:58.44 bitrate=50298.3kbits/s speed=   1x
02:07:17 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=29919 fps= 50 q=29.0 size=  370688kB time=00:00:59.83 bitrate=50748.3kbits/s speed= 0.1x
02:07:17 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=29918 fps= 50 q=21.0 size=  369664kB time=00:00:59.83 bitrate=50609.8kbits/s speed= 0.1x
02:07:17 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525378.728032, '127.0.0.1', 4003]
02:07:17 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525378.872032, '127.0.0.1', 4003]
02:07:17 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525379.004736, '127.0.0.1', 4003]
02:07:18 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=299476 fps=501 q=25.0 size= 3677976kB time=00:09:58.95 bitrate=50304.5kbits/s speed=   1x
02:07:18 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=29943 fps= 50 q=20.0 size=  369920kB time=00:00:59.88 bitrate=50602.6kbits/s speed= 0.1x
02:07:18 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=29945 fps= 50 q=29.0 size=  370944kB time=00:00:59.89 bitrate=50739.2kbits/s speed= 0.1x
02:07:18 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525379.14256, '127.0.0.1', 4003]
02:07:18 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525379.291264, '127.0.0.1', 4003]
02:07:18 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525379.428768, '127.0.0.1', 4003]
02:07:18 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525379.571552, '127.0.0.1', 4003]
02:07:18 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=299739 fps=501 q=25.0 size= 3681560kB time=00:09:59.47 bitrate=50309.3kbits/s speed=   1x
02:07:18 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=29971 fps= 50 q=28.0 size=  371200kB time=00:00:59.94 bitrate=50730.2kbits/s speed= 0.1x
02:07:18 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=29969 fps= 50 q=21.0 size=  370176kB time=00:00:59.93 bitrate=50593.6kbits/s speed= 0.1x
02:07:18 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525379.720288, '127.0.0.1', 4003]
02:07:18 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 525379.723968, '127.0.0.1', 4003]
02:07:18 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525379.87264, '127.0.0.1', 4003]
02:07:18 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 525379.9104, '127.0.0.1', 4003]
02:07:18 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525380.03072, '127.0.0.1', 4003]
02:07:19 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=299994 fps=501 q=25.0 size= 3684632kB time=00:09:59.98 bitrate=50308.5kbits/s speed=   1x
02:07:19 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=29995 fps= 50 q=21.0 size=  370432kB time=00:00:59.99 bitrate=50584.7kbits/s speed= 0.1x
02:07:19 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=29997 fps= 50 q=29.0 size=  371456kB time=00:00:59.99 bitrate=50721.2kbits/s speed= 0.1x
02:07:19 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525380.172512, '127.0.0.1', 4003]
02:07:19 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525380.314048, '127.0.0.1', 4003]
02:07:19 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525380.4632, '127.0.0.1', 4003]
02:07:19 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525380.60688, '127.0.0.1', 4003]
02:07:19 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=300256 fps=501 q=25.0 size= 3688216kB time=00:10:00.51 bitrate=50313.5kbits/s speed=   1x
02:07:19 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=30021 fps= 50 q=20.0 size=  370944kB time=00:01:00.04 bitrate=50610.8kbits/s speed= 0.1x
02:07:19 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=30023 fps= 50 q=29.0 size=  371968kB time=00:01:00.04 bitrate=50747.1kbits/s speed= 0.1x
02:07:19 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525380.763552, '127.0.0.1', 4003]
02:07:19 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/TrialEndTime', 525380.9, '127.0.0.1', 4002]
02:07:19 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/BehaviorEvent', 525380.902496, '127.0.0.1', 4002]
02:07:19 PM:WARNING:MyFunctions:MyFunctions.py:_GenerateATrial:line 172:
02:07:19 PM:WARNING:MyFunctions:MyFunctions.py:_CheckStop:line 1191:
02:07:19 PM:INFO:Foraging:Foraging.py:_StartTrialLoop:line 4141:Current trial: 59
02:07:19 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525380.917888, '127.0.0.1', 4003]
02:07:19 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/TrialStartTime', 525380.917984, '127.0.0.1', 4002]
02:07:19 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/ITIStartTimeHarp', 525380.921504, '127.0.0.1', 4002]
02:07:19 PM:INFO:Foraging:Foraging.py:_Save:line 2441:Saving current session, ForceSave=1
02:07:19 PM:INFO:Foraging:Foraging.py:_GetWaterCalibration:line 1064:Loaded Water Calibration
02:07:19 PM:INFO:GenerateMetadata:GenerateMetadata.py:__init__:line 88:processing:D:\dynamic-foraging\323_EPHYS1\741210\behavior_741210_2024-11-25_13-57-14
02:07:19 PM:INFO:GenerateMetadata:GenerateMetadata.py:_handle_edge_cases:line 361:Cannot log reward delivery in session metadata - missing fields
02:07:19 PM:INFO:GenerateMetadata:GenerateMetadata.py:_get_reward_delivery:line 1192:No reward delivery metadata found!
02:07:19 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525381.064992, '127.0.0.1', 4003]
02:07:20 PM:INFO:GenerateMetadata:GenerateMetadata.py:_get_ephys_stream:line 865:No ephys data stream detected!
02:07:20 PM:INFO:GenerateMetadata:GenerateMetadata.py:_get_ophys_stream:line 584:No photometry data stream detected!
02:07:20 PM:INFO:GenerateMetadata:GenerateMetadata.py:__init__:line 114:Session metadata generated successfully: True
02:07:20 PM:INFO:GenerateMetadata:GenerateMetadata.py:__init__:line 115:Rig metadata generated successfully: True
02:07:20 PM:INFO:GenerateMetadata:GenerateMetadata.py:_get_reward_delivery:line 1192:No reward delivery metadata found!
02:07:20 PM:INFO:GenerateMetadata:GenerateMetadata.py:_get_ephys_stream:line 865:No ephys data stream detected!
02:07:20 PM:INFO:GenerateMetadata:GenerateMetadata.py:_get_ophys_stream:line 584:No photometry data stream detected!
02:07:20 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=300510 fps=501 q=24.0 size= 3691544kB time=00:10:01.02 bitrate=50316.3kbits/s speed=   1x
02:07:20 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=30046 fps= 50 q=21.0 size=  371200kB time=00:01:00.09 bitrate=50603.6kbits/s speed= 0.1x
02:07:20 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=30048 fps= 50 q=29.0 size=  372224kB time=00:01:00.09 bitrate=50739.8kbits/s speed= 0.1x
02:07:20 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525381.216704, '127.0.0.1', 4003]
02:07:20 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525381.354816, '127.0.0.1', 4003]
02:07:20 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525381.502272, '127.0.0.1', 4003]
02:07:20 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525381.641568, '127.0.0.1', 4003]
02:07:20 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=300765 fps=501 q=24.0 size= 3694616kB time=00:10:01.53 bitrate=50315.5kbits/s speed=   1x
02:07:20 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=30072 fps= 50 q=21.0 size=  371456kB time=00:01:00.14 bitrate=50594.7kbits/s speed= 0.1x
02:07:20 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=30074 fps= 50 q=29.0 size=  372736kB time=00:01:00.14 bitrate=50765.7kbits/s speed= 0.1x
02:07:20 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525381.79232, '127.0.0.1', 4003]
02:07:20 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525381.932736, '127.0.0.1', 4003]
02:07:20 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525382.079616, '127.0.0.1', 4003]
02:07:21 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=301028 fps=501 q=24.0 size= 3697944kB time=00:10:02.05 bitrate=50316.8kbits/s speed=   1x
02:07:21 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=30098 fps= 50 q=21.0 size=  371712kB time=00:01:00.19 bitrate=50585.8kbits/s speed= 0.1x
02:07:21 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=30100 fps= 50 q=29.0 size=  372992kB time=00:01:00.20 bitrate=50756.7kbits/s speed= 0.1x
02:07:21 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525382.221472, '127.0.0.1', 4003]
02:07:21 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525382.368672, '127.0.0.1', 4003]
02:07:21 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525382.51296, '127.0.0.1', 4003]
02:07:21 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525382.65904, '127.0.0.1', 4003]
02:07:21 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=301282 fps=501 q=24.0 size= 3701016kB time=00:10:02.56 bitrate=50316.2kbits/s speed=   1x
02:07:21 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=30124 fps= 50 q=21.0 size=  372224kB time=00:01:00.24 bitrate=50611.8kbits/s speed= 0.1x
02:07:21 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=30125 fps= 50 q=29.0 size=  373248kB time=00:01:00.25 bitrate=50749.3kbits/s speed= 0.1x
02:07:21 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525382.802368, '127.0.0.1', 4003]
02:07:21 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525382.952288, '127.0.0.1', 4003]
02:07:22 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525383.10208, '127.0.0.1', 4003]
02:07:22 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=301545 fps=501 q=25.0 size= 3704344kB time=00:10:03.09 bitrate=50317.5kbits/s speed=   1x
02:07:22 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=30152 fps= 50 q=29.0 size=  373504kB time=00:01:00.30 bitrate=50738.7kbits/s speed= 0.1x
02:07:22 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=30150 fps= 50 q=20.0 size=  372480kB time=00:01:00.30 bitrate=50602.9kbits/s speed= 0.1x
02:07:22 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525383.247424, '127.0.0.1', 4003]
02:07:22 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525383.381504, '127.0.0.1', 4003]
02:07:22 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525383.521632, '127.0.0.1', 4003]
02:07:22 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525383.675072, '127.0.0.1', 4003]
02:07:22 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/DelayStartTime', 525383.684, '127.0.0.1', 4002]
02:07:22 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/BehaviorEvent', 525383.685504, '127.0.0.1', 4002]
02:07:22 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=301800 fps=501 q=25.0 size= 3707416kB time=00:10:03.60 bitrate=50316.7kbits/s speed=   1x
02:07:22 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=30177 fps= 50 q=29.0 size=  374016kB time=00:01:00.35 bitrate=50766.1kbits/s speed= 0.1x
02:07:22 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=30175 fps= 50 q=20.0 size=  372736kB time=00:01:00.35 bitrate=50595.7kbits/s speed= 0.1x
02:07:22 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525383.829728, '127.0.0.1', 4003]
02:07:22 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525383.986432, '127.0.0.1', 4003]
02:07:23 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525384.143488, '127.0.0.1', 4003]
02:07:23 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=302056 fps=501 q=25.0 size= 3710744kB time=00:10:04.11 bitrate=50319.2kbits/s speed=   1x
02:07:23 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=30201 fps= 50 q=21.0 size=  372992kB time=00:01:00.40 bitrate=50586.9kbits/s speed= 0.1x
02:07:23 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=30203 fps= 50 q=29.0 size=  374272kB time=00:01:00.40 bitrate=50757.1kbits/s speed= 0.1x
02:07:23 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525384.30256, '127.0.0.1', 4003]
02:07:23 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525384.460032, '127.0.0.1', 4003]
02:07:23 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525384.615776, '127.0.0.1', 4003]
02:07:23 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525384.770944, '127.0.0.1', 4003]
02:07:23 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=302318 fps=501 q=25.0 size= 3713816kB time=00:10:04.63 bitrate=50317.2kbits/s speed=   1x
02:07:23 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=30229 fps= 50 q=29.0 size=  374528kB time=00:01:00.45 bitrate=50748.2kbits/s speed= 0.1x
02:07:23 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=30227 fps= 50 q=21.0 size=  373248kB time=00:01:00.45 bitrate=50578.1kbits/s speed= 0.1x
02:07:23 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525384.921696, '127.0.0.1', 4003]
02:07:23 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525385.078592, '127.0.0.1', 4003]
02:07:24 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525385.21792, '127.0.0.1', 4003]
02:07:24 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=302573 fps=501 q=24.0 size= 3717144kB time=00:10:05.14 bitrate=50319.8kbits/s speed=   1x
02:07:24 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=30253 fps= 50 q=20.0 size=  373760kB time=00:01:00.50 bitrate=50603.9kbits/s speed= 0.1x
02:07:24 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=30255 fps= 50 q=29.0 size=  375040kB time=00:01:00.51 bitrate=50773.9kbits/s speed= 0.1x
02:07:24 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525385.367648, '127.0.0.1', 4003]
02:07:24 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525385.523648, '127.0.0.1', 4003]
02:07:24 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525385.672256, '127.0.0.1', 4003]
02:07:24 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=302828 fps=501 q=25.0 size= 3720216kB time=00:10:05.65 bitrate=50319.0kbits/s speed=   1x
02:07:24 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=30278 fps= 50 q=21.0 size=  374016kB time=00:01:00.55 bitrate=50596.8kbits/s speed= 0.1x
02:07:24 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=30280 fps= 50 q=29.0 size=  375296kB time=00:01:00.56 bitrate=50766.6kbits/s speed= 0.1x
02:07:24 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525385.819296, '127.0.0.1', 4003]
02:07:24 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525385.969568, '127.0.0.1', 4003]
02:07:24 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 525386.004736, '127.0.0.1', 4003]
02:07:25 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525386.152736, '127.0.0.1', 4003]
02:07:25 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525386.318752, '127.0.0.1', 4003]
02:07:25 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=303091 fps=501 q=25.0 size= 3723288kB time=00:10:06.18 bitrate=50316.9kbits/s speed=   1x
02:07:25 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=30304 fps= 50 q=20.0 size=  374272kB time=00:01:00.60 bitrate=50588.0kbits/s speed= 0.1x
02:07:25 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=30306 fps= 50 q=28.0 size=  375552kB time=00:01:00.61 bitrate=50757.6kbits/s speed= 0.1x
02:07:25 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525386.485856, '127.0.0.1', 4003]
02:07:25 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525386.66384, '127.0.0.1', 4003]
02:07:25 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525386.822784, '127.0.0.1', 4003]
02:07:25 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=303345 fps=501 q=25.0 size= 3726360kB time=00:10:06.69 bitrate=50316.2kbits/s speed=   1x
02:07:25 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=30332 fps= 50 q=29.0 size=  375808kB time=00:01:00.66 bitrate=50748.7kbits/s speed= 0.1x
02:07:25 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=30330 fps= 50 q=20.0 size=  374784kB time=00:01:00.66 bitrate=50613.8kbits/s speed= 0.1x
02:07:25 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525386.964736, '127.0.0.1', 4003]
02:07:26 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525387.122912, '127.0.0.1', 4003]
02:07:26 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525387.303584, '127.0.0.1', 4003]
02:07:26 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=303608 fps=501 q=24.0 size= 3729688kB time=00:10:07.21 bitrate=50317.5kbits/s speed=   1x
02:07:26 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=30358 fps= 50 q=29.0 size=  376320kB time=00:01:00.71 bitrate=50774.3kbits/s speed= 0.1x
02:07:26 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=30356 fps= 50 q=20.0 size=  375040kB time=00:01:00.71 bitrate=50604.9kbits/s speed= 0.1x
02:07:26 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525387.467456, '127.0.0.1', 4003]
02:07:26 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/LeftLickTime', 525387.469408, '127.0.0.1', 4003]
02:07:26 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=30383 fps= 50 q=27.0 size=  376320kB time=00:01:00.76 bitrate=50732.5kbits/s speed= 0.1x
02:07:26 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=303862 fps=501 q=24.0 size= 3732248kB time=00:10:07.72 bitrate=50310.0kbits/s speed=   1x
02:07:26 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=30382 fps= 50 q=21.0 size=  375296kB time=00:01:00.76 bitrate=50596.2kbits/s speed= 0.1x
02:07:27 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=304118 fps=501 q=24.0 size= 3734808kB time=00:10:08.23 bitrate=50302.3kbits/s speed=   1x
02:07:27 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=30409 fps= 50 q=24.0 size=  376576kB time=00:01:00.81 bitrate=50723.6kbits/s speed= 0.1x
02:07:27 PM:INFO:Foraging:Foraging.py:log_subprocess_output:line 4782:BONSAI: frame=30407 fps= 50 q=21.0 size=  375552kB time=00:01:00.81 bitrate=50589.0kbits/s speed= 0.1x
02:07:27 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525388.442912, '127.0.0.1', 4003]
02:07:27 PM:INFO:rigcontrol:rigcontrol.py:msg_handler:line 56:['/RightLickTime', 525388.567488, '127.0.0.1', 4003]
02:07:27 PM:INFO:MyFunctions:MyFunctions.py:calculate_inter_lick_intervals:line 913:Percentage of left lick intervals under 100 ms is 0.00%.
02:07:27 PM:INFO:MyFunctions:MyFunctions.py:calculate_inter_lick_intervals:line 919:Percentage of right lick intervals under 100 ms is 0.00%.
02:07:27 PM:INFO:MyFunctions:MyFunctions.py:calculate_inter_lick_intervals:line 926:Percentage of right and left lick intervals under 100 ms is 0.00%.
02:07:27 PM:INFO:MyFunctions:MyFunctions.py:calculate_inter_lick_intervals:line 951:Percentage of cross side lick intervals under 100 ms is 15.38%.
02:07:27 PM:ERROR:MyFunctions:MyFunctions.py:calculate_inter_lick_intervals:line 957:Percentage of cross side lick intervals under 100 ms in Box A mouse 741210 exceeded 10%
@alexpiet alexpiet added the low priority - auto-generated autogenerated issues that have only occurred once label Nov 26, 2024
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
low priority - auto-generated autogenerated issues that have only occurred once
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
1 participant
@alexpiet