[02/04/18 20:10:40.405][DEBUG] [Main Thread] Licensing: Machine ID found... [02/04/18 20:10:40.411][DEBUG] [Main Thread] Checking for valid internet connection... [02/04/18 20:10:45.211][DEBUG] [Main Thread] Valid internet connection detected... [02/04/18 20:10:46.904][DEBUG] [Main Thread] Licensing: Request for licenses successful, writing cookie... [02/04/18 20:10:46.912][DEBUG] [Main Thread] Licensing: Applying licenses.. [02/04/18 20:10:46.912][DEBUG] [Main Thread] Licensing: Licenses applied.. [02/04/18 20:10:46.914][DEBUG] [Main Thread] SGPro retail license... [02/04/18 20:10:47.820][DEBUG] [Main Thread] API successfully started on port 59590... [02/04/18 20:10:48.346][DEBUG] [Main Thread] Error loading notification settings! Could not find file 'C:\Users\Steve\AppData\Local\SequenceGenerator\Sequence Generator Pro\notifications.sgd'. [02/04/18 20:10:48.350][DEBUG] [Main Thread] Error loading GNS endpoint settings! Could not find file 'C:\Users\Steve\AppData\Local\SequenceGenerator\Sequence Generator Pro\gnsSettings.sgd'. [02/04/18 20:10:48.352][DEBUG] [Main Thread] Error loading email endpoint addresses! Could not find file 'C:\Users\Steve\AppData\Local\SequenceGenerator\Sequence Generator Pro\1-emailEndpointAddresses.sgd'. [02/04/18 20:10:48.352][DEBUG] [Main Thread] Error loading email endpoint addresses! Could not find file 'C:\Users\Steve\AppData\Local\SequenceGenerator\Sequence Generator Pro\2-emailEndpointAddresses.sgd'. [02/04/18 20:10:48.352][DEBUG] [Main Thread] Error loading email endpoint addresses! Could not find file 'C:\Users\Steve\AppData\Local\SequenceGenerator\Sequence Generator Pro\3-emailEndpointAddresses.sgd'. [02/04/18 20:10:48.353][DEBUG] [Main Thread] Error loading text file endpoint data! Could not find file 'C:\Users\Steve\AppData\Local\SequenceGenerator\Sequence Generator Pro\1-textFileEndpointSettings.sgd'. [02/04/18 20:10:48.353][DEBUG] [Main Thread] Error loading text file endpoint data! Could not find file 'C:\Users\Steve\AppData\Local\SequenceGenerator\Sequence Generator Pro\2-textFileEndpointSettings.sgd'. [02/04/18 20:10:48.353][DEBUG] [Main Thread] Error loading text file endpoint data! Could not find file 'C:\Users\Steve\AppData\Local\SequenceGenerator\Sequence Generator Pro\3-textFileEndpointSettings.sgd'. [02/04/18 20:10:48.450][DEBUG] [Main Thread] ===== S E Q U E N C E G E N E R A T O R (v3.0.0.7) ===== [02/04/18 20:10:48.570][DEBUG] [Main Thread] OS: Microsoft Windows 7 Home Premium [02/04/18 20:10:48.919][DEBUG] [Focuser Thread] Entering Fouser message dispatch loop... [02/04/18 20:10:48.921][DEBUG] [Camera Thread] Entering camera message dispatch loop... [02/04/18 20:10:48.927][DEBUG] [TEC Thread] Entering TEC message dispatch loop... [02/04/18 20:10:48.933][DEBUG] [Filter Wheel Thread] Entering Filter Wheel message dispatch loop... [02/04/18 20:10:48.939][DEBUG] [Rotator Thread] Entering Rotator message dispatch loop... [02/04/18 20:10:48.940][DEBUG] [Auto Guider Thread] Entering Auto Guider message dispatch loop... [02/04/18 20:10:48.941][DEBUG] [Auto Guider Error Thread] Entering Auto Guider message dispatch loop... [02/04/18 20:10:48.941][DEBUG] [Dome Thread] Entering Dome message dispatch loop... [02/04/18 20:10:48.941][DEBUG] [Safety Thread] Starting safety monitor... [02/04/18 20:10:48.943][DEBUG] [Environment Device Thread] Starting environment device monitor... [02/04/18 20:10:48.950][DEBUG] [Telescope Thread] Entering Telescope message dispatch loop... [02/04/18 20:10:48.966][DEBUG] [Main Thread] Settings changed (or loaded)... [02/04/18 20:10:48.967][DEBUG] [Main Thread] * SETTING: settingMdiAutoStretch = False [02/04/18 20:10:48.967][DEBUG] [Main Thread] * SETTING: settingIgLastFileFolder = [02/04/18 20:10:48.967][DEBUG] [Main Thread] * SETTING: settingFormScaleWarning = False [02/04/18 20:10:48.967][DEBUG] [Main Thread] * SETTING: settingRecoveryDuration = 90 [02/04/18 20:10:48.967][DEBUG] [Main Thread] * SETTING: settingDelayBetween = 0 [02/04/18 20:10:48.967][DEBUG] [Main Thread] * SETTING: settingFliFlushLow = 0 [02/04/18 20:10:48.967][DEBUG] [Main Thread] * SETTING: settingSBIGLpt2Address = 278 [02/04/18 20:10:48.967][DEBUG] [Main Thread] * SETTING: settingShowBetas = False [02/04/18 20:10:48.967][DEBUG] [Main Thread] * SETTING: settingMdiMouseAction = Stretch [02/04/18 20:10:48.967][DEBUG] [Main Thread] * SETTING: settingLastAscomCamera = [02/04/18 20:10:48.967][DEBUG] [Main Thread] * SETTING: settingAutoRecoveryActive = False [02/04/18 20:10:48.967][DEBUG] [Main Thread] * SETTING: settingTemperhumInterval = 300 [02/04/18 20:10:48.967][DEBUG] [Main Thread] * SETTING: settingASCOMWarningShown = False [02/04/18 20:10:48.967][DEBUG] [Main Thread] * SETTING: settingPlateCamScale = 0 [02/04/18 20:10:48.967][DEBUG] [Main Thread] * SETTING: settingCanonMirrorSettle = 0 [02/04/18 20:10:48.967][DEBUG] [Main Thread] * SETTING: settingInterface = [02/04/18 20:10:48.967][DEBUG] [Main Thread] * SETTING: settingLastAscomFilterWheel = [02/04/18 20:10:48.967][DEBUG] [Main Thread] * SETTING: settingAstroArtDisplayInstructions = True [02/04/18 20:10:48.967][DEBUG] [Main Thread] * SETTING: settingSBIGLpt1Address = 378 [02/04/18 20:10:48.967][DEBUG] [Main Thread] * SETTING: settingTrialQuestionsAsked = False [02/04/18 20:10:48.967][DEBUG] [Main Thread] * SETTING: settingCheckForUpdatesOnStart = True [02/04/18 20:10:48.967][DEBUG] [Main Thread] * SETTING: settingMdiLastImageDir = [02/04/18 20:10:48.967][DEBUG] [Main Thread] * SETTING: settingTemperHumUserTempComp = 0 [02/04/18 20:10:48.967][DEBUG] [Main Thread] * SETTING: settingCaptureCalFramesAlways = False [02/04/18 20:10:48.967][DEBUG] [Main Thread] * SETTING: settingDelayFirst = 0 [02/04/18 20:10:48.967][DEBUG] [Main Thread] * SETTING: settingFliNormalSpeedMode = 0 [02/04/18 20:10:48.967][DEBUG] [Main Thread] * SETTING: settingFliHighSpeedMode = 0 [02/04/18 20:10:48.967][DEBUG] [Main Thread] * SETTING: settingMfPosition = {X=0,Y=0} [02/04/18 20:10:48.967][DEBUG] [Main Thread] * SETTING: settingSoundAttention = [02/04/18 20:10:48.967][DEBUG] [Main Thread] * SETTING: settingPinpointCatalog = [02/04/18 20:10:48.967][DEBUG] [Main Thread] * SETTING: settingCameraOverhead = [02/04/18 20:10:48.967][DEBUG] [Main Thread] * SETTING: settingPlateSolveOn = False [02/04/18 20:10:48.980][DEBUG] [Main Thread] * SETTING: settingAstrometryNetEndpointList = System.Collections.Specialized.StringCollection [02/04/18 20:10:48.980][DEBUG] [Main Thread] * SETTING: settingSBIGGuider = None [02/04/18 20:10:48.980][DEBUG] [Main Thread] * SETTING: settingFocusLockDirectory = [02/04/18 20:10:48.980][DEBUG] [Main Thread] * SETTING: settingQSIFanSpeed = 2 [02/04/18 20:10:48.980][DEBUG] [Main Thread] * SETTING: settingNaggerText = [02/04/18 20:10:48.980][DEBUG] [Main Thread] * SETTING: settingMdiPhdDisplay = RA/Dec [02/04/18 20:10:48.980][DEBUG] [Main Thread] * SETTING: settingMdiShowToolStrip = True [02/04/18 20:10:48.980][DEBUG] [Main Thread] * SETTING: settingMdiShowCrosshairs = True [02/04/18 20:10:48.980][DEBUG] [Main Thread] * SETTING: WindowGeometry = [02/04/18 20:10:48.980][DEBUG] [Main Thread] * SETTING: settingIgRenameStringLocation = 2 [02/04/18 20:10:48.980][DEBUG] [Main Thread] * SETTING: settingIgSubFolders = False [02/04/18 20:10:48.980][DEBUG] [Main Thread] * SETTING: WindowGeometrySearch = [02/04/18 20:10:48.980][DEBUG] [Main Thread] * SETTING: settingOffset = -1 [02/04/18 20:10:48.980][DEBUG] [Main Thread] * SETTING: settingAutoStretchLevel = Low [02/04/18 20:10:48.980][DEBUG] [Main Thread] * SETTING: settingTemperHumUnits = C [02/04/18 20:10:48.980][DEBUG] [Main Thread] * SETTING: settingProfileDirectory = [02/04/18 20:10:48.981][DEBUG] [Main Thread] * SETTING: settingBasename = [02/04/18 20:10:48.981][DEBUG] [Main Thread] * SETTING: settingHfrColor = Color [RoyalBlue] [02/04/18 20:10:48.981][DEBUG] [Main Thread] * SETTING: settingNikonAfBinning = 1 [02/04/18 20:10:48.981][DEBUG] [Main Thread] * SETTING: settingSBIGFanOffWithTec = False [02/04/18 20:10:48.981][DEBUG] [Main Thread] * SETTING: settingLastAscomSafetyMonitor = [02/04/18 20:10:48.981][DEBUG] [Main Thread] * SETTING: settingMdiSize = {Width=800, Height=600} [02/04/18 20:10:48.981][DEBUG] [Main Thread] * SETTING: settingIgCopyAndRenameFiles = False [02/04/18 20:10:48.981][DEBUG] [Main Thread] * SETTING: settingSimDownloadTimesAf = False [02/04/18 20:10:48.981][DEBUG] [Main Thread] * SETTING: settingNagger = False [02/04/18 20:10:48.981][DEBUG] [Main Thread] * SETTING: settingImportFromCfw = True [02/04/18 20:10:48.981][DEBUG] [Main Thread] * SETTING: settingMdiShowStatusTrip = True [02/04/18 20:10:48.981][DEBUG] [Main Thread] * SETTING: settingShowTooltipHelp = True [02/04/18 20:10:48.981][DEBUG] [Main Thread] * SETTING: settingLastUpdateCheck = 1/1/2012 12:00:00 AM [02/04/18 20:10:48.981][DEBUG] [Main Thread] * SETTING: settingEventSuffixes = null [02/04/18 20:10:48.981][DEBUG] [Main Thread] * SETTING: settingCpPosition = {X=0,Y=0} [02/04/18 20:10:48.981][DEBUG] [Main Thread] * SETTING: settingSBIGCfwPort = [02/04/18 20:10:48.982][DEBUG] [Main Thread] * SETTING: settingMdiLocation = {X=0,Y=0} [02/04/18 20:10:48.982][DEBUG] [Main Thread] * SETTING: settingIgLastCsvFolder = [02/04/18 20:10:48.982][DEBUG] [Main Thread] * SETTING: settingSBIGFilterWheel = Auto Detect [02/04/18 20:10:48.982][DEBUG] [Main Thread] * SETTING: settingSBIGConnection = USB [02/04/18 20:10:48.982][DEBUG] [Main Thread] * SETTING: settingDSLRSoftwareBinForAf = False [02/04/18 20:10:48.982][DEBUG] [Main Thread] * SETTING: settingAutoSave = True [02/04/18 20:10:48.982][DEBUG] [Main Thread] * SETTING: settingFirstTime = True [02/04/18 20:10:48.982][DEBUG] [Main Thread] * SETTING: settingFliFlushHigh = 0 [02/04/18 20:10:48.982][DEBUG] [Main Thread] * SETTING: settingMdiState = maximized [02/04/18 20:10:48.982][DEBUG] [Main Thread] * SETTING: settingUseSequenceOptions = False [02/04/18 20:10:48.982][DEBUG] [Main Thread] * SETTING: settingSoundCompleted = [02/04/18 20:10:48.982][DEBUG] [Main Thread] * SETTING: settingTrialExpiredShown = False [02/04/18 20:10:48.982][DEBUG] [Main Thread] * SETTING: settingTemperHumColor = Grey [02/04/18 20:10:48.982][DEBUG] [Main Thread] * SETTING: settingDataDirectory = [02/04/18 20:10:48.982][DEBUG] [Main Thread] * SETTING: settingCanonFileFormat = Single Image (CFA FITS) [02/04/18 20:10:48.982][DEBUG] [Main Thread] * SETTING: settingAlnitakComPort = [02/04/18 20:10:48.982][DEBUG] [Main Thread] * SETTING: settingIgRenameFiles = False [02/04/18 20:10:48.982][DEBUG] [Main Thread] * SETTING: settingSubFolder = True [02/04/18 20:10:48.982][DEBUG] [Main Thread] * SETTING: settingLastSGSaveFolder = [02/04/18 20:10:48.982][DEBUG] [Main Thread] * SETTING: settingFindStars = Normal (recommended) [02/04/18 20:10:48.982][DEBUG] [Main Thread] * SETTING: settingNikonDataFormat = FITS [02/04/18 20:10:48.982][DEBUG] [Main Thread] * SETTING: settingPlaySounds = False [02/04/18 20:10:48.982][DEBUG] [Main Thread] * SETTING: settingPlateSolve3Catalogs = [02/04/18 20:10:48.982][DEBUG] [Main Thread] * SETTING: settingLastNikonType = Auto Connect [02/04/18 20:10:48.982][DEBUG] [Main Thread] * SETTING: settingLastSequenceStart = 1/1/0001 12:00:00 AM [02/04/18 20:10:48.982][DEBUG] [Main Thread] * SETTING: settingLastAscomTelescope = [02/04/18 20:10:48.982][DEBUG] [Main Thread] * SETTING: settingMdiPhdScale = 100 [02/04/18 20:10:48.982][DEBUG] [Main Thread] * SETTING: settingIgRenameType = 0 [02/04/18 20:10:48.982][DEBUG] [Main Thread] * SETTING: settingHelpLanguage = English [02/04/18 20:10:48.982][DEBUG] [Main Thread] * SETTING: settingTemperHumUserHumidityComp = 0 [02/04/18 20:10:48.982][DEBUG] [Main Thread] * SETTING: settingMdiPhdRaColor = Color [Blue] [02/04/18 20:10:48.982][DEBUG] [Main Thread] * SETTING: settingLastImageSaveFolder = [02/04/18 20:10:48.982][DEBUG] [Main Thread] * SETTING: settingEnableImageHistory = False [02/04/18 20:10:48.982][DEBUG] [Main Thread] * SETTING: settingFinishFullEvent = True [02/04/18 20:10:48.983][DEBUG] [Main Thread] * SETTING: settingMosaicOverlap = 20 [02/04/18 20:10:48.983][DEBUG] [Main Thread] * SETTING: settingQSIFanOffWithTec = False [02/04/18 20:10:48.983][DEBUG] [Main Thread] * SETTING: settingRecentSequences = [02/04/18 20:10:48.983][DEBUG] [Main Thread] * SETTING: settingDefaultDirectory = [02/04/18 20:10:48.983][DEBUG] [Main Thread] * SETTING: settingMdiPhdDecColor = Color [Red] [02/04/18 20:10:48.983][DEBUG] [Main Thread] * SETTING: settingAstrometryNetEndpoint = Astrometry.NET (Remote) [02/04/18 20:10:48.983][DEBUG] [Main Thread] * SETTING: settingSBIGCfwUseRS232 = False [02/04/18 20:10:48.983][DEBUG] [Main Thread] * SETTING: settingStarsColor = Color [OrangeRed] [02/04/18 20:10:48.983][DEBUG] [Main Thread] * SETTING: settingPhd2InstallPath = [02/04/18 20:10:48.983][DEBUG] [Main Thread] * SETTING: settingIgOverwriteExisting = False [02/04/18 20:10:48.983][DEBUG] [Main Thread] * SETTING: settingLastAscomFocuser = [02/04/18 20:10:48.983][DEBUG] [Main Thread] * SETTING: settingIgLastFolder = [02/04/18 20:10:48.983][DEBUG] [Main Thread] * SETTING: WindowGeometryOpen = [02/04/18 20:10:48.983][DEBUG] [Main Thread] * SETTING: settingNumEvents = 5 [02/04/18 20:10:48.983][DEBUG] [Main Thread] * SETTING: settingCanonAfBinning = 1 [02/04/18 20:10:48.983][DEBUG] [Main Thread] * SETTING: settingPlateCamBlank = [02/04/18 20:10:48.983][DEBUG] [Main Thread] * SETTING: settingLogApiCalls = False [02/04/18 20:10:48.983][DEBUG] [Main Thread] * SETTING: settingNikonUseBulbModeAlways = False [02/04/18 20:10:48.983][DEBUG] [Main Thread] * SETTING: settingTempFiles = C:\Temp [02/04/18 20:10:48.983][DEBUG] [Main Thread] * SETTING: settingFliHighSpeedDesc = [02/04/18 20:10:48.983][DEBUG] [Main Thread] * SETTING: settingRecoveryInterval = 10 [02/04/18 20:10:48.983][DEBUG] [Main Thread] * SETTING: settingDefaultMfwFov = 4.0 [02/04/18 20:10:48.983][DEBUG] [Main Thread] * SETTING: settingSBIGEthernetIP = 0.0.0.0 [02/04/18 20:10:48.983][DEBUG] [Main Thread] * SETTING: settingSBIGLpt3Address = 3BC [02/04/18 20:10:48.983][DEBUG] [Main Thread] * SETTING: settingFliNormalSpeedDesc = [02/04/18 20:10:48.983][DEBUG] [Main Thread] * SETTING: settingIgCopyLocation = [02/04/18 20:10:48.983][DEBUG] [Main Thread] * SETTING: settingSBIGAutoDetectIp = True [02/04/18 20:10:48.983][DEBUG] [Main Thread] * SETTING: settingAstroArtStartApp = True [02/04/18 20:10:48.983][DEBUG] [Main Thread] * SETTING: settingSimDownloadTimesPlate = False [02/04/18 20:10:48.983][DEBUG] [Main Thread] * SETTING: settingMicroTouchLocation = [02/04/18 20:10:48.983][DEBUG] [Main Thread] * SETTING: settingMdiPhdZoom = 40 [02/04/18 20:10:48.983][DEBUG] [Main Thread] * SETTING: settingMdiNewWindow = False [02/04/18 20:10:48.983][DEBUG] [Main Thread] * SETTING: settingEventAlwaysVisible = False [02/04/18 20:10:48.984][DEBUG] [Main Thread] * SETTING: settingFliRbiMitigation = False [02/04/18 20:10:48.984][DEBUG] [Main Thread] * SETTING: settingGain = -1 [02/04/18 20:10:48.984][DEBUG] [Main Thread] * SETTING: settingLastAscomDome = [02/04/18 20:10:48.984][DEBUG] [Main Thread] * SETTING: settingLookForTemperDevices = False [02/04/18 20:10:48.984][DEBUG] [Main Thread] * SETTING: settingLastAscomRotator = [02/04/18 20:10:48.984][DEBUG] [Main Thread] * SETTING: settingAfTestPackPath = [02/04/18 20:10:49.112][DEBUG] [Main Thread] ASCOM Observing Conditions Hub (OCH) [02/04/18 20:10:49.112][DEBUG] [Main Thread] ASCOM Observing Conditions Simulator [02/04/18 20:10:49.112][DEBUG] [Main Thread] OpenWeatherMap ObservingConditions [02/04/18 20:10:49.112][DEBUG] [Main Thread] TEMPerHUM [02/04/18 20:10:49.114][DEBUG] [Main Thread] Performing initialization... [02/04/18 20:10:49.114][DEBUG] [Main Thread] Creating new sequence with default profile: Steve Zwo [02/04/18 20:10:49.130][DEBUG] [Main Thread] Performing full form reset (with profile Steve Zwo)... [02/04/18 20:10:49.137][DEBUG] [Main Thread] Retreiving new equipment objects... [02/04/18 20:10:49.137][DEBUG] [Main Thread] New camera object (ASI Camera (1)) dispatched... [02/04/18 20:10:49.141][DEBUG] [Main Thread] New filter wheel object (No Filter Wheel) dispatched... [02/04/18 20:10:49.141][DEBUG] [Main Thread] New environment device object (OpenWeatherMap ObservingConditions) dispatched... [02/04/18 20:10:49.143][DEBUG] [Main Thread] New focuser object (Moonlite DRO Focuser Driver) dispatched... [02/04/18 20:10:49.145][DEBUG] [Main Thread] New telescope object (iOptron Mount ASCOM Driver) dispatched... [02/04/18 20:10:49.146][DEBUG] [Main Thread] New rotator object (No Rotator) dispatched... [02/04/18 20:10:49.147][DEBUG] [Main Thread] New dome object (No Observatory) dispatched... [02/04/18 20:10:49.148][DEBUG] [Main Thread] New flat box object (No Flat Box) dispatched... [02/04/18 20:10:49.148][DEBUG] [Main Thread] New safety monitor object (No Safety Monitor) dispatched... [02/04/18 20:10:49.149][DEBUG] [Main Thread] New auto guider object (PHD2) dispatched... [02/04/18 20:10:49.149][DEBUG] [Main Thread] New plate solver object (PlateSolve2) dispatched... [02/04/18 20:10:49.171][DEBUG] [Main Thread] Populating the form controls... [02/04/18 20:10:49.215][DEBUG] [Main Thread] Added row 0... [02/04/18 20:10:49.248][DEBUG] [Main Thread] Added row 1... [02/04/18 20:10:49.283][DEBUG] [Main Thread] Added row 2... [02/04/18 20:10:49.328][DEBUG] [Main Thread] Added row 3... [02/04/18 20:10:49.382][DEBUG] [Main Thread] Added row 4... [02/04/18 20:10:49.395][DEBUG] [Main Thread] Loading custom filter names... [02/04/18 20:10:49.403][DEBUG] [Main Thread] PopulateDataModel: Transferring view to the data model... [02/04/18 20:10:49.467][DEBUG] [Main Thread] Retreiving new equipment objects... [02/04/18 20:10:49.467][DEBUG] [Main Thread] No change in camera object... [02/04/18 20:10:49.467][DEBUG] [Main Thread] New filter wheel object (No Filter Wheel) dispatched... [02/04/18 20:10:49.468][DEBUG] [Main Thread] No change in environment device object... [02/04/18 20:10:49.468][DEBUG] [Main Thread] No change in focuser object... [02/04/18 20:10:49.469][DEBUG] [Sequence Thread] Entering sequence message dispatch loop... [02/04/18 20:10:49.469][DEBUG] [Main Thread] MoveFocuserAbs: Error! The focuser is no longer connected! [02/04/18 20:10:49.469][DEBUG] [Main Thread] No change in telescope object... [02/04/18 20:10:49.469][DEBUG] [Main Thread] New rotator object (No Rotator) dispatched... [02/04/18 20:10:49.470][DEBUG] [Main Thread] New dome object (No Observatory) dispatched... [02/04/18 20:10:49.471][DEBUG] [Main Thread] New flat box object (No Flat Box) dispatched... [02/04/18 20:10:49.472][DEBUG] [Main Thread] New safety monitor object (No Safety Monitor) dispatched... [02/04/18 20:10:49.473][DEBUG] [Main Thread] New auto guider object (PHD2) dispatched... [02/04/18 20:10:49.473][DEBUG] [Main Thread] No change in plate solver object... [02/04/18 20:10:49.476][DEBUG] [Main Thread] Checking if version check is required... [02/04/18 20:10:49.581][DEBUG] [Cleaner Thread] Running cleanup... [02/04/18 20:10:49.582][DEBUG] [Main Thread] UI layout found, loading layout at C:\Users\Steve\AppData\Local\SequenceGenerator\sg_ui_config.xml [02/04/18 20:10:49.582][DEBUG] [Cleaner Thread] Cleaning 1 files in directory C:\Users\Steve\AppData\Local\SequenceGenerator\Temp\... [02/04/18 20:10:49.584][DEBUG] [Cleaner Thread] Cleaning multi camera registration file... [02/04/18 20:10:49.584][DEBUG] [Cleaner Thread] Cleaning finished... [02/04/18 20:11:17.762][DEBUG] [Main Thread] Connecting ASCOM focuser... [02/04/18 20:11:18.089][DEBUG] [Main Thread] Focuser supports temperature [02/04/18 20:11:18.089][DEBUG] [Main Thread] Current temperature reports: 11 [02/04/18 20:11:18.091][DEBUG] [Main Thread] Focuser reports Max Increment as: 10000 [02/04/18 20:11:18.091][DEBUG] [Main Thread] Focuser reports max steps as: 10000 [02/04/18 20:11:43.158][DEBUG] [Main Thread] Connecting camera in main thread... [02/04/18 20:11:43.164][DEBUG] [Main Thread] Connecting ASCOM camera: ASCOM.ASICamera2.Camera... [02/04/18 20:11:44.838][DEBUG] [Main Thread] Camera implements ASCOM gain. Current gain is 104 [02/04/18 20:11:44.847][DEBUG] [Main Thread] Failed to fetch ASCOM camera gains list, checking min / max... : Property read ASCOM.ASICamera2.Camera Gains is not implemented in this driver. (System.Reflection.TargetInvocationException: Exception has been thrown by the target of an invocation. ---> System.Runtime.InteropServices.COMException: Property Unknown is not implemented in this driver. --- End of inner exception stack trace --- at System.RuntimeType.InvokeDispMethod(String name, BindingFlags invokeAttr, Object target, Object[] args, Boolean[] byrefModifiers, Int32 culture, String[] namedParameters) at System.RuntimeType.InvokeMember(String name, BindingFlags bindingFlags, Binder binder, Object target, Object[] providedArgs, ParameterModifier[] modifiers, CultureInfo culture, String[] namedParams) at System.Type.InvokeMember(String name, BindingFlags invokeAttr, Binder binder, Object target, Object[] args, CultureInfo culture) at ASCOM.DriverAccess.MemberFactory.CallMember(Int32 memberCode, String memberName, Type[] parameterTypes, Object[] parms) in c:\ASCOM Build\Export\ASCOM.DriverAccess\MemberFactory.cs:line 243) at ASCOM.DriverAccess.MemberFactory.CallMember(Int32 memberCode, String memberName, Type[] parameterTypes, Object[] parms) in c:\ASCOM Build\Export\ASCOM.DriverAccess\MemberFactory.cs:line 279 at ASCOM.DriverAccess.Camera.get_Gains() in c:\ASCOM Build\Export\ASCOM.DriverAccess\Camera.cs:line 864 at q5.bz() [02/04/18 20:11:44.865][DEBUG] [Main Thread] Camera cooler detected... [02/04/18 20:11:44.866][DEBUG] [Main Thread] Camera cooler is OFF... [02/04/18 20:11:44.866][DEBUG] [Main Thread] Camera cooldown on connect is selected... [02/04/18 20:11:44.866][DEBUG] [Main Thread] Camera cooldown is starting... [02/04/18 20:11:44.870][DEBUG] [TEC Thread] SGM_CHANGE_COOLER_TEMP message received... [02/04/18 20:11:44.871][DEBUG] [TEC Thread] TEC Change: Starting... [02/04/18 20:11:44.872][DEBUG] [TEC Thread] TEC Change: After camera connect change, waiting ~5 seconds... [02/04/18 20:11:47.439][DEBUG] [Telescope Thread] Telescope Dispatch loop: Received SGM_TELESCOPE_CONNECT... [02/04/18 20:11:47.628][DEBUG] [Telescope Thread] Telescope can slewing supported... [02/04/18 20:11:47.632][DEBUG] [Telescope Thread] Telescope: Implements MoveAxis... [02/04/18 20:11:47.632][DEBUG] [Telescope Thread] RA Move Rate Range: Min->0.00416666666666667 Max->0.00416666666666667 [02/04/18 20:11:47.632][DEBUG] [Telescope Thread] RA Move Rate Range: Min->0.00833333333333333 Max->0.00833333333333333 [02/04/18 20:11:47.632][DEBUG] [Telescope Thread] RA Move Rate Range: Min->0.0333333333333333 Max->0.0333333333333333 [02/04/18 20:11:47.632][DEBUG] [Telescope Thread] RA Move Rate Range: Min->0.0666666666666667 Max->0.0666666666666667 [02/04/18 20:11:47.632][DEBUG] [Telescope Thread] RA Move Rate Range: Min->0.266666666666667 Max->0.266666666666667 [02/04/18 20:11:47.632][DEBUG] [Telescope Thread] RA Move Rate Range: Min->0.533333333333333 Max->0.533333333333333 [02/04/18 20:11:47.632][DEBUG] [Telescope Thread] RA Move Rate Range: Min->1.06666666666667 Max->1.06666666666667 [02/04/18 20:11:47.632][DEBUG] [Telescope Thread] RA Move Rate Range: Min->2.13333333333333 Max->2.13333333333333 [02/04/18 20:11:47.632][DEBUG] [Telescope Thread] RA Move Rate Range: Min->3.75 Max->3.75 [02/04/18 20:11:47.632][DEBUG] [Telescope Thread] DEC Move Rate Range: Min->0.00416666666666667 Max->0.00416666666666667 [02/04/18 20:11:47.632][DEBUG] [Telescope Thread] DEC Move Rate Range: Min->0.00833333333333333 Max->0.00833333333333333 [02/04/18 20:11:47.632][DEBUG] [Telescope Thread] DEC Move Rate Range: Min->0.0333333333333333 Max->0.0333333333333333 [02/04/18 20:11:47.632][DEBUG] [Telescope Thread] DEC Move Rate Range: Min->0.0666666666666667 Max->0.0666666666666667 [02/04/18 20:11:47.632][DEBUG] [Telescope Thread] DEC Move Rate Range: Min->0.266666666666667 Max->0.266666666666667 [02/04/18 20:11:47.632][DEBUG] [Telescope Thread] DEC Move Rate Range: Min->0.533333333333333 Max->0.533333333333333 [02/04/18 20:11:47.632][DEBUG] [Telescope Thread] DEC Move Rate Range: Min->1.06666666666667 Max->1.06666666666667 [02/04/18 20:11:47.632][DEBUG] [Telescope Thread] DEC Move Rate Range: Min->2.13333333333333 Max->2.13333333333333 [02/04/18 20:11:47.632][DEBUG] [Telescope Thread] DEC Move Rate Range: Min->3.75 Max->3.75 [02/04/18 20:11:47.633][DEBUG] [Telescope Thread] Telescope: CanSetSideOfPier returned False [02/04/18 20:11:47.634][DEBUG] [Telescope Thread] Telescope: CanPark returned True [02/04/18 20:11:47.634][DEBUG] [Telescope Thread] Telescope equatorial system is JNOW... [02/04/18 20:11:47.635][DEBUG] [Telescope Thread] SGM_TELESCOPE_CONNECT complete... [02/04/18 20:11:47.752][DEBUG] [Main Thread] Telescope: Setting sync behavior to: Sync [02/04/18 20:11:49.872][DEBUG] [TEC Thread] TEC Change: Attempting to turn the camera cooler on... [02/04/18 20:11:49.872][DEBUG] [TEC Thread] Turning camera cooler on... [02/04/18 20:11:49.904][DEBUG] [Main Thread] Turning camera cooler on... [02/04/18 20:11:54.873][DEBUG] [TEC Thread] TEC Change: Changing temp from 1.70 to -20.00 in 300 seconds... [02/04/18 20:12:51.101][DEBUG] [PHD2 Startup Thread] PHD2: PHD2 is already open... [02/04/18 20:12:51.103][DEBUG] [PHD2 Startup Thread] Connecting to PHD2... [02/04/18 20:12:51.116][DEBUG] [PHD2 Listener Thread] Attempting to connect to PHD2... [02/04/18 20:12:51.119][DEBUG] [PHD2 Startup Thread] Successfully connected to PHD2... [02/04/18 20:12:51.120][DEBUG] [PHD2 Startup Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [02/04/18 20:12:51.120][DEBUG] [PHD2 Startup Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [02/04/18 20:12:51.320][DEBUG] [PHD2 Startup Thread] PHD2 GetPhdStatus - Post-Wait: Looping [02/04/18 20:12:51.322][DEBUG] [PHD2 Startup Thread] PHD2: PHD2 is already open... [02/04/18 20:12:51.322][DEBUG] [PHD2 Startup Thread] Sending to PHD2: {"method": "get_profiles", "id": 1009} [02/04/18 20:13:02.030][DEBUG] [Main Thread] Astrometry.NET: Sequence endpoint data: Astrometry.NET (Remote) [02/04/18 20:13:15.221][DEBUG] [Camera Thread] SGM_FOCUSER_AUTO_FOCUS message received... [02/04/18 20:13:15.226][DEBUG] [Camera Thread] Checking for auto focus... [02/04/18 20:13:15.226][DEBUG] [Camera Thread] Auto focus required (focus before first frame)... [02/04/18 20:13:15.241][DEBUG] [Camera Thread] Auto focus: setting filter None [02/04/18 20:13:15.249][DEBUG] [Camera Thread] Auto focus running... [02/04/18 20:13:15.260][DEBUG] [Camera Thread] Turning temp comp off.. [02/04/18 20:13:15.263][DEBUG] [Camera Thread] ASCOM Focuser: Temp comp is avaialble, setting to False [02/04/18 20:13:15.278][DEBUG] [Camera Thread] Performing Sequence Generator auto focus using Half Flux Radius. AFID: 0 [02/04/18 20:13:15.315][DEBUG] [AfChartThread] Showing AF chart dialog... [02/04/18 20:13:15.336][DEBUG] [Camera Thread] Auto focus data [02/04/18 20:13:15.337][DEBUG] [Camera Thread] - Data Points: 7 [02/04/18 20:13:15.337][DEBUG] [Camera Thread] - Step Size: 10 [02/04/18 20:13:15.337][DEBUG] [Camera Thread] - Current Position: 1892 [02/04/18 20:13:15.337][DEBUG] [Camera Thread] - Initial Move Position: 1922 [02/04/18 20:13:15.348][DEBUG] [Camera Thread] AF Darks exception: The path is not of a legal form. [02/04/18 20:13:15.349][DEBUG] [Camera Thread] Moving focuser to next position (1922)... [02/04/18 20:13:15.354][DEBUG] [Focuser Move Thread] Focuser moving to 1922 [02/04/18 20:13:15.368][DEBUG] [Focuser Move Thread] Focuser backlash active, modified move to 2122 [02/04/18 20:13:15.400][DEBUG] [Focuser Move Thread] Focuser move call complete [02/04/18 20:13:15.402][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has started... [02/04/18 20:13:17.428][DEBUG] [Focuser Backlash Thread] Backlash thread has detected that movement to modified position (2122) is complete... [02/04/18 20:13:17.428][DEBUG] [Focuser Backlash Thread] Moving focuser to original requested position (1922)... [02/04/18 20:13:17.428][DEBUG] [Focuser Backlash Thread] Focuser backlash compensation needed. Steps: 200 Direction:IN [02/04/18 20:13:17.428][DEBUG] [Focuser Backlash Thread] Focuser moving to 1922 [02/04/18 20:13:17.492][DEBUG] [Focuser Backlash Thread] Focuser move call complete [02/04/18 20:13:19.504][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1922 matches requested position 1922 [02/04/18 20:13:19.504][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [02/04/18 20:13:19.520][DEBUG] [Focuser Backlash Thread] Focuser backlash completed. Focuser is at original request position (1922)... [02/04/18 20:13:19.520][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has ended... [02/04/18 20:13:20.526][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [02/04/18 20:13:20.526][DEBUG] [Camera Thread] Focuser move complete... [02/04/18 20:13:20.527][DEBUG] [Camera Thread] Focuser position matches requested position (1922), continuing... [02/04/18 20:13:20.558][DEBUG] [Camera Thread] Auto focus settling before next frame... [02/04/18 20:13:20.565][DEBUG] [Camera Thread] Calculating step metric... [02/04/18 20:13:20.569][DEBUG] [Camera Thread] Taking auto focus frame(s)... [02/04/18 20:13:20.571][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [02/04/18 20:13:20.572][DEBUG] [Camera Thread] ASCOM: AF Binning: 1x1... [02/04/18 20:13:20.572][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 1 seconds... [02/04/18 20:13:20.572][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: b [02/04/18 20:13:20.579][DEBUG] [Camera Thread] SetAscomNormalSpeed... [02/04/18 20:13:20.579][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [02/04/18 20:13:24.843][DEBUG] [Camera Thread] SaveFileAscom: Start... [02/04/18 20:13:24.843][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [02/04/18 20:13:24.843][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [02/04/18 20:13:24.860][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [02/04/18 20:13:24.861][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [02/04/18 20:13:25.122][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [02/04/18 20:13:25.122][DEBUG] [Camera Thread] SaveFileAscom: Done [02/04/18 20:13:25.123][DEBUG] [Camera Thread] Internal Interface: Set Preview... [02/04/18 20:13:25.124][DEBUG] [Camera Thread] Display image preview using asynch task... [02/04/18 20:13:25.704][DEBUG] [Main Thread] --> Find stars (normal) [02/04/18 20:13:25.918][DEBUG] [Main Thread] AF frame was too large... downsample = 0.5... [02/04/18 20:13:29.895][DEBUG] [Main Thread] Star detection using min star size of 3px... [02/04/18 20:13:29.895][DEBUG] [Main Thread] Star detection using max star size of 60px... [02/04/18 20:13:30.011][DEBUG] [Main Thread] Find stars took: 4306 ms... [02/04/18 20:13:30.012][DEBUG] [Main Thread] Star list contains 5 stars... [02/04/18 20:13:30.012][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [02/04/18 20:13:30.672][DEBUG] [Camera Thread] Auto focus HFR calculated at: 8.50 [02/04/18 20:13:31.693][DEBUG] [Camera Thread] Moving focuser to next position (1912)... [02/04/18 20:13:31.694][DEBUG] [Focuser Move Thread] Focuser moving to 1912 [02/04/18 20:13:31.739][DEBUG] [Focuser Move Thread] Focuser move call complete [02/04/18 20:13:32.761][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1912 matches requested position 1912 [02/04/18 20:13:32.761][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [02/04/18 20:13:32.777][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [02/04/18 20:13:32.777][DEBUG] [Camera Thread] Focuser move complete... [02/04/18 20:13:32.793][DEBUG] [Camera Thread] Focuser position matches requested position (1912), continuing... [02/04/18 20:13:32.809][DEBUG] [Camera Thread] Auto focus settling before next frame... [02/04/18 20:13:32.811][DEBUG] [Camera Thread] Calculating step metric... [02/04/18 20:13:32.811][DEBUG] [Camera Thread] Taking auto focus frame(s)... [02/04/18 20:13:32.812][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [02/04/18 20:13:32.812][DEBUG] [Camera Thread] ASCOM: AF Binning: 1x1... [02/04/18 20:13:32.812][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 1 seconds... [02/04/18 20:13:32.812][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: b [02/04/18 20:13:32.814][DEBUG] [Camera Thread] SetAscomNormalSpeed... [02/04/18 20:13:32.814][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [02/04/18 20:13:37.108][DEBUG] [Camera Thread] SaveFileAscom: Start... [02/04/18 20:13:37.108][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [02/04/18 20:13:37.108][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [02/04/18 20:13:37.127][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [02/04/18 20:13:37.127][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [02/04/18 20:13:37.388][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [02/04/18 20:13:37.388][DEBUG] [Camera Thread] SaveFileAscom: Done [02/04/18 20:13:37.388][DEBUG] [Camera Thread] Internal Interface: Set Preview... [02/04/18 20:13:37.388][DEBUG] [Camera Thread] Display image preview using asynch task... [02/04/18 20:13:38.424][DEBUG] [Main Thread] --> Find stars (normal) [02/04/18 20:13:38.626][DEBUG] [Main Thread] AF frame was too large... downsample = 0.5... [02/04/18 20:13:42.440][DEBUG] [Main Thread] Star detection using min star size of 3px... [02/04/18 20:13:42.441][DEBUG] [Main Thread] Star detection using max star size of 60px... [02/04/18 20:13:42.528][DEBUG] [Main Thread] Find stars took: 4103 ms... [02/04/18 20:13:42.528][DEBUG] [Main Thread] Star list contains 3 stars... [02/04/18 20:13:42.528][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [02/04/18 20:13:43.246][DEBUG] [Camera Thread] Auto focus HFR calculated at: 9.55 [02/04/18 20:13:44.252][DEBUG] [Camera Thread] Moving focuser to next position (1902)... [02/04/18 20:13:44.253][DEBUG] [Focuser Move Thread] Focuser moving to 1902 [02/04/18 20:13:44.309][DEBUG] [Focuser Move Thread] Focuser move call complete [02/04/18 20:13:45.333][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1902 matches requested position 1902 [02/04/18 20:13:45.333][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [02/04/18 20:13:45.347][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [02/04/18 20:13:45.347][DEBUG] [Camera Thread] Focuser move complete... [02/04/18 20:13:45.363][DEBUG] [Camera Thread] Focuser position matches requested position (1902), continuing... [02/04/18 20:13:45.380][DEBUG] [Camera Thread] Auto focus settling before next frame... [02/04/18 20:13:45.381][DEBUG] [Camera Thread] Calculating step metric... [02/04/18 20:13:45.381][DEBUG] [Camera Thread] Taking auto focus frame(s)... [02/04/18 20:13:45.383][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [02/04/18 20:13:45.383][DEBUG] [Camera Thread] ASCOM: AF Binning: 1x1... [02/04/18 20:13:45.383][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 1 seconds... [02/04/18 20:13:45.383][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: b [02/04/18 20:13:45.385][DEBUG] [Camera Thread] SetAscomNormalSpeed... [02/04/18 20:13:45.385][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [02/04/18 20:13:49.691][DEBUG] [Camera Thread] SaveFileAscom: Start... [02/04/18 20:13:49.691][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [02/04/18 20:13:49.691][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [02/04/18 20:13:49.708][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [02/04/18 20:13:49.708][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [02/04/18 20:13:49.975][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [02/04/18 20:13:49.975][DEBUG] [Camera Thread] SaveFileAscom: Done [02/04/18 20:13:49.975][DEBUG] [Camera Thread] Internal Interface: Set Preview... [02/04/18 20:13:49.975][DEBUG] [Camera Thread] Display image preview using asynch task... [02/04/18 20:13:50.934][DEBUG] [Main Thread] --> Find stars (normal) [02/04/18 20:13:51.231][DEBUG] [Main Thread] AF frame was too large... downsample = 0.5... [02/04/18 20:13:55.043][DEBUG] [Main Thread] Star detection using min star size of 3px... [02/04/18 20:13:55.043][DEBUG] [Main Thread] Star detection using max star size of 60px... [02/04/18 20:13:55.168][DEBUG] [Main Thread] Find stars took: 4234 ms... [02/04/18 20:13:55.169][DEBUG] [Main Thread] Star list contains 2 stars... [02/04/18 20:13:55.169][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [02/04/18 20:13:56.370][DEBUG] [Camera Thread] Auto focus HFR calculated at: 13.11 [02/04/18 20:13:57.395][DEBUG] [Camera Thread] Moving focuser to next position (1892)... [02/04/18 20:13:57.396][DEBUG] [Focuser Move Thread] Focuser moving to 1892 [02/04/18 20:13:57.469][DEBUG] [Focuser Move Thread] Focuser move call complete [02/04/18 20:13:58.496][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1892 matches requested position 1892 [02/04/18 20:13:58.496][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [02/04/18 20:13:58.496][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [02/04/18 20:13:58.496][DEBUG] [Camera Thread] Focuser move complete... [02/04/18 20:13:58.508][DEBUG] [Camera Thread] Focuser position matches requested position (1892), continuing... [02/04/18 20:13:58.524][DEBUG] [Camera Thread] Auto focus settling before next frame... [02/04/18 20:13:58.526][DEBUG] [Camera Thread] Calculating step metric... [02/04/18 20:13:58.526][DEBUG] [Camera Thread] Taking auto focus frame(s)... [02/04/18 20:13:58.527][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [02/04/18 20:13:58.527][DEBUG] [Camera Thread] ASCOM: AF Binning: 1x1... [02/04/18 20:13:58.528][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 1 seconds... [02/04/18 20:13:58.528][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: b [02/04/18 20:13:58.529][DEBUG] [Camera Thread] SetAscomNormalSpeed... [02/04/18 20:13:58.530][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [02/04/18 20:14:02.844][DEBUG] [Camera Thread] SaveFileAscom: Start... [02/04/18 20:14:02.845][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [02/04/18 20:14:02.845][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [02/04/18 20:14:02.859][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [02/04/18 20:14:02.859][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [02/04/18 20:14:03.229][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [02/04/18 20:14:03.229][DEBUG] [Camera Thread] SaveFileAscom: Done [02/04/18 20:14:03.229][DEBUG] [Camera Thread] Internal Interface: Set Preview... [02/04/18 20:14:03.229][DEBUG] [Camera Thread] Display image preview using asynch task... [02/04/18 20:14:04.206][DEBUG] [Main Thread] --> Find stars (normal) [02/04/18 20:14:04.454][DEBUG] [Main Thread] AF frame was too large... downsample = 0.5... [02/04/18 20:14:08.306][DEBUG] [Main Thread] Star detection using min star size of 3px... [02/04/18 20:14:08.306][DEBUG] [Main Thread] Star detection using max star size of 60px... [02/04/18 20:14:08.398][DEBUG] [Main Thread] Find stars took: 4192 ms... [02/04/18 20:14:08.398][DEBUG] [Main Thread] Star list contains 2 stars... [02/04/18 20:14:08.398][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [02/04/18 20:14:09.588][DEBUG] [Camera Thread] Auto focus HFR calculated at: 13.52 [02/04/18 20:14:10.638][DEBUG] [Camera Thread] Detected focus getting worse! Shifting auto focus range [02/04/18 20:14:10.647][DEBUG] [Focuser Move Thread] Focuser moving to 1937 [02/04/18 20:14:10.719][DEBUG] [Focuser Move Thread] Focuser backlash active, modified move to 2137 [02/04/18 20:14:10.741][DEBUG] [Focuser Move Thread] Focuser move call complete [02/04/18 20:14:10.742][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has started... [02/04/18 20:14:13.743][DEBUG] [Focuser Backlash Thread] Backlash thread has detected that movement to modified position (2137) is complete... [02/04/18 20:14:13.743][DEBUG] [Focuser Backlash Thread] Moving focuser to original requested position (1937)... [02/04/18 20:14:13.743][DEBUG] [Focuser Backlash Thread] Focuser backlash compensation needed. Steps: 200 Direction:IN [02/04/18 20:14:13.743][DEBUG] [Focuser Backlash Thread] Focuser moving to 1937 [02/04/18 20:14:13.792][DEBUG] [Focuser Backlash Thread] Focuser move call complete [02/04/18 20:14:15.821][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1937 matches requested position 1937 [02/04/18 20:14:15.821][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [02/04/18 20:14:16.811][DEBUG] [Focuser Backlash Thread] Focuser backlash completed. Focuser is at original request position (1937)... [02/04/18 20:14:16.811][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has ended... [02/04/18 20:14:16.821][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [02/04/18 20:14:16.821][DEBUG] [Camera Thread] Focuser move complete... [02/04/18 20:14:16.843][DEBUG] [Camera Thread] Auto focus data [02/04/18 20:14:16.843][DEBUG] [Camera Thread] - Data Points: 7 [02/04/18 20:14:16.843][DEBUG] [Camera Thread] - Step Size: 10 [02/04/18 20:14:16.843][DEBUG] [Camera Thread] - Current Position: 1937 [02/04/18 20:14:16.843][DEBUG] [Camera Thread] - Initial Move Position: 1967 [02/04/18 20:14:16.853][DEBUG] [Camera Thread] AF Darks exception: The path is not of a legal form. [02/04/18 20:14:16.854][DEBUG] [Camera Thread] Moving focuser to next position (1967)... [02/04/18 20:14:16.855][DEBUG] [Focuser Move Thread] Focuser moving to 1967 [02/04/18 20:14:16.875][DEBUG] [Focuser Move Thread] Focuser backlash active, modified move to 2167 [02/04/18 20:14:16.907][DEBUG] [Focuser Move Thread] Focuser move call complete [02/04/18 20:14:16.907][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has started... [02/04/18 20:14:19.907][DEBUG] [Focuser Backlash Thread] Backlash thread has detected that movement to modified position (2167) is complete... [02/04/18 20:14:19.907][DEBUG] [Focuser Backlash Thread] Moving focuser to original requested position (1967)... [02/04/18 20:14:19.907][DEBUG] [Focuser Backlash Thread] Focuser backlash compensation needed. Steps: 200 Direction:IN [02/04/18 20:14:19.907][DEBUG] [Focuser Backlash Thread] Focuser moving to 1967 [02/04/18 20:14:19.957][DEBUG] [Focuser Backlash Thread] Focuser move call complete [02/04/18 20:14:21.985][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1967 matches requested position 1967 [02/04/18 20:14:21.985][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [02/04/18 20:14:22.975][DEBUG] [Focuser Backlash Thread] Focuser backlash completed. Focuser is at original request position (1967)... [02/04/18 20:14:22.975][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has ended... [02/04/18 20:14:22.985][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [02/04/18 20:14:22.985][DEBUG] [Camera Thread] Focuser move complete... [02/04/18 20:14:23.007][DEBUG] [Camera Thread] Focuser position matches requested position (1967), continuing... [02/04/18 20:14:23.023][DEBUG] [Camera Thread] Auto focus settling before next frame... [02/04/18 20:14:23.024][DEBUG] [Camera Thread] Calculating step metric... [02/04/18 20:14:23.024][DEBUG] [Camera Thread] Taking auto focus frame(s)... [02/04/18 20:14:23.026][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [02/04/18 20:14:23.026][DEBUG] [Camera Thread] ASCOM: AF Binning: 1x1... [02/04/18 20:14:23.026][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 1 seconds... [02/04/18 20:14:23.026][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: b [02/04/18 20:14:23.028][DEBUG] [Camera Thread] SetAscomNormalSpeed... [02/04/18 20:14:23.028][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [02/04/18 20:14:27.812][DEBUG] [Camera Thread] SaveFileAscom: Start... [02/04/18 20:14:27.812][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [02/04/18 20:14:27.812][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [02/04/18 20:14:27.832][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [02/04/18 20:14:27.832][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [02/04/18 20:14:28.275][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [02/04/18 20:14:28.275][DEBUG] [Camera Thread] SaveFileAscom: Done [02/04/18 20:14:28.275][DEBUG] [Camera Thread] Internal Interface: Set Preview... [02/04/18 20:14:28.275][DEBUG] [Camera Thread] Display image preview using asynch task... [02/04/18 20:14:29.245][DEBUG] [Main Thread] --> Find stars (normal) [02/04/18 20:14:29.441][DEBUG] [Main Thread] AF frame was too large... downsample = 0.5... [02/04/18 20:14:33.187][DEBUG] [Main Thread] Star detection using min star size of 3px... [02/04/18 20:14:33.187][DEBUG] [Main Thread] Star detection using max star size of 60px... [02/04/18 20:14:33.272][DEBUG] [Main Thread] Find stars took: 4026 ms... [02/04/18 20:14:33.273][DEBUG] [Main Thread] Star list contains 3 stars... [02/04/18 20:14:33.273][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [02/04/18 20:14:34.514][DEBUG] [Camera Thread] Auto focus HFR calculated at: 11.58 [02/04/18 20:14:35.520][DEBUG] [Camera Thread] Moving focuser to next position (1957)... [02/04/18 20:14:35.520][DEBUG] [Focuser Move Thread] Focuser moving to 1957 [02/04/18 20:14:35.577][DEBUG] [Focuser Move Thread] Focuser move call complete [02/04/18 20:14:36.599][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1957 matches requested position 1957 [02/04/18 20:14:36.599][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [02/04/18 20:14:36.615][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [02/04/18 20:14:36.615][DEBUG] [Camera Thread] Focuser move complete... [02/04/18 20:14:36.631][DEBUG] [Camera Thread] Focuser position matches requested position (1957), continuing... [02/04/18 20:14:36.648][DEBUG] [Camera Thread] Auto focus settling before next frame... [02/04/18 20:14:36.649][DEBUG] [Camera Thread] Calculating step metric... [02/04/18 20:14:36.649][DEBUG] [Camera Thread] Taking auto focus frame(s)... [02/04/18 20:14:36.650][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [02/04/18 20:14:36.650][DEBUG] [Camera Thread] ASCOM: AF Binning: 1x1... [02/04/18 20:14:36.650][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 1 seconds... [02/04/18 20:14:36.650][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: b [02/04/18 20:14:36.652][DEBUG] [Camera Thread] SetAscomNormalSpeed... [02/04/18 20:14:36.652][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [02/04/18 20:14:40.969][DEBUG] [Camera Thread] SaveFileAscom: Start... [02/04/18 20:14:40.969][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [02/04/18 20:14:40.969][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [02/04/18 20:14:40.988][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [02/04/18 20:14:40.988][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [02/04/18 20:14:41.362][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [02/04/18 20:14:41.362][DEBUG] [Camera Thread] SaveFileAscom: Done [02/04/18 20:14:41.362][DEBUG] [Camera Thread] Internal Interface: Set Preview... [02/04/18 20:14:41.362][DEBUG] [Camera Thread] Display image preview using asynch task... [02/04/18 20:14:42.260][DEBUG] [Main Thread] --> Find stars (normal) [02/04/18 20:14:42.415][DEBUG] [Main Thread] AF frame was too large... downsample = 0.5... [02/04/18 20:14:45.844][DEBUG] [Main Thread] Star detection using min star size of 3px... [02/04/18 20:14:45.844][DEBUG] [Main Thread] Star detection using max star size of 60px... [02/04/18 20:14:45.998][DEBUG] [Main Thread] Find stars took: 3738 ms... [02/04/18 20:14:45.998][DEBUG] [Main Thread] Star list contains 2 stars... [02/04/18 20:14:45.998][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [02/04/18 20:14:47.066][DEBUG] [Camera Thread] Auto focus HFR calculated at: 15.60 [02/04/18 20:14:48.071][DEBUG] [Camera Thread] Moving focuser to next position (1947)... [02/04/18 20:14:48.072][DEBUG] [Focuser Move Thread] Focuser moving to 1947 [02/04/18 20:14:48.115][DEBUG] [Focuser Move Thread] Focuser move call complete [02/04/18 20:14:49.137][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1947 matches requested position 1947 [02/04/18 20:14:49.137][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [02/04/18 20:14:50.137][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [02/04/18 20:14:50.137][DEBUG] [Camera Thread] Focuser move complete... [02/04/18 20:14:50.159][DEBUG] [Camera Thread] Focuser position matches requested position (1947), continuing... [02/04/18 20:14:50.175][DEBUG] [Camera Thread] Auto focus settling before next frame... [02/04/18 20:14:50.177][DEBUG] [Camera Thread] Calculating step metric... [02/04/18 20:14:50.177][DEBUG] [Camera Thread] Taking auto focus frame(s)... [02/04/18 20:14:50.178][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [02/04/18 20:14:50.178][DEBUG] [Camera Thread] ASCOM: AF Binning: 1x1... [02/04/18 20:14:50.178][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 1 seconds... [02/04/18 20:14:50.178][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: b [02/04/18 20:14:50.180][DEBUG] [Camera Thread] SetAscomNormalSpeed... [02/04/18 20:14:50.180][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [02/04/18 20:14:54.459][DEBUG] [Camera Thread] SaveFileAscom: Start... [02/04/18 20:14:54.459][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [02/04/18 20:14:54.459][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [02/04/18 20:14:54.481][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [02/04/18 20:14:54.482][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [02/04/18 20:14:54.870][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [02/04/18 20:14:54.871][DEBUG] [Camera Thread] SaveFileAscom: Done [02/04/18 20:14:54.871][DEBUG] [Camera Thread] Internal Interface: Set Preview... [02/04/18 20:14:54.871][DEBUG] [Camera Thread] Display image preview using asynch task... [02/04/18 20:14:55.841][DEBUG] [Main Thread] --> Find stars (normal) [02/04/18 20:14:56.044][DEBUG] [Main Thread] AF frame was too large... downsample = 0.5... [02/04/18 20:14:59.891][DEBUG] [Main Thread] Star detection using min star size of 3px... [02/04/18 20:14:59.891][DEBUG] [Main Thread] Star detection using max star size of 60px... [02/04/18 20:15:00.008][DEBUG] [Main Thread] Find stars took: 4166 ms... [02/04/18 20:15:00.008][DEBUG] [Main Thread] Star list contains 5 stars... [02/04/18 20:15:00.008][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [02/04/18 20:15:01.214][DEBUG] [Camera Thread] Auto focus HFR calculated at: 9.83 [02/04/18 20:15:02.220][DEBUG] [Camera Thread] Moving focuser to next position (1937)... [02/04/18 20:15:02.220][DEBUG] [Focuser Move Thread] Focuser moving to 1937 [02/04/18 20:15:02.266][DEBUG] [Focuser Move Thread] Focuser move call complete [02/04/18 20:15:03.288][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1937 matches requested position 1937 [02/04/18 20:15:03.288][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [02/04/18 20:15:04.288][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [02/04/18 20:15:04.288][DEBUG] [Camera Thread] Focuser move complete... [02/04/18 20:15:04.310][DEBUG] [Camera Thread] Focuser position matches requested position (1937), continuing... [02/04/18 20:15:04.326][DEBUG] [Camera Thread] Auto focus settling before next frame... [02/04/18 20:15:04.328][DEBUG] [Camera Thread] Calculating step metric... [02/04/18 20:15:04.328][DEBUG] [Camera Thread] Taking auto focus frame(s)... [02/04/18 20:15:04.330][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [02/04/18 20:15:04.330][DEBUG] [Camera Thread] ASCOM: AF Binning: 1x1... [02/04/18 20:15:04.330][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 1 seconds... [02/04/18 20:15:04.330][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: b [02/04/18 20:15:04.331][DEBUG] [Camera Thread] SetAscomNormalSpeed... [02/04/18 20:15:04.331][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [02/04/18 20:15:08.642][DEBUG] [Camera Thread] SaveFileAscom: Start... [02/04/18 20:15:08.642][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [02/04/18 20:15:08.642][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [02/04/18 20:15:08.657][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [02/04/18 20:15:08.657][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [02/04/18 20:15:09.034][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [02/04/18 20:15:09.034][DEBUG] [Camera Thread] SaveFileAscom: Done [02/04/18 20:15:09.034][DEBUG] [Camera Thread] Internal Interface: Set Preview... [02/04/18 20:15:09.034][DEBUG] [Camera Thread] Display image preview using asynch task... [02/04/18 20:15:09.989][DEBUG] [Main Thread] --> Find stars (normal) [02/04/18 20:15:10.174][DEBUG] [Main Thread] AF frame was too large... downsample = 0.5... [02/04/18 20:15:13.958][DEBUG] [Main Thread] Star detection using min star size of 3px... [02/04/18 20:15:13.958][DEBUG] [Main Thread] Star detection using max star size of 60px... [02/04/18 20:15:14.150][DEBUG] [Main Thread] Find stars took: 4161 ms... [02/04/18 20:15:14.150][DEBUG] [Main Thread] Star list contains 2 stars... [02/04/18 20:15:14.150][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [02/04/18 20:15:15.360][DEBUG] [Camera Thread] Auto focus HFR calculated at: 14.67 [02/04/18 20:15:16.371][DEBUG] [Camera Thread] Moving focuser to next position (1927)... [02/04/18 20:15:16.372][DEBUG] [Focuser Move Thread] Focuser moving to 1927 [02/04/18 20:15:16.435][DEBUG] [Focuser Move Thread] Focuser move call complete [02/04/18 20:15:17.455][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1927 matches requested position 1927 [02/04/18 20:15:17.480][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [02/04/18 20:15:17.480][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [02/04/18 20:15:17.480][DEBUG] [Camera Thread] Focuser move complete... [02/04/18 20:15:17.502][DEBUG] [Camera Thread] Focuser position matches requested position (1927), continuing... [02/04/18 20:15:17.535][DEBUG] [Camera Thread] Auto focus settling before next frame... [02/04/18 20:15:17.537][DEBUG] [Camera Thread] Calculating step metric... [02/04/18 20:15:17.537][DEBUG] [Camera Thread] Taking auto focus frame(s)... [02/04/18 20:15:17.538][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [02/04/18 20:15:17.538][DEBUG] [Camera Thread] ASCOM: AF Binning: 1x1... [02/04/18 20:15:17.538][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 1 seconds... [02/04/18 20:15:17.538][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: b [02/04/18 20:15:17.540][DEBUG] [Camera Thread] SetAscomNormalSpeed... [02/04/18 20:15:17.540][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [02/04/18 20:15:21.834][DEBUG] [Camera Thread] SaveFileAscom: Start... [02/04/18 20:15:21.834][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [02/04/18 20:15:21.834][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [02/04/18 20:15:21.851][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [02/04/18 20:15:21.851][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [02/04/18 20:15:22.239][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [02/04/18 20:15:22.240][DEBUG] [Camera Thread] SaveFileAscom: Done [02/04/18 20:15:22.240][DEBUG] [Camera Thread] Internal Interface: Set Preview... [02/04/18 20:15:22.240][DEBUG] [Camera Thread] Display image preview using asynch task... [02/04/18 20:15:23.304][DEBUG] [Main Thread] --> Find stars (normal) [02/04/18 20:15:23.491][DEBUG] [Main Thread] AF frame was too large... downsample = 0.5... [02/04/18 20:15:27.271][DEBUG] [Main Thread] Star detection using min star size of 3px... [02/04/18 20:15:27.271][DEBUG] [Main Thread] Star detection using max star size of 60px... [02/04/18 20:15:27.356][DEBUG] [Main Thread] Find stars took: 4051 ms... [02/04/18 20:15:27.356][DEBUG] [Main Thread] Star list contains 4 stars... [02/04/18 20:15:27.356][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [02/04/18 20:15:28.571][DEBUG] [Camera Thread] Auto focus HFR calculated at: 12.26 [02/04/18 20:15:29.578][DEBUG] [Camera Thread] Moving focuser to next position (1917)... [02/04/18 20:15:29.579][DEBUG] [Focuser Move Thread] Focuser moving to 1917 [02/04/18 20:15:29.641][DEBUG] [Focuser Move Thread] Focuser move call complete [02/04/18 20:15:30.663][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1917 matches requested position 1917 [02/04/18 20:15:30.663][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [02/04/18 20:15:30.679][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [02/04/18 20:15:30.680][DEBUG] [Camera Thread] Focuser move complete... [02/04/18 20:15:30.711][DEBUG] [Camera Thread] Focuser position matches requested position (1917), continuing... [02/04/18 20:15:30.727][DEBUG] [Camera Thread] Auto focus settling before next frame... [02/04/18 20:15:30.729][DEBUG] [Camera Thread] Calculating step metric... [02/04/18 20:15:30.729][DEBUG] [Camera Thread] Taking auto focus frame(s)... [02/04/18 20:15:30.731][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [02/04/18 20:15:30.731][DEBUG] [Camera Thread] ASCOM: AF Binning: 1x1... [02/04/18 20:15:30.731][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 1 seconds... [02/04/18 20:15:30.731][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: b [02/04/18 20:15:30.732][DEBUG] [Camera Thread] SetAscomNormalSpeed... [02/04/18 20:15:30.732][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [02/04/18 20:15:35.076][DEBUG] [Camera Thread] SaveFileAscom: Start... [02/04/18 20:15:35.076][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [02/04/18 20:15:35.076][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [02/04/18 20:15:35.093][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [02/04/18 20:15:35.094][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [02/04/18 20:15:35.476][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [02/04/18 20:15:35.476][DEBUG] [Camera Thread] SaveFileAscom: Done [02/04/18 20:15:35.476][DEBUG] [Camera Thread] Internal Interface: Set Preview... [02/04/18 20:15:35.476][DEBUG] [Camera Thread] Display image preview using asynch task... [02/04/18 20:15:36.473][DEBUG] [Main Thread] --> Find stars (normal) [02/04/18 20:15:36.666][DEBUG] [Main Thread] AF frame was too large... downsample = 0.5... [02/04/18 20:15:40.379][DEBUG] [Main Thread] Star detection using min star size of 3px... [02/04/18 20:15:40.380][DEBUG] [Main Thread] Star detection using max star size of 60px... [02/04/18 20:15:40.619][DEBUG] [Main Thread] Find stars took: 4145 ms... [02/04/18 20:15:40.619][DEBUG] [Main Thread] Star list contains 2 stars... [02/04/18 20:15:40.619][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [02/04/18 20:15:41.824][DEBUG] [Camera Thread] Auto focus HFR calculated at: 14.73 [02/04/18 20:15:42.830][DEBUG] [Camera Thread] Detected focus getting worse! Shifting auto focus range [02/04/18 20:15:42.836][DEBUG] [Focuser Move Thread] Focuser moving to 1982 [02/04/18 20:15:42.866][DEBUG] [Focuser Move Thread] Focuser backlash active, modified move to 2182 [02/04/18 20:15:42.921][DEBUG] [Focuser Move Thread] Focuser move call complete [02/04/18 20:15:42.922][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has started... [02/04/18 20:15:45.980][DEBUG] [Focuser Backlash Thread] Backlash thread has detected that movement to modified position (2182) is complete... [02/04/18 20:15:45.980][DEBUG] [Focuser Backlash Thread] Moving focuser to original requested position (1982)... [02/04/18 20:15:45.980][DEBUG] [Focuser Backlash Thread] Focuser backlash compensation needed. Steps: 200 Direction:IN [02/04/18 20:15:45.980][DEBUG] [Focuser Backlash Thread] Focuser moving to 1982 [02/04/18 20:15:46.044][DEBUG] [Focuser Backlash Thread] Focuser move call complete [02/04/18 20:15:48.040][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1982 matches requested position 1982 [02/04/18 20:15:48.040][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [02/04/18 20:15:48.072][DEBUG] [Focuser Backlash Thread] Focuser backlash completed. Focuser is at original request position (1982)... [02/04/18 20:15:48.072][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has ended... [02/04/18 20:15:49.062][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [02/04/18 20:15:49.062][DEBUG] [Camera Thread] Focuser move complete... [02/04/18 20:15:49.095][DEBUG] [Camera Thread] Auto focus data [02/04/18 20:15:49.095][DEBUG] [Camera Thread] - Data Points: 7 [02/04/18 20:15:49.095][DEBUG] [Camera Thread] - Step Size: 10 [02/04/18 20:15:49.095][DEBUG] [Camera Thread] - Current Position: 1982 [02/04/18 20:15:49.095][DEBUG] [Camera Thread] - Initial Move Position: 2012 [02/04/18 20:15:49.101][DEBUG] [Camera Thread] AF Darks exception: The path is not of a legal form. [02/04/18 20:15:49.102][DEBUG] [Camera Thread] Moving focuser to next position (2012)... [02/04/18 20:15:49.103][DEBUG] [Focuser Move Thread] Focuser moving to 2012 [02/04/18 20:15:49.126][DEBUG] [Focuser Move Thread] Focuser backlash active, modified move to 2212 [02/04/18 20:15:49.158][DEBUG] [Focuser Move Thread] Focuser move call complete [02/04/18 20:15:49.159][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has started... [02/04/18 20:15:51.218][DEBUG] [Focuser Backlash Thread] Backlash thread has detected that movement to modified position (2212) is complete... [02/04/18 20:15:51.218][DEBUG] [Focuser Backlash Thread] Moving focuser to original requested position (2012)... [02/04/18 20:15:51.218][DEBUG] [Focuser Backlash Thread] Focuser backlash compensation needed. Steps: 200 Direction:IN [02/04/18 20:15:51.218][DEBUG] [Focuser Backlash Thread] Focuser moving to 2012 [02/04/18 20:15:51.267][DEBUG] [Focuser Backlash Thread] Focuser move call complete [02/04/18 20:15:53.263][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 2012 matches requested position 2012 [02/04/18 20:15:53.263][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [02/04/18 20:15:53.299][DEBUG] [Focuser Backlash Thread] Focuser backlash completed. Focuser is at original request position (2012)... [02/04/18 20:15:53.299][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has ended... [02/04/18 20:15:54.284][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [02/04/18 20:15:54.284][DEBUG] [Camera Thread] Focuser move complete... [02/04/18 20:15:54.316][DEBUG] [Camera Thread] Focuser position matches requested position (2012), continuing... [02/04/18 20:15:54.333][DEBUG] [Camera Thread] Auto focus settling before next frame... [02/04/18 20:15:54.335][DEBUG] [Camera Thread] Calculating step metric... [02/04/18 20:15:54.335][DEBUG] [Camera Thread] Taking auto focus frame(s)... [02/04/18 20:15:54.337][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [02/04/18 20:15:54.337][DEBUG] [Camera Thread] ASCOM: AF Binning: 1x1... [02/04/18 20:15:54.337][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 1 seconds... [02/04/18 20:15:54.337][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: b [02/04/18 20:15:54.339][DEBUG] [Camera Thread] SetAscomNormalSpeed... [02/04/18 20:15:54.339][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [02/04/18 20:15:58.590][DEBUG] [Camera Thread] SaveFileAscom: Start... [02/04/18 20:15:58.590][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [02/04/18 20:15:58.590][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [02/04/18 20:15:58.609][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [02/04/18 20:15:58.609][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [02/04/18 20:15:58.885][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [02/04/18 20:15:58.885][DEBUG] [Camera Thread] SaveFileAscom: Done [02/04/18 20:15:58.885][DEBUG] [Camera Thread] Internal Interface: Set Preview... [02/04/18 20:15:58.885][DEBUG] [Camera Thread] Display image preview using asynch task... [02/04/18 20:15:59.871][DEBUG] [Main Thread] --> Find stars (normal) [02/04/18 20:16:00.074][DEBUG] [Main Thread] AF frame was too large... downsample = 0.5... [02/04/18 20:16:03.919][DEBUG] [Main Thread] Star detection using min star size of 3px... [02/04/18 20:16:03.919][DEBUG] [Main Thread] Star detection using max star size of 60px... [02/04/18 20:16:04.039][DEBUG] [Main Thread] Find stars took: 4167 ms... [02/04/18 20:16:04.039][DEBUG] [Main Thread] Star list contains 3 stars... [02/04/18 20:16:04.039][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [02/04/18 20:16:05.320][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.35 [02/04/18 20:16:06.328][DEBUG] [Camera Thread] Moving focuser to next position (2002)... [02/04/18 20:16:06.329][DEBUG] [Focuser Move Thread] Focuser moving to 2002 [02/04/18 20:16:06.375][DEBUG] [Focuser Move Thread] Focuser move call complete [02/04/18 20:16:07.397][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 2002 matches requested position 2002 [02/04/18 20:16:07.397][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [02/04/18 20:16:07.414][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [02/04/18 20:16:07.414][DEBUG] [Camera Thread] Focuser move complete... [02/04/18 20:16:07.429][DEBUG] [Camera Thread] Focuser position matches requested position (2002), continuing... [02/04/18 20:16:07.445][DEBUG] [Camera Thread] Auto focus settling before next frame... [02/04/18 20:16:07.479][DEBUG] [Camera Thread] Calculating step metric... [02/04/18 20:16:07.479][DEBUG] [Camera Thread] Taking auto focus frame(s)... [02/04/18 20:16:07.481][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [02/04/18 20:16:07.481][DEBUG] [Camera Thread] ASCOM: AF Binning: 1x1... [02/04/18 20:16:07.481][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 1 seconds... [02/04/18 20:16:07.481][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: b [02/04/18 20:16:07.482][DEBUG] [Camera Thread] SetAscomNormalSpeed... [02/04/18 20:16:07.482][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [02/04/18 20:16:11.780][DEBUG] [Camera Thread] SaveFileAscom: Start... [02/04/18 20:16:11.780][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [02/04/18 20:16:11.780][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [02/04/18 20:16:11.796][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [02/04/18 20:16:11.796][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [02/04/18 20:16:12.172][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [02/04/18 20:16:12.173][DEBUG] [Camera Thread] SaveFileAscom: Done [02/04/18 20:16:12.173][DEBUG] [Camera Thread] Internal Interface: Set Preview... [02/04/18 20:16:12.173][DEBUG] [Camera Thread] Display image preview using asynch task... [02/04/18 20:16:13.168][DEBUG] [Main Thread] --> Find stars (normal) [02/04/18 20:16:13.364][DEBUG] [Main Thread] AF frame was too large... downsample = 0.5... [02/04/18 20:16:17.236][DEBUG] [Main Thread] Star detection using min star size of 3px... [02/04/18 20:16:17.236][DEBUG] [Main Thread] Star detection using max star size of 60px... [02/04/18 20:16:17.351][DEBUG] [Main Thread] Find stars took: 4183 ms... [02/04/18 20:16:17.351][DEBUG] [Main Thread] Star list contains 3 stars... [02/04/18 20:16:17.351][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [02/04/18 20:16:18.758][DEBUG] [Camera Thread] Auto focus HFR calculated at: 7.15 [02/04/18 20:16:19.763][DEBUG] [Camera Thread] Moving focuser to next position (1992)... [02/04/18 20:16:19.774][DEBUG] [Focuser Move Thread] Focuser moving to 1992 [02/04/18 20:16:19.855][DEBUG] [Focuser Move Thread] Focuser move call complete [02/04/18 20:16:20.888][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1992 matches requested position 1992 [02/04/18 20:16:20.888][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [02/04/18 20:16:20.888][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [02/04/18 20:16:20.888][DEBUG] [Camera Thread] Focuser move complete... [02/04/18 20:16:20.910][DEBUG] [Camera Thread] Focuser position matches requested position (1992), continuing... [02/04/18 20:16:20.925][DEBUG] [Camera Thread] Auto focus settling before next frame... [02/04/18 20:16:20.927][DEBUG] [Camera Thread] Calculating step metric... [02/04/18 20:16:20.927][DEBUG] [Camera Thread] Taking auto focus frame(s)... [02/04/18 20:16:20.928][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [02/04/18 20:16:20.928][DEBUG] [Camera Thread] ASCOM: AF Binning: 1x1... [02/04/18 20:16:20.928][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 1 seconds... [02/04/18 20:16:20.929][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: b [02/04/18 20:16:20.930][DEBUG] [Camera Thread] SetAscomNormalSpeed... [02/04/18 20:16:20.931][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [02/04/18 20:16:25.219][DEBUG] [Camera Thread] SaveFileAscom: Start... [02/04/18 20:16:25.219][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [02/04/18 20:16:25.219][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [02/04/18 20:16:25.237][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [02/04/18 20:16:25.237][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [02/04/18 20:16:25.631][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [02/04/18 20:16:25.631][DEBUG] [Camera Thread] SaveFileAscom: Done [02/04/18 20:16:25.631][DEBUG] [Camera Thread] Internal Interface: Set Preview... [02/04/18 20:16:25.631][DEBUG] [Camera Thread] Display image preview using asynch task... [02/04/18 20:16:26.637][DEBUG] [Main Thread] --> Find stars (normal) [02/04/18 20:16:26.818][DEBUG] [Main Thread] AF frame was too large... downsample = 0.5... [02/04/18 20:16:30.629][DEBUG] [Main Thread] Star detection using min star size of 3px... [02/04/18 20:16:30.629][DEBUG] [Main Thread] Star detection using max star size of 60px... [02/04/18 20:16:30.712][DEBUG] [Main Thread] Find stars took: 4075 ms... [02/04/18 20:16:30.712][DEBUG] [Main Thread] Star list contains 2 stars... [02/04/18 20:16:30.712][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [02/04/18 20:16:31.956][DEBUG] [Camera Thread] Auto focus HFR calculated at: 14.55 [02/04/18 20:16:32.963][DEBUG] [Camera Thread] Moving focuser to next position (1982)... [02/04/18 20:16:32.964][DEBUG] [Focuser Move Thread] Focuser moving to 1982 [02/04/18 20:16:33.031][DEBUG] [Focuser Move Thread] Focuser move call complete [02/04/18 20:16:34.057][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1982 matches requested position 1982 [02/04/18 20:16:34.057][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [02/04/18 20:16:34.069][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [02/04/18 20:16:34.069][DEBUG] [Camera Thread] Focuser move complete... [02/04/18 20:16:34.085][DEBUG] [Camera Thread] Focuser position matches requested position (1982), continuing... [02/04/18 20:16:34.101][DEBUG] [Camera Thread] Auto focus settling before next frame... [02/04/18 20:16:34.103][DEBUG] [Camera Thread] Calculating step metric... [02/04/18 20:16:34.103][DEBUG] [Camera Thread] Taking auto focus frame(s)... [02/04/18 20:16:34.105][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [02/04/18 20:16:34.105][DEBUG] [Camera Thread] ASCOM: AF Binning: 1x1... [02/04/18 20:16:34.105][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 1 seconds... [02/04/18 20:16:34.105][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: b [02/04/18 20:16:34.106][DEBUG] [Camera Thread] SetAscomNormalSpeed... [02/04/18 20:16:34.106][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [02/04/18 20:16:38.447][DEBUG] [Camera Thread] SaveFileAscom: Start... [02/04/18 20:16:38.447][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [02/04/18 20:16:38.447][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [02/04/18 20:16:38.465][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [02/04/18 20:16:38.465][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [02/04/18 20:16:38.876][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [02/04/18 20:16:38.876][DEBUG] [Camera Thread] SaveFileAscom: Done [02/04/18 20:16:38.876][DEBUG] [Camera Thread] Internal Interface: Set Preview... [02/04/18 20:16:38.876][DEBUG] [Camera Thread] Display image preview using asynch task... [02/04/18 20:16:39.863][DEBUG] [Main Thread] --> Find stars (normal) [02/04/18 20:16:40.062][DEBUG] [Main Thread] AF frame was too large... downsample = 0.5... [02/04/18 20:16:44.268][DEBUG] [Main Thread] Star detection using min star size of 3px... [02/04/18 20:16:44.268][DEBUG] [Main Thread] Star detection using max star size of 60px... [02/04/18 20:16:44.522][DEBUG] [Main Thread] Find stars took: 4659 ms... [02/04/18 20:16:44.522][DEBUG] [Main Thread] Star list contains 3 stars... [02/04/18 20:16:44.522][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [02/04/18 20:16:45.903][DEBUG] [Camera Thread] Auto focus HFR calculated at: 5.45 [02/04/18 20:16:46.908][DEBUG] [Camera Thread] Detected focus getting worse! Shifting auto focus range [02/04/18 20:16:46.908][DEBUG] [Focuser Move Thread] Focuser moving to 2027 [02/04/18 20:16:46.927][DEBUG] [Focuser Move Thread] Focuser backlash active, modified move to 2227 [02/04/18 20:16:46.960][DEBUG] [Focuser Move Thread] Focuser move call complete [02/04/18 20:16:46.960][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has started... [02/04/18 20:16:50.025][DEBUG] [Focuser Backlash Thread] Backlash thread has detected that movement to modified position (2227) is complete... [02/04/18 20:16:50.025][DEBUG] [Focuser Backlash Thread] Moving focuser to original requested position (2027)... [02/04/18 20:16:50.025][DEBUG] [Focuser Backlash Thread] Focuser backlash compensation needed. Steps: 200 Direction:IN [02/04/18 20:16:50.025][DEBUG] [Focuser Backlash Thread] Focuser moving to 2027 [02/04/18 20:16:50.089][DEBUG] [Focuser Backlash Thread] Focuser move call complete [02/04/18 20:16:52.085][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 2027 matches requested position 2027 [02/04/18 20:16:52.085][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [02/04/18 20:16:52.101][DEBUG] [Focuser Backlash Thread] Focuser backlash completed. Focuser is at original request position (2027)... [02/04/18 20:16:52.102][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has ended... [02/04/18 20:16:53.108][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [02/04/18 20:16:53.108][DEBUG] [Camera Thread] Focuser move complete... [02/04/18 20:16:53.108][DEBUG] [Camera Thread] Killing smart focus... we are lost (too many range shifts). Restoring focuser to best guess position [02/04/18 20:16:53.111][DEBUG] [Focuser Move Thread] Focuser moving to 2012 [02/04/18 20:16:53.156][DEBUG] [Focuser Move Thread] Focuser move call complete [02/04/18 20:16:54.178][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 2012 matches requested position 2012 [02/04/18 20:16:54.178][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [02/04/18 20:16:54.178][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [02/04/18 20:16:54.178][DEBUG] [Camera Thread] Focuser move complete... [02/04/18 20:16:54.194][DEBUG] [Camera Thread] Focuser successfully set to focus position (2012) [02/04/18 20:16:54.205][DEBUG] [AfChartThread] AF chart dialog is closed... [02/04/18 20:16:54.206][DEBUG] [Camera Thread] Auto focus complete... [02/04/18 20:16:54.221][DEBUG] [Camera Thread] SGM_FOCUSER_AUTO_FOCUS complete... [02/04/18 20:16:54.906][DEBUG] [TEC Thread] TEC Change: Complete... [02/04/18 20:16:54.907][DEBUG] [TEC Thread] SGM_CHANGE_COOLER_TEMP complete... [02/04/18 20:17:17.582][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS message received... [02/04/18 20:17:17.600][DEBUG] [Camera Thread] ASCOM camera: frame and focus... [02/04/18 20:17:17.601][DEBUG] [Camera Thread] SetAscomNormalSpeed... [02/04/18 20:17:17.601][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [02/04/18 20:17:21.564][DEBUG] [Camera Thread] SaveFileAscom: Start... [02/04/18 20:17:21.564][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [02/04/18 20:17:21.564][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [02/04/18 20:17:21.569][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [02/04/18 20:17:21.569][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [02/04/18 20:17:21.642][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [02/04/18 20:17:21.642][DEBUG] [Camera Thread] SaveFileAscom: Done [02/04/18 20:17:21.644][DEBUG] [Camera Thread] Internal Interface: Set Preview... [02/04/18 20:17:21.644][DEBUG] [Camera Thread] Display image preview using asynch task... [02/04/18 20:17:22.483][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS complete... [02/04/18 20:17:35.970][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS message received... [02/04/18 20:17:35.977][DEBUG] [Camera Thread] ASCOM camera: frame and focus... [02/04/18 20:17:35.980][DEBUG] [Camera Thread] SetAscomNormalSpeed... [02/04/18 20:17:35.980][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [02/04/18 20:17:39.279][DEBUG] [Camera Thread] SaveFileAscom: Start... [02/04/18 20:17:39.279][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [02/04/18 20:17:39.279][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [02/04/18 20:17:39.284][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [02/04/18 20:17:39.284][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [02/04/18 20:17:39.351][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [02/04/18 20:17:39.351][DEBUG] [Camera Thread] SaveFileAscom: Done [02/04/18 20:17:39.352][DEBUG] [Camera Thread] Internal Interface: Set Preview... [02/04/18 20:17:39.352][DEBUG] [Camera Thread] Display image preview using asynch task... [02/04/18 20:17:40.099][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS complete... [02/04/18 20:17:47.888][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS message received... [02/04/18 20:17:47.892][DEBUG] [Camera Thread] ASCOM camera: frame and focus... [02/04/18 20:17:47.893][DEBUG] [Camera Thread] SetAscomNormalSpeed... [02/04/18 20:17:47.893][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [02/04/18 20:17:51.213][DEBUG] [Camera Thread] SaveFileAscom: Start... [02/04/18 20:17:51.213][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [02/04/18 20:17:51.213][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [02/04/18 20:17:51.218][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [02/04/18 20:17:51.218][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [02/04/18 20:17:51.287][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [02/04/18 20:17:51.287][DEBUG] [Camera Thread] SaveFileAscom: Done [02/04/18 20:17:51.288][DEBUG] [Camera Thread] Internal Interface: Set Preview... [02/04/18 20:17:51.288][DEBUG] [Camera Thread] Display image preview using asynch task... [02/04/18 20:17:52.080][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS complete... [02/04/18 20:18:00.787][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS message received... [02/04/18 20:18:00.791][DEBUG] [Camera Thread] ASCOM camera: frame and focus... [02/04/18 20:18:00.793][DEBUG] [Camera Thread] SetAscomNormalSpeed... [02/04/18 20:18:00.794][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [02/04/18 20:18:04.129][DEBUG] [Camera Thread] SaveFileAscom: Start... [02/04/18 20:18:04.129][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [02/04/18 20:18:04.129][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [02/04/18 20:18:04.132][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [02/04/18 20:18:04.132][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [02/04/18 20:18:04.205][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [02/04/18 20:18:04.205][DEBUG] [Camera Thread] SaveFileAscom: Done [02/04/18 20:18:04.207][DEBUG] [Camera Thread] Internal Interface: Set Preview... [02/04/18 20:18:04.207][DEBUG] [Camera Thread] Display image preview using asynch task... [02/04/18 20:18:05.025][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS complete... [02/04/18 20:18:12.715][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS message received... [02/04/18 20:18:12.718][DEBUG] [Camera Thread] ASCOM camera: frame and focus... [02/04/18 20:18:12.723][DEBUG] [Camera Thread] SetAscomNormalSpeed... [02/04/18 20:18:12.723][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [02/04/18 20:18:16.043][DEBUG] [Camera Thread] SaveFileAscom: Start... [02/04/18 20:18:16.043][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [02/04/18 20:18:16.043][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [02/04/18 20:18:16.047][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [02/04/18 20:18:16.047][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [02/04/18 20:18:16.112][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [02/04/18 20:18:16.112][DEBUG] [Camera Thread] SaveFileAscom: Done [02/04/18 20:18:16.113][DEBUG] [Camera Thread] Internal Interface: Set Preview... [02/04/18 20:18:16.113][DEBUG] [Camera Thread] Display image preview using asynch task... [02/04/18 20:18:16.892][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS complete... [02/04/18 20:18:25.210][DEBUG] [Camera Thread] SGM_FOCUSER_AUTO_FOCUS message received... [02/04/18 20:18:25.210][DEBUG] [Camera Thread] Checking for auto focus... [02/04/18 20:18:25.210][DEBUG] [Camera Thread] Auto focus required (focus before first frame)... [02/04/18 20:18:25.225][DEBUG] [Camera Thread] Auto focus: setting filter None [02/04/18 20:18:25.230][DEBUG] [Camera Thread] Auto focus running... [02/04/18 20:18:25.241][DEBUG] [Camera Thread] Turning temp comp off.. [02/04/18 20:18:25.266][DEBUG] [Camera Thread] ASCOM Focuser: Temp comp is avaialble, setting to False [02/04/18 20:18:25.266][DEBUG] [Camera Thread] Performing Sequence Generator auto focus using Half Flux Radius. AFID: 1 [02/04/18 20:18:25.314][DEBUG] [AfChartThread] Showing AF chart dialog... [02/04/18 20:18:25.352][DEBUG] [Camera Thread] Auto focus data [02/04/18 20:18:25.353][DEBUG] [Camera Thread] - Data Points: 7 [02/04/18 20:18:25.353][DEBUG] [Camera Thread] - Step Size: 10 [02/04/18 20:18:25.353][DEBUG] [Camera Thread] - Current Position: 1546 [02/04/18 20:18:25.353][DEBUG] [Camera Thread] - Initial Move Position: 1576 [02/04/18 20:18:25.358][DEBUG] [Camera Thread] AF Darks exception: The path is not of a legal form. [02/04/18 20:18:25.359][DEBUG] [Camera Thread] Moving focuser to next position (1576)... [02/04/18 20:18:25.360][DEBUG] [Focuser Move Thread] Focuser moving to 1576 [02/04/18 20:18:25.384][DEBUG] [Focuser Move Thread] Focuser backlash active, modified move to 1776 [02/04/18 20:18:25.414][DEBUG] [Focuser Move Thread] Focuser move call complete [02/04/18 20:18:25.415][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has started... [02/04/18 20:18:27.443][DEBUG] [Focuser Backlash Thread] Backlash thread has detected that movement to modified position (1776) is complete... [02/04/18 20:18:27.443][DEBUG] [Focuser Backlash Thread] Moving focuser to original requested position (1576)... [02/04/18 20:18:27.443][DEBUG] [Focuser Backlash Thread] Focuser backlash compensation needed. Steps: 200 Direction:IN [02/04/18 20:18:27.443][DEBUG] [Focuser Backlash Thread] Focuser moving to 1576 [02/04/18 20:18:27.538][DEBUG] [Focuser Backlash Thread] Focuser move call complete [02/04/18 20:18:29.535][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1576 matches requested position 1576 [02/04/18 20:18:29.535][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [02/04/18 20:18:29.551][DEBUG] [Focuser Backlash Thread] Focuser backlash completed. Focuser is at original request position (1576)... [02/04/18 20:18:29.551][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has ended... [02/04/18 20:18:30.557][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [02/04/18 20:18:30.557][DEBUG] [Camera Thread] Focuser move complete... [02/04/18 20:18:30.573][DEBUG] [Camera Thread] Focuser position matches requested position (1576), continuing... [02/04/18 20:18:30.589][DEBUG] [Camera Thread] Auto focus settling before next frame... [02/04/18 20:18:30.591][DEBUG] [Camera Thread] Calculating step metric... [02/04/18 20:18:30.591][DEBUG] [Camera Thread] Taking auto focus frame(s)... [02/04/18 20:18:30.593][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [02/04/18 20:18:30.593][DEBUG] [Camera Thread] ASCOM: AF Binning: 1x1... [02/04/18 20:18:30.594][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 1 seconds... [02/04/18 20:18:30.594][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: b [02/04/18 20:18:30.595][DEBUG] [Camera Thread] SetAscomNormalSpeed... [02/04/18 20:18:30.595][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [02/04/18 20:18:35.515][DEBUG] [Camera Thread] SaveFileAscom: Start... [02/04/18 20:18:35.520][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [02/04/18 20:18:35.521][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [02/04/18 20:18:35.537][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [02/04/18 20:18:35.537][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [02/04/18 20:18:35.946][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [02/04/18 20:18:35.946][DEBUG] [Camera Thread] SaveFileAscom: Done [02/04/18 20:18:35.946][DEBUG] [Camera Thread] Internal Interface: Set Preview... [02/04/18 20:18:35.946][DEBUG] [Camera Thread] Display image preview using asynch task... [02/04/18 20:18:36.919][DEBUG] [Main Thread] --> Find stars (normal) [02/04/18 20:18:37.128][DEBUG] [Main Thread] AF frame was too large... downsample = 0.5... [02/04/18 20:18:41.085][DEBUG] [Main Thread] Star detection using min star size of 3px... [02/04/18 20:18:41.085][DEBUG] [Main Thread] Star detection using max star size of 60px... [02/04/18 20:18:41.166][DEBUG] [Main Thread] Find stars took: 4246 ms... [02/04/18 20:18:41.166][DEBUG] [Main Thread] Star list contains 28 stars... [02/04/18 20:18:41.166][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [02/04/18 20:18:42.408][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.94 [02/04/18 20:18:43.419][DEBUG] [Camera Thread] Moving focuser to next position (1566)... [02/04/18 20:18:43.420][DEBUG] [Focuser Move Thread] Focuser moving to 1566 [02/04/18 20:18:43.465][DEBUG] [Focuser Move Thread] Focuser move call complete [02/04/18 20:18:44.500][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1566 matches requested position 1566 [02/04/18 20:18:44.500][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [02/04/18 20:18:44.516][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [02/04/18 20:18:44.516][DEBUG] [Camera Thread] Focuser move complete... [02/04/18 20:18:44.532][DEBUG] [Camera Thread] Focuser position matches requested position (1566), continuing... [02/04/18 20:18:44.549][DEBUG] [Camera Thread] Auto focus settling before next frame... [02/04/18 20:18:44.550][DEBUG] [Camera Thread] Calculating step metric... [02/04/18 20:18:44.550][DEBUG] [Camera Thread] Taking auto focus frame(s)... [02/04/18 20:18:44.552][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [02/04/18 20:18:44.552][DEBUG] [Camera Thread] ASCOM: AF Binning: 1x1... [02/04/18 20:18:44.552][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 1 seconds... [02/04/18 20:18:44.552][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: b [02/04/18 20:18:44.553][DEBUG] [Camera Thread] SetAscomNormalSpeed... [02/04/18 20:18:44.553][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [02/04/18 20:18:49.318][DEBUG] [Camera Thread] SaveFileAscom: Start... [02/04/18 20:18:49.318][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [02/04/18 20:18:49.318][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [02/04/18 20:18:49.337][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [02/04/18 20:18:49.337][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [02/04/18 20:18:49.694][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [02/04/18 20:18:49.694][DEBUG] [Camera Thread] SaveFileAscom: Done [02/04/18 20:18:49.694][DEBUG] [Camera Thread] Internal Interface: Set Preview... [02/04/18 20:18:49.695][DEBUG] [Camera Thread] Display image preview using asynch task... [02/04/18 20:18:50.590][DEBUG] [Main Thread] --> Find stars (normal) [02/04/18 20:18:50.746][DEBUG] [Main Thread] AF frame was too large... downsample = 0.5... [02/04/18 20:18:54.125][DEBUG] [Main Thread] Star detection using min star size of 3px... [02/04/18 20:18:54.125][DEBUG] [Main Thread] Star detection using max star size of 60px... [02/04/18 20:18:54.202][DEBUG] [Main Thread] Find stars took: 3612 ms... [02/04/18 20:18:54.202][DEBUG] [Main Thread] Star list contains 27 stars... [02/04/18 20:18:54.202][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [02/04/18 20:18:55.309][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.86 [02/04/18 20:18:56.315][DEBUG] [Camera Thread] Moving focuser to next position (1556)... [02/04/18 20:18:56.316][DEBUG] [Focuser Move Thread] Focuser moving to 1556 [02/04/18 20:18:56.368][DEBUG] [Focuser Move Thread] Focuser move call complete [02/04/18 20:18:57.390][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1556 matches requested position 1556 [02/04/18 20:18:57.390][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [02/04/18 20:18:57.406][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [02/04/18 20:18:57.406][DEBUG] [Camera Thread] Focuser move complete... [02/04/18 20:18:57.422][DEBUG] [Camera Thread] Focuser position matches requested position (1556), continuing... [02/04/18 20:18:57.438][DEBUG] [Camera Thread] Auto focus settling before next frame... [02/04/18 20:18:57.486][DEBUG] [Camera Thread] Calculating step metric... [02/04/18 20:18:57.486][DEBUG] [Camera Thread] Taking auto focus frame(s)... [02/04/18 20:18:57.487][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [02/04/18 20:18:57.497][DEBUG] [Camera Thread] ASCOM: AF Binning: 1x1... [02/04/18 20:18:57.497][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 1 seconds... [02/04/18 20:18:57.498][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: b [02/04/18 20:18:57.502][DEBUG] [Camera Thread] SetAscomNormalSpeed... [02/04/18 20:18:57.503][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [02/04/18 20:19:01.804][DEBUG] [Camera Thread] SaveFileAscom: Start... [02/04/18 20:19:01.804][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [02/04/18 20:19:01.804][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [02/04/18 20:19:01.822][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [02/04/18 20:19:01.822][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [02/04/18 20:19:02.239][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [02/04/18 20:19:02.239][DEBUG] [Camera Thread] SaveFileAscom: Done [02/04/18 20:19:02.239][DEBUG] [Camera Thread] Internal Interface: Set Preview... [02/04/18 20:19:02.239][DEBUG] [Camera Thread] Display image preview using asynch task... [02/04/18 20:19:03.172][DEBUG] [Main Thread] --> Find stars (normal) [02/04/18 20:19:03.371][DEBUG] [Main Thread] AF frame was too large... downsample = 0.5... [02/04/18 20:19:07.261][DEBUG] [Main Thread] Star detection using min star size of 3px... [02/04/18 20:19:07.261][DEBUG] [Main Thread] Star detection using max star size of 60px... [02/04/18 20:19:07.385][DEBUG] [Main Thread] Find stars took: 4212 ms... [02/04/18 20:19:07.385][DEBUG] [Main Thread] Star list contains 29 stars... [02/04/18 20:19:07.385][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [02/04/18 20:19:08.592][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.69 [02/04/18 20:19:09.599][DEBUG] [Camera Thread] Moving focuser to next position (1546)... [02/04/18 20:19:09.600][DEBUG] [Focuser Move Thread] Focuser moving to 1546 [02/04/18 20:19:09.671][DEBUG] [Focuser Move Thread] Focuser move call complete [02/04/18 20:19:10.693][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1546 matches requested position 1546 [02/04/18 20:19:10.693][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [02/04/18 20:19:10.709][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [02/04/18 20:19:10.709][DEBUG] [Camera Thread] Focuser move complete... [02/04/18 20:19:10.725][DEBUG] [Camera Thread] Focuser position matches requested position (1546), continuing... [02/04/18 20:19:10.741][DEBUG] [Camera Thread] Auto focus settling before next frame... [02/04/18 20:19:10.743][DEBUG] [Camera Thread] Calculating step metric... [02/04/18 20:19:10.743][DEBUG] [Camera Thread] Taking auto focus frame(s)... [02/04/18 20:19:10.745][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [02/04/18 20:19:10.745][DEBUG] [Camera Thread] ASCOM: AF Binning: 1x1... [02/04/18 20:19:10.745][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 1 seconds... [02/04/18 20:19:10.745][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: b [02/04/18 20:19:10.746][DEBUG] [Camera Thread] SetAscomNormalSpeed... [02/04/18 20:19:10.747][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [02/04/18 20:19:15.515][DEBUG] [Camera Thread] SaveFileAscom: Start... [02/04/18 20:19:15.516][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [02/04/18 20:19:15.516][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [02/04/18 20:19:15.531][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [02/04/18 20:19:15.531][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [02/04/18 20:19:15.902][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [02/04/18 20:19:15.902][DEBUG] [Camera Thread] SaveFileAscom: Done [02/04/18 20:19:15.902][DEBUG] [Camera Thread] Internal Interface: Set Preview... [02/04/18 20:19:15.902][DEBUG] [Camera Thread] Display image preview using asynch task... [02/04/18 20:19:16.855][DEBUG] [Main Thread] --> Find stars (normal) [02/04/18 20:19:17.047][DEBUG] [Main Thread] AF frame was too large... downsample = 0.5... [02/04/18 20:19:20.940][DEBUG] [Main Thread] Star detection using min star size of 3px... [02/04/18 20:19:20.940][DEBUG] [Main Thread] Star detection using max star size of 60px... [02/04/18 20:19:21.034][DEBUG] [Main Thread] Find stars took: 4179 ms... [02/04/18 20:19:21.035][DEBUG] [Main Thread] Star list contains 32 stars... [02/04/18 20:19:21.035][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [02/04/18 20:19:22.225][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.66 [02/04/18 20:19:23.232][DEBUG] [Camera Thread] Moving focuser to next position (1536)... [02/04/18 20:19:23.233][DEBUG] [Focuser Move Thread] Focuser moving to 1536 [02/04/18 20:19:23.288][DEBUG] [Focuser Move Thread] Focuser move call complete [02/04/18 20:19:24.301][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1536 matches requested position 1536 [02/04/18 20:19:24.301][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [02/04/18 20:19:25.301][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [02/04/18 20:19:25.301][DEBUG] [Camera Thread] Focuser move complete... [02/04/18 20:19:25.323][DEBUG] [Camera Thread] Focuser position matches requested position (1536), continuing... [02/04/18 20:19:25.339][DEBUG] [Camera Thread] Auto focus settling before next frame... [02/04/18 20:19:25.341][DEBUG] [Camera Thread] Calculating step metric... [02/04/18 20:19:25.341][DEBUG] [Camera Thread] Taking auto focus frame(s)... [02/04/18 20:19:25.343][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [02/04/18 20:19:25.343][DEBUG] [Camera Thread] ASCOM: AF Binning: 1x1... [02/04/18 20:19:25.343][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 1 seconds... [02/04/18 20:19:25.343][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: b [02/04/18 20:19:25.345][DEBUG] [Camera Thread] SetAscomNormalSpeed... [02/04/18 20:19:25.345][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [02/04/18 20:19:29.660][DEBUG] [Camera Thread] SaveFileAscom: Start... [02/04/18 20:19:29.660][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [02/04/18 20:19:29.660][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [02/04/18 20:19:29.678][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [02/04/18 20:19:29.678][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [02/04/18 20:19:30.026][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [02/04/18 20:19:30.026][DEBUG] [Camera Thread] SaveFileAscom: Done [02/04/18 20:19:30.026][DEBUG] [Camera Thread] Internal Interface: Set Preview... [02/04/18 20:19:30.026][DEBUG] [Camera Thread] Display image preview using asynch task... [02/04/18 20:19:31.170][DEBUG] [Main Thread] --> Find stars (normal) [02/04/18 20:19:31.365][DEBUG] [Main Thread] AF frame was too large... downsample = 0.5... [02/04/18 20:19:35.087][DEBUG] [Main Thread] Star detection using min star size of 3px... [02/04/18 20:19:35.087][DEBUG] [Main Thread] Star detection using max star size of 60px... [02/04/18 20:19:35.172][DEBUG] [Main Thread] Find stars took: 4002 ms... [02/04/18 20:19:35.172][DEBUG] [Main Thread] Star list contains 29 stars... [02/04/18 20:19:35.173][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [02/04/18 20:19:36.364][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.79 [02/04/18 20:19:37.374][DEBUG] [Camera Thread] Moving focuser to next position (1526)... [02/04/18 20:19:37.375][DEBUG] [Focuser Move Thread] Focuser moving to 1526 [02/04/18 20:19:37.432][DEBUG] [Focuser Move Thread] Focuser move call complete [02/04/18 20:19:38.452][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1526 matches requested position 1526 [02/04/18 20:19:38.453][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [02/04/18 20:19:39.453][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [02/04/18 20:19:39.483][DEBUG] [Camera Thread] Focuser move complete... [02/04/18 20:19:39.507][DEBUG] [Camera Thread] Focuser position matches requested position (1526), continuing... [02/04/18 20:19:39.522][DEBUG] [Camera Thread] Auto focus settling before next frame... [02/04/18 20:19:39.524][DEBUG] [Camera Thread] Calculating step metric... [02/04/18 20:19:39.524][DEBUG] [Camera Thread] Taking auto focus frame(s)... [02/04/18 20:19:39.526][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [02/04/18 20:19:39.526][DEBUG] [Camera Thread] ASCOM: AF Binning: 1x1... [02/04/18 20:19:39.526][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 1 seconds... [02/04/18 20:19:39.526][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: b [02/04/18 20:19:39.527][DEBUG] [Camera Thread] SetAscomNormalSpeed... [02/04/18 20:19:39.527][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [02/04/18 20:19:43.813][DEBUG] [Camera Thread] SaveFileAscom: Start... [02/04/18 20:19:43.813][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [02/04/18 20:19:43.813][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [02/04/18 20:19:43.831][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [02/04/18 20:19:43.832][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [02/04/18 20:19:44.076][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [02/04/18 20:19:44.076][DEBUG] [Camera Thread] SaveFileAscom: Done [02/04/18 20:19:44.076][DEBUG] [Camera Thread] Internal Interface: Set Preview... [02/04/18 20:19:44.076][DEBUG] [Camera Thread] Display image preview using asynch task... [02/04/18 20:19:45.062][DEBUG] [Main Thread] --> Find stars (normal) [02/04/18 20:19:45.269][DEBUG] [Main Thread] AF frame was too large... downsample = 0.5... [02/04/18 20:19:49.085][DEBUG] [Main Thread] Star detection using min star size of 3px... [02/04/18 20:19:49.085][DEBUG] [Main Thread] Star detection using max star size of 60px... [02/04/18 20:19:49.295][DEBUG] [Main Thread] Find stars took: 4232 ms... [02/04/18 20:19:49.295][DEBUG] [Main Thread] Star list contains 29 stars... [02/04/18 20:19:49.295][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [02/04/18 20:19:50.478][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.56 [02/04/18 20:19:51.487][DEBUG] [Camera Thread] Moving focuser to next position (1516)... [02/04/18 20:19:51.488][DEBUG] [Focuser Move Thread] Focuser moving to 1516 [02/04/18 20:19:51.534][DEBUG] [Focuser Move Thread] Focuser move call complete [02/04/18 20:19:52.556][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1516 matches requested position 1516 [02/04/18 20:19:52.556][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [02/04/18 20:19:52.572][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [02/04/18 20:19:52.572][DEBUG] [Camera Thread] Focuser move complete... [02/04/18 20:19:52.588][DEBUG] [Camera Thread] Focuser position matches requested position (1516), continuing... [02/04/18 20:19:52.604][DEBUG] [Camera Thread] Auto focus settling before next frame... [02/04/18 20:19:52.606][DEBUG] [Camera Thread] Calculating step metric... [02/04/18 20:19:52.606][DEBUG] [Camera Thread] Taking auto focus frame(s)... [02/04/18 20:19:52.607][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [02/04/18 20:19:52.607][DEBUG] [Camera Thread] ASCOM: AF Binning: 1x1... [02/04/18 20:19:52.607][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 1 seconds... [02/04/18 20:19:52.607][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: b [02/04/18 20:19:52.609][DEBUG] [Camera Thread] SetAscomNormalSpeed... [02/04/18 20:19:52.609][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [02/04/18 20:19:56.904][DEBUG] [Camera Thread] SaveFileAscom: Start... [02/04/18 20:19:56.904][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [02/04/18 20:19:56.905][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [02/04/18 20:19:56.927][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [02/04/18 20:19:56.927][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [02/04/18 20:19:57.307][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [02/04/18 20:19:57.307][DEBUG] [Camera Thread] SaveFileAscom: Done [02/04/18 20:19:57.307][DEBUG] [Camera Thread] Internal Interface: Set Preview... [02/04/18 20:19:57.307][DEBUG] [Camera Thread] Display image preview using asynch task... [02/04/18 20:19:58.254][DEBUG] [Main Thread] --> Find stars (normal) [02/04/18 20:19:58.455][DEBUG] [Main Thread] AF frame was too large... downsample = 0.5... [02/04/18 20:20:02.341][DEBUG] [Main Thread] Star detection using min star size of 3px... [02/04/18 20:20:02.341][DEBUG] [Main Thread] Star detection using max star size of 60px... [02/04/18 20:20:02.489][DEBUG] [Main Thread] Find stars took: 4234 ms... [02/04/18 20:20:02.489][DEBUG] [Main Thread] Star list contains 31 stars... [02/04/18 20:20:02.489][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [02/04/18 20:20:03.727][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.66 [02/04/18 20:20:04.737][DEBUG] [Camera Thread] Moving focuser to next position (1506)... [02/04/18 20:20:04.738][DEBUG] [Focuser Move Thread] Focuser moving to 1506 [02/04/18 20:20:04.821][DEBUG] [Focuser Move Thread] Focuser move call complete [02/04/18 20:20:05.844][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1506 matches requested position 1506 [02/04/18 20:20:05.844][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [02/04/18 20:20:05.844][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [02/04/18 20:20:05.844][DEBUG] [Camera Thread] Focuser move complete... [02/04/18 20:20:05.861][DEBUG] [Camera Thread] Focuser position matches requested position (1506), continuing... [02/04/18 20:20:05.876][DEBUG] [Camera Thread] Auto focus settling before next frame... [02/04/18 20:20:05.878][DEBUG] [Camera Thread] Calculating step metric... [02/04/18 20:20:05.878][DEBUG] [Camera Thread] Taking auto focus frame(s)... [02/04/18 20:20:05.879][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [02/04/18 20:20:05.879][DEBUG] [Camera Thread] ASCOM: AF Binning: 1x1... [02/04/18 20:20:05.879][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 1 seconds... [02/04/18 20:20:05.879][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: b [02/04/18 20:20:05.881][DEBUG] [Camera Thread] SetAscomNormalSpeed... [02/04/18 20:20:05.881][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [02/04/18 20:20:10.176][DEBUG] [Camera Thread] SaveFileAscom: Start... [02/04/18 20:20:10.176][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [02/04/18 20:20:10.176][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [02/04/18 20:20:10.194][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [02/04/18 20:20:10.194][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [02/04/18 20:20:10.579][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [02/04/18 20:20:10.579][DEBUG] [Camera Thread] SaveFileAscom: Done [02/04/18 20:20:10.579][DEBUG] [Camera Thread] Internal Interface: Set Preview... [02/04/18 20:20:10.579][DEBUG] [Camera Thread] Display image preview using asynch task... [02/04/18 20:20:11.628][DEBUG] [Main Thread] --> Find stars (normal) [02/04/18 20:20:11.838][DEBUG] [Main Thread] AF frame was too large... downsample = 0.5... [02/04/18 20:20:15.773][DEBUG] [Main Thread] Star detection using min star size of 3px... [02/04/18 20:20:15.773][DEBUG] [Main Thread] Star detection using max star size of 60px... [02/04/18 20:20:15.854][DEBUG] [Main Thread] Find stars took: 4226 ms... [02/04/18 20:20:15.854][DEBUG] [Main Thread] Star list contains 30 stars... [02/04/18 20:20:15.854][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [02/04/18 20:20:17.057][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.62 [02/04/18 20:20:18.075][DEBUG] [Camera Thread] Moving focuser to next position (1496)... [02/04/18 20:20:18.078][DEBUG] [Focuser Move Thread] Focuser moving to 1496 [02/04/18 20:20:18.145][DEBUG] [Focuser Move Thread] Focuser move call complete [02/04/18 20:20:19.164][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1496 matches requested position 1496 [02/04/18 20:20:19.164][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [02/04/18 20:20:19.180][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [02/04/18 20:20:19.180][DEBUG] [Camera Thread] Focuser move complete... [02/04/18 20:20:19.196][DEBUG] [Camera Thread] Focuser position matches requested position (1496), continuing... [02/04/18 20:20:19.212][DEBUG] [Camera Thread] Auto focus settling before next frame... [02/04/18 20:20:19.213][DEBUG] [Camera Thread] Calculating step metric... [02/04/18 20:20:19.213][DEBUG] [Camera Thread] Taking auto focus frame(s)... [02/04/18 20:20:19.214][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [02/04/18 20:20:19.214][DEBUG] [Camera Thread] ASCOM: AF Binning: 1x1... [02/04/18 20:20:19.214][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 1 seconds... [02/04/18 20:20:19.214][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: b [02/04/18 20:20:19.216][DEBUG] [Camera Thread] SetAscomNormalSpeed... [02/04/18 20:20:19.216][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [02/04/18 20:20:23.526][DEBUG] [Camera Thread] SaveFileAscom: Start... [02/04/18 20:20:23.526][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [02/04/18 20:20:23.526][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [02/04/18 20:20:23.544][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [02/04/18 20:20:23.544][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [02/04/18 20:20:23.943][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [02/04/18 20:20:23.943][DEBUG] [Camera Thread] SaveFileAscom: Done [02/04/18 20:20:23.943][DEBUG] [Camera Thread] Internal Interface: Set Preview... [02/04/18 20:20:23.943][DEBUG] [Camera Thread] Display image preview using asynch task... [02/04/18 20:20:24.907][DEBUG] [Main Thread] --> Find stars (normal) [02/04/18 20:20:25.091][DEBUG] [Main Thread] AF frame was too large... downsample = 0.5... [02/04/18 20:20:28.889][DEBUG] [Main Thread] Star detection using min star size of 3px... [02/04/18 20:20:28.889][DEBUG] [Main Thread] Star detection using max star size of 60px... [02/04/18 20:20:28.972][DEBUG] [Main Thread] Find stars took: 4065 ms... [02/04/18 20:20:28.972][DEBUG] [Main Thread] Star list contains 29 stars... [02/04/18 20:20:28.972][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [02/04/18 20:20:30.174][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.54 [02/04/18 20:20:31.193][DEBUG] [Camera Thread] Moving focuser to next position (1486)... [02/04/18 20:20:31.195][DEBUG] [Focuser Move Thread] Focuser moving to 1486 [02/04/18 20:20:31.297][DEBUG] [Focuser Move Thread] Focuser move call complete [02/04/18 20:20:32.309][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1486 matches requested position 1486 [02/04/18 20:20:32.309][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [02/04/18 20:20:32.309][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [02/04/18 20:20:32.309][DEBUG] [Camera Thread] Focuser move complete... [02/04/18 20:20:32.325][DEBUG] [Camera Thread] Focuser position matches requested position (1486), continuing... [02/04/18 20:20:32.341][DEBUG] [Camera Thread] Auto focus settling before next frame... [02/04/18 20:20:32.342][DEBUG] [Camera Thread] Calculating step metric... [02/04/18 20:20:32.342][DEBUG] [Camera Thread] Taking auto focus frame(s)... [02/04/18 20:20:32.344][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [02/04/18 20:20:32.344][DEBUG] [Camera Thread] ASCOM: AF Binning: 1x1... [02/04/18 20:20:32.344][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 1 seconds... [02/04/18 20:20:32.344][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: b [02/04/18 20:20:32.346][DEBUG] [Camera Thread] SetAscomNormalSpeed... [02/04/18 20:20:32.346][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [02/04/18 20:20:36.618][DEBUG] [Camera Thread] SaveFileAscom: Start... [02/04/18 20:20:36.618][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [02/04/18 20:20:36.618][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [02/04/18 20:20:36.637][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [02/04/18 20:20:36.637][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [02/04/18 20:20:37.016][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [02/04/18 20:20:37.017][DEBUG] [Camera Thread] SaveFileAscom: Done [02/04/18 20:20:37.017][DEBUG] [Camera Thread] Internal Interface: Set Preview... [02/04/18 20:20:37.017][DEBUG] [Camera Thread] Display image preview using asynch task... [02/04/18 20:20:38.015][DEBUG] [Main Thread] --> Find stars (normal) [02/04/18 20:20:38.203][DEBUG] [Main Thread] AF frame was too large... downsample = 0.5... [02/04/18 20:20:41.916][DEBUG] [Main Thread] Star detection using min star size of 3px... [02/04/18 20:20:41.916][DEBUG] [Main Thread] Star detection using max star size of 60px... [02/04/18 20:20:42.089][DEBUG] [Main Thread] Find stars took: 4073 ms... [02/04/18 20:20:42.089][DEBUG] [Main Thread] Star list contains 29 stars... [02/04/18 20:20:42.089][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [02/04/18 20:20:43.379][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.79 [02/04/18 20:20:44.388][DEBUG] [Camera Thread] Moving focuser to next position (1476)... [02/04/18 20:20:44.390][DEBUG] [Focuser Move Thread] Focuser moving to 1476 [02/04/18 20:20:44.431][DEBUG] [Focuser Move Thread] Focuser move call complete [02/04/18 20:20:45.454][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1476 matches requested position 1476 [02/04/18 20:20:45.511][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [02/04/18 20:20:45.533][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [02/04/18 20:20:45.533][DEBUG] [Camera Thread] Focuser move complete... [02/04/18 20:20:45.565][DEBUG] [Camera Thread] Focuser position matches requested position (1476), continuing... [02/04/18 20:20:45.581][DEBUG] [Camera Thread] Auto focus settling before next frame... [02/04/18 20:20:45.583][DEBUG] [Camera Thread] Calculating step metric... [02/04/18 20:20:45.583][DEBUG] [Camera Thread] Taking auto focus frame(s)... [02/04/18 20:20:45.585][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [02/04/18 20:20:45.585][DEBUG] [Camera Thread] ASCOM: AF Binning: 1x1... [02/04/18 20:20:45.585][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 1 seconds... [02/04/18 20:20:45.585][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: b [02/04/18 20:20:45.587][DEBUG] [Camera Thread] SetAscomNormalSpeed... [02/04/18 20:20:45.588][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [02/04/18 20:20:49.932][DEBUG] [Camera Thread] SaveFileAscom: Start... [02/04/18 20:20:49.932][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [02/04/18 20:20:49.932][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [02/04/18 20:20:49.948][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [02/04/18 20:20:49.948][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [02/04/18 20:20:50.344][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [02/04/18 20:20:50.344][DEBUG] [Camera Thread] SaveFileAscom: Done [02/04/18 20:20:50.344][DEBUG] [Camera Thread] Internal Interface: Set Preview... [02/04/18 20:20:50.344][DEBUG] [Camera Thread] Display image preview using asynch task... [02/04/18 20:20:51.306][DEBUG] [Main Thread] --> Find stars (normal) [02/04/18 20:20:51.499][DEBUG] [Main Thread] AF frame was too large... downsample = 0.5... [02/04/18 20:20:55.384][DEBUG] [Main Thread] Star detection using min star size of 3px... [02/04/18 20:20:55.384][DEBUG] [Main Thread] Star detection using max star size of 60px... [02/04/18 20:20:55.522][DEBUG] [Main Thread] Find stars took: 4216 ms... [02/04/18 20:20:55.522][DEBUG] [Main Thread] Star list contains 28 stars... [02/04/18 20:20:55.522][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [02/04/18 20:20:56.734][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.89 [02/04/18 20:20:57.743][DEBUG] [Camera Thread] Moving focuser to next position (1466)... [02/04/18 20:20:57.744][DEBUG] [Focuser Move Thread] Focuser moving to 1466 [02/04/18 20:20:57.802][DEBUG] [Focuser Move Thread] Focuser move call complete [02/04/18 20:20:58.822][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1466 matches requested position 1466 [02/04/18 20:20:58.822][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [02/04/18 20:20:58.838][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [02/04/18 20:20:58.838][DEBUG] [Camera Thread] Focuser move complete... [02/04/18 20:20:58.854][DEBUG] [Camera Thread] Focuser position matches requested position (1466), continuing... [02/04/18 20:20:58.870][DEBUG] [Camera Thread] Auto focus settling before next frame... [02/04/18 20:20:58.872][DEBUG] [Camera Thread] Calculating step metric... [02/04/18 20:20:58.872][DEBUG] [Camera Thread] Taking auto focus frame(s)... [02/04/18 20:20:58.873][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [02/04/18 20:20:58.879][DEBUG] [Camera Thread] ASCOM: AF Binning: 1x1... [02/04/18 20:20:58.879][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 1 seconds... [02/04/18 20:20:58.879][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: b [02/04/18 20:20:58.881][DEBUG] [Camera Thread] SetAscomNormalSpeed... [02/04/18 20:20:58.881][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [02/04/18 20:21:03.138][DEBUG] [Camera Thread] SaveFileAscom: Start... [02/04/18 20:21:03.138][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [02/04/18 20:21:03.138][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [02/04/18 20:21:03.156][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [02/04/18 20:21:03.157][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [02/04/18 20:21:03.543][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [02/04/18 20:21:03.543][DEBUG] [Camera Thread] SaveFileAscom: Done [02/04/18 20:21:03.543][DEBUG] [Camera Thread] Internal Interface: Set Preview... [02/04/18 20:21:03.543][DEBUG] [Camera Thread] Display image preview using asynch task... [02/04/18 20:21:04.521][DEBUG] [Main Thread] --> Find stars (normal) [02/04/18 20:21:04.734][DEBUG] [Main Thread] AF frame was too large... downsample = 0.5... [02/04/18 20:21:08.657][DEBUG] [Main Thread] Star detection using min star size of 3px... [02/04/18 20:21:08.657][DEBUG] [Main Thread] Star detection using max star size of 60px... [02/04/18 20:21:08.743][DEBUG] [Main Thread] Find stars took: 4222 ms... [02/04/18 20:21:08.743][DEBUG] [Main Thread] Star list contains 28 stars... [02/04/18 20:21:08.744][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [02/04/18 20:21:09.948][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.95 [02/04/18 20:21:10.969][DEBUG] [Camera Thread] AF has detected >= 3 left slope and >= 3 right slope but an asymmetrical fit (Ratio: 49.3). Something isn't right here! Defaulting to lowest weighted HFR method [02/04/18 20:21:10.971][DEBUG] [Camera Thread] New auto focus method calculated focus at: 1510 [02/04/18 20:21:10.971][DEBUG] [Camera Thread] Old auto focus method calculated focus at: 1498 [02/04/18 20:21:10.971][DEBUG] [Camera Thread] Using old auto focus method for focuser position [02/04/18 20:21:10.971][DEBUG] [Camera Thread] New focus position is at 1498 (@11.00C). Moving focuser... [02/04/18 20:21:10.972][DEBUG] [Focuser Move Thread] Focuser moving to 1498 [02/04/18 20:21:10.992][DEBUG] [Focuser Move Thread] Focuser backlash active, modified move to 1698 [02/04/18 20:21:11.077][DEBUG] [Focuser Move Thread] Focuser move call complete [02/04/18 20:21:11.078][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has started... [02/04/18 20:21:14.078][DEBUG] [Focuser Backlash Thread] Backlash thread has detected that movement to modified position (1698) is complete... [02/04/18 20:21:14.078][DEBUG] [Focuser Backlash Thread] Moving focuser to original requested position (1498)... [02/04/18 20:21:14.078][DEBUG] [Focuser Backlash Thread] Focuser backlash compensation needed. Steps: 200 Direction:IN [02/04/18 20:21:14.078][DEBUG] [Focuser Backlash Thread] Focuser moving to 1498 [02/04/18 20:21:14.138][DEBUG] [Focuser Backlash Thread] Focuser move call complete [02/04/18 20:21:16.151][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1498 matches requested position 1498 [02/04/18 20:21:16.151][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [02/04/18 20:21:17.174][DEBUG] [Focuser Backlash Thread] Focuser backlash completed. Focuser is at original request position (1498)... [02/04/18 20:21:17.174][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has ended... [02/04/18 20:21:18.179][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [02/04/18 20:21:18.179][DEBUG] [Camera Thread] Focuser move complete... [02/04/18 20:21:18.195][DEBUG] [Camera Thread] Focuser successfully set to focus position (1498) [02/04/18 20:21:18.212][DEBUG] [Camera Thread] Taking validation frame at focus position... [02/04/18 20:21:18.213][DEBUG] [Camera Thread] Taking auto focus frame(s)... [02/04/18 20:21:18.214][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [02/04/18 20:21:18.215][DEBUG] [Camera Thread] ASCOM: AF Binning: 1x1... [02/04/18 20:21:18.215][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 1 seconds... [02/04/18 20:21:18.215][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: b [02/04/18 20:21:18.216][DEBUG] [Camera Thread] SetAscomNormalSpeed... [02/04/18 20:21:18.216][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [02/04/18 20:21:22.535][DEBUG] [Camera Thread] SaveFileAscom: Start... [02/04/18 20:21:22.535][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [02/04/18 20:21:22.535][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [02/04/18 20:21:22.552][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [02/04/18 20:21:22.552][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [02/04/18 20:21:22.951][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [02/04/18 20:21:22.951][DEBUG] [Camera Thread] SaveFileAscom: Done [02/04/18 20:21:22.951][DEBUG] [Camera Thread] Internal Interface: Set Preview... [02/04/18 20:21:22.951][DEBUG] [Camera Thread] Display image preview using asynch task... [02/04/18 20:21:23.913][DEBUG] [Main Thread] --> Find stars (normal) [02/04/18 20:21:24.105][DEBUG] [Main Thread] AF frame was too large... downsample = 0.5... [02/04/18 20:21:28.097][DEBUG] [Main Thread] Star detection using min star size of 3px... [02/04/18 20:21:28.098][DEBUG] [Main Thread] Star detection using max star size of 60px... [02/04/18 20:21:28.177][DEBUG] [Main Thread] Find stars took: 4263 ms... [02/04/18 20:21:28.177][DEBUG] [Main Thread] Star list contains 30 stars... [02/04/18 20:21:28.177][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [02/04/18 20:21:29.478][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.56 [02/04/18 20:21:30.481][DEBUG] [Camera Thread] Auto focus is complete (weighted avg method: 1498; HFR 3.56)... [02/04/18 20:21:30.484][DEBUG] [Camera Thread] Entering chart disposal loop... [02/04/18 20:22:16.652][DEBUG] [Camera Thread] Chart disposal loop is complete... [02/04/18 20:22:16.652][DEBUG] [Camera Thread] Auto focus is done... [02/04/18 20:22:16.653][DEBUG] [AfChartThread] AF chart dialog is closed... [02/04/18 20:22:16.715][DEBUG] [Camera Thread] Auto focus complete... [02/04/18 20:22:16.775][DEBUG] [Camera Thread] SGM_FOCUSER_AUTO_FOCUS complete... [02/04/18 20:23:02.392][DEBUG] [DSS Fetch Thread] Object name NGC7380 resolved by Local DSO DB... [02/04/18 20:23:02.501][DEBUG] [DSS Fetch Thread] Internal Interface: Set Preview... [02/04/18 20:23:02.501][DEBUG] [DSS Fetch Thread] Display image preview using asynch task... [02/04/18 20:23:21.027][DEBUG] [DSS Fetch Thread] Object name NGC7380 resolved by Local DSO DB... [02/04/18 20:23:21.031][DEBUG] [DSS Fetch Thread] Fetching canvas at: https://skyview.gsfc.nasa.gov/cgi-bin/images?Survey=dss2r&Return=JPG&Size=2.500&Pixels=1500&position=341.825,58.133 [02/04/18 20:23:21.031][DEBUG] [DSS Fetch Thread] URI: https://skyview.gsfc.nasa.gov/cgi-bin/images?Survey=dss2r&Return=JPG&Size=2.500&Pixels=1500&position=341.825,58.133 [02/04/18 20:23:21.419][DEBUG] [DSS Fetch Thread] MFW: Requested canvas image, awaiting response... [02/04/18 20:24:16.156][DEBUG] [Main Thread] MFW: User requested canvas download cancel... [02/04/18 20:24:16.156][DEBUG] [Main Thread] MFW: Canceling asynch download... [02/04/18 20:24:16.230][DEBUG] [Unknown] MFW: Canvas download canceled... [02/04/18 20:24:16.478][DEBUG] [DSS Fetch Thread] MFW: Canvas image download complete... [02/04/18 20:24:16.478][DEBUG] [DSS Fetch Thread] -- FOV took 55.45s... [02/04/18 20:24:16.481][DEBUG] [DSS Fetch Thread] SaveFileBmp16: Locking bitmap for disk write... [02/04/18 20:24:16.481][DEBUG] [DSS Fetch Thread] SaveFileBmp16: Opening file stream... [02/04/18 20:24:16.482][DEBUG] [DSS Fetch Thread] SaveFileBmp16: Writing FITS headers... [02/04/18 20:24:16.489][DEBUG] [DSS Fetch Thread] SaveFileBmp16: Traversing and persisting bitmap data... [02/04/18 20:24:16.548][DEBUG] [DSS Fetch Thread] SaveFileBmp16: FITS block fill... [02/04/18 20:24:16.548][DEBUG] [DSS Fetch Thread] SaveFileBmp16: Unlocking bitmap... [02/04/18 20:24:16.548][DEBUG] [DSS Fetch Thread] SaveFileBmp16: Closing file stream [02/04/18 20:24:16.553][DEBUG] [DSS Fetch Thread] Internal Interface: Set Preview... [02/04/18 20:24:16.553][DEBUG] [DSS Fetch Thread] Display image preview using asynch task... [02/04/18 20:24:16.554][DEBUG] [Main Thread] Error in Open Image! Source pixel format is not supported. [02/04/18 20:24:29.632][DEBUG] [DSS Fetch Thread] Object name NGC7380 resolved by Local DSO DB... [02/04/18 20:24:30.840][DEBUG] [DSS Fetch Thread] Internal Interface: Set Preview... [02/04/18 20:24:30.841][DEBUG] [DSS Fetch Thread] Display image preview using asynch task... [02/04/18 20:24:54.476][DEBUG] [Main Thread] Display image preview using asynch task... [02/04/18 20:24:54.763][DEBUG] [Main Thread] Tile 1 at pos 780,779 => 341.75,58.09 [02/04/18 20:24:54.775][DEBUG] [Main Thread] Populating the form controls... [02/04/18 20:24:54.796][DEBUG] [Main Thread] Added row 0... [02/04/18 20:24:54.830][DEBUG] [Main Thread] Added row 1... [02/04/18 20:24:54.865][DEBUG] [Main Thread] Added row 2... [02/04/18 20:24:54.906][DEBUG] [Main Thread] Added row 3... [02/04/18 20:24:54.957][DEBUG] [Main Thread] Added row 4... [02/04/18 20:24:54.989][DEBUG] [Main Thread] PopulateDataModel: Transferring view to the data model... [02/04/18 20:25:00.770][DEBUG] [Main Thread] Error calculating start / end for graph! : Object reference not set to an instance of an object. at SequenceGenerator.Astrometry.a(DateTime A_0, UserProfile A_1, DateTime& A_2, DateTime& A_3) [02/04/18 20:26:43.433][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [02/04/18 20:26:43.433][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [02/04/18 20:26:43.433][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Looping [02/04/18 20:26:43.433][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [02/04/18 20:26:43.433][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Looping [02/04/18 20:27:04.382][DEBUG] [Main Thread] Focuser is required... [02/04/18 20:27:04.382][DEBUG] [Main Thread] Focuser is still connected. Ignoring connect step... [02/04/18 20:27:04.382][DEBUG] [Main Thread] Telescope is required... [02/04/18 20:27:04.382][DEBUG] [Main Thread] Telescope is still connected. Ignoring connect step... [02/04/18 20:27:20.775][DEBUG] [Main Thread] Rotator is required... [02/04/18 20:27:20.776][DEBUG] [Main Thread] Rotator is not connected. Sending connect command... [02/04/18 20:27:24.809][DEBUG] [Main Thread] Aborting sequence timer thread... [02/04/18 20:27:24.809][DEBUG] [Main Thread] Sending sequence end notification... [02/04/18 20:27:24.809][DEBUG] [Main Thread] Resetting UI elements... [02/04/18 20:27:24.809][DEBUG] [Main Thread] Checking if sequence has work left... [02/04/18 20:27:24.809][DEBUG] [Main Thread] Work left... [02/04/18 20:27:24.811][DEBUG] [Main Thread] Updating big status... [02/04/18 20:27:24.811][DEBUG] [Main Thread] Enabling menu items... [02/04/18 20:27:24.855][DEBUG] [Main Thread] Set target icons... [02/04/18 20:27:24.855][DEBUG] [Main Thread] Removing camera protection... [02/04/18 20:27:24.886][DEBUG] [Main Thread] Monitoring system shutdown... [02/04/18 20:27:24.887][DEBUG] [Main Thread] SequenceEnds complete... [02/04/18 20:27:31.659][DEBUG] [Camera Thread] SGM_FOCUSER_AUTO_FOCUS message received... [02/04/18 20:27:31.659][DEBUG] [Camera Thread] Checking for auto focus... [02/04/18 20:27:31.659][DEBUG] [Camera Thread] Auto focus required (focus before first frame)... [02/04/18 20:27:31.667][DEBUG] [Camera Thread] Auto focus: setting filter None [02/04/18 20:27:31.668][DEBUG] [Camera Thread] Auto focus running... [02/04/18 20:27:31.675][DEBUG] [Camera Thread] Turning temp comp off.. [02/04/18 20:27:31.676][DEBUG] [Camera Thread] ASCOM Focuser: Temp comp is avaialble, setting to False [02/04/18 20:27:31.677][DEBUG] [Camera Thread] Performing Sequence Generator auto focus using Half Flux Radius. AFID: 2 [02/04/18 20:27:31.715][DEBUG] [AfChartThread] Showing AF chart dialog... [02/04/18 20:27:31.746][DEBUG] [Camera Thread] Auto focus data [02/04/18 20:27:31.746][DEBUG] [Camera Thread] - Data Points: 7 [02/04/18 20:27:31.746][DEBUG] [Camera Thread] - Step Size: 10 [02/04/18 20:27:31.746][DEBUG] [Camera Thread] - Current Position: 1498 [02/04/18 20:27:31.746][DEBUG] [Camera Thread] - Initial Move Position: 1528 [02/04/18 20:27:31.760][DEBUG] [Camera Thread] AF Darks exception: The path is not of a legal form. [02/04/18 20:27:31.760][DEBUG] [Camera Thread] Moving focuser to next position (1528)... [02/04/18 20:27:31.764][DEBUG] [Focuser Move Thread] Focuser moving to 1528 [02/04/18 20:27:31.791][DEBUG] [Focuser Move Thread] Focuser backlash active, modified move to 1728 [02/04/18 20:27:31.823][DEBUG] [Focuser Move Thread] Focuser move call complete [02/04/18 20:27:31.824][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has started... [02/04/18 20:27:33.851][DEBUG] [Focuser Backlash Thread] Backlash thread has detected that movement to modified position (1728) is complete... [02/04/18 20:27:33.851][DEBUG] [Focuser Backlash Thread] Moving focuser to original requested position (1528)... [02/04/18 20:27:33.851][DEBUG] [Focuser Backlash Thread] Focuser backlash compensation needed. Steps: 200 Direction:IN [02/04/18 20:27:33.851][DEBUG] [Focuser Backlash Thread] Focuser moving to 1528 [02/04/18 20:27:33.947][DEBUG] [Focuser Backlash Thread] Focuser move call complete [02/04/18 20:27:35.927][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1528 matches requested position 1528 [02/04/18 20:27:35.927][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [02/04/18 20:27:35.959][DEBUG] [Focuser Backlash Thread] Focuser backlash completed. Focuser is at original request position (1528)... [02/04/18 20:27:35.959][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has ended... [02/04/18 20:27:36.949][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [02/04/18 20:27:36.949][DEBUG] [Camera Thread] Focuser move complete... [02/04/18 20:27:36.949][DEBUG] [Camera Thread] Focuser position matches requested position (1528), continuing... [02/04/18 20:27:36.982][DEBUG] [Camera Thread] Auto focus settling before next frame... [02/04/18 20:27:36.984][DEBUG] [Camera Thread] Calculating step metric... [02/04/18 20:27:36.984][DEBUG] [Camera Thread] Taking auto focus frame(s)... [02/04/18 20:27:36.991][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [02/04/18 20:27:36.991][DEBUG] [Camera Thread] ASCOM: AF Binning: 1x1... [02/04/18 20:27:36.991][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 1 seconds... [02/04/18 20:27:36.991][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: b [02/04/18 20:27:36.992][DEBUG] [Camera Thread] SetAscomNormalSpeed... [02/04/18 20:27:36.992][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [02/04/18 20:27:41.275][DEBUG] [Camera Thread] SaveFileAscom: Start... [02/04/18 20:27:41.275][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [02/04/18 20:27:41.275][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [02/04/18 20:27:41.292][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [02/04/18 20:27:41.292][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [02/04/18 20:27:41.679][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [02/04/18 20:27:41.679][DEBUG] [Camera Thread] SaveFileAscom: Done [02/04/18 20:27:41.679][DEBUG] [Camera Thread] Internal Interface: Set Preview... [02/04/18 20:27:41.679][DEBUG] [Camera Thread] Display image preview using asynch task... [02/04/18 20:27:42.148][DEBUG] [Main Thread] --> Find stars (normal) [02/04/18 20:27:42.360][DEBUG] [Main Thread] AF frame was too large... downsample = 0.5... [02/04/18 20:27:43.544][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [02/04/18 20:27:43.544][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [02/04/18 20:27:43.544][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [02/04/18 20:27:43.544][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [02/04/18 20:27:43.544][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [02/04/18 20:27:46.103][DEBUG] [Main Thread] Star detection using min star size of 3px... [02/04/18 20:27:46.103][DEBUG] [Main Thread] Star detection using max star size of 60px... [02/04/18 20:27:46.188][DEBUG] [Main Thread] Find stars took: 4040 ms... [02/04/18 20:27:46.188][DEBUG] [Main Thread] Star list contains 30 stars... [02/04/18 20:27:46.188][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [02/04/18 20:27:46.898][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.56 [02/04/18 20:27:47.904][DEBUG] [Camera Thread] Moving focuser to next position (1518)... [02/04/18 20:27:47.909][DEBUG] [Focuser Move Thread] Focuser moving to 1518 [02/04/18 20:27:47.954][DEBUG] [Focuser Move Thread] Focuser move call complete [02/04/18 20:27:48.976][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1518 matches requested position 1518 [02/04/18 20:27:48.976][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [02/04/18 20:27:48.992][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [02/04/18 20:27:48.992][DEBUG] [Camera Thread] Focuser move complete... [02/04/18 20:27:49.009][DEBUG] [Camera Thread] Focuser position matches requested position (1518), continuing... [02/04/18 20:27:49.025][DEBUG] [Camera Thread] Auto focus settling before next frame... [02/04/18 20:27:49.026][DEBUG] [Camera Thread] Calculating step metric... [02/04/18 20:27:49.026][DEBUG] [Camera Thread] Taking auto focus frame(s)... [02/04/18 20:27:49.028][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [02/04/18 20:27:49.028][DEBUG] [Camera Thread] ASCOM: AF Binning: 1x1... [02/04/18 20:27:49.028][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 1 seconds... [02/04/18 20:27:49.028][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: b [02/04/18 20:27:49.030][DEBUG] [Camera Thread] SetAscomNormalSpeed... [02/04/18 20:27:49.030][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [02/04/18 20:27:53.351][DEBUG] [Camera Thread] SaveFileAscom: Start... [02/04/18 20:27:53.351][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [02/04/18 20:27:53.351][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [02/04/18 20:27:53.380][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [02/04/18 20:27:53.380][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [02/04/18 20:27:53.787][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [02/04/18 20:27:53.787][DEBUG] [Camera Thread] SaveFileAscom: Done [02/04/18 20:27:53.787][DEBUG] [Camera Thread] Internal Interface: Set Preview... [02/04/18 20:27:53.787][DEBUG] [Camera Thread] Display image preview using asynch task... [02/04/18 20:27:54.853][DEBUG] [Main Thread] --> Find stars (normal) [02/04/18 20:27:55.044][DEBUG] [Main Thread] AF frame was too large... downsample = 0.5... [02/04/18 20:27:58.792][DEBUG] [Main Thread] Star detection using min star size of 3px... [02/04/18 20:27:58.792][DEBUG] [Main Thread] Star detection using max star size of 60px... [02/04/18 20:27:58.922][DEBUG] [Main Thread] Find stars took: 4067 ms... [02/04/18 20:27:58.922][DEBUG] [Main Thread] Star list contains 29 stars... [02/04/18 20:27:58.922][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [02/04/18 20:27:59.642][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.63 [02/04/18 20:28:00.651][DEBUG] [Camera Thread] Moving focuser to next position (1508)... [02/04/18 20:28:00.661][DEBUG] [Focuser Move Thread] Focuser moving to 1508 [02/04/18 20:28:00.717][DEBUG] [Focuser Move Thread] Focuser move call complete [02/04/18 20:28:01.738][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1508 matches requested position 1508 [02/04/18 20:28:01.738][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [02/04/18 20:28:01.753][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [02/04/18 20:28:01.754][DEBUG] [Camera Thread] Focuser move complete... [02/04/18 20:28:01.769][DEBUG] [Camera Thread] Focuser position matches requested position (1508), continuing... [02/04/18 20:28:01.786][DEBUG] [Camera Thread] Auto focus settling before next frame... [02/04/18 20:28:01.787][DEBUG] [Camera Thread] Calculating step metric... [02/04/18 20:28:01.788][DEBUG] [Camera Thread] Taking auto focus frame(s)... [02/04/18 20:28:01.789][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [02/04/18 20:28:01.789][DEBUG] [Camera Thread] ASCOM: AF Binning: 1x1... [02/04/18 20:28:01.789][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 1 seconds... [02/04/18 20:28:01.789][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: b [02/04/18 20:28:01.791][DEBUG] [Camera Thread] SetAscomNormalSpeed... [02/04/18 20:28:01.791][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [02/04/18 20:28:06.059][DEBUG] [Camera Thread] SaveFileAscom: Start... [02/04/18 20:28:06.059][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [02/04/18 20:28:06.059][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [02/04/18 20:28:06.077][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [02/04/18 20:28:06.077][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [02/04/18 20:28:06.476][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [02/04/18 20:28:06.476][DEBUG] [Camera Thread] SaveFileAscom: Done [02/04/18 20:28:06.476][DEBUG] [Camera Thread] Internal Interface: Set Preview... [02/04/18 20:28:06.476][DEBUG] [Camera Thread] Display image preview using asynch task... [02/04/18 20:28:07.457][DEBUG] [Main Thread] --> Find stars (normal) [02/04/18 20:28:07.659][DEBUG] [Main Thread] AF frame was too large... downsample = 0.5... [02/04/18 20:28:11.436][DEBUG] [Main Thread] Star detection using min star size of 3px... [02/04/18 20:28:11.436][DEBUG] [Main Thread] Star detection using max star size of 60px... [02/04/18 20:28:11.518][DEBUG] [Main Thread] Find stars took: 4061 ms... [02/04/18 20:28:11.518][DEBUG] [Main Thread] Star list contains 32 stars... [02/04/18 20:28:11.518][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [02/04/18 20:28:12.858][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.47 [02/04/18 20:28:13.863][DEBUG] [Camera Thread] Moving focuser to next position (1498)... [02/04/18 20:28:13.864][DEBUG] [Focuser Move Thread] Focuser moving to 1498 [02/04/18 20:28:13.940][DEBUG] [Focuser Move Thread] Focuser move call complete [02/04/18 20:28:14.962][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1498 matches requested position 1498 [02/04/18 20:28:14.962][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [02/04/18 20:28:14.962][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [02/04/18 20:28:14.962][DEBUG] [Camera Thread] Focuser move complete... [02/04/18 20:28:14.978][DEBUG] [Camera Thread] Focuser position matches requested position (1498), continuing... [02/04/18 20:28:14.994][DEBUG] [Camera Thread] Auto focus settling before next frame... [02/04/18 20:28:14.995][DEBUG] [Camera Thread] Calculating step metric... [02/04/18 20:28:14.995][DEBUG] [Camera Thread] Taking auto focus frame(s)... [02/04/18 20:28:14.997][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [02/04/18 20:28:14.997][DEBUG] [Camera Thread] ASCOM: AF Binning: 1x1... [02/04/18 20:28:14.997][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 1 seconds... [02/04/18 20:28:14.997][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: b [02/04/18 20:28:14.999][DEBUG] [Camera Thread] SetAscomNormalSpeed... [02/04/18 20:28:14.999][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [02/04/18 20:28:19.281][DEBUG] [Camera Thread] SaveFileAscom: Start... [02/04/18 20:28:19.281][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [02/04/18 20:28:19.281][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [02/04/18 20:28:19.300][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [02/04/18 20:28:19.300][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [02/04/18 20:28:19.700][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [02/04/18 20:28:19.700][DEBUG] [Camera Thread] SaveFileAscom: Done [02/04/18 20:28:19.700][DEBUG] [Camera Thread] Internal Interface: Set Preview... [02/04/18 20:28:19.700][DEBUG] [Camera Thread] Display image preview using asynch task... [02/04/18 20:28:20.650][DEBUG] [Main Thread] --> Find stars (normal) [02/04/18 20:28:20.871][DEBUG] [Main Thread] AF frame was too large... downsample = 0.5... [02/04/18 20:28:24.553][DEBUG] [Main Thread] Star detection using min star size of 3px... [02/04/18 20:28:24.553][DEBUG] [Main Thread] Star detection using max star size of 60px... [02/04/18 20:28:24.638][DEBUG] [Main Thread] Find stars took: 3987 ms... [02/04/18 20:28:24.638][DEBUG] [Main Thread] Star list contains 31 stars... [02/04/18 20:28:24.638][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [02/04/18 20:28:25.951][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.47 [02/04/18 20:28:26.956][DEBUG] [Camera Thread] Moving focuser to next position (1488)... [02/04/18 20:28:26.957][DEBUG] [Focuser Move Thread] Focuser moving to 1488 [02/04/18 20:28:27.004][DEBUG] [Focuser Move Thread] Focuser move call complete [02/04/18 20:28:28.026][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1488 matches requested position 1488 [02/04/18 20:28:28.026][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [02/04/18 20:28:28.045][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [02/04/18 20:28:28.045][DEBUG] [Camera Thread] Focuser move complete... [02/04/18 20:28:28.058][DEBUG] [Camera Thread] Focuser position matches requested position (1488), continuing... [02/04/18 20:28:28.074][DEBUG] [Camera Thread] Auto focus settling before next frame... [02/04/18 20:28:28.075][DEBUG] [Camera Thread] Calculating step metric... [02/04/18 20:28:28.075][DEBUG] [Camera Thread] Taking auto focus frame(s)... [02/04/18 20:28:28.077][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [02/04/18 20:28:28.077][DEBUG] [Camera Thread] ASCOM: AF Binning: 1x1... [02/04/18 20:28:28.077][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 1 seconds... [02/04/18 20:28:28.077][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: b [02/04/18 20:28:28.078][DEBUG] [Camera Thread] SetAscomNormalSpeed... [02/04/18 20:28:28.079][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [02/04/18 20:28:32.846][DEBUG] [Camera Thread] SaveFileAscom: Start... [02/04/18 20:28:32.846][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [02/04/18 20:28:32.846][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [02/04/18 20:28:32.862][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [02/04/18 20:28:32.863][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [02/04/18 20:28:33.124][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [02/04/18 20:28:33.124][DEBUG] [Camera Thread] SaveFileAscom: Done [02/04/18 20:28:33.124][DEBUG] [Camera Thread] Internal Interface: Set Preview... [02/04/18 20:28:33.124][DEBUG] [Camera Thread] Display image preview using asynch task... [02/04/18 20:28:34.086][DEBUG] [Main Thread] --> Find stars (normal) [02/04/18 20:28:34.287][DEBUG] [Main Thread] AF frame was too large... downsample = 0.5... [02/04/18 20:28:38.031][DEBUG] [Main Thread] Star detection using min star size of 3px... [02/04/18 20:28:38.031][DEBUG] [Main Thread] Star detection using max star size of 60px... [02/04/18 20:28:38.118][DEBUG] [Main Thread] Find stars took: 4032 ms... [02/04/18 20:28:38.118][DEBUG] [Main Thread] Star list contains 29 stars... [02/04/18 20:28:38.118][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [02/04/18 20:28:39.429][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.73 [02/04/18 20:28:40.452][DEBUG] [Camera Thread] Moving focuser to next position (1478)... [02/04/18 20:28:40.453][DEBUG] [Focuser Move Thread] Focuser moving to 1478 [02/04/18 20:28:40.500][DEBUG] [Focuser Move Thread] Focuser move call complete [02/04/18 20:28:41.522][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1478 matches requested position 1478 [02/04/18 20:28:41.522][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [02/04/18 20:28:41.522][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [02/04/18 20:28:41.522][DEBUG] [Camera Thread] Focuser move complete... [02/04/18 20:28:41.538][DEBUG] [Camera Thread] Focuser position matches requested position (1478), continuing... [02/04/18 20:28:41.554][DEBUG] [Camera Thread] Auto focus settling before next frame... [02/04/18 20:28:41.555][DEBUG] [Camera Thread] Calculating step metric... [02/04/18 20:28:41.555][DEBUG] [Camera Thread] Taking auto focus frame(s)... [02/04/18 20:28:41.561][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [02/04/18 20:28:41.561][DEBUG] [Camera Thread] ASCOM: AF Binning: 1x1... [02/04/18 20:28:41.561][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 1 seconds... [02/04/18 20:28:41.561][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: b [02/04/18 20:28:41.562][DEBUG] [Camera Thread] SetAscomNormalSpeed... [02/04/18 20:28:41.562][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [02/04/18 20:28:43.652][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [02/04/18 20:28:43.654][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [02/04/18 20:28:43.654][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [02/04/18 20:28:43.654][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [02/04/18 20:28:43.654][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [02/04/18 20:28:45.863][DEBUG] [Camera Thread] SaveFileAscom: Start... [02/04/18 20:28:45.863][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [02/04/18 20:28:45.863][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [02/04/18 20:28:45.883][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [02/04/18 20:28:45.883][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [02/04/18 20:28:46.319][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [02/04/18 20:28:46.320][DEBUG] [Camera Thread] SaveFileAscom: Done [02/04/18 20:28:46.320][DEBUG] [Camera Thread] Internal Interface: Set Preview... [02/04/18 20:28:46.320][DEBUG] [Camera Thread] Display image preview using asynch task... [02/04/18 20:28:47.350][DEBUG] [Main Thread] --> Find stars (normal) [02/04/18 20:28:47.540][DEBUG] [Main Thread] AF frame was too large... downsample = 0.5... [02/04/18 20:28:51.348][DEBUG] [Main Thread] Star detection using min star size of 3px... [02/04/18 20:28:51.348][DEBUG] [Main Thread] Star detection using max star size of 60px... [02/04/18 20:28:51.441][DEBUG] [Main Thread] Find stars took: 4089 ms... [02/04/18 20:28:51.441][DEBUG] [Main Thread] Star list contains 28 stars... [02/04/18 20:28:51.441][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [02/04/18 20:28:52.673][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.79 [02/04/18 20:28:53.681][DEBUG] [Camera Thread] Moving focuser to next position (1468)... [02/04/18 20:28:53.681][DEBUG] [Focuser Move Thread] Focuser moving to 1468 [02/04/18 20:28:53.768][DEBUG] [Focuser Move Thread] Focuser move call complete [02/04/18 20:28:54.794][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1468 matches requested position 1468 [02/04/18 20:28:54.794][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [02/04/18 20:28:54.794][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [02/04/18 20:28:54.794][DEBUG] [Camera Thread] Focuser move complete... [02/04/18 20:28:54.810][DEBUG] [Camera Thread] Focuser position matches requested position (1468), continuing... [02/04/18 20:28:54.826][DEBUG] [Camera Thread] Auto focus settling before next frame... [02/04/18 20:28:54.828][DEBUG] [Camera Thread] Calculating step metric... [02/04/18 20:28:54.828][DEBUG] [Camera Thread] Taking auto focus frame(s)... [02/04/18 20:28:54.830][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [02/04/18 20:28:54.830][DEBUG] [Camera Thread] ASCOM: AF Binning: 1x1... [02/04/18 20:28:54.830][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 1 seconds... [02/04/18 20:28:54.830][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: b [02/04/18 20:28:54.832][DEBUG] [Camera Thread] SetAscomNormalSpeed... [02/04/18 20:28:54.832][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [02/04/18 20:28:59.102][DEBUG] [Camera Thread] SaveFileAscom: Start... [02/04/18 20:28:59.103][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [02/04/18 20:28:59.103][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [02/04/18 20:28:59.120][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [02/04/18 20:28:59.120][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [02/04/18 20:28:59.524][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [02/04/18 20:28:59.528][DEBUG] [Camera Thread] SaveFileAscom: Done [02/04/18 20:28:59.528][DEBUG] [Camera Thread] Internal Interface: Set Preview... [02/04/18 20:28:59.528][DEBUG] [Camera Thread] Display image preview using asynch task... [02/04/18 20:29:00.441][DEBUG] [Main Thread] --> Find stars (normal) [02/04/18 20:29:00.609][DEBUG] [Main Thread] AF frame was too large... downsample = 0.5... [02/04/18 20:29:03.908][DEBUG] [Main Thread] Star detection using min star size of 3px... [02/04/18 20:29:03.908][DEBUG] [Main Thread] Star detection using max star size of 60px... [02/04/18 20:29:04.058][DEBUG] [Main Thread] Find stars took: 3617 ms... [02/04/18 20:29:04.058][DEBUG] [Main Thread] Star list contains 27 stars... [02/04/18 20:29:04.058][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [02/04/18 20:29:05.160][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.77 [02/04/18 20:29:06.184][DEBUG] [Camera Thread] Moving focuser to next position (1458)... [02/04/18 20:29:06.188][DEBUG] [Focuser Move Thread] Focuser moving to 1458 [02/04/18 20:29:06.276][DEBUG] [Focuser Move Thread] Focuser move call complete [02/04/18 20:29:07.300][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1458 matches requested position 1458 [02/04/18 20:29:07.300][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [02/04/18 20:29:07.300][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [02/04/18 20:29:07.300][DEBUG] [Camera Thread] Focuser move complete... [02/04/18 20:29:07.316][DEBUG] [Camera Thread] Focuser position matches requested position (1458), continuing... [02/04/18 20:29:07.332][DEBUG] [Camera Thread] Auto focus settling before next frame... [02/04/18 20:29:07.334][DEBUG] [Camera Thread] Calculating step metric... [02/04/18 20:29:07.334][DEBUG] [Camera Thread] Taking auto focus frame(s)... [02/04/18 20:29:07.335][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [02/04/18 20:29:07.336][DEBUG] [Camera Thread] ASCOM: AF Binning: 1x1... [02/04/18 20:29:07.336][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 1 seconds... [02/04/18 20:29:07.336][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: b [02/04/18 20:29:07.337][DEBUG] [Camera Thread] SetAscomNormalSpeed... [02/04/18 20:29:07.337][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [02/04/18 20:29:11.668][DEBUG] [Camera Thread] SaveFileAscom: Start... [02/04/18 20:29:11.668][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [02/04/18 20:29:11.668][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [02/04/18 20:29:11.685][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [02/04/18 20:29:11.685][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [02/04/18 20:29:12.049][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [02/04/18 20:29:12.049][DEBUG] [Camera Thread] SaveFileAscom: Done [02/04/18 20:29:12.049][DEBUG] [Camera Thread] Internal Interface: Set Preview... [02/04/18 20:29:12.049][DEBUG] [Camera Thread] Display image preview using asynch task... [02/04/18 20:29:13.096][DEBUG] [Main Thread] --> Find stars (normal) [02/04/18 20:29:13.283][DEBUG] [Main Thread] AF frame was too large... downsample = 0.5... [02/04/18 20:29:16.962][DEBUG] [Main Thread] Star detection using min star size of 3px... [02/04/18 20:29:16.963][DEBUG] [Main Thread] Star detection using max star size of 60px... [02/04/18 20:29:17.046][DEBUG] [Main Thread] Find stars took: 3950 ms... [02/04/18 20:29:17.046][DEBUG] [Main Thread] Star list contains 26 stars... [02/04/18 20:29:17.046][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [02/04/18 20:29:18.243][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.89 [02/04/18 20:29:19.251][DEBUG] [Camera Thread] Moving focuser to next position (1448)... [02/04/18 20:29:19.251][DEBUG] [Focuser Move Thread] Focuser moving to 1448 [02/04/18 20:29:19.326][DEBUG] [Focuser Move Thread] Focuser move call complete [02/04/18 20:29:20.379][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1448 matches requested position 1448 [02/04/18 20:29:20.379][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [02/04/18 20:29:20.379][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [02/04/18 20:29:20.379][DEBUG] [Camera Thread] Focuser move complete... [02/04/18 20:29:20.395][DEBUG] [Camera Thread] Focuser position matches requested position (1448), continuing... [02/04/18 20:29:20.412][DEBUG] [Camera Thread] Auto focus settling before next frame... [02/04/18 20:29:20.413][DEBUG] [Camera Thread] Calculating step metric... [02/04/18 20:29:20.413][DEBUG] [Camera Thread] Taking auto focus frame(s)... [02/04/18 20:29:20.414][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [02/04/18 20:29:20.414][DEBUG] [Camera Thread] ASCOM: AF Binning: 1x1... [02/04/18 20:29:20.414][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 1 seconds... [02/04/18 20:29:20.415][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: b [02/04/18 20:29:20.416][DEBUG] [Camera Thread] SetAscomNormalSpeed... [02/04/18 20:29:20.416][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [02/04/18 20:29:24.687][DEBUG] [Camera Thread] SaveFileAscom: Start... [02/04/18 20:29:24.687][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [02/04/18 20:29:24.687][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [02/04/18 20:29:24.705][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [02/04/18 20:29:24.706][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [02/04/18 20:29:25.077][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [02/04/18 20:29:25.078][DEBUG] [Camera Thread] SaveFileAscom: Done [02/04/18 20:29:25.078][DEBUG] [Camera Thread] Internal Interface: Set Preview... [02/04/18 20:29:25.078][DEBUG] [Camera Thread] Display image preview using asynch task... [02/04/18 20:29:26.082][DEBUG] [Main Thread] --> Find stars (normal) [02/04/18 20:29:26.271][DEBUG] [Main Thread] AF frame was too large... downsample = 0.5... [02/04/18 20:29:30.155][DEBUG] [Main Thread] Star detection using min star size of 3px... [02/04/18 20:29:30.155][DEBUG] [Main Thread] Star detection using max star size of 60px... [02/04/18 20:29:30.242][DEBUG] [Main Thread] Find stars took: 4159 ms... [02/04/18 20:29:30.242][DEBUG] [Main Thread] Star list contains 23 stars... [02/04/18 20:29:30.242][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [02/04/18 20:29:31.414][DEBUG] [Camera Thread] Auto focus HFR calculated at: 4.11 [02/04/18 20:29:32.425][DEBUG] [Camera Thread] Moving focuser to next position (1438)... [02/04/18 20:29:32.427][DEBUG] [Focuser Move Thread] Focuser moving to 1438 [02/04/18 20:29:32.470][DEBUG] [Focuser Move Thread] Focuser move call complete [02/04/18 20:29:33.492][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1438 matches requested position 1438 [02/04/18 20:29:33.492][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [02/04/18 20:29:33.492][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [02/04/18 20:29:33.492][DEBUG] [Camera Thread] Focuser move complete... [02/04/18 20:29:33.508][DEBUG] [Camera Thread] Focuser position matches requested position (1438), continuing... [02/04/18 20:29:33.540][DEBUG] [Camera Thread] Auto focus settling before next frame... [02/04/18 20:29:33.542][DEBUG] [Camera Thread] Calculating step metric... [02/04/18 20:29:33.542][DEBUG] [Camera Thread] Taking auto focus frame(s)... [02/04/18 20:29:33.543][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [02/04/18 20:29:33.543][DEBUG] [Camera Thread] ASCOM: AF Binning: 1x1... [02/04/18 20:29:33.543][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 1 seconds... [02/04/18 20:29:33.543][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: b [02/04/18 20:29:33.545][DEBUG] [Camera Thread] SetAscomNormalSpeed... [02/04/18 20:29:33.545][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [02/04/18 20:29:37.879][DEBUG] [Camera Thread] SaveFileAscom: Start... [02/04/18 20:29:37.879][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [02/04/18 20:29:37.879][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [02/04/18 20:29:37.898][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [02/04/18 20:29:37.898][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [02/04/18 20:29:38.263][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [02/04/18 20:29:38.263][DEBUG] [Camera Thread] SaveFileAscom: Done [02/04/18 20:29:38.263][DEBUG] [Camera Thread] Internal Interface: Set Preview... [02/04/18 20:29:38.263][DEBUG] [Camera Thread] Display image preview using asynch task... [02/04/18 20:29:39.250][DEBUG] [Main Thread] --> Find stars (normal) [02/04/18 20:29:39.438][DEBUG] [Main Thread] AF frame was too large... downsample = 0.5... [02/04/18 20:29:43.208][DEBUG] [Main Thread] Star detection using min star size of 3px... [02/04/18 20:29:43.208][DEBUG] [Main Thread] Star detection using max star size of 60px... [02/04/18 20:29:43.291][DEBUG] [Main Thread] Find stars took: 4040 ms... [02/04/18 20:29:43.291][DEBUG] [Main Thread] Star list contains 22 stars... [02/04/18 20:29:43.291][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [02/04/18 20:29:43.757][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [02/04/18 20:29:43.757][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [02/04/18 20:29:43.757][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [02/04/18 20:29:43.757][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [02/04/18 20:29:43.757][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [02/04/18 20:29:44.484][DEBUG] [Camera Thread] Auto focus HFR calculated at: 4.44 [02/04/18 20:29:45.492][DEBUG] [Camera Thread] AF Have >= 6 left slope and >= 3 right slope but no fit. Something isn't right here! [02/04/18 20:29:45.515][DEBUG] [Camera Thread] New auto focus method calculated focus at: [02/04/18 20:29:45.515][DEBUG] [Camera Thread] Old auto focus method calculated focus at: 1503 [02/04/18 20:29:45.515][DEBUG] [Camera Thread] Unable to calculate focus with best fit lines, defaulting to weighted average of low HFRs... [02/04/18 20:29:45.515][DEBUG] [Camera Thread] Using old auto focus method for focuser position [02/04/18 20:29:45.516][DEBUG] [Camera Thread] New focus position is at 1503 (@10.59C). Moving focuser... [02/04/18 20:29:45.523][DEBUG] [Focuser Move Thread] Focuser moving to 1503 [02/04/18 20:29:45.548][DEBUG] [Focuser Move Thread] Focuser backlash active, modified move to 1703 [02/04/18 20:29:45.583][DEBUG] [Focuser Move Thread] Focuser move call complete [02/04/18 20:29:45.583][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has started... [02/04/18 20:29:49.623][DEBUG] [Focuser Backlash Thread] Backlash thread has detected that movement to modified position (1703) is complete... [02/04/18 20:29:49.623][DEBUG] [Focuser Backlash Thread] Moving focuser to original requested position (1503)... [02/04/18 20:29:49.623][DEBUG] [Focuser Backlash Thread] Focuser backlash compensation needed. Steps: 200 Direction:IN [02/04/18 20:29:49.623][DEBUG] [Focuser Backlash Thread] Focuser moving to 1503 [02/04/18 20:29:49.671][DEBUG] [Focuser Backlash Thread] Focuser move call complete [02/04/18 20:29:51.699][DEBUG] [Focuser Backlash Thread] Focuser backlash completed. Focuser is at original request position (1503)... [02/04/18 20:29:51.699][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has ended... [02/04/18 20:29:51.715][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1503 matches requested position 1503 [02/04/18 20:29:51.715][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [02/04/18 20:29:51.715][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [02/04/18 20:29:51.715][DEBUG] [Camera Thread] Focuser move complete... [02/04/18 20:29:51.731][DEBUG] [Camera Thread] Focuser successfully set to focus position (1503) [02/04/18 20:29:51.747][DEBUG] [Camera Thread] Taking validation frame at focus position... [02/04/18 20:29:51.748][DEBUG] [Camera Thread] Taking auto focus frame(s)... [02/04/18 20:29:51.749][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [02/04/18 20:29:51.749][DEBUG] [Camera Thread] ASCOM: AF Binning: 1x1... [02/04/18 20:29:51.749][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 1 seconds... [02/04/18 20:29:51.749][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: b [02/04/18 20:29:51.751][DEBUG] [Camera Thread] SetAscomNormalSpeed... [02/04/18 20:29:51.752][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [02/04/18 20:29:56.008][DEBUG] [Camera Thread] SaveFileAscom: Start... [02/04/18 20:29:56.008][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [02/04/18 20:29:56.008][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [02/04/18 20:29:56.027][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [02/04/18 20:29:56.027][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [02/04/18 20:29:56.297][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [02/04/18 20:29:56.297][DEBUG] [Camera Thread] SaveFileAscom: Done [02/04/18 20:29:56.297][DEBUG] [Camera Thread] Internal Interface: Set Preview... [02/04/18 20:29:56.297][DEBUG] [Camera Thread] Display image preview using asynch task... [02/04/18 20:29:57.311][DEBUG] [Main Thread] --> Find stars (normal) [02/04/18 20:29:57.523][DEBUG] [Main Thread] AF frame was too large... downsample = 0.5... [02/04/18 20:30:01.480][DEBUG] [Main Thread] Star detection using min star size of 3px... [02/04/18 20:30:01.480][DEBUG] [Main Thread] Star detection using max star size of 60px... [02/04/18 20:30:01.576][DEBUG] [Main Thread] Find stars took: 4263 ms... [02/04/18 20:30:01.576][DEBUG] [Main Thread] Star list contains 28 stars... [02/04/18 20:30:01.576][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [02/04/18 20:30:02.762][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.49 [02/04/18 20:30:03.764][DEBUG] [Camera Thread] Auto focus is complete (weighted avg method: 1503; HFR 3.49)... [02/04/18 20:30:03.767][DEBUG] [Camera Thread] Entering chart disposal loop... [02/04/18 20:30:43.871][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [02/04/18 20:30:43.871][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [02/04/18 20:30:43.871][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [02/04/18 20:30:43.871][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [02/04/18 20:30:43.871][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [02/04/18 20:30:51.394][DEBUG] [AfChartThread] AF chart dialog is closed... [02/04/18 20:30:51.408][DEBUG] [Camera Thread] Chart disposal loop is complete... [02/04/18 20:30:51.408][DEBUG] [Camera Thread] Auto focus is done... [02/04/18 20:30:51.445][DEBUG] [Camera Thread] Auto focus complete... [02/04/18 20:30:51.492][DEBUG] [Camera Thread] SGM_FOCUSER_AUTO_FOCUS complete... [02/04/18 20:31:03.183][DEBUG] [Main Thread] Turning camera cooler off... [02/04/18 20:31:03.193][DEBUG] [Main Thread] Disconnecting ASCOM camera: ASI Camera (1)... [02/04/18 20:31:03.408][DEBUG] [Main Thread] Turning camera cooler off... [02/04/18 20:31:05.491][DEBUG] [Main Thread] Disconnecting ASCOM Focuser: ASCOM.MoonliteDRO.Focuser [02/04/18 20:31:06.624][DEBUG] [Main Thread] Disconnecting ASCOM Telescope: ASCOM.iOptron.Telescope [02/04/18 20:31:14.941][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:31:24.954][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:31:34.995][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:31:43.992][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [02/04/18 20:31:43.992][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [02/04/18 20:31:43.992][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [02/04/18 20:31:43.992][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [02/04/18 20:31:43.992][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [02/04/18 20:31:45.040][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:31:51.548][DEBUG] [Main Thread] Connecting camera in main thread... [02/04/18 20:31:51.557][DEBUG] [Main Thread] Connecting ASCOM camera: ASCOM.ASICamera2.Camera... [02/04/18 20:31:53.129][DEBUG] [Main Thread] Camera implements ASCOM gain. Current gain is 104 [02/04/18 20:31:53.132][DEBUG] [Main Thread] Failed to fetch ASCOM camera gains list, checking min / max... : Property read ASCOM.ASICamera2.Camera Gains is not implemented in this driver. (System.Reflection.TargetInvocationException: Exception has been thrown by the target of an invocation. ---> System.Runtime.InteropServices.COMException: Property Unknown is not implemented in this driver. --- End of inner exception stack trace --- at System.RuntimeType.InvokeDispMethod(String name, BindingFlags invokeAttr, Object target, Object[] args, Boolean[] byrefModifiers, Int32 culture, String[] namedParameters) at System.RuntimeType.InvokeMember(String name, BindingFlags bindingFlags, Binder binder, Object target, Object[] providedArgs, ParameterModifier[] modifiers, CultureInfo culture, String[] namedParams) at System.Type.InvokeMember(String name, BindingFlags invokeAttr, Binder binder, Object target, Object[] args, CultureInfo culture) at ASCOM.DriverAccess.MemberFactory.CallMember(Int32 memberCode, String memberName, Type[] parameterTypes, Object[] parms) in c:\ASCOM Build\Export\ASCOM.DriverAccess\MemberFactory.cs:line 243) at ASCOM.DriverAccess.MemberFactory.CallMember(Int32 memberCode, String memberName, Type[] parameterTypes, Object[] parms) in c:\ASCOM Build\Export\ASCOM.DriverAccess\MemberFactory.cs:line 279 at ASCOM.DriverAccess.Camera.get_Gains() in c:\ASCOM Build\Export\ASCOM.DriverAccess\Camera.cs:line 864 at q5.bz() [02/04/18 20:31:53.149][DEBUG] [Main Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:31:53.150][DEBUG] [Main Thread] Camera cooler detected... [02/04/18 20:31:53.150][DEBUG] [Main Thread] Camera cooler is OFF... [02/04/18 20:31:53.150][DEBUG] [Main Thread] Camera cooldown on connect is selected... [02/04/18 20:31:53.150][DEBUG] [Main Thread] Camera cooldown is starting... [02/04/18 20:31:53.154][DEBUG] [TEC Thread] SGM_CHANGE_COOLER_TEMP message received... [02/04/18 20:31:53.154][DEBUG] [TEC Thread] TEC Change: Starting... [02/04/18 20:31:53.154][DEBUG] [TEC Thread] TEC Change: After camera connect change, waiting ~5 seconds... [02/04/18 20:31:55.097][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:31:58.154][DEBUG] [TEC Thread] TEC Change: Attempting to turn the camera cooler on... [02/04/18 20:31:58.154][DEBUG] [TEC Thread] Turning camera cooler on... [02/04/18 20:31:58.492][DEBUG] [Main Thread] Turning camera cooler on... [02/04/18 20:32:03.159][DEBUG] [TEC Thread] TEC Change: Changing temp from -3.40 to -20.00 in 300 seconds... [02/04/18 20:32:05.149][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:32:15.154][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:32:25.173][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:32:35.211][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:32:44.136][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [02/04/18 20:32:44.136][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [02/04/18 20:32:44.136][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [02/04/18 20:32:44.136][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [02/04/18 20:32:44.136][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [02/04/18 20:32:45.285][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:32:55.362][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:33:04.970][DEBUG] [TEC Thread] TEC Change: Aborted... [02/04/18 20:33:04.970][DEBUG] [TEC Thread] SGM_CHANGE_COOLER_TEMP complete... [02/04/18 20:33:05.434][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:33:07.375][DEBUG] [TEC Thread] SGM_CHANGE_COOLER_TEMP message received... [02/04/18 20:33:07.375][DEBUG] [TEC Thread] TEC Change: Starting... [02/04/18 20:33:07.375][DEBUG] [TEC Thread] TEC Change: Changing temp from -3.00 to 20.00 in 480 seconds... [02/04/18 20:33:15.523][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:33:25.594][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:33:35.641][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:33:44.285][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [02/04/18 20:33:44.285][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [02/04/18 20:33:44.285][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [02/04/18 20:33:44.285][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [02/04/18 20:33:44.285][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [02/04/18 20:33:45.684][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:33:55.742][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:34:05.804][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:34:15.857][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:34:25.923][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:34:35.925][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:34:44.398][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [02/04/18 20:34:44.398][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [02/04/18 20:34:44.398][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [02/04/18 20:34:44.398][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [02/04/18 20:34:44.398][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [02/04/18 20:34:45.979][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:34:56.030][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:35:06.111][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:35:16.163][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:35:26.195][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:35:36.242][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:35:44.521][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [02/04/18 20:35:44.521][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [02/04/18 20:35:44.521][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [02/04/18 20:35:44.521][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [02/04/18 20:35:44.521][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [02/04/18 20:35:46.303][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:35:56.348][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:36:06.399][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:36:16.412][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:36:26.462][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:36:36.517][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:36:44.638][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [02/04/18 20:36:44.638][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [02/04/18 20:36:44.638][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [02/04/18 20:36:44.638][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [02/04/18 20:36:44.638][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [02/04/18 20:36:46.595][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:36:56.672][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:37:06.681][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:37:16.758][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:37:26.835][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:37:36.888][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:37:44.747][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [02/04/18 20:37:44.747][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [02/04/18 20:37:44.747][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [02/04/18 20:37:44.747][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [02/04/18 20:37:44.747][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [02/04/18 20:37:46.958][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:37:57.024][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:38:07.102][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:38:17.192][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:38:27.277][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:38:37.328][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:38:44.888][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [02/04/18 20:38:44.888][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [02/04/18 20:38:44.888][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [02/04/18 20:38:44.888][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [02/04/18 20:38:44.888][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [02/04/18 20:38:47.336][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:38:57.361][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:39:07.427][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:39:17.436][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:39:27.443][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:39:37.536][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:39:44.996][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [02/04/18 20:39:44.996][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [02/04/18 20:39:44.996][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [02/04/18 20:39:44.996][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [02/04/18 20:39:44.996][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [02/04/18 20:39:47.563][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:39:57.602][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:40:07.635][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:40:17.668][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:40:27.689][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:40:37.726][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:40:45.145][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [02/04/18 20:40:45.145][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [02/04/18 20:40:45.145][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [02/04/18 20:40:45.145][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [02/04/18 20:40:45.145][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [02/04/18 20:40:47.737][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:40:57.780][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:41:07.402][DEBUG] [TEC Thread] TEC Change: Complete... [02/04/18 20:41:07.403][DEBUG] [TEC Thread] Turning camera cooler off... [02/04/18 20:41:07.404][DEBUG] [TEC Thread] SGM_CHANGE_COOLER_TEMP complete... [02/04/18 20:41:07.692][DEBUG] [Main Thread] Turning camera cooler off... [02/04/18 20:41:07.806][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:41:17.845][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:41:27.856][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:41:32.778][DEBUG] [Main Thread] Disconnecting ASCOM camera: ASI Camera (1)... [02/04/18 20:41:32.820][DEBUG] [Main Thread] Caught exception in CanPierFlipNow : Object reference not set to an instance of an object. at qn.kw() [02/04/18 20:41:32.828][DEBUG] [Main Thread] Sending to PHD2: {"method":"set_connected","params":[false],"id":1007} [02/04/18 20:41:35.346][DEBUG] [Camera Thread] Camera Dispatch loop: Received SGM_TERMINATE... [02/04/18 20:41:35.347][DEBUG] [Telescope Thread] Telescope Dispatch loop: Received SGM_TERMINATE... [02/04/18 20:41:35.348][DEBUG] [Focuser Thread] Focuser Dispatch loop: Received SGM_TERMINATE... [02/04/18 20:41:35.348][DEBUG] [Auto Guider Thread] Auto Guider Dispatch loop: Received SGM_TERMINATE... [02/04/18 20:41:35.348][DEBUG] [Sequence Thread] Dispatch loop: Received SGM_TERMINATE... [02/04/18 20:41:35.349][DEBUG] [TEC Thread] TEC Dispatch loop: Received SGM_TERMINATE... [02/04/18 20:41:35.349][DEBUG] [Filter Wheel Thread] Filter Wheel Dispatch loop: Received SGM_TERMINATE... [02/04/18 20:41:35.400][DEBUG] [Main Thread] Performing clean up...