[10/05/17 19:26:44.330][DEBUG] [Main Thread] Licensing: Machine ID found... [10/05/17 19:26:44.335][DEBUG] [Main Thread] Checking for valid internet connection... [10/05/17 19:26:44.565][DEBUG] [Main Thread] Valid internet connection detected... [10/05/17 19:26:45.495][DEBUG] [Main Thread] Licensing: Request for licenses successful, writing cookie... [10/05/17 19:26:45.505][DEBUG] [Main Thread] Licensing: Applying licenses.. [10/05/17 19:26:45.505][DEBUG] [Main Thread] Licensing: Licenses applied.. [10/05/17 19:26:45.510][DEBUG] [Main Thread] SGPro trial license... [10/05/17 19:26:46.535][DEBUG] [Main Thread] API successfully started on port 59590... [10/05/17 19:26:47.237][DEBUG] [Main Thread] Error loading notification settings! Could not find file 'C:\Users\Wes\AppData\Local\SequenceGenerator\Sequence Generator Pro\notifications.sgd'. [10/05/17 19:26:47.244][DEBUG] [Main Thread] Error loading GNS endpoint settings! Could not find file 'C:\Users\Wes\AppData\Local\SequenceGenerator\Sequence Generator Pro\gnsSettings.sgd'. [10/05/17 19:26:47.245][DEBUG] [Main Thread] Error loading email endpoint addresses! Could not find file 'C:\Users\Wes\AppData\Local\SequenceGenerator\Sequence Generator Pro\1-emailEndpointAddresses.sgd'. [10/05/17 19:26:47.246][DEBUG] [Main Thread] Error loading email endpoint addresses! Could not find file 'C:\Users\Wes\AppData\Local\SequenceGenerator\Sequence Generator Pro\2-emailEndpointAddresses.sgd'. [10/05/17 19:26:47.246][DEBUG] [Main Thread] Error loading email endpoint addresses! Could not find file 'C:\Users\Wes\AppData\Local\SequenceGenerator\Sequence Generator Pro\3-emailEndpointAddresses.sgd'. [10/05/17 19:26:47.247][DEBUG] [Main Thread] Error loading text file endpoint data! Could not find file 'C:\Users\Wes\AppData\Local\SequenceGenerator\Sequence Generator Pro\1-textFileEndpointSettings.sgd'. [10/05/17 19:26:47.247][DEBUG] [Main Thread] Error loading text file endpoint data! Could not find file 'C:\Users\Wes\AppData\Local\SequenceGenerator\Sequence Generator Pro\2-textFileEndpointSettings.sgd'. [10/05/17 19:26:47.248][DEBUG] [Main Thread] Error loading text file endpoint data! Could not find file 'C:\Users\Wes\AppData\Local\SequenceGenerator\Sequence Generator Pro\3-textFileEndpointSettings.sgd'. [10/05/17 19:26:47.409][DEBUG] [Main Thread] ===== S E Q U E N C E G E N E R A T O R (v2.6.0.23) ===== [10/05/17 19:26:47.512][DEBUG] [Main Thread] OS: Microsoft Windows 7 Home Premium [10/05/17 19:26:47.894][DEBUG] [Focuser Thread] Entering Fouser message dispatch loop... [10/05/17 19:26:47.895][DEBUG] [Camera Thread] Entering camera message dispatch loop... [10/05/17 19:26:47.897][DEBUG] [TEC Thread] Entering TEC message dispatch loop... [10/05/17 19:26:47.898][DEBUG] [Filter Wheel Thread] Entering Filter Wheel message dispatch loop... [10/05/17 19:26:47.899][DEBUG] [Rotator Thread] Entering Rotator message dispatch loop... [10/05/17 19:26:47.911][DEBUG] [Auto Guider Error Thread] Entering Auto Guider message dispatch loop... [10/05/17 19:26:47.911][DEBUG] [Auto Guider Thread] Entering Auto Guider message dispatch loop... [10/05/17 19:26:47.912][DEBUG] [Safety Thread] Starting safety monitor... [10/05/17 19:26:47.913][DEBUG] [Dome Thread] Entering Dome message dispatch loop... [10/05/17 19:26:47.915][DEBUG] [Environment Device Thread] Starting environment device monitor... [10/05/17 19:26:47.923][DEBUG] [Telescope Thread] Entering Telescope message dispatch loop... [10/05/17 19:26:47.948][DEBUG] [Main Thread] Settings changed (or loaded)... [10/05/17 19:26:47.949][DEBUG] [Main Thread] * SETTING: settingMdiAutoStretch = False [10/05/17 19:26:47.949][DEBUG] [Main Thread] * SETTING: settingIgLastFileFolder = [10/05/17 19:26:47.949][DEBUG] [Main Thread] * SETTING: settingFormScaleWarning = False [10/05/17 19:26:47.949][DEBUG] [Main Thread] * SETTING: settingRecoveryDuration = 90 [10/05/17 19:26:47.949][DEBUG] [Main Thread] * SETTING: settingDelayBetween = 0 [10/05/17 19:26:47.949][DEBUG] [Main Thread] * SETTING: settingFliFlushLow = 0 [10/05/17 19:26:47.949][DEBUG] [Main Thread] * SETTING: settingSBIGLpt2Address = 278 [10/05/17 19:26:47.949][DEBUG] [Main Thread] * SETTING: settingMdiMouseAction = Stretch [10/05/17 19:26:47.949][DEBUG] [Main Thread] * SETTING: settingLastAscomCamera = [10/05/17 19:26:47.949][DEBUG] [Main Thread] * SETTING: settingAutoRecoveryActive = False [10/05/17 19:26:47.949][DEBUG] [Main Thread] * SETTING: settingTemperhumInterval = 300 [10/05/17 19:26:47.949][DEBUG] [Main Thread] * SETTING: settingASCOMWarningShown = False [10/05/17 19:26:47.949][DEBUG] [Main Thread] * SETTING: settingPlateCamScale = 0 [10/05/17 19:26:47.949][DEBUG] [Main Thread] * SETTING: settingCanonMirrorSettle = 0 [10/05/17 19:26:47.949][DEBUG] [Main Thread] * SETTING: settingInterface = [10/05/17 19:26:47.949][DEBUG] [Main Thread] * SETTING: settingLastAscomFilterWheel = [10/05/17 19:26:47.949][DEBUG] [Main Thread] * SETTING: settingAstroArtDisplayInstructions = True [10/05/17 19:26:47.949][DEBUG] [Main Thread] * SETTING: settingSBIGLpt1Address = 378 [10/05/17 19:26:47.949][DEBUG] [Main Thread] * SETTING: settingTrialQuestionsAsked = False [10/05/17 19:26:47.949][DEBUG] [Main Thread] * SETTING: settingCheckForUpdatesOnStart = True [10/05/17 19:26:47.949][DEBUG] [Main Thread] * SETTING: settingMdiLastImageDir = [10/05/17 19:26:47.949][DEBUG] [Main Thread] * SETTING: settingTemperHumUserTempComp = 0 [10/05/17 19:26:47.949][DEBUG] [Main Thread] * SETTING: settingCaptureCalFramesAlways = False [10/05/17 19:26:47.949][DEBUG] [Main Thread] * SETTING: settingDelayFirst = 0 [10/05/17 19:26:47.949][DEBUG] [Main Thread] * SETTING: settingFliNormalSpeedMode = 0 [10/05/17 19:26:47.949][DEBUG] [Main Thread] * SETTING: settingFliHighSpeedMode = 0 [10/05/17 19:26:47.949][DEBUG] [Main Thread] * SETTING: settingMfPosition = {X=0,Y=0} [10/05/17 19:26:47.949][DEBUG] [Main Thread] * SETTING: settingSoundAttention = [10/05/17 19:26:47.949][DEBUG] [Main Thread] * SETTING: settingPinpointCatalog = [10/05/17 19:26:47.949][DEBUG] [Main Thread] * SETTING: settingCameraOverhead = [10/05/17 19:26:47.949][DEBUG] [Main Thread] * SETTING: settingPlateSolveOn = False [10/05/17 19:26:47.965][DEBUG] [Main Thread] * SETTING: settingAstrometryNetEndpointList = System.Collections.Specialized.StringCollection [10/05/17 19:26:47.965][DEBUG] [Main Thread] * SETTING: settingSBIGGuider = None [10/05/17 19:26:47.965][DEBUG] [Main Thread] * SETTING: settingQSIFanSpeed = 2 [10/05/17 19:26:47.965][DEBUG] [Main Thread] * SETTING: settingNaggerText = [10/05/17 19:26:47.965][DEBUG] [Main Thread] * SETTING: settingMdiPhdDisplay = RA/Dec [10/05/17 19:26:47.965][DEBUG] [Main Thread] * SETTING: settingMdiShowToolStrip = True [10/05/17 19:26:47.965][DEBUG] [Main Thread] * SETTING: settingMdiShowCrosshairs = True [10/05/17 19:26:47.965][DEBUG] [Main Thread] * SETTING: WindowGeometry = [10/05/17 19:26:47.965][DEBUG] [Main Thread] * SETTING: settingIgRenameStringLocation = 2 [10/05/17 19:26:47.965][DEBUG] [Main Thread] * SETTING: settingIgSubFolders = False [10/05/17 19:26:47.965][DEBUG] [Main Thread] * SETTING: WindowGeometrySearch = [10/05/17 19:26:47.965][DEBUG] [Main Thread] * SETTING: settingOffset = -1 [10/05/17 19:26:47.965][DEBUG] [Main Thread] * SETTING: settingAutoStretchLevel = Low [10/05/17 19:26:47.965][DEBUG] [Main Thread] * SETTING: settingTemperHumUnits = C [10/05/17 19:26:47.965][DEBUG] [Main Thread] * SETTING: settingProfileDirectory = [10/05/17 19:26:47.965][DEBUG] [Main Thread] * SETTING: settingBasename = [10/05/17 19:26:47.965][DEBUG] [Main Thread] * SETTING: settingHfrColor = Color [RoyalBlue] [10/05/17 19:26:47.965][DEBUG] [Main Thread] * SETTING: settingNikonAfBinning = 1 [10/05/17 19:26:47.965][DEBUG] [Main Thread] * SETTING: settingSBIGFanOffWithTec = False [10/05/17 19:26:47.965][DEBUG] [Main Thread] * SETTING: settingLastAscomSafetyMonitor = [10/05/17 19:26:47.965][DEBUG] [Main Thread] * SETTING: settingMdiSize = {Width=800, Height=600} [10/05/17 19:26:47.965][DEBUG] [Main Thread] * SETTING: settingIgCopyAndRenameFiles = False [10/05/17 19:26:47.965][DEBUG] [Main Thread] * SETTING: settingSimDownloadTimesAf = False [10/05/17 19:26:47.965][DEBUG] [Main Thread] * SETTING: settingNagger = False [10/05/17 19:26:47.965][DEBUG] [Main Thread] * SETTING: settingImportFromCfw = True [10/05/17 19:26:47.965][DEBUG] [Main Thread] * SETTING: settingMdiShowStatusTrip = True [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingShowTooltipHelp = True [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingLastUpdateCheck = 1/1/2012 12:00:00 AM [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingEventSuffixes = null [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingCpPosition = {X=0,Y=0} [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingSBIGCfwPort = [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingMdiLocation = {X=0,Y=0} [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingIgLastCsvFolder = [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingSBIGFilterWheel = Auto Detect [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingSBIGConnection = USB [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingDSLRSoftwareBinForAf = False [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingAutoSave = True [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingFirstTime = True [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingFliFlushHigh = 0 [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingMdiState = maximized [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingUseSequenceOptions = False [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingSoundCompleted = [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingTrialExpiredShown = False [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingTemperHumColor = Grey [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingDataDirectory = [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingCanonFileFormat = Single Image (CFA FITS) [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingAlnitakComPort = [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingIgRenameFiles = False [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingSubFolder = True [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingLastSGSaveFolder = [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingFindStars = Normal (recommended) [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingNikonDataFormat = FITS [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingPlaySounds = False [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingPlateSolve3Catalogs = [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingLastNikonType = Auto Connect [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingLastSequenceStart = 1/1/0001 12:00:00 AM [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingLastAscomTelescope = [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingMdiPhdScale = 100 [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingIgRenameType = 0 [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingHelpLanguage = English [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingTemperHumUserHumidityComp = 0 [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingMdiPhdRaColor = Color [Blue] [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingLastImageSaveFolder = [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingEnableImageHistory = False [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingFinishFullEvent = True [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingMosaicOverlap = 20 [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingQSIFanOffWithTec = False [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingRecentSequences = [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingDefaultDirectory = [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingMdiPhdDecColor = Color [Red] [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingAstrometryNetEndpoint = Astrometry.NET (Remote) [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingSBIGCfwUseRS232 = False [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingStarsColor = Color [OrangeRed] [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingPhd2InstallPath = [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingIgOverwriteExisting = False [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingLastAscomFocuser = [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingIgLastFolder = [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: WindowGeometryOpen = [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingNumEvents = 5 [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingCanonAfBinning = 1 [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingPlateCamBlank = [10/05/17 19:26:47.966][DEBUG] [Main Thread] * SETTING: settingLogApiCalls = False [10/05/17 19:26:47.967][DEBUG] [Main Thread] * SETTING: settingNikonUseBulbModeAlways = False [10/05/17 19:26:47.967][DEBUG] [Main Thread] * SETTING: settingTempFiles = C:\Temp [10/05/17 19:26:47.967][DEBUG] [Main Thread] * SETTING: settingFliHighSpeedDesc = [10/05/17 19:26:47.967][DEBUG] [Main Thread] * SETTING: settingRecoveryInterval = 10 [10/05/17 19:26:47.967][DEBUG] [Main Thread] * SETTING: settingDefaultMfwFov = 4.0 [10/05/17 19:26:47.967][DEBUG] [Main Thread] * SETTING: settingSBIGEthernetIP = 0.0.0.0 [10/05/17 19:26:47.967][DEBUG] [Main Thread] * SETTING: settingSBIGLpt3Address = 3BC [10/05/17 19:26:47.967][DEBUG] [Main Thread] * SETTING: settingFliNormalSpeedDesc = [10/05/17 19:26:47.967][DEBUG] [Main Thread] * SETTING: settingIgCopyLocation = [10/05/17 19:26:47.967][DEBUG] [Main Thread] * SETTING: settingSBIGAutoDetectIp = True [10/05/17 19:26:47.967][DEBUG] [Main Thread] * SETTING: settingAstroArtStartApp = True [10/05/17 19:26:47.967][DEBUG] [Main Thread] * SETTING: settingSimDownloadTimesPlate = False [10/05/17 19:26:47.967][DEBUG] [Main Thread] * SETTING: settingMicroTouchLocation = [10/05/17 19:26:47.967][DEBUG] [Main Thread] * SETTING: settingMdiPhdZoom = 40 [10/05/17 19:26:47.967][DEBUG] [Main Thread] * SETTING: settingMdiNewWindow = False [10/05/17 19:26:47.967][DEBUG] [Main Thread] * SETTING: settingEventAlwaysVisible = False [10/05/17 19:26:47.967][DEBUG] [Main Thread] * SETTING: settingFliRbiMitigation = False [10/05/17 19:26:47.967][DEBUG] [Main Thread] * SETTING: settingGain = -1 [10/05/17 19:26:47.967][DEBUG] [Main Thread] * SETTING: settingLastAscomDome = [10/05/17 19:26:47.967][DEBUG] [Main Thread] * SETTING: settingLookForTemperDevices = False [10/05/17 19:26:47.967][DEBUG] [Main Thread] * SETTING: settingLastAscomRotator = [10/05/17 19:26:47.967][DEBUG] [Main Thread] * SETTING: settingAfTestPackPath = [10/05/17 19:26:48.121][DEBUG] [Main Thread] ASCOM Boltwood Observing Conditions [10/05/17 19:26:48.121][DEBUG] [Main Thread] ASCOM Observing Conditions Hub (OCH) [10/05/17 19:26:48.121][DEBUG] [Main Thread] ASCOM Observing Conditions Simulator [10/05/17 19:26:48.121][DEBUG] [Main Thread] OpenWeatherMap ObservingConditions [10/05/17 19:26:48.121][DEBUG] [Main Thread] TEMPerHUM [10/05/17 19:26:48.124][DEBUG] [Main Thread] Performing initialization... [10/05/17 19:26:48.125][DEBUG] [Main Thread] Creating new sequence with default profile: Takahashi [10/05/17 19:26:48.146][DEBUG] [Main Thread] Performing full form reset (with profile Takahashi)... [10/05/17 19:26:48.154][DEBUG] [Main Thread] Retreiving new equipment objects... [10/05/17 19:26:48.154][DEBUG] [Main Thread] New camera object (QSI CCD Camera) dispatched... [10/05/17 19:26:48.162][DEBUG] [Main Thread] New filter wheel object (QSI Internal Filter Wheel) dispatched... [10/05/17 19:26:48.164][DEBUG] [Main Thread] New environment device object (ASCOM Boltwood Observing Conditions) dispatched... [10/05/17 19:26:48.167][DEBUG] [Main Thread] New focuser object (RoboFocus) dispatched... [10/05/17 19:26:48.170][DEBUG] [Main Thread] New telescope object (POTH Hub) dispatched... [10/05/17 19:26:48.172][DEBUG] [Main Thread] New rotator object (No Rotator) dispatched... [10/05/17 19:26:48.174][DEBUG] [Main Thread] New dome object (No Observatory) dispatched... [10/05/17 19:26:48.175][DEBUG] [Main Thread] New flat box object (No Flat Box) dispatched... [10/05/17 19:26:48.177][DEBUG] [Main Thread] New safety monitor object (ASCOM Boltwood OK to Image) dispatched... [10/05/17 19:26:48.179][DEBUG] [Main Thread] New auto guider object (PHD2) dispatched... [10/05/17 19:26:48.179][DEBUG] [Main Thread] New plate solver object (No Plate Solver) dispatched... [10/05/17 19:26:48.204][DEBUG] [Main Thread] Populating the form controls... [10/05/17 19:26:48.265][DEBUG] [Main Thread] Added row 0... [10/05/17 19:26:48.316][DEBUG] [Main Thread] Added row 1... [10/05/17 19:26:48.369][DEBUG] [Main Thread] Added row 2... [10/05/17 19:26:48.431][DEBUG] [Main Thread] Added row 3... [10/05/17 19:26:48.510][DEBUG] [Main Thread] Added row 4... [10/05/17 19:26:48.536][DEBUG] [Main Thread] Loading custom filter names... [10/05/17 19:26:48.545][DEBUG] [Main Thread] PopulateDataModel: Transferring view to the data model... [10/05/17 19:26:48.629][DEBUG] [Main Thread] Retreiving new equipment objects... [10/05/17 19:26:48.629][DEBUG] [Main Thread] No change in camera object... [10/05/17 19:26:48.629][DEBUG] [Main Thread] No change in filter wheel object... [10/05/17 19:26:48.629][DEBUG] [Main Thread] No change in environment device object... [10/05/17 19:26:48.629][DEBUG] [Main Thread] No change in focuser object... [10/05/17 19:26:48.630][DEBUG] [Main Thread] MoveFocuserAbs: Error! The focuser is no longer connected! [10/05/17 19:26:48.630][DEBUG] [Main Thread] No change in telescope object... [10/05/17 19:26:48.630][DEBUG] [Main Thread] New rotator object (No Rotator) dispatched... [10/05/17 19:26:48.630][DEBUG] [Sequence Thread] Entering sequence message dispatch loop... [10/05/17 19:26:48.631][DEBUG] [Main Thread] New dome object (No Observatory) dispatched... [10/05/17 19:26:48.632][DEBUG] [Main Thread] New flat box object (No Flat Box) dispatched... [10/05/17 19:26:48.633][DEBUG] [Main Thread] No change in safety monitor object... [10/05/17 19:26:48.633][DEBUG] [Main Thread] New auto guider object (PHD2) dispatched... [10/05/17 19:26:48.634][DEBUG] [Main Thread] New plate solver object (No Plate Solver) dispatched... [10/05/17 19:26:48.637][DEBUG] [Main Thread] Checking if version check is required... [10/05/17 19:26:48.639][DEBUG] [Main Thread] Checking for updated version... [10/05/17 19:26:48.940][DEBUG] [Cleaner Thread] Running cleanup... [10/05/17 19:26:48.940][DEBUG] [Cleaner Thread] Cleaning 0 files in directory C:\Users\Wes\AppData\Local\SequenceGenerator\Temp\... [10/05/17 19:26:48.940][DEBUG] [Cleaner Thread] Cleaning multi camera registration file... [10/05/17 19:26:48.940][DEBUG] [Cleaner Thread] Cleaning finished... [10/05/17 19:26:48.941][DEBUG] [Main Thread] UI layout found, loading layout at C:\Users\Wes\AppData\Local\SequenceGenerator\sg_ui_config.xml [10/05/17 19:27:14.298][DEBUG] [Main Thread] Creating new sequence with profile: Takahashi [10/05/17 19:27:14.298][DEBUG] [Main Thread] Performing full form reset (with profile Takahashi)... [10/05/17 19:27:14.300][DEBUG] [Main Thread] Retreiving new equipment objects... [10/05/17 19:27:14.300][DEBUG] [Main Thread] No change in camera object... [10/05/17 19:27:14.300][DEBUG] [Main Thread] No change in filter wheel object... [10/05/17 19:27:14.301][DEBUG] [Main Thread] No change in environment device object... [10/05/17 19:27:14.301][DEBUG] [Main Thread] No change in focuser object... [10/05/17 19:27:14.301][DEBUG] [Main Thread] MoveFocuserAbs: Error! The focuser is no longer connected! [10/05/17 19:27:14.301][DEBUG] [Main Thread] No change in telescope object... [10/05/17 19:27:14.302][DEBUG] [Main Thread] New rotator object (No Rotator) dispatched... [10/05/17 19:27:14.303][DEBUG] [Main Thread] New dome object (No Observatory) dispatched... [10/05/17 19:27:14.304][DEBUG] [Main Thread] New flat box object (No Flat Box) dispatched... [10/05/17 19:27:14.306][DEBUG] [Main Thread] No change in safety monitor object... [10/05/17 19:27:14.306][DEBUG] [Main Thread] New auto guider object (PHD2) dispatched... [10/05/17 19:27:14.306][DEBUG] [Main Thread] New plate solver object (No Plate Solver) dispatched... [10/05/17 19:27:14.434][DEBUG] [Main Thread] Populating the form controls... [10/05/17 19:27:14.467][DEBUG] [Main Thread] Added row 0... [10/05/17 19:27:14.506][DEBUG] [Main Thread] Added row 1... [10/05/17 19:27:14.555][DEBUG] [Main Thread] Added row 2... [10/05/17 19:27:14.631][DEBUG] [Main Thread] Added row 3... [10/05/17 19:27:14.702][DEBUG] [Main Thread] Added row 4... [10/05/17 19:27:15.076][DEBUG] [Main Thread] Loading custom filter names... [10/05/17 19:27:15.123][DEBUG] [Main Thread] PopulateDataModel: Transferring view to the data model... [10/05/17 19:27:15.169][DEBUG] [Main Thread] Retreiving new equipment objects... [10/05/17 19:27:15.170][DEBUG] [Main Thread] No change in camera object... [10/05/17 19:27:15.170][DEBUG] [Main Thread] No change in filter wheel object... [10/05/17 19:27:15.170][DEBUG] [Main Thread] No change in environment device object... [10/05/17 19:27:15.171][DEBUG] [Main Thread] No change in focuser object... [10/05/17 19:27:15.171][DEBUG] [Main Thread] MoveFocuserAbs: Error! The focuser is no longer connected! [10/05/17 19:27:15.171][DEBUG] [Main Thread] No change in telescope object... [10/05/17 19:27:15.171][DEBUG] [Main Thread] New rotator object (No Rotator) dispatched... [10/05/17 19:27:15.173][DEBUG] [Main Thread] New dome object (No Observatory) dispatched... [10/05/17 19:27:15.175][DEBUG] [Main Thread] New flat box object (No Flat Box) dispatched... [10/05/17 19:27:15.178][DEBUG] [Main Thread] No change in safety monitor object... [10/05/17 19:27:15.178][DEBUG] [Main Thread] New auto guider object (PHD2) dispatched... [10/05/17 19:27:15.178][DEBUG] [Main Thread] New plate solver object (No Plate Solver) dispatched... [10/05/17 19:29:44.043][DEBUG] [Main Thread] Creating new sequence with profile: Takahashi [10/05/17 19:29:44.043][DEBUG] [Main Thread] Performing full form reset (with profile Takahashi)... [10/05/17 19:29:44.046][DEBUG] [Main Thread] Retreiving new equipment objects... [10/05/17 19:29:44.046][DEBUG] [Main Thread] No change in camera object... [10/05/17 19:29:44.046][DEBUG] [Main Thread] No change in filter wheel object... [10/05/17 19:29:44.046][DEBUG] [Main Thread] No change in environment device object... [10/05/17 19:29:44.047][DEBUG] [Main Thread] No change in focuser object... [10/05/17 19:29:44.047][DEBUG] [Main Thread] MoveFocuserAbs: Error! The focuser is no longer connected! [10/05/17 19:29:44.047][DEBUG] [Main Thread] No change in telescope object... [10/05/17 19:29:44.047][DEBUG] [Main Thread] New rotator object (No Rotator) dispatched... [10/05/17 19:29:44.049][DEBUG] [Main Thread] New dome object (No Observatory) dispatched... [10/05/17 19:29:44.050][DEBUG] [Main Thread] New flat box object (No Flat Box) dispatched... [10/05/17 19:29:44.052][DEBUG] [Main Thread] No change in safety monitor object... [10/05/17 19:29:44.052][DEBUG] [Main Thread] New auto guider object (PHD2) dispatched... [10/05/17 19:29:44.052][DEBUG] [Main Thread] New plate solver object (No Plate Solver) dispatched... [10/05/17 19:29:44.167][DEBUG] [Main Thread] Populating the form controls... [10/05/17 19:29:44.196][DEBUG] [Main Thread] Added row 0... [10/05/17 19:29:44.250][DEBUG] [Main Thread] Added row 1... [10/05/17 19:29:44.300][DEBUG] [Main Thread] Added row 2... [10/05/17 19:29:44.358][DEBUG] [Main Thread] Added row 3... [10/05/17 19:29:44.426][DEBUG] [Main Thread] Added row 4... [10/05/17 19:29:44.482][DEBUG] [Main Thread] Loading custom filter names... [10/05/17 19:29:44.492][DEBUG] [Main Thread] PopulateDataModel: Transferring view to the data model... [10/05/17 19:29:44.508][DEBUG] [Main Thread] Retreiving new equipment objects... [10/05/17 19:29:44.508][DEBUG] [Main Thread] No change in camera object... [10/05/17 19:29:44.509][DEBUG] [Main Thread] No change in filter wheel object... [10/05/17 19:29:44.509][DEBUG] [Main Thread] No change in environment device object... [10/05/17 19:29:44.509][DEBUG] [Main Thread] No change in focuser object... [10/05/17 19:29:44.509][DEBUG] [Main Thread] MoveFocuserAbs: Error! The focuser is no longer connected! [10/05/17 19:29:44.510][DEBUG] [Main Thread] No change in telescope object... [10/05/17 19:29:44.510][DEBUG] [Main Thread] New rotator object (No Rotator) dispatched... [10/05/17 19:29:44.511][DEBUG] [Main Thread] New dome object (No Observatory) dispatched... [10/05/17 19:29:44.513][DEBUG] [Main Thread] New flat box object (No Flat Box) dispatched... [10/05/17 19:29:44.514][DEBUG] [Main Thread] No change in safety monitor object... [10/05/17 19:29:44.514][DEBUG] [Main Thread] New auto guider object (PHD2) dispatched... [10/05/17 19:29:44.514][DEBUG] [Main Thread] New plate solver object (No Plate Solver) dispatched... [10/05/17 19:31:39.940][DEBUG] [Main Thread] Connecting camera in camera thread... [10/05/17 19:31:39.945][DEBUG] [Camera Thread] SGM_CAMERA_CONNECT message received... [10/05/17 19:31:39.946][DEBUG] [Camera Thread] Connecting QSI camera... [10/05/17 19:31:39.964][DEBUG] [Camera Thread] QSI Camera: version check... [10/05/17 19:31:39.967][DEBUG] [Camera Thread] QSI Driver Version: 7.4.1824.0(QSI - Camera not connected) [10/05/17 19:31:41.862][DEBUG] [Camera Thread] Connected to 683ws [10/05/17 19:31:41.862][DEBUG] [Camera Thread] SGM_CAMERA_CONNECT complete... [10/05/17 19:31:41.995][DEBUG] [Main Thread] Camera cooler detected... [10/05/17 19:31:42.035][DEBUG] [Main Thread] Camera cooler is OFF... [10/05/17 19:31:42.036][DEBUG] [Main Thread] Camera cooldown on connect is selected... [10/05/17 19:31:42.036][DEBUG] [Main Thread] Camera cooldown is starting... [10/05/17 19:31:42.047][DEBUG] [TEC Thread] SGM_CHANGE_COOLER_TEMP message received... [10/05/17 19:31:42.049][DEBUG] [TEC Thread] TEC Change: Starting... [10/05/17 19:31:42.049][DEBUG] [TEC Thread] TEC Change: After camera connect change, waiting ~5 seconds... [10/05/17 19:31:43.547][DEBUG] [Main Thread] Connecting ASCOM focuser... [10/05/17 19:31:43.653][DEBUG] [Main Thread] Focuser supports temperature [10/05/17 19:31:43.653][DEBUG] [Main Thread] Current temperature reports: 100 [10/05/17 19:31:43.654][DEBUG] [Main Thread] Focuser reports Max Increment as: 10000 [10/05/17 19:31:43.654][DEBUG] [Main Thread] Focuser reports max steps as: 10000 [10/05/17 19:31:43.880][DEBUG] [Telescope Thread] Telescope Dispatch loop: Received SGM_TELESCOPE_CONNECT... [10/05/17 19:31:47.049][DEBUG] [TEC Thread] TEC Change: Attempting to turn the camera cooler on... [10/05/17 19:31:47.050][DEBUG] [TEC Thread] Turning camera cooler on... [10/05/17 19:31:50.772][DEBUG] [Telescope Thread] Telescope can slewing supported... [10/05/17 19:31:50.773][DEBUG] [Telescope Thread] Telescope: Does not implement MoveAxis... [10/05/17 19:31:50.783][DEBUG] [Telescope Thread] Telescope: Exception thrown while querying SideOfPier, setting to false. : Property read POTH.Telescope SideOfPier is not implemented in this driver. (System.Reflection.TargetInvocationException: Exception has been thrown by the target of an invocation. ---> System.Runtime.InteropServices.COMException: Property SideOfPier is not implemented by 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.Telescope.get_SideOfPier() in c:\ASCOM Build\Export\ASCOM.DriverAccess\Telescope.cs:line 895 at p6.jo() [10/05/17 19:31:50.784][DEBUG] [Telescope Thread] Telescope: CanSetSideOfPier returned False [10/05/17 19:31:50.785][DEBUG] [Telescope Thread] Telescope: CanPark returned True [10/05/17 19:31:50.787][DEBUG] [Telescope Thread] Telescope equatorial system is JNOW... [10/05/17 19:31:50.787][DEBUG] [Telescope Thread] SGM_TELESCOPE_CONNECT complete... [10/05/17 19:31:51.119][DEBUG] [Main Thread] Telescope: Setting sync behavior to: Sync [10/05/17 19:31:51.134][DEBUG] [Main Thread] Connecting ASCOM Safety Monitor... [10/05/17 19:31:51.474][DEBUG] [Main Thread] ASCOM EnvironmentDevice: Connected to ASCOM Boltwood Observing Conditions [10/05/17 19:31:51.477][DEBUG] [Main Thread] ASCOM EnvironmentDevice: Device Name - Boltwood ObservingConditions [10/05/17 19:31:51.479][DEBUG] [Main Thread] ASCOM EnvironmentDevice: Description - ASCOM Boltwood Observing Conditions [10/05/17 19:31:51.481][DEBUG] [Main Thread] ASCOM EnvironmentDevice: Driver Info Boltwood obseving conditions driver. Version: 6.2.5823.16903 [10/05/17 19:31:51.483][DEBUG] [Main Thread] ASCOM EnvironmentDevice: Driver Version 6.2 [10/05/17 19:31:51.514][DEBUG] [Main Thread] ASCOM EnvironmentDevice: Failed to get Pressure! : Property read ASCOM.Boltwood.ObservingConditions Pressure is not implemented in this driver. (System.Reflection.TargetInvocationException: Exception has been thrown by the target of an invocation. ---> System.Runtime.InteropServices.COMException: Property read Pressure 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.ObservingConditions.get_Pressure() in c:\ASCOM Build\Export\ASCOM.DriverAccess\ObservingConditions.cs:line 147 at jd.br() [10/05/17 19:31:51.524][DEBUG] [Main Thread] ASCOM EnvironmentDevice: Failed to get Seeing! : Property read ASCOM.Boltwood.ObservingConditions StarFWHM is not implemented in this driver. (System.Reflection.TargetInvocationException: Exception has been thrown by the target of an invocation. ---> System.Runtime.InteropServices.COMException: Property read StarFWHM 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.ObservingConditions.get_StarFWHM() in c:\ASCOM Build\Export\ASCOM.DriverAccess\ObservingConditions.cs:line 232 at jd.br() [10/05/17 19:31:51.529][DEBUG] [Main Thread] ASCOM EnvironmentDevice: Failed to get SkyBrightness! : Property read ASCOM.Boltwood.ObservingConditions SkyBrightness is not implemented in this driver. (System.Reflection.TargetInvocationException: Exception has been thrown by the target of an invocation. ---> System.Runtime.InteropServices.COMException: Property read SkyBrightness 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.ObservingConditions.get_SkyBrightness() in c:\ASCOM Build\Export\ASCOM.DriverAccess\ObservingConditions.cs:line 204 at jd.br() [10/05/17 19:31:51.537][DEBUG] [Main Thread] ASCOM EnvironmentDevice: Failed to get SkyQuality! : Property read ASCOM.Boltwood.ObservingConditions SkyQuality is not implemented in this driver. (System.Reflection.TargetInvocationException: Exception has been thrown by the target of an invocation. ---> System.Runtime.InteropServices.COMException: Property read SkyQuality 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.ObservingConditions.get_SkyQuality() in c:\ASCOM Build\Export\ASCOM.DriverAccess\ObservingConditions.cs:line 218 at jd.br() [10/05/17 19:31:51.550][DEBUG] [Main Thread] ASCOM EnvironmentDevice: Failed to get WindDirection! : Property read ASCOM.Boltwood.ObservingConditions WindDirection is not implemented in this driver. (System.Reflection.TargetInvocationException: Exception has been thrown by the target of an invocation. ---> System.Runtime.InteropServices.COMException: Property read WindDirection 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.ObservingConditions.get_WindDirection() in c:\ASCOM Build\Export\ASCOM.DriverAccess\ObservingConditions.cs:line 282 at jd.br() [10/05/17 19:31:51.556][DEBUG] [Main Thread] ASCOM EnvironmentDevice: Failed to get WindGust! : Property read ASCOM.Boltwood.ObservingConditions WindGust is not implemented in this driver. (System.Reflection.TargetInvocationException: Exception has been thrown by the target of an invocation. ---> System.Runtime.InteropServices.COMException: Property read WindGust 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.ObservingConditions.get_WindGust() in c:\ASCOM Build\Export\ASCOM.DriverAccess\ObservingConditions.cs:line 298 at jd.br() [10/05/17 19:31:51.599][DEBUG] [Main Thread] Turning camera cooler on... [10/05/17 19:31:52.136][DEBUG] [TEC Thread] TEC Change: Changing temp from 20.00 to -10.00 in 360 seconds... [10/05/17 19:31:52.613][DEBUG] [Auto Guider Equipment Connection Thread] Connecting to PHD2... [10/05/17 19:31:52.618][DEBUG] [Auto Guider Equipment Connection Thread] Waiting for socket connection to PHD2... [10/05/17 19:31:52.625][DEBUG] [PHD2 Listener Thread] Attempting to connect to PHD2... [10/05/17 19:31:53.618][DEBUG] [Auto Guider Equipment Connection Thread] Successfully connected to PHD2... [10/05/17 19:31:53.619][DEBUG] [Auto Guider Equipment Connection Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/05/17 19:31:53.619][DEBUG] [Auto Guider Equipment Connection Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 19:31:53.719][DEBUG] [Auto Guider Equipment Connection Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/05/17 19:31:53.725][DEBUG] [Auto Guider Equipment Connection Thread] PHD2: PHD2 is already open... [10/05/17 19:31:53.725][DEBUG] [Auto Guider Equipment Connection Thread] Sending to PHD2: {"method": "get_profiles", "id": 1009} [10/05/17 19:31:54.226][DEBUG] [Auto Guider Equipment Connection Thread] Sending to PHD2: {"method":"set_connected","params":[false],"id":1007} [10/05/17 19:31:54.227][DEBUG] [Auto Guider Equipment Connection Thread] Sending to PHD2: {"method":"set_profile","params":[2],"id":1008} [10/05/17 19:31:54.227][DEBUG] [Auto Guider Equipment Connection Thread] Sending to PHD2: {"method":"set_connected","params":[true],"id":1007} [10/05/17 19:32:02.745][DEBUG] [Filter Wheel Thread] SGM_FW_SET_POSITION message received... [10/05/17 19:32:04.613][DEBUG] [Filter Wheel Thread] SGM_FW_SET_POSITION complete... [10/05/17 19:32:55.271][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [10/05/17 19:32:55.271][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [10/05/17 19:32:55.272][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/05/17 19:32:55.272][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 19:32:55.272][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/05/17 19:33:49.179][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS message received... [10/05/17 19:33:49.191][DEBUG] [Camera Thread] ASCOM (QSI) camera: frame and focus... [10/05/17 19:33:49.221][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 19:33:49.222][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 19:33:55.411][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [10/05/17 19:33:55.411][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [10/05/17 19:33:55.411][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/05/17 19:33:55.411][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 19:33:55.411][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/05/17 19:34:05.929][DEBUG] [Camera Thread] QSI expose and download: 16.643952s [10/05/17 19:34:06.122][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 19:34:06.122][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 19:34:06.983][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS complete... [10/05/17 19:34:55.539][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [10/05/17 19:34:55.539][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [10/05/17 19:34:55.539][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/05/17 19:34:55.539][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 19:34:55.539][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/05/17 19:35:43.410][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS message received... [10/05/17 19:35:43.413][DEBUG] [Camera Thread] ASCOM (QSI) camera: frame and focus... [10/05/17 19:35:43.433][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 19:35:43.433][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 19:35:55.680][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [10/05/17 19:35:55.680][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [10/05/17 19:35:55.680][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/05/17 19:35:55.680][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 19:35:55.680][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/05/17 19:36:09.174][DEBUG] [Camera Thread] QSI expose and download: 25.6764686s [10/05/17 19:36:09.399][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 19:36:09.399][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 19:36:10.817][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS complete... [10/05/17 19:36:55.800][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [10/05/17 19:36:55.800][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [10/05/17 19:36:55.800][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/05/17 19:36:55.800][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 19:36:55.800][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/05/17 19:37:52.191][DEBUG] [TEC Thread] TEC Change: Complete... [10/05/17 19:37:52.235][DEBUG] [TEC Thread] SGM_CHANGE_COOLER_TEMP complete... [10/05/17 19:37:55.909][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [10/05/17 19:37:55.909][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [10/05/17 19:37:55.909][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/05/17 19:37:55.909][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 19:37:55.909][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/05/17 19:38:56.047][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [10/05/17 19:38:56.047][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [10/05/17 19:38:56.047][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/05/17 19:38:56.047][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 19:38:56.047][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/05/17 19:39:29.106][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS message received... [10/05/17 19:39:29.115][DEBUG] [Camera Thread] ASCOM (QSI) camera: frame and focus... [10/05/17 19:39:29.143][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 19:39:29.143][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 19:39:54.924][DEBUG] [Camera Thread] QSI expose and download: 25.7164709s [10/05/17 19:39:55.173][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 19:39:55.173][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 19:39:56.184][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [10/05/17 19:39:56.184][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [10/05/17 19:39:56.184][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/05/17 19:39:56.184][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 19:39:56.184][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/05/17 19:39:56.601][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS complete... [10/05/17 19:40:56.285][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [10/05/17 19:40:56.285][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [10/05/17 19:40:56.285][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/05/17 19:40:56.285][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 19:40:56.285][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/05/17 19:41:46.871][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS message received... [10/05/17 19:41:46.880][DEBUG] [Camera Thread] ASCOM (QSI) camera: frame and focus... [10/05/17 19:41:46.919][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 19:41:46.919][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 19:41:56.402][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [10/05/17 19:41:56.402][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [10/05/17 19:41:56.402][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/05/17 19:41:56.402][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 19:41:56.402][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/05/17 19:42:12.640][DEBUG] [Camera Thread] QSI expose and download: 25.6564674s [10/05/17 19:42:12.830][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 19:42:12.830][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 19:42:14.374][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS complete... [10/05/17 19:42:56.527][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [10/05/17 19:42:56.527][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [10/05/17 19:42:56.527][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/05/17 19:42:56.527][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 19:42:56.527][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/05/17 19:43:56.634][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [10/05/17 19:43:56.634][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [10/05/17 19:43:56.634][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/05/17 19:43:56.634][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 19:43:56.634][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/05/17 19:44:41.758][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS message received... [10/05/17 19:44:41.764][DEBUG] [Camera Thread] ASCOM (QSI) camera: frame and focus... [10/05/17 19:44:41.799][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 19:44:41.799][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 19:44:56.758][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [10/05/17 19:44:56.758][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [10/05/17 19:44:56.758][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/05/17 19:44:56.758][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 19:44:56.758][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/05/17 19:45:07.539][DEBUG] [Camera Thread] QSI expose and download: 25.6734685s [10/05/17 19:45:07.742][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 19:45:07.743][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 19:45:09.145][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS complete... [10/05/17 19:45:18.945][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS message received... [10/05/17 19:45:18.979][DEBUG] [Camera Thread] ASCOM (QSI) camera: frame and focus... [10/05/17 19:45:19.005][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 19:45:19.005][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 19:45:44.698][DEBUG] [Camera Thread] QSI expose and download: 25.6324661s [10/05/17 19:45:44.870][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 19:45:44.870][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 19:45:46.318][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS complete... [10/05/17 19:45:56.869][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [10/05/17 19:45:56.869][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [10/05/17 19:45:56.869][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/05/17 19:45:56.869][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 19:45:56.869][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/05/17 19:46:56.971][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [10/05/17 19:46:56.971][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [10/05/17 19:46:56.971][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/05/17 19:46:56.971][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 19:46:56.971][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/05/17 19:47:57.087][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [10/05/17 19:47:57.087][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [10/05/17 19:47:57.087][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/05/17 19:47:57.087][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 19:47:57.087][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/05/17 19:48:57.216][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [10/05/17 19:48:57.216][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [10/05/17 19:48:57.216][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/05/17 19:48:57.216][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 19:48:57.216][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/05/17 19:49:57.319][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [10/05/17 19:49:57.319][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [10/05/17 19:49:57.319][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/05/17 19:49:57.319][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 19:49:57.319][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/05/17 19:50:57.463][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [10/05/17 19:50:57.463][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [10/05/17 19:50:57.463][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/05/17 19:50:57.463][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 19:50:57.463][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/05/17 19:51:57.602][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [10/05/17 19:51:57.602][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [10/05/17 19:51:57.602][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/05/17 19:51:57.602][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 19:51:57.602][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/05/17 19:52:36.152][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS message received... [10/05/17 19:52:36.154][DEBUG] [Camera Thread] ASCOM (QSI) camera: frame and focus... [10/05/17 19:52:36.179][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 19:52:36.179][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 19:52:57.726][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [10/05/17 19:52:57.726][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [10/05/17 19:52:57.726][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/05/17 19:52:57.726][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 19:52:57.726][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/05/17 19:53:01.936][DEBUG] [Camera Thread] QSI expose and download: 25.6604676s [10/05/17 19:53:02.114][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 19:53:02.114][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 19:53:03.238][DEBUG] [Camera Thread] ASCOM (QSI) camera: frame and focus... [10/05/17 19:53:03.271][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 19:53:03.271][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 19:53:28.984][DEBUG] [Camera Thread] QSI expose and download: 25.649467s [10/05/17 19:53:29.151][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS complete... [10/05/17 19:53:36.343][DEBUG] [Camera Thread] SGM_FOCUSER_AUTO_FOCUS message received... [10/05/17 19:53:36.349][DEBUG] [Camera Thread] Checking for auto focus... [10/05/17 19:53:36.349][DEBUG] [Camera Thread] Auto focus required (frame count trigger)... [10/05/17 19:53:36.359][DEBUG] [Camera Thread] Auto focus: setting filter Ha [10/05/17 19:53:36.362][DEBUG] [Camera Thread] Setting filter position 5... [10/05/17 19:53:36.362][DEBUG] [Camera Thread] Filter position 5 is already set. Skipping... [10/05/17 19:53:36.364][DEBUG] [Camera Thread] Auto focus running... [10/05/17 19:53:36.374][DEBUG] [Camera Thread] Turning temp comp off.. [10/05/17 19:53:36.394][DEBUG] [Camera Thread] Performing Sequence Generator auto focus using Half Flux Radius. AFID: 0 [10/05/17 19:53:36.465][DEBUG] [AfChartThread] Showing AF chart dialog... [10/05/17 19:53:36.563][DEBUG] [Camera Thread] Auto focus data [10/05/17 19:53:36.563][DEBUG] [Camera Thread] - Data Points: 7 [10/05/17 19:53:36.563][DEBUG] [Camera Thread] - Step Size: 10 [10/05/17 19:53:36.563][DEBUG] [Camera Thread] - Current Position: 7773 [10/05/17 19:53:36.563][DEBUG] [Camera Thread] - Initial Move Position: 7803 [10/05/17 19:53:36.573][DEBUG] [Camera Thread] AF Darks exception: The path is not of a legal form. [10/05/17 19:53:36.576][DEBUG] [Camera Thread] Moving focuser to next position (7803)... [10/05/17 19:53:36.584][DEBUG] [Focuser Move Thread] Focuser moving to 7803 [10/05/17 19:53:36.641][DEBUG] [Focuser Move Thread] Focuser move call complete [10/05/17 19:53:37.696][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7803 matches requested position 7803 [10/05/17 19:53:37.696][DEBUG] [Camera Thread] Focuser move complete... [10/05/17 19:53:37.752][DEBUG] [Camera Thread] Focuser position matches requested position (7803), continuing... [10/05/17 19:53:37.812][DEBUG] [Camera Thread] Calculating step metric... [10/05/17 19:53:37.814][DEBUG] [Camera Thread] Taking auto focus frame(s)... [10/05/17 19:53:37.818][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [10/05/17 19:53:37.818][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [10/05/17 19:53:37.818][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [10/05/17 19:53:37.818][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [10/05/17 19:53:37.849][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 19:53:37.849][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 19:53:54.530][DEBUG] [Camera Thread] QSI expose and download: 16.6169505s [10/05/17 19:53:54.719][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 19:53:54.719][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 19:53:55.372][DEBUG] [Main Thread] --> Find stars (normal) [10/05/17 19:53:55.502][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [10/05/17 19:53:57.836][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [10/05/17 19:53:57.836][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [10/05/17 19:53:57.836][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/05/17 19:53:57.836][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 19:53:57.836][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/05/17 19:54:01.186][DEBUG] [Main Thread] Star detection using min star size of 4px... [10/05/17 19:54:01.186][DEBUG] [Main Thread] Star detection using max star size of 80px... [10/05/17 19:54:01.494][DEBUG] [Main Thread] Find stars took: 6122 ms... [10/05/17 19:54:01.496][DEBUG] [Main Thread] Star list contains 25 stars... [10/05/17 19:54:01.496][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [10/05/17 19:54:01.814][DEBUG] [Camera Thread] Auto focus HFR calculated at: 2.47 [10/05/17 19:54:02.857][DEBUG] [Camera Thread] Moving focuser to next position (7793)... [10/05/17 19:54:02.858][DEBUG] [Focuser Move Thread] Focuser moving to 7793 [10/05/17 19:54:02.975][DEBUG] [Focuser Move Thread] Focuser move call complete [10/05/17 19:54:04.067][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7793 matches requested position 7793 [10/05/17 19:54:04.067][DEBUG] [Camera Thread] Focuser move complete... [10/05/17 19:54:04.126][DEBUG] [Camera Thread] Focuser position matches requested position (7793), continuing... [10/05/17 19:54:04.184][DEBUG] [Camera Thread] Calculating step metric... [10/05/17 19:54:04.184][DEBUG] [Camera Thread] Taking auto focus frame(s)... [10/05/17 19:54:04.197][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [10/05/17 19:54:04.197][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [10/05/17 19:54:04.197][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [10/05/17 19:54:04.197][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [10/05/17 19:54:04.219][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 19:54:04.219][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 19:54:20.930][DEBUG] [Camera Thread] QSI expose and download: 16.6469522s [10/05/17 19:54:21.117][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 19:54:21.117][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 19:54:21.781][DEBUG] [Main Thread] --> Find stars (normal) [10/05/17 19:54:21.917][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [10/05/17 19:54:27.268][DEBUG] [Main Thread] Star detection using min star size of 4px... [10/05/17 19:54:27.268][DEBUG] [Main Thread] Star detection using max star size of 80px... [10/05/17 19:54:27.357][DEBUG] [Main Thread] Find stars took: 5575 ms... [10/05/17 19:54:27.357][DEBUG] [Main Thread] Star list contains 14 stars... [10/05/17 19:54:27.357][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [10/05/17 19:54:27.635][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.13 [10/05/17 19:54:28.673][DEBUG] [Camera Thread] Moving focuser to next position (7783)... [10/05/17 19:54:28.683][DEBUG] [Focuser Move Thread] Focuser moving to 7783 [10/05/17 19:54:28.740][DEBUG] [Focuser Move Thread] Focuser move call complete [10/05/17 19:54:29.798][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7783 matches requested position 7783 [10/05/17 19:54:29.798][DEBUG] [Camera Thread] Focuser move complete... [10/05/17 19:54:29.857][DEBUG] [Camera Thread] Focuser position matches requested position (7783), continuing... [10/05/17 19:54:29.920][DEBUG] [Camera Thread] Calculating step metric... [10/05/17 19:54:29.920][DEBUG] [Camera Thread] Taking auto focus frame(s)... [10/05/17 19:54:29.980][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [10/05/17 19:54:29.980][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [10/05/17 19:54:29.980][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [10/05/17 19:54:29.980][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [10/05/17 19:54:30.013][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 19:54:30.014][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 19:54:46.710][DEBUG] [Camera Thread] QSI expose and download: 16.6319513s [10/05/17 19:54:46.888][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 19:54:46.888][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 19:54:47.552][DEBUG] [Main Thread] --> Find stars (normal) [10/05/17 19:54:47.663][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [10/05/17 19:54:53.177][DEBUG] [Main Thread] Star detection using min star size of 4px... [10/05/17 19:54:53.177][DEBUG] [Main Thread] Star detection using max star size of 80px... [10/05/17 19:54:53.394][DEBUG] [Main Thread] Find stars took: 5842 ms... [10/05/17 19:54:53.395][DEBUG] [Main Thread] Star list contains 13 stars... [10/05/17 19:54:53.395][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [10/05/17 19:54:53.668][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.78 [10/05/17 19:54:54.682][DEBUG] [Camera Thread] Moving focuser to next position (7773)... [10/05/17 19:54:54.683][DEBUG] [Focuser Move Thread] Focuser moving to 7773 [10/05/17 19:54:54.740][DEBUG] [Focuser Move Thread] Focuser move call complete [10/05/17 19:54:55.795][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7773 matches requested position 7773 [10/05/17 19:54:55.795][DEBUG] [Camera Thread] Focuser move complete... [10/05/17 19:54:55.850][DEBUG] [Camera Thread] Focuser position matches requested position (7773), continuing... [10/05/17 19:54:55.909][DEBUG] [Camera Thread] Calculating step metric... [10/05/17 19:54:55.909][DEBUG] [Camera Thread] Taking auto focus frame(s)... [10/05/17 19:54:55.913][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [10/05/17 19:54:55.913][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [10/05/17 19:54:55.913][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [10/05/17 19:54:55.913][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [10/05/17 19:54:55.935][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 19:54:55.935][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 19:54:57.938][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [10/05/17 19:54:57.938][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [10/05/17 19:54:57.938][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/05/17 19:54:57.938][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 19:54:57.938][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/05/17 19:55:12.622][DEBUG] [Camera Thread] QSI expose and download: 16.6199506s [10/05/17 19:55:12.814][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 19:55:12.814][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 19:55:13.435][DEBUG] [Main Thread] --> Find stars (normal) [10/05/17 19:55:13.549][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [10/05/17 19:55:18.930][DEBUG] [Main Thread] Star detection using min star size of 4px... [10/05/17 19:55:18.931][DEBUG] [Main Thread] Star detection using max star size of 80px... [10/05/17 19:55:19.083][DEBUG] [Main Thread] Find stars took: 5647 ms... [10/05/17 19:55:19.083][DEBUG] [Main Thread] Star list contains 7 stars... [10/05/17 19:55:19.083][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [10/05/17 19:55:19.416][DEBUG] [Camera Thread] Auto focus HFR calculated at: 4.77 [10/05/17 19:55:20.480][DEBUG] [Camera Thread] Detected focus getting worse! Shifting auto focus range [10/05/17 19:55:20.482][DEBUG] [Focuser Move Thread] Focuser moving to 7818 [10/05/17 19:55:20.543][DEBUG] [Focuser Move Thread] Focuser move call complete [10/05/17 19:55:21.653][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7818 matches requested position 7818 [10/05/17 19:55:21.653][DEBUG] [Camera Thread] Focuser move complete... [10/05/17 19:55:21.818][DEBUG] [Camera Thread] Auto focus data [10/05/17 19:55:21.818][DEBUG] [Camera Thread] - Data Points: 7 [10/05/17 19:55:21.818][DEBUG] [Camera Thread] - Step Size: 10 [10/05/17 19:55:21.818][DEBUG] [Camera Thread] - Current Position: 7818 [10/05/17 19:55:21.819][DEBUG] [Camera Thread] - Initial Move Position: 7848 [10/05/17 19:55:21.849][DEBUG] [Camera Thread] AF Darks exception: The path is not of a legal form. [10/05/17 19:55:21.852][DEBUG] [Camera Thread] Moving focuser to next position (7848)... [10/05/17 19:55:21.857][DEBUG] [Focuser Move Thread] Focuser moving to 7848 [10/05/17 19:55:21.917][DEBUG] [Focuser Move Thread] Focuser move call complete [10/05/17 19:55:22.971][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7848 matches requested position 7848 [10/05/17 19:55:22.973][DEBUG] [Camera Thread] Focuser move complete... [10/05/17 19:55:23.027][DEBUG] [Camera Thread] Focuser position matches requested position (7848), continuing... [10/05/17 19:55:23.088][DEBUG] [Camera Thread] Calculating step metric... [10/05/17 19:55:23.088][DEBUG] [Camera Thread] Taking auto focus frame(s)... [10/05/17 19:55:23.091][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [10/05/17 19:55:23.091][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [10/05/17 19:55:23.091][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [10/05/17 19:55:23.091][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [10/05/17 19:55:23.122][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 19:55:23.122][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 19:55:39.840][DEBUG] [Camera Thread] QSI expose and download: 16.6539525s [10/05/17 19:55:40.004][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 19:55:40.005][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 19:55:40.659][DEBUG] [Main Thread] --> Find stars (normal) [10/05/17 19:55:40.796][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [10/05/17 19:55:46.425][DEBUG] [Main Thread] Star detection using min star size of 4px... [10/05/17 19:55:46.425][DEBUG] [Main Thread] Star detection using max star size of 80px... [10/05/17 19:55:46.520][DEBUG] [Main Thread] Find stars took: 5861 ms... [10/05/17 19:55:46.520][DEBUG] [Main Thread] Star list contains 36 stars... [10/05/17 19:55:46.520][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [10/05/17 19:55:46.818][DEBUG] [Camera Thread] Auto focus HFR calculated at: 2.30 [10/05/17 19:55:47.842][DEBUG] [Camera Thread] Moving focuser to next position (7838)... [10/05/17 19:55:47.844][DEBUG] [Focuser Move Thread] Focuser moving to 7838 [10/05/17 19:55:47.900][DEBUG] [Focuser Move Thread] Focuser move call complete [10/05/17 19:55:48.954][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7838 matches requested position 7838 [10/05/17 19:55:48.954][DEBUG] [Camera Thread] Focuser move complete... [10/05/17 19:55:49.010][DEBUG] [Camera Thread] Focuser position matches requested position (7838), continuing... [10/05/17 19:55:49.122][DEBUG] [Camera Thread] Calculating step metric... [10/05/17 19:55:49.123][DEBUG] [Camera Thread] Taking auto focus frame(s)... [10/05/17 19:55:49.156][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [10/05/17 19:55:49.156][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [10/05/17 19:55:49.156][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [10/05/17 19:55:49.156][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [10/05/17 19:55:49.188][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 19:55:49.188][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 19:55:58.084][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [10/05/17 19:55:58.084][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [10/05/17 19:55:58.084][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/05/17 19:55:58.084][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 19:55:58.084][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/05/17 19:56:05.904][DEBUG] [Camera Thread] QSI expose and download: 16.6489522s [10/05/17 19:56:06.080][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 19:56:06.080][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 19:56:06.838][DEBUG] [Main Thread] --> Find stars (normal) [10/05/17 19:56:06.972][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [10/05/17 19:56:12.317][DEBUG] [Main Thread] Star detection using min star size of 4px... [10/05/17 19:56:12.317][DEBUG] [Main Thread] Star detection using max star size of 80px... [10/05/17 19:56:12.555][DEBUG] [Main Thread] Find stars took: 5717 ms... [10/05/17 19:56:12.555][DEBUG] [Main Thread] Star list contains 50 stars... [10/05/17 19:56:12.555][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [10/05/17 19:56:12.840][DEBUG] [Camera Thread] Auto focus HFR calculated at: 1.92 [10/05/17 19:56:13.866][DEBUG] [Camera Thread] Moving focuser to next position (7828)... [10/05/17 19:56:13.867][DEBUG] [Focuser Move Thread] Focuser moving to 7828 [10/05/17 19:56:13.929][DEBUG] [Focuser Move Thread] Focuser move call complete [10/05/17 19:56:14.987][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7828 matches requested position 7828 [10/05/17 19:56:14.987][DEBUG] [Camera Thread] Focuser move complete... [10/05/17 19:56:15.042][DEBUG] [Camera Thread] Focuser position matches requested position (7828), continuing... [10/05/17 19:56:15.097][DEBUG] [Camera Thread] Calculating step metric... [10/05/17 19:56:15.097][DEBUG] [Camera Thread] Taking auto focus frame(s)... [10/05/17 19:56:15.102][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [10/05/17 19:56:15.102][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [10/05/17 19:56:15.102][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [10/05/17 19:56:15.102][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [10/05/17 19:56:15.128][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 19:56:15.128][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 19:56:31.868][DEBUG] [Camera Thread] QSI expose and download: 16.6759538s [10/05/17 19:56:32.049][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 19:56:32.049][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 19:56:32.743][DEBUG] [Main Thread] --> Find stars (normal) [10/05/17 19:56:32.863][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [10/05/17 19:56:38.352][DEBUG] [Main Thread] Star detection using min star size of 4px... [10/05/17 19:56:38.352][DEBUG] [Main Thread] Star detection using max star size of 80px... [10/05/17 19:56:38.588][DEBUG] [Main Thread] Find stars took: 5844 ms... [10/05/17 19:56:38.588][DEBUG] [Main Thread] Star list contains 49 stars... [10/05/17 19:56:38.588][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [10/05/17 19:56:38.914][DEBUG] [Camera Thread] Auto focus HFR calculated at: 1.48 [10/05/17 19:56:39.924][DEBUG] [Camera Thread] Moving focuser to next position (7818)... [10/05/17 19:56:39.926][DEBUG] [Focuser Move Thread] Focuser moving to 7818 [10/05/17 19:56:39.986][DEBUG] [Focuser Move Thread] Focuser move call complete [10/05/17 19:56:41.046][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7818 matches requested position 7818 [10/05/17 19:56:41.047][DEBUG] [Camera Thread] Focuser move complete... [10/05/17 19:56:41.102][DEBUG] [Camera Thread] Focuser position matches requested position (7818), continuing... [10/05/17 19:56:41.160][DEBUG] [Camera Thread] Calculating step metric... [10/05/17 19:56:41.160][DEBUG] [Camera Thread] Taking auto focus frame(s)... [10/05/17 19:56:41.164][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [10/05/17 19:56:41.164][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [10/05/17 19:56:41.164][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [10/05/17 19:56:41.164][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [10/05/17 19:56:41.194][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 19:56:41.194][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 19:56:57.913][DEBUG] [Camera Thread] QSI expose and download: 16.6559527s [10/05/17 19:56:58.075][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 19:56:58.076][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 19:56:58.195][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [10/05/17 19:56:58.195][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [10/05/17 19:56:58.195][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/05/17 19:56:58.195][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 19:56:58.195][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/05/17 19:56:58.732][DEBUG] [Main Thread] --> Find stars (normal) [10/05/17 19:56:58.862][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [10/05/17 19:57:04.466][DEBUG] [Main Thread] Star detection using min star size of 4px... [10/05/17 19:57:04.466][DEBUG] [Main Thread] Star detection using max star size of 80px... [10/05/17 19:57:04.564][DEBUG] [Main Thread] Find stars took: 5831 ms... [10/05/17 19:57:04.564][DEBUG] [Main Thread] Star list contains 52 stars... [10/05/17 19:57:04.564][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [10/05/17 19:57:04.840][DEBUG] [Camera Thread] Auto focus HFR calculated at: 1.58 [10/05/17 19:57:05.872][DEBUG] [Camera Thread] Moving focuser to next position (7808)... [10/05/17 19:57:05.878][DEBUG] [Focuser Move Thread] Focuser moving to 7808 [10/05/17 19:57:05.934][DEBUG] [Focuser Move Thread] Focuser move call complete [10/05/17 19:57:07.005][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7808 matches requested position 7808 [10/05/17 19:57:07.005][DEBUG] [Camera Thread] Focuser move complete... [10/05/17 19:57:07.060][DEBUG] [Camera Thread] Focuser position matches requested position (7808), continuing... [10/05/17 19:57:07.129][DEBUG] [Camera Thread] Calculating step metric... [10/05/17 19:57:07.130][DEBUG] [Camera Thread] Taking auto focus frame(s)... [10/05/17 19:57:07.132][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [10/05/17 19:57:07.132][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [10/05/17 19:57:07.132][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [10/05/17 19:57:07.132][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [10/05/17 19:57:07.162][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 19:57:07.162][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 19:57:23.911][DEBUG] [Camera Thread] QSI expose and download: 16.6529525s [10/05/17 19:57:24.089][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 19:57:24.089][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 19:57:24.733][DEBUG] [Main Thread] --> Find stars (normal) [10/05/17 19:57:24.866][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [10/05/17 19:57:30.409][DEBUG] [Main Thread] Star detection using min star size of 4px... [10/05/17 19:57:30.409][DEBUG] [Main Thread] Star detection using max star size of 80px... [10/05/17 19:57:30.492][DEBUG] [Main Thread] Find stars took: 5759 ms... [10/05/17 19:57:30.492][DEBUG] [Main Thread] Star list contains 44 stars... [10/05/17 19:57:30.492][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [10/05/17 19:57:30.815][DEBUG] [Camera Thread] Auto focus HFR calculated at: 2.17 [10/05/17 19:57:31.842][DEBUG] [Camera Thread] Moving focuser to next position (7798)... [10/05/17 19:57:31.843][DEBUG] [Focuser Move Thread] Focuser moving to 7798 [10/05/17 19:57:31.899][DEBUG] [Focuser Move Thread] Focuser move call complete [10/05/17 19:57:32.954][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7798 matches requested position 7798 [10/05/17 19:57:32.954][DEBUG] [Camera Thread] Focuser move complete... [10/05/17 19:57:33.009][DEBUG] [Camera Thread] Focuser position matches requested position (7798), continuing... [10/05/17 19:57:33.064][DEBUG] [Camera Thread] Calculating step metric... [10/05/17 19:57:33.064][DEBUG] [Camera Thread] Taking auto focus frame(s)... [10/05/17 19:57:33.068][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [10/05/17 19:57:33.068][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [10/05/17 19:57:33.068][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [10/05/17 19:57:33.068][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [10/05/17 19:57:33.100][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 19:57:33.100][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 19:57:49.815][DEBUG] [Camera Thread] QSI expose and download: 16.6509524s [10/05/17 19:57:50.003][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 19:57:50.004][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 19:57:50.631][DEBUG] [Main Thread] --> Find stars (normal) [10/05/17 19:57:50.738][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [10/05/17 19:57:56.117][DEBUG] [Main Thread] Star detection using min star size of 4px... [10/05/17 19:57:56.117][DEBUG] [Main Thread] Star detection using max star size of 80px... [10/05/17 19:57:56.307][DEBUG] [Main Thread] Find stars took: 5675 ms... [10/05/17 19:57:56.307][DEBUG] [Main Thread] Star list contains 25 stars... [10/05/17 19:57:56.307][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [10/05/17 19:57:56.604][DEBUG] [Camera Thread] Auto focus HFR calculated at: 2.61 [10/05/17 19:57:57.680][DEBUG] [Camera Thread] Detected focus getting worse! Shifting auto focus range [10/05/17 19:57:57.686][DEBUG] [Focuser Move Thread] Focuser moving to 7863 [10/05/17 19:57:57.742][DEBUG] [Focuser Move Thread] Focuser move call complete [10/05/17 19:57:58.342][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [10/05/17 19:57:58.342][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [10/05/17 19:57:58.342][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/05/17 19:57:58.342][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 19:57:58.342][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/05/17 19:57:58.804][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7863 matches requested position 7863 [10/05/17 19:57:58.804][DEBUG] [Camera Thread] Focuser move complete... [10/05/17 19:57:58.864][DEBUG] [Camera Thread] Auto focus data [10/05/17 19:57:58.865][DEBUG] [Camera Thread] - Data Points: 7 [10/05/17 19:57:58.865][DEBUG] [Camera Thread] - Step Size: 10 [10/05/17 19:57:58.865][DEBUG] [Camera Thread] - Current Position: 7863 [10/05/17 19:57:58.865][DEBUG] [Camera Thread] - Initial Move Position: 7893 [10/05/17 19:57:58.869][DEBUG] [Camera Thread] AF Darks exception: The path is not of a legal form. [10/05/17 19:57:58.870][DEBUG] [Camera Thread] Moving focuser to next position (7893)... [10/05/17 19:57:58.870][DEBUG] [Focuser Move Thread] Focuser moving to 7893 [10/05/17 19:57:58.933][DEBUG] [Focuser Move Thread] Focuser move call complete [10/05/17 19:58:00.023][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7893 matches requested position 7893 [10/05/17 19:58:00.023][DEBUG] [Camera Thread] Focuser move complete... [10/05/17 19:58:00.142][DEBUG] [Camera Thread] Focuser position matches requested position (7893), continuing... [10/05/17 19:58:00.201][DEBUG] [Camera Thread] Calculating step metric... [10/05/17 19:58:00.201][DEBUG] [Camera Thread] Taking auto focus frame(s)... [10/05/17 19:58:00.223][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [10/05/17 19:58:00.223][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [10/05/17 19:58:00.223][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [10/05/17 19:58:00.223][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [10/05/17 19:58:00.255][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 19:58:00.255][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 19:58:17.044][DEBUG] [Camera Thread] QSI expose and download: 16.7249566s [10/05/17 19:58:17.222][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 19:58:17.222][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 19:58:17.902][DEBUG] [Main Thread] --> Find stars (normal) [10/05/17 19:58:18.038][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [10/05/17 19:58:23.761][DEBUG] [Main Thread] Star detection using min star size of 4px... [10/05/17 19:58:23.761][DEBUG] [Main Thread] Star detection using max star size of 80px... [10/05/17 19:58:23.849][DEBUG] [Main Thread] Find stars took: 5947 ms... [10/05/17 19:58:23.849][DEBUG] [Main Thread] Star list contains 6 stars... [10/05/17 19:58:23.849][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [10/05/17 19:58:24.207][DEBUG] [Camera Thread] Auto focus HFR calculated at: 4.81 [10/05/17 19:58:25.255][DEBUG] [Camera Thread] Moving focuser to next position (7883)... [10/05/17 19:58:25.255][DEBUG] [Focuser Move Thread] Focuser moving to 7883 [10/05/17 19:58:25.313][DEBUG] [Focuser Move Thread] Focuser move call complete [10/05/17 19:58:26.420][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7883 matches requested position 7883 [10/05/17 19:58:26.420][DEBUG] [Camera Thread] Focuser move complete... [10/05/17 19:58:26.530][DEBUG] [Camera Thread] Focuser position matches requested position (7883), continuing... [10/05/17 19:58:26.638][DEBUG] [Camera Thread] Calculating step metric... [10/05/17 19:58:26.638][DEBUG] [Camera Thread] Taking auto focus frame(s)... [10/05/17 19:58:26.642][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [10/05/17 19:58:26.642][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [10/05/17 19:58:26.643][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [10/05/17 19:58:26.643][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [10/05/17 19:58:26.676][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 19:58:26.676][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 19:58:43.414][DEBUG] [Camera Thread] QSI expose and download: 16.6739537s [10/05/17 19:58:43.595][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 19:58:43.595][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 19:58:44.207][DEBUG] [Main Thread] --> Find stars (normal) [10/05/17 19:58:44.327][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [10/05/17 19:58:49.761][DEBUG] [Main Thread] Star detection using min star size of 4px... [10/05/17 19:58:49.761][DEBUG] [Main Thread] Star detection using max star size of 80px... [10/05/17 19:58:50.047][DEBUG] [Main Thread] Find stars took: 5840 ms... [10/05/17 19:58:50.047][DEBUG] [Main Thread] Star list contains 7 stars... [10/05/17 19:58:50.047][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [10/05/17 19:58:50.377][DEBUG] [Camera Thread] Auto focus HFR calculated at: 4.70 [10/05/17 19:58:51.393][DEBUG] [Camera Thread] Moving focuser to next position (7873)... [10/05/17 19:58:51.394][DEBUG] [Focuser Move Thread] Focuser moving to 7873 [10/05/17 19:58:51.462][DEBUG] [Focuser Move Thread] Focuser move call complete [10/05/17 19:58:52.517][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7873 matches requested position 7873 [10/05/17 19:58:52.517][DEBUG] [Camera Thread] Focuser move complete... [10/05/17 19:58:52.572][DEBUG] [Camera Thread] Focuser position matches requested position (7873), continuing... [10/05/17 19:58:52.630][DEBUG] [Camera Thread] Calculating step metric... [10/05/17 19:58:52.630][DEBUG] [Camera Thread] Taking auto focus frame(s)... [10/05/17 19:58:52.639][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [10/05/17 19:58:52.639][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [10/05/17 19:58:52.639][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [10/05/17 19:58:52.639][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [10/05/17 19:58:52.662][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 19:58:52.663][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 19:58:58.472][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [10/05/17 19:58:58.472][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [10/05/17 19:58:58.472][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/05/17 19:58:58.472][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 19:58:58.472][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/05/17 19:59:09.387][DEBUG] [Camera Thread] QSI expose and download: 16.6599529s [10/05/17 19:59:09.572][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 19:59:09.572][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 19:59:10.190][DEBUG] [Main Thread] --> Find stars (normal) [10/05/17 19:59:10.319][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [10/05/17 19:59:15.692][DEBUG] [Main Thread] Star detection using min star size of 4px... [10/05/17 19:59:15.692][DEBUG] [Main Thread] Star detection using max star size of 80px... [10/05/17 19:59:15.918][DEBUG] [Main Thread] Find stars took: 5728 ms... [10/05/17 19:59:15.918][DEBUG] [Main Thread] Star list contains 8 stars... [10/05/17 19:59:15.919][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [10/05/17 19:59:16.219][DEBUG] [Camera Thread] Auto focus HFR calculated at: 4.07 [10/05/17 19:59:17.236][DEBUG] [Camera Thread] Moving focuser to next position (7863)... [10/05/17 19:59:17.237][DEBUG] [Focuser Move Thread] Focuser moving to 7863 [10/05/17 19:59:17.294][DEBUG] [Focuser Move Thread] Focuser move call complete [10/05/17 19:59:18.348][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7863 matches requested position 7863 [10/05/17 19:59:18.349][DEBUG] [Camera Thread] Focuser move complete... [10/05/17 19:59:18.407][DEBUG] [Camera Thread] Focuser position matches requested position (7863), continuing... [10/05/17 19:59:18.464][DEBUG] [Camera Thread] Calculating step metric... [10/05/17 19:59:18.464][DEBUG] [Camera Thread] Taking auto focus frame(s)... [10/05/17 19:59:18.467][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [10/05/17 19:59:18.467][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [10/05/17 19:59:18.467][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [10/05/17 19:59:18.467][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [10/05/17 19:59:18.488][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 19:59:18.488][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 19:59:35.209][DEBUG] [Camera Thread] QSI expose and download: 16.6569527s [10/05/17 19:59:35.377][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 19:59:35.377][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 19:59:36.089][DEBUG] [Main Thread] --> Find stars (normal) [10/05/17 19:59:36.211][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [10/05/17 19:59:41.816][DEBUG] [Main Thread] Star detection using min star size of 4px... [10/05/17 19:59:41.816][DEBUG] [Main Thread] Star detection using max star size of 80px... [10/05/17 19:59:41.896][DEBUG] [Main Thread] Find stars took: 5806 ms... [10/05/17 19:59:41.896][DEBUG] [Main Thread] Star list contains 13 stars... [10/05/17 19:59:41.896][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [10/05/17 19:59:42.186][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.62 [10/05/17 19:59:43.230][DEBUG] [Camera Thread] Moving focuser to next position (7853)... [10/05/17 19:59:43.230][DEBUG] [Focuser Move Thread] Focuser moving to 7853 [10/05/17 19:59:43.291][DEBUG] [Focuser Move Thread] Focuser move call complete [10/05/17 19:59:44.348][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7853 matches requested position 7853 [10/05/17 19:59:44.348][DEBUG] [Camera Thread] Focuser move complete... [10/05/17 19:59:44.403][DEBUG] [Camera Thread] Focuser position matches requested position (7853), continuing... [10/05/17 19:59:44.466][DEBUG] [Camera Thread] Calculating step metric... [10/05/17 19:59:44.467][DEBUG] [Camera Thread] Taking auto focus frame(s)... [10/05/17 19:59:44.471][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [10/05/17 19:59:44.471][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [10/05/17 19:59:44.471][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [10/05/17 19:59:44.471][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [10/05/17 19:59:44.506][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 19:59:44.506][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 19:59:58.589][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [10/05/17 19:59:58.589][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [10/05/17 19:59:58.589][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/05/17 19:59:58.589][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 19:59:58.589][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/05/17 20:00:01.234][DEBUG] [Camera Thread] QSI expose and download: 16.6639531s [10/05/17 20:00:01.418][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 20:00:01.418][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 20:00:02.025][DEBUG] [Main Thread] --> Find stars (normal) [10/05/17 20:00:02.165][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [10/05/17 20:00:07.720][DEBUG] [Main Thread] Star detection using min star size of 4px... [10/05/17 20:00:07.721][DEBUG] [Main Thread] Star detection using max star size of 80px... [10/05/17 20:00:07.924][DEBUG] [Main Thread] Find stars took: 5899 ms... [10/05/17 20:00:07.924][DEBUG] [Main Thread] Star list contains 25 stars... [10/05/17 20:00:07.924][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [10/05/17 20:00:08.205][DEBUG] [Camera Thread] Auto focus HFR calculated at: 2.74 [10/05/17 20:00:09.221][DEBUG] [Camera Thread] Moving focuser to next position (7843)... [10/05/17 20:00:09.222][DEBUG] [Focuser Move Thread] Focuser moving to 7843 [10/05/17 20:00:09.291][DEBUG] [Focuser Move Thread] Focuser move call complete [10/05/17 20:00:10.347][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7843 matches requested position 7843 [10/05/17 20:00:10.347][DEBUG] [Camera Thread] Focuser move complete... [10/05/17 20:00:10.410][DEBUG] [Camera Thread] Focuser position matches requested position (7843), continuing... [10/05/17 20:00:10.520][DEBUG] [Camera Thread] Calculating step metric... [10/05/17 20:00:10.520][DEBUG] [Camera Thread] Taking auto focus frame(s)... [10/05/17 20:00:10.534][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [10/05/17 20:00:10.535][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [10/05/17 20:00:10.535][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [10/05/17 20:00:10.535][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [10/05/17 20:00:10.572][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 20:00:10.572][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 20:00:27.330][DEBUG] [Camera Thread] QSI expose and download: 16.6939549s [10/05/17 20:00:27.507][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 20:00:27.507][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 20:00:28.158][DEBUG] [Main Thread] --> Find stars (normal) [10/05/17 20:00:28.279][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [10/05/17 20:00:33.754][DEBUG] [Main Thread] Star detection using min star size of 4px... [10/05/17 20:00:33.754][DEBUG] [Main Thread] Star detection using max star size of 80px... [10/05/17 20:00:33.856][DEBUG] [Main Thread] Find stars took: 5697 ms... [10/05/17 20:00:33.856][DEBUG] [Main Thread] Star list contains 33 stars... [10/05/17 20:00:33.856][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [10/05/17 20:00:34.109][DEBUG] [Camera Thread] Auto focus HFR calculated at: 2.38 [10/05/17 20:00:35.122][DEBUG] [Camera Thread] Moving focuser to next position (7833)... [10/05/17 20:00:35.123][DEBUG] [Focuser Move Thread] Focuser moving to 7833 [10/05/17 20:00:35.178][DEBUG] [Focuser Move Thread] Focuser move call complete [10/05/17 20:00:36.233][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7833 matches requested position 7833 [10/05/17 20:00:36.233][DEBUG] [Camera Thread] Focuser move complete... [10/05/17 20:00:36.288][DEBUG] [Camera Thread] Focuser position matches requested position (7833), continuing... [10/05/17 20:00:36.406][DEBUG] [Camera Thread] Calculating step metric... [10/05/17 20:00:36.406][DEBUG] [Camera Thread] Taking auto focus frame(s)... [10/05/17 20:00:36.413][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [10/05/17 20:00:36.413][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [10/05/17 20:00:36.413][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [10/05/17 20:00:36.414][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [10/05/17 20:00:36.448][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 20:00:36.448][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 20:00:53.188][DEBUG] [Camera Thread] QSI expose and download: 16.6759538s [10/05/17 20:00:53.367][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 20:00:53.367][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 20:00:54.025][DEBUG] [Main Thread] --> Find stars (normal) [10/05/17 20:00:54.141][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [10/05/17 20:00:58.702][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [10/05/17 20:00:58.702][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [10/05/17 20:00:58.702][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/05/17 20:00:58.702][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 20:00:58.702][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/05/17 20:00:59.555][DEBUG] [Main Thread] Star detection using min star size of 4px... [10/05/17 20:00:59.555][DEBUG] [Main Thread] Star detection using max star size of 80px... [10/05/17 20:00:59.673][DEBUG] [Main Thread] Find stars took: 5648 ms... [10/05/17 20:00:59.674][DEBUG] [Main Thread] Star list contains 47 stars... [10/05/17 20:00:59.674][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [10/05/17 20:01:00.014][DEBUG] [Camera Thread] Auto focus HFR calculated at: 1.87 [10/05/17 20:01:01.066][DEBUG] [Camera Thread] Moving focuser to next position (7823)... [10/05/17 20:01:01.066][DEBUG] [Focuser Move Thread] Focuser moving to 7823 [10/05/17 20:01:01.126][DEBUG] [Focuser Move Thread] Focuser move call complete [10/05/17 20:01:02.181][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7823 matches requested position 7823 [10/05/17 20:01:02.181][DEBUG] [Camera Thread] Focuser move complete... [10/05/17 20:01:02.236][DEBUG] [Camera Thread] Focuser position matches requested position (7823), continuing... [10/05/17 20:01:02.302][DEBUG] [Camera Thread] Calculating step metric... [10/05/17 20:01:02.303][DEBUG] [Camera Thread] Taking auto focus frame(s)... [10/05/17 20:01:02.306][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [10/05/17 20:01:02.306][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [10/05/17 20:01:02.306][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [10/05/17 20:01:02.306][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [10/05/17 20:01:02.338][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 20:01:02.338][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 20:01:19.087][DEBUG] [Camera Thread] QSI expose and download: 16.6849543s [10/05/17 20:01:19.293][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 20:01:19.293][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 20:01:19.998][DEBUG] [Main Thread] --> Find stars (normal) [10/05/17 20:01:20.130][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [10/05/17 20:01:25.637][DEBUG] [Main Thread] Star detection using min star size of 4px... [10/05/17 20:01:25.637][DEBUG] [Main Thread] Star detection using max star size of 80px... [10/05/17 20:01:25.782][DEBUG] [Main Thread] Find stars took: 5783 ms... [10/05/17 20:01:25.782][DEBUG] [Main Thread] Star list contains 52 stars... [10/05/17 20:01:25.782][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [10/05/17 20:01:26.064][DEBUG] [Camera Thread] Auto focus HFR calculated at: 1.48 [10/05/17 20:01:27.078][DEBUG] [Camera Thread] Moving focuser to next position (7813)... [10/05/17 20:01:27.079][DEBUG] [Focuser Move Thread] Focuser moving to 7813 [10/05/17 20:01:27.136][DEBUG] [Focuser Move Thread] Focuser move call complete [10/05/17 20:01:28.195][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7813 matches requested position 7813 [10/05/17 20:01:28.195][DEBUG] [Camera Thread] Focuser move complete... [10/05/17 20:01:28.250][DEBUG] [Camera Thread] Focuser position matches requested position (7813), continuing... [10/05/17 20:01:28.313][DEBUG] [Camera Thread] Calculating step metric... [10/05/17 20:01:28.313][DEBUG] [Camera Thread] Taking auto focus frame(s)... [10/05/17 20:01:28.320][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [10/05/17 20:01:28.320][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [10/05/17 20:01:28.320][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [10/05/17 20:01:28.320][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [10/05/17 20:01:28.357][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 20:01:28.357][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 20:01:45.075][DEBUG] [Camera Thread] QSI expose and download: 16.6549526s [10/05/17 20:01:45.237][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 20:01:45.237][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 20:01:45.873][DEBUG] [Main Thread] --> Find stars (normal) [10/05/17 20:01:45.997][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [10/05/17 20:01:51.510][DEBUG] [Main Thread] Star detection using min star size of 4px... [10/05/17 20:01:51.510][DEBUG] [Main Thread] Star detection using max star size of 80px... [10/05/17 20:01:51.602][DEBUG] [Main Thread] Find stars took: 5729 ms... [10/05/17 20:01:51.602][DEBUG] [Main Thread] Star list contains 52 stars... [10/05/17 20:01:51.602][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [10/05/17 20:01:51.876][DEBUG] [Camera Thread] Auto focus HFR calculated at: 1.71 [10/05/17 20:01:52.884][DEBUG] [Camera Thread] Moving focuser to next position (7803)... [10/05/17 20:01:52.884][DEBUG] [Focuser Move Thread] Focuser moving to 7803 [10/05/17 20:01:52.940][DEBUG] [Focuser Move Thread] Focuser move call complete [10/05/17 20:01:53.995][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7803 matches requested position 7803 [10/05/17 20:01:53.995][DEBUG] [Camera Thread] Focuser move complete... [10/05/17 20:01:54.050][DEBUG] [Camera Thread] Focuser position matches requested position (7803), continuing... [10/05/17 20:01:54.115][DEBUG] [Camera Thread] Calculating step metric... [10/05/17 20:01:54.115][DEBUG] [Camera Thread] Taking auto focus frame(s)... [10/05/17 20:01:54.118][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [10/05/17 20:01:54.118][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [10/05/17 20:01:54.118][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [10/05/17 20:01:54.118][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [10/05/17 20:01:54.150][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 20:01:54.150][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 20:01:58.816][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [10/05/17 20:01:58.816][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [10/05/17 20:01:58.816][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/05/17 20:01:58.816][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 20:01:58.816][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/05/17 20:02:10.850][DEBUG] [Camera Thread] QSI expose and download: 16.6359516s [10/05/17 20:02:11.025][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 20:02:11.025][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 20:02:11.689][DEBUG] [Main Thread] --> Find stars (normal) [10/05/17 20:02:11.822][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [10/05/17 20:02:17.188][DEBUG] [Main Thread] Star detection using min star size of 4px... [10/05/17 20:02:17.189][DEBUG] [Main Thread] Star detection using max star size of 80px... [10/05/17 20:02:17.395][DEBUG] [Main Thread] Find stars took: 5704 ms... [10/05/17 20:02:17.395][DEBUG] [Main Thread] Star list contains 41 stars... [10/05/17 20:02:17.395][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [10/05/17 20:02:17.667][DEBUG] [Camera Thread] Auto focus HFR calculated at: 2.32 [10/05/17 20:02:18.694][DEBUG] [Camera Thread] Moving focuser to next position (7793)... [10/05/17 20:02:18.696][DEBUG] [Focuser Move Thread] Focuser moving to 7793 [10/05/17 20:02:18.763][DEBUG] [Focuser Move Thread] Focuser move call complete [10/05/17 20:02:19.866][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7793 matches requested position 7793 [10/05/17 20:02:19.866][DEBUG] [Camera Thread] Focuser move complete... [10/05/17 20:02:19.921][DEBUG] [Camera Thread] Focuser position matches requested position (7793), continuing... [10/05/17 20:02:20.095][DEBUG] [Camera Thread] Calculating step metric... [10/05/17 20:02:20.095][DEBUG] [Camera Thread] Taking auto focus frame(s)... [10/05/17 20:02:20.124][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [10/05/17 20:02:20.124][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [10/05/17 20:02:20.124][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [10/05/17 20:02:20.124][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [10/05/17 20:02:20.153][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 20:02:20.153][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 20:02:36.854][DEBUG] [Camera Thread] QSI expose and download: 16.6369516s [10/05/17 20:02:37.020][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 20:02:37.020][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 20:02:37.641][DEBUG] [Main Thread] --> Find stars (normal) [10/05/17 20:02:37.777][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [10/05/17 20:02:43.540][DEBUG] [Main Thread] Star detection using min star size of 4px... [10/05/17 20:02:43.541][DEBUG] [Main Thread] Star detection using max star size of 80px... [10/05/17 20:02:43.637][DEBUG] [Main Thread] Find stars took: 5996 ms... [10/05/17 20:02:43.637][DEBUG] [Main Thread] Star list contains 21 stars... [10/05/17 20:02:43.638][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [10/05/17 20:02:43.931][DEBUG] [Camera Thread] Auto focus HFR calculated at: 2.81 [10/05/17 20:02:44.978][DEBUG] [Camera Thread] New auto focus method calculated focus at: 7822 [10/05/17 20:02:44.979][DEBUG] [Camera Thread] Old auto focus method calculated focus at: 7825 [10/05/17 20:02:44.979][DEBUG] [Camera Thread] Using new auto focus method for focuser position [10/05/17 20:02:44.979][DEBUG] [Camera Thread] New focus position is at 7822 (@100.00C). Moving focuser... [10/05/17 20:02:44.981][DEBUG] [Focuser Move Thread] Focuser moving to 7822 [10/05/17 20:02:45.043][DEBUG] [Focuser Move Thread] Focuser move call complete [10/05/17 20:02:46.142][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7822 matches requested position 7822 [10/05/17 20:02:46.142][DEBUG] [Camera Thread] Focuser move complete... [10/05/17 20:02:46.252][DEBUG] [Camera Thread] Focuser successfully set to focus position (7822) [10/05/17 20:02:46.367][DEBUG] [Camera Thread] Taking validation frame at focus position... [10/05/17 20:02:46.371][DEBUG] [Camera Thread] Taking auto focus frame(s)... [10/05/17 20:02:46.377][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [10/05/17 20:02:46.377][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [10/05/17 20:02:46.377][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [10/05/17 20:02:46.377][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [10/05/17 20:02:46.412][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 20:02:46.412][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 20:02:58.951][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [10/05/17 20:02:58.951][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [10/05/17 20:02:58.951][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/05/17 20:02:58.951][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 20:02:58.951][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/05/17 20:03:03.152][DEBUG] [Camera Thread] QSI expose and download: 16.6759539s [10/05/17 20:03:03.324][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 20:03:03.324][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 20:03:03.999][DEBUG] [Main Thread] --> Find stars (normal) [10/05/17 20:03:04.115][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [10/05/17 20:03:09.432][DEBUG] [Main Thread] Star detection using min star size of 4px... [10/05/17 20:03:09.433][DEBUG] [Main Thread] Star detection using max star size of 80px... [10/05/17 20:03:09.654][DEBUG] [Main Thread] Find stars took: 5654 ms... [10/05/17 20:03:09.654][DEBUG] [Main Thread] Star list contains 55 stars... [10/05/17 20:03:09.654][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [10/05/17 20:03:09.987][DEBUG] [Camera Thread] Auto focus HFR calculated at: 1.43 [10/05/17 20:03:10.990][DEBUG] [Camera Thread] Auto focus is complete (best fit method: 7822; HFR 1.43)... [10/05/17 20:03:10.995][DEBUG] [Camera Thread] Entering chart disposal loop... [10/05/17 20:03:59.090][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [10/05/17 20:03:59.090][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [10/05/17 20:03:59.090][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/05/17 20:03:59.090][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 20:03:59.090][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/05/17 20:04:32.599][DEBUG] [Camera Thread] Chart disposal loop is complete... [10/05/17 20:04:32.599][DEBUG] [Camera Thread] Auto focus is done... [10/05/17 20:04:32.614][DEBUG] [AfChartThread] AF chart dialog is closed... [10/05/17 20:04:32.649][DEBUG] [Camera Thread] Auto focus complete... [10/05/17 20:04:32.687][DEBUG] [Camera Thread] SGM_FOCUSER_AUTO_FOCUS complete... [10/05/17 20:04:40.952][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS message received... [10/05/17 20:04:40.955][DEBUG] [Camera Thread] ASCOM (QSI) camera: frame and focus... [10/05/17 20:04:40.987][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 20:04:40.987][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 20:04:59.218][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [10/05/17 20:04:59.218][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [10/05/17 20:04:59.218][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/05/17 20:04:59.218][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 20:04:59.218][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/05/17 20:05:16.745][DEBUG] [Camera Thread] QSI expose and download: 35.6620397s [10/05/17 20:05:16.926][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 20:05:16.926][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 20:05:18.356][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS complete... [10/05/17 20:05:38.518][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS message received... [10/05/17 20:05:38.525][DEBUG] [Camera Thread] ASCOM (QSI) camera: frame and focus... [10/05/17 20:05:38.557][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 20:05:38.557][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 20:05:59.341][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [10/05/17 20:05:59.341][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [10/05/17 20:05:59.341][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/05/17 20:05:59.341][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 20:05:59.341][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/05/17 20:06:14.329][DEBUG] [Camera Thread] QSI expose and download: 35.7080424s [10/05/17 20:06:14.501][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 20:06:14.501][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 20:06:15.421][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS complete... [10/05/17 20:06:38.208][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS message received... [10/05/17 20:06:38.213][DEBUG] [Camera Thread] ASCOM (QSI) camera: frame and focus... [10/05/17 20:06:38.239][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 20:06:38.239][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 20:06:44.077][DEBUG] [Camera Thread] QSI expose and download: 5.7743303s [10/05/17 20:06:44.166][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 20:06:44.166][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 20:06:44.325][DEBUG] [Camera Thread] ASCOM (QSI) camera: frame and focus... [10/05/17 20:06:44.352][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 20:06:44.352][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 20:06:50.212][DEBUG] [Camera Thread] QSI expose and download: 5.7963315s [10/05/17 20:06:50.292][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 20:06:50.292][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 20:06:50.426][DEBUG] [Camera Thread] ASCOM (QSI) camera: frame and focus... [10/05/17 20:06:50.446][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 20:06:50.446][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 20:06:56.289][DEBUG] [Camera Thread] QSI expose and download: 5.7663298s [10/05/17 20:06:56.393][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 20:06:56.393][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 20:06:56.648][DEBUG] [Camera Thread] ASCOM (QSI) camera: frame and focus... [10/05/17 20:06:56.669][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 20:06:56.669][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 20:06:59.481][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [10/05/17 20:06:59.481][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [10/05/17 20:06:59.481][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/05/17 20:06:59.481][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 20:06:59.481][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/05/17 20:07:02.507][DEBUG] [Camera Thread] QSI expose and download: 5.7743303s [10/05/17 20:07:02.594][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 20:07:02.594][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 20:07:02.780][DEBUG] [Camera Thread] ASCOM (QSI) camera: frame and focus... [10/05/17 20:07:02.814][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 20:07:02.814][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 20:07:08.646][DEBUG] [Camera Thread] QSI expose and download: 5.7683299s [10/05/17 20:07:08.721][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 20:07:08.721][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 20:07:08.899][DEBUG] [Camera Thread] ASCOM (QSI) camera: frame and focus... [10/05/17 20:07:08.924][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 20:07:08.924][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 20:07:14.778][DEBUG] [Camera Thread] QSI expose and download: 5.788331s [10/05/17 20:07:14.867][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 20:07:14.867][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 20:07:15.051][DEBUG] [Camera Thread] ASCOM (QSI) camera: frame and focus... [10/05/17 20:07:15.084][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 20:07:15.084][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 20:07:20.919][DEBUG] [Camera Thread] QSI expose and download: 5.7713301s [10/05/17 20:07:21.011][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 20:07:21.011][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 20:07:21.169][DEBUG] [Camera Thread] ASCOM (QSI) camera: frame and focus... [10/05/17 20:07:21.193][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 20:07:21.194][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 20:07:27.030][DEBUG] [Camera Thread] QSI expose and download: 5.7733302s [10/05/17 20:07:27.118][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 20:07:27.118][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 20:07:27.290][DEBUG] [Camera Thread] ASCOM (QSI) camera: frame and focus... [10/05/17 20:07:27.320][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 20:07:27.320][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 20:07:33.173][DEBUG] [Camera Thread] QSI expose and download: 5.7863309s [10/05/17 20:07:33.268][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 20:07:33.268][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 20:07:33.462][DEBUG] [Camera Thread] ASCOM (QSI) camera: frame and focus... [10/05/17 20:07:33.499][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 20:07:33.499][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 20:07:36.748][DEBUG] [Camera Thread] ASCOM (QSI) Camera: abort message received... [10/05/17 20:07:36.760][DEBUG] [Camera Thread] ASCOM (QSI) Camera: Attempting to abort exposure... [10/05/17 20:07:37.048][DEBUG] [Camera Thread] ASCOM (QSI) Camera: Exposure aborted... [10/05/17 20:07:37.105][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS complete... [10/05/17 20:08:27.094][DEBUG] [Main Thread] Filter wheel is required... [10/05/17 20:08:27.094][DEBUG] [Main Thread] Filter wheel is still connected. Ignoring connect step... [10/05/17 20:08:27.095][DEBUG] [Main Thread] Focuser is required... [10/05/17 20:08:27.095][DEBUG] [Main Thread] Focuser is still connected. Ignoring connect step... [10/05/17 20:08:27.095][DEBUG] [Main Thread] Telescope is required... [10/05/17 20:08:27.096][DEBUG] [Main Thread] Telescope is still connected. Ignoring connect step... [10/05/17 20:08:27.104][DEBUG] [Main Thread] Safety device might be required... [10/05/17 20:08:27.104][DEBUG] [Main Thread] Safety device is still connected. Ignoring connect step... [10/05/17 20:08:27.105][DEBUG] [Main Thread] Environment device required... [10/05/17 20:08:27.105][DEBUG] [Main Thread] Environment device is still connected. Ignoring connect step... [10/05/17 20:08:27.202][DEBUG] [Main Thread] PopulateDataModel: Transferring view to the data model... [10/05/17 20:08:27.208][DEBUG] [Main Thread] GNS Startup... [10/05/17 20:08:27.227][DEBUG] [Sequence Thread] Dispatch loop: Received SGM_RUN_SEQUENCE... [10/05/17 20:08:27.243][DEBUG] [Sequence Thread] GuiderCheck: Checking auto guider status... [10/05/17 20:08:27.243][DEBUG] [Sequence Thread] GuiderCheck: Auto guider reports connected... [10/05/17 20:08:27.243][DEBUG] [Sequence Thread] Checking auto guider version... [10/05/17 20:08:27.244][DEBUG] [Sequence Thread] PHD2: Performing version check... [10/05/17 20:08:27.244][DEBUG] [Sequence Thread] PHD2: Min version is 2.6.0 [10/05/17 20:08:27.244][DEBUG] [Sequence Thread] PHD2: User version is 2.6.3 [10/05/17 20:08:27.245][DEBUG] [Sequence Thread] Finding first active group... [10/05/17 20:08:27.245][DEBUG] [Sequence Thread] Looking at target Veil... [10/05/17 20:08:27.245][DEBUG] [Sequence Thread] Target is active: True [10/05/17 20:08:27.246][DEBUG] [Sequence Thread] Target is complete: False [10/05/17 20:08:27.246][DEBUG] [Sequence Thread] Target marked as past end time: False [10/05/17 20:08:27.246][DEBUG] [Sequence Thread] Target has end time: False [10/05/17 20:08:27.246][DEBUG] [Sequence Thread] Target end time: 10/5/2017 7:29:00 PM [10/05/17 20:08:27.246][DEBUG] [Sequence Thread] Active group found... [10/05/17 20:08:27.246][DEBUG] [Sequence Thread] Sequence should find starting target; Found target: 0 [10/05/17 20:08:27.250][DEBUG] [Sequence Thread] ************** SEQUENCE DUMP ************** [10/05/17 20:08:27.250][DEBUG] [Sequence Thread] sDirectory: C:\Users\Public\Documents\SGP Images [10/05/17 20:08:27.250][DEBUG] [Sequence Thread] sBaseName: [10/05/17 20:08:27.250][DEBUG] [Sequence Thread] nDelayFirst: 0 [10/05/17 20:08:27.250][DEBUG] [Sequence Thread] nDelayBetween: 0 [10/05/17 20:08:27.250][DEBUG] [Sequence Thread] bFinishFullEventFirst: False [10/05/17 20:08:27.250][DEBUG] [Sequence Thread] arEventGroups: System.Collections.Generic.List`1[SequenceGenerator.EventGroup] [10/05/17 20:08:27.250][DEBUG] [Sequence Thread] nCurrentEventIndex: 0 [10/05/17 20:08:27.250][DEBUG] [Sequence Thread] dtElapsedTime: 1/1/0001 12:00:00 AM +00:00 [10/05/17 20:08:27.250][DEBUG] [Sequence Thread] bStarted: False [10/05/17 20:08:27.250][DEBUG] [Sequence Thread] bIsSequenceRunning: False [10/05/17 20:08:27.250][DEBUG] [Sequence Thread] sFileNamePattern: %ft\%tn_%el_%bi_%su_%fn [10/05/17 20:08:27.250][DEBUG] [Sequence Thread] sVersion: 2.6.0.23 [10/05/17 20:08:27.250][DEBUG] [Sequence Thread] tsElapsedTime: 00:00:00 [10/05/17 20:08:27.250][DEBUG] [Sequence Thread] bCaptureUntil: False [10/05/17 20:08:27.250][DEBUG] [Sequence Thread] dtCaptureUntil: 10/5/2017 7:29:00 PM [10/05/17 20:08:27.250][DEBUG] [Sequence Thread] userProfile: SequenceGenerator.UserProfile [10/05/17 20:08:27.250][DEBUG] [Sequence Thread] nAfNebulosityRejection: 0.85 [10/05/17 20:08:27.250][DEBUG] [Sequence Thread] sequenceFiles: System.Collections.Generic.Dictionary`2[System.String,System.String] [10/05/17 20:08:27.250][DEBUG] [Sequence Thread] sequenceFileList: System.Collections.Generic.List`1[SequenceGenerator.SequenceFile] [10/05/17 20:08:27.250][DEBUG] [Sequence Thread] imageHistory: SequenceGenerator.ImageHistoryManager [10/05/17 20:08:27.250][DEBUG] [Sequence Thread] nAfSampleSize: 100 [10/05/17 20:08:27.250][DEBUG] [Sequence Thread] bCaptureCalFramesIfSequenceFails: False [10/05/17 20:08:27.250][DEBUG] [Sequence Thread] notificationsList: System.Collections.Generic.List`1[SequenceGenerator.NotoficationInfo] [10/05/17 20:08:27.250][DEBUG] [Sequence Thread] bRunEndOfSequenceScript: True [10/05/17 20:08:27.250][DEBUG] [Sequence Thread] sEndOfSequenceScript: C:\Users\Public\Documents\MyObservatoryScripts\ShutDownfor_SGP_Boot.vbs [10/05/17 20:08:27.250][DEBUG] [Sequence Thread] bDisconnectAllGearOnSequenceEnd: False [10/05/17 20:08:27.250][DEBUG] [Sequence Thread] ************** PROFILE DUMP ************** [10/05/17 20:08:27.250][DEBUG] [Sequence Thread] sForImagingInstance: Sequence Generator Pro [10/05/17 20:08:27.250][DEBUG] [Sequence Thread] sProfileName: Takahashi [10/05/17 20:08:27.250][DEBUG] [Sequence Thread] bDefault: True [10/05/17 20:08:27.250][DEBUG] [Sequence Thread] sCameraName: QSI CCD Camera [10/05/17 20:08:27.250][DEBUG] [Sequence Thread] nGain1x1: 0 [10/05/17 20:08:27.250][DEBUG] [Sequence Thread] nOffset1x1: 100 [10/05/17 20:08:27.250][DEBUG] [Sequence Thread] nGain2x2: 0 [10/05/17 20:08:27.250][DEBUG] [Sequence Thread] nOffset2x2: 100 [10/05/17 20:08:27.250][DEBUG] [Sequence Thread] nGain3x3: 0 [10/05/17 20:08:27.250][DEBUG] [Sequence Thread] nOffset3x3: 100 [10/05/17 20:08:27.250][DEBUG] [Sequence Thread] nGain4x4: 0 [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] nOffset4x4: 100 [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] nCooldownTemp: -10 [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] nCooldownTime: 6 [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] nWarmupTemp: 20 [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] nWarmupTime: 3 [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] bAutoCoolDown: True [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] bAutoWarmUp: True [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] sFilterWheelName: QSI Internal Filter Wheel [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] nFilterDelay: 0 [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] sFocuserName: RoboFocus [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] bReverseFocuser: False [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] nFineFocusStepSize: 10 [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] nCoarseFocusStepSize: 30 [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] bAutoAdjustFocusPerFilter: False [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] bAutoFocusForFilterChangeOnly: True [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] bUseAutoFocus: True [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] bAutoFocusPerFrame: True [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] bAutoFocusOverTime: False [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] nAutoFocusFramerInterval: 3 [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] nAutoFocusTimerInterval: 10 [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] bAutoFocusForceForFilter: False [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] bAutoFocusBeforeFirstFrame: False [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] bAutoFocusManualStar: False [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] bAutoManualFocusForFilterChangeOnly: True [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] bUseAutoManualFocus: False [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] bAutoManualFocusPerFrame: False [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] bAutoManualFocusOverTime: False [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] nAutoManualFocusFramerInterval: 1 [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] nAutoManualFocusTimerInterval: 10 [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] bAutoManualFocusForceForFilter: False [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] bAutoManualFocusBeforeFirstFrame: True [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] bOverrideMaxSteps: False [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] nOverrideMaxSteps: 1 [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] bTempComp: False [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] nStepsPerDegree: 0 [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] bPhdDither: False [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] nPhdDitherIndex: 0 [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] nPhdSettleValue: 0.6 [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] bRecalGuiderOnSlew: False [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] nDecRecalValue: 0 [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] sTelescopeName: POTH Hub [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] bAutoSlew: False [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] tpFocusFrame: [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] tpTargetFrame: [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] bPausePhdDuringDownload: True [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] bShutdownPhdWhenDone: True [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] bParkTelescopeWhenDone: True [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] sReferenceImagePath: [10/05/17 20:08:27.251][DEBUG] [Sequence Thread] bAutoCenterFrame: False [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] siReference: nEstRa: 0 nEstDec: 0 nRightAscention: 0 nDeclination: 0 nArcsecPerPixel: 0 nAngle: -500 bFlipped: False sSearchType: sBinning: nExposureLength: 0 sImagePath: bFailed: True sInfo: bDoneSolving: True nSides: 0 nMaxSolveTime: 60 [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] siScope: nEstRa: 0 nEstDec: 0 nRightAscention: 0 nDeclination: 0 nArcsecPerPixel: 0 nAngle: 0 bFlipped: False sSearchType: Not Available sBinning: 2x2 nExposureLength: 0 sImagePath: bFailed: True sInfo: bDoneSolving: True nSides: 0 nMaxSolveTime: 60 [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] nSolverExposure1x1: 1 [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] nSolverExposure2x2: 1 [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] nSolverExposure3x3: 1 [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] nSolverExposure4x4: 1 [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] nArcsec1x1: 0 [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] nArcsec2x2: 0 [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] nArcsec3x3: 0 [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] nArcsec4x4: 0 [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] sDithertext: Small Dither [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] sAutoGuider: PHD2 [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] nFFBinning: 3 [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] nFFExposure: 1 [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] nAFExposure: 1 [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] nAFBinning: 0 [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] nAFStepSize: 10 [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] nAFDataPoints: 7 [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] sPlateSolver: No Plate Solver [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] sAutoFocuser: Half Flux Radius [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] nAutoFocusDialogCloseDelay: 30 [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] bAutoFocusDegree: False [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] bAutoManualFocusDegree: False [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] nAutoFocusDegreeInterval: 1 [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] nAutoManualFocusDegreeInterval: 1 [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] bAutoFocusAutoContinue: False [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] nNudgeSpeed: 0 [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] bReverseNs: False [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] bReverseEw: False [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] nHiSpeedRate: 1 [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] nLoSpeedRate: 0.5 [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] sCameraGain1x1: High [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] sCameraGain2x2: High [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] sCameraGain3x3: High [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] sCameraGain4x4: High [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] sCameraOffset1x1: [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] sCameraOffset2x2: [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] sCameraOffset3x3: [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] sCameraOffset4x4: [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] bPauseAutoGuiderDuringAutoFocus: False [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] bUseSameStarsForAf: False [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] bSaveAfData: False [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] sSaveAfDataPath: C:\Users\Public\Documents\SGP Images [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] bAllowExternalScopeControl: False [10/05/17 20:08:27.252][DEBUG] [Sequence Thread] sFFBinning: 1x1 [10/05/17 20:08:27.253][DEBUG] [Sequence Thread] sAFBinning: 1x1 [10/05/17 20:08:27.253][DEBUG] [Sequence Thread] nMountSettling: 0 [10/05/17 20:08:27.253][DEBUG] [Sequence Thread] ffExposure: 1 [10/05/17 20:08:27.253][DEBUG] [Sequence Thread] noFilter: SequenceGenerator.Filter [10/05/17 20:08:27.253][DEBUG] [Sequence Thread] bAutoFocusOnResume: False [10/05/17 20:08:27.253][DEBUG] [Sequence Thread] sFlatBoxName: No Flat Box [10/05/17 20:08:27.253][DEBUG] [Sequence Thread] flatBoxBrightness: 0 [10/05/17 20:08:27.253][DEBUG] [Sequence Thread] bFocuserBacklash: False [10/05/17 20:08:27.253][DEBUG] [Sequence Thread] nFocuserBacklashStepSize: 0 [10/05/17 20:08:27.253][DEBUG] [Sequence Thread] bFocuserBacklashMovesIn: True [10/05/17 20:08:27.253][DEBUG] [Sequence Thread] nGuiderSettlingTime: 0 [10/05/17 20:08:27.253][DEBUG] [Sequence Thread] nAutoCenterAttempts: 1 [10/05/17 20:08:27.253][DEBUG] [Sequence Thread] nAutoCenterError: 50 [10/05/17 20:08:27.253][DEBUG] [Sequence Thread] bFrameRestart: False [10/05/17 20:08:27.253][DEBUG] [Sequence Thread] nFrameRestartError: 0 [10/05/17 20:08:27.253][DEBUG] [Sequence Thread] bDoAutoMeridianFlip: False [10/05/17 20:08:27.253][DEBUG] [Sequence Thread] autoMeridianFlipVals: SequenceGenerator.AutoMeridianFlipValues [10/05/17 20:08:27.253][DEBUG] [Sequence Thread] bUseAfDarks: False [10/05/17 20:08:27.253][DEBUG] [Sequence Thread] sAfDarksPath: [10/05/17 20:08:27.253][DEBUG] [Sequence Thread] bUseAutoCenterFilter: False [10/05/17 20:08:27.253][DEBUG] [Sequence Thread] sAutoCenterFilter: Lum [10/05/17 20:08:27.253][DEBUG] [Sequence Thread] bUseAutoFocusFilter: False [10/05/17 20:08:27.253][DEBUG] [Sequence Thread] sAutoFocusFilter: Lum [10/05/17 20:08:27.253][DEBUG] [Sequence Thread] bSettleAgBeforeAf: False [10/05/17 20:08:27.253][DEBUG] [Sequence Thread] bUseHighSpeedDl: False [10/05/17 20:08:27.253][DEBUG] [Sequence Thread] bBlindSolveFailover: False [10/05/17 20:08:27.253][DEBUG] [Sequence Thread] nReadoutNoise: 0 [10/05/17 20:08:27.253][DEBUG] [Sequence Thread] ccdArraySize: {Width=0, Height=0} [10/05/17 20:08:27.253][DEBUG] [Sequence Thread] nCameraGain1x1: 0 [10/05/17 20:08:27.253][DEBUG] [Sequence Thread] nCameraGain2x2: 0 [10/05/17 20:08:27.253][DEBUG] [Sequence Thread] nCameraGain3x3: 0 [10/05/17 20:08:27.253][DEBUG] [Sequence Thread] nCameraGain4x4: 0 [10/05/17 20:08:27.253][DEBUG] [Sequence Thread] sRotatorName: No Rotator [10/05/17 20:08:27.253][DEBUG] [Sequence Thread] nRotationError: 3 [10/05/17 20:08:27.253][DEBUG] [Sequence Thread] bUseTemperDevice: False [10/05/17 20:08:27.253][DEBUG] [Sequence Thread] bAutoFocusAfterFlip: False [10/05/17 20:08:27.253][DEBUG] [Sequence Thread] bReverseManualRotatorCorrections: False [10/05/17 20:08:27.253][DEBUG] [Sequence Thread] sObservatoryName: No Observatory [10/05/17 20:08:27.253][DEBUG] [Sequence Thread] sObsType: Roll Off Roof [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] SlavingVals: Radius: 0 NorthOffset: 0 VerticalOffset: 0 GemOffset: 0 SlavingFrequency: 60 SlavingAngleAllowance: 0 SlaveOnSeqStart: False UnparkWithMount: False ParkMountFirst: False OpenShutterFirst: False UnparkOpensShutter: False ParkClosesShutter: False [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] bSlaveObsToScope: False [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] bPromptToPlaceLightBox: False [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] nDownloadTime1x1: 0 [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] nDownloadTime2x2: 0 [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] nDownloadTime3x3: 0 [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] nDownloadTime4x4: 0 [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] bAfCropFrames: False [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] nAfCropFramesPercent: 0 [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] sFilePattern: [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] sWeatherStationName: ASCOM Boltwood OK to Open [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] nScopeFocalLength: 0 [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] bCameraCooldownOnSequenceStart: False [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] sRtfNotes: [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] bStopTracking: False [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] bHasCentralObstruction: False [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] sCustomVariables: [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] sPhd2Profile: Tak [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] bAutoConnectPhd2Gear: True [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] bAutoDisconnectPhd2Gear: True [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] bPauseGuiderDuringBacklashComp: False [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] bSetAbsPositiononFirstFilterChange: False [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] sEnvironmentDeviceName: ASCOM Boltwood Observing Conditions [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] bSettleGuiderTime: False [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] equipmentData: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.EquipmentData] [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] nMinStarSize: 6 [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] telescopeSyncOption: Sync [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] nAutoguiderDitherEveryXFrameCount: 1 [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] ************** FILTER DUMP ************** [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] >> FILTER 1: [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] bActive: True [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] sName: None [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] nAfExposureTime: 1 [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] nFocusPosition: 0 [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] nFocusTemp: 0 [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] nFlat1: 0 [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] nFlat2: 0 [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] nFlat3: 0 [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] nFlat4: 0 [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] nNumFlats: 0 [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] flatsData: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] flatsDataBin: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] flatsDataIso: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] sFocusWith: Default [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] arFocPositions: System.Collections.Generic.List`1[SequenceGenerator.FocusPosData] [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] >> FILTER 2: [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] bActive: True [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] sName: Lum [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] nAfExposureTime: 1 [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] nFocusPosition: 0 [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] nFocusTemp: 0 [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] nFlat1: 0 [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] nFlat2: 0 [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] nFlat3: 0 [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] nFlat4: 0 [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] nNumFlats: 0 [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] flatsData: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] flatsDataBin: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] flatsDataIso: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] sFocusWith: Default [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] arFocPositions: System.Collections.Generic.List`1[SequenceGenerator.FocusPosData] [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] >> FILTER 3: [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] bActive: True [10/05/17 20:08:27.254][DEBUG] [Sequence Thread] sName: Red [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nAfExposureTime: 1 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nFocusPosition: 0 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nFocusTemp: 0 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nFlat1: 0 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nFlat2: 0 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nFlat3: 0 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nFlat4: 0 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nNumFlats: 0 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] flatsData: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] flatsDataBin: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] flatsDataIso: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] sFocusWith: Default [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] arFocPositions: System.Collections.Generic.List`1[SequenceGenerator.FocusPosData] [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] >> FILTER 4: [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] bActive: True [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] sName: Green [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nAfExposureTime: 1 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nFocusPosition: 0 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nFocusTemp: 0 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nFlat1: 0 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nFlat2: 0 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nFlat3: 0 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nFlat4: 0 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nNumFlats: 0 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] flatsData: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] flatsDataBin: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] flatsDataIso: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] sFocusWith: Default [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] arFocPositions: System.Collections.Generic.List`1[SequenceGenerator.FocusPosData] [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] >> FILTER 5: [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] bActive: True [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] sName: Blue [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nAfExposureTime: 2 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nFocusPosition: 0 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nFocusTemp: 0 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nFlat1: 0 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nFlat2: 0 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nFlat3: 0 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nFlat4: 0 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nNumFlats: 0 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] flatsData: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] flatsDataBin: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] flatsDataIso: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] sFocusWith: Default [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] arFocPositions: System.Collections.Generic.List`1[SequenceGenerator.FocusPosData] [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] >> FILTER 6: [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] bActive: True [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] sName: Ha [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nAfExposureTime: 1 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nFocusPosition: 0 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nFocusTemp: 0 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nFlat1: 0 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nFlat2: 0 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nFlat3: 0 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nFlat4: 0 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nNumFlats: 0 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] flatsData: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] flatsDataBin: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] flatsDataIso: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] sFocusWith: Default [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] arFocPositions: System.Collections.Generic.List`1[SequenceGenerator.FocusPosData] [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] >> FILTER 7: [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] bActive: True [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] sName: O3 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nAfExposureTime: 1 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nFocusPosition: 0 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nFocusTemp: 0 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nFlat1: 0 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nFlat2: 0 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nFlat3: 0 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nFlat4: 0 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nNumFlats: 0 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] flatsData: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] flatsDataBin: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] flatsDataIso: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] sFocusWith: Default [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] arFocPositions: System.Collections.Generic.List`1[SequenceGenerator.FocusPosData] [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] >> FILTER 8: [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] bActive: True [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] sName: S2 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nAfExposureTime: 1 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nFocusPosition: 0 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nFocusTemp: 0 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nFlat1: 0 [10/05/17 20:08:27.255][DEBUG] [Sequence Thread] nFlat2: 0 [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] nFlat3: 0 [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] nFlat4: 0 [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] nNumFlats: 0 [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] flatsData: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] flatsDataBin: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] flatsDataIso: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] sFocusWith: Default [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] arFocPositions: System.Collections.Generic.List`1[SequenceGenerator.FocusPosData] [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] >> FILTER 9: [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] bActive: False [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] sName: [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] nAfExposureTime: 1 [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] nFocusPosition: 0 [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] nFocusTemp: 0 [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] nFlat1: 0 [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] nFlat2: 0 [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] nFlat3: 0 [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] nFlat4: 0 [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] nNumFlats: 0 [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] flatsData: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] flatsDataBin: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] flatsDataIso: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] sFocusWith: Default [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] arFocPositions: System.Collections.Generic.List`1[SequenceGenerator.FocusPosData] [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] >> FILTER 10: [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] bActive: False [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] sName: [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] nAfExposureTime: 1 [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] nFocusPosition: 0 [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] nFocusTemp: 0 [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] nFlat1: 0 [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] nFlat2: 0 [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] nFlat3: 0 [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] nFlat4: 0 [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] nNumFlats: 0 [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] flatsData: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] flatsDataBin: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] flatsDataIso: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] sFocusWith: Default [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] arFocPositions: System.Collections.Generic.List`1[SequenceGenerator.FocusPosData] [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] >> FILTER 11: [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] bActive: False [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] sName: [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] nAfExposureTime: 1 [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] nFocusPosition: 0 [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] nFocusTemp: 0 [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] nFlat1: 0 [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] nFlat2: 0 [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] nFlat3: 0 [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] nFlat4: 0 [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] nNumFlats: 0 [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] flatsData: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] flatsDataBin: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] flatsDataIso: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] sFocusWith: Default [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] arFocPositions: System.Collections.Generic.List`1[SequenceGenerator.FocusPosData] [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] >> FILTER 12: [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] bActive: False [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] sName: [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] nAfExposureTime: 1 [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] nFocusPosition: 0 [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] nFocusTemp: 0 [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] nFlat1: 0 [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] nFlat2: 0 [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] nFlat3: 0 [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] nFlat4: 0 [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] nNumFlats: 0 [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] flatsData: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] flatsDataBin: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] flatsDataIso: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] sFocusWith: Default [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] arFocPositions: System.Collections.Generic.List`1[SequenceGenerator.FocusPosData] [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] >> FILTER 13: [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] bActive: False [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] sName: [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] nAfExposureTime: 1 [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] nFocusPosition: 0 [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] nFocusTemp: 0 [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] nFlat1: 0 [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] nFlat2: 0 [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] nFlat3: 0 [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] nFlat4: 0 [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] nNumFlats: 0 [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] flatsData: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] flatsDataBin: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [10/05/17 20:08:27.256][DEBUG] [Sequence Thread] flatsDataIso: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] sFocusWith: Default [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] arFocPositions: System.Collections.Generic.List`1[SequenceGenerator.FocusPosData] [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] ************** EVENT DUMP ************** [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] * Target Veil [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Active: True [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Start Time: False [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Start: 10/5/2017 7:29:00 PM [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -End Time: False [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -End: 10/5/2017 7:29:00 PM [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Auto Rotate: False [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Auto Center: False [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Location set: False [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -RA: 0 [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -DEC: 0 [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Finish Full Event: True [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Completed: False [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -After End Time: False [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Rotate Camera: False [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Rotation: 0 [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] ** Event 1 [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Active: True [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Frame Type: Light [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Filter: 5 [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Suffix: Ha [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Exposure Time: 300 [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Binning: 1x1 [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Repeat: 15 [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Gain: 0 [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Offset: 100 [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Num Complete: 0 [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] ** Event 2 [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Active: False [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Frame Type: Light [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Filter: 0 [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Suffix: [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Exposure Time: 0 [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Binning: 1x1 [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Repeat: 1 [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Gain: 0 [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Offset: 100 [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Num Complete: 0 [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] ** Event 3 [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Active: False [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Frame Type: Light [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Filter: 0 [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Suffix: [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Exposure Time: 0 [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Binning: 1x1 [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Repeat: 1 [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Gain: 0 [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Offset: 100 [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Num Complete: 0 [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] ** Event 4 [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Active: False [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Frame Type: Light [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Filter: 0 [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Suffix: [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Exposure Time: 0 [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Binning: 1x1 [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Repeat: 1 [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Gain: 0 [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Offset: 100 [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Num Complete: 0 [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] ** Event 5 [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Active: False [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Frame Type: Light [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Filter: 0 [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Suffix: [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Exposure Time: 0 [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Binning: 1x1 [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Repeat: 1 [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Gain: 0 [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Offset: 100 [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] -Num Complete: 0 [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] ************** END SEQUENCE DUMP ************** [10/05/17 20:08:27.257][DEBUG] [Sequence Thread] ********** Run sequence started ********** [10/05/17 20:08:27.330][DEBUG] [Sequence Thread] Checking if observatory requires auto slaving... [10/05/17 20:08:27.330][DEBUG] [Sequence Thread] Observatory does not require auto slave... [10/05/17 20:08:27.337][DEBUG] [Sequence Thread] DoEventGroupChange: Changing to event group: Veil [10/05/17 20:08:27.339][DEBUG] [Sequence Thread] Checking for start delay... [10/05/17 20:08:27.414][DEBUG] [Sequence Thread] DoEventGroupChange: Complete [10/05/17 20:08:27.416][DEBUG] [Sequence Thread] Attempting to find next event... [10/05/17 20:08:27.416][DEBUG] [Sequence Thread] Current event[0] frame count: 0/15... [10/05/17 20:08:27.418][DEBUG] [Sequence Thread] Looking at event[0]... [10/05/17 20:08:27.418][DEBUG] [Sequence Thread] Found event event[0] with remaining frames. [10/05/17 20:08:27.418][DEBUG] [Sequence Thread] Event[0] frame count: 0/15... [10/05/17 20:08:27.418][DEBUG] [Sequence Thread] Getting first event (0)... [10/05/17 20:08:27.419][DEBUG] [Main Thread] PopulateDataModel: Transferring view to the data model... [10/05/17 20:08:27.430][DEBUG] [Sequence Thread] Running pre event... [10/05/17 20:08:27.449][DEBUG] [Sequence Thread] Running capture event... [10/05/17 20:08:27.452][DEBUG] [Sequence Thread] SetStartTemp: Using focuser for temperature... [10/05/17 20:08:27.452][DEBUG] [Sequence Thread] Set start frame temp to 100... [10/05/17 20:08:27.455][DEBUG] [Sequence Thread] ------------- Starting capture frame for event[0] ------------- [10/05/17 20:08:27.460][DEBUG] [Sequence Thread] Sending commands... [10/05/17 20:08:27.462][DEBUG] [Sequence Thread] SetFlatBox: Frame Type is Light [10/05/17 20:08:27.463][DEBUG] [Sequence Thread] EventMarker 2 - t:Veil (0); e:0; f:0; o: [10/05/17 20:08:27.466][DEBUG] [Sequence Thread] EventMarker 3 - t:Veil (0); e:0; f:0; o: [10/05/17 20:08:27.467][DEBUG] [Sequence Thread] Waiting for AF to complete... [10/05/17 20:08:27.477][DEBUG] [Camera Thread] SGM_FOCUSER_AUTO_FOCUS message received... [10/05/17 20:08:27.477][DEBUG] [Camera Thread] Checking for auto focus... [10/05/17 20:08:27.477][DEBUG] [Camera Thread] SGM_FOCUSER_AUTO_FOCUS complete... [10/05/17 20:08:27.667][DEBUG] [Sequence Thread] AF is complete, continuing sequence... [10/05/17 20:08:27.667][DEBUG] [Sequence Thread] EventMarker 4 - t:Veil (0); e:0; f:0; o: [10/05/17 20:08:27.667][DEBUG] [Sequence Thread] Setting filter position 5... [10/05/17 20:08:27.667][DEBUG] [Sequence Thread] Filter position 5 is already set. Skipping... [10/05/17 20:08:27.667][DEBUG] [Sequence Thread] EventMarker 5 - t:Veil (0); e:0; f:0; o: [10/05/17 20:08:27.670][DEBUG] [Sequence Thread] Checking for auto manual focus (pre)... [10/05/17 20:08:27.670][DEBUG] [Sequence Thread] EventMarker 6 - t:Veil (0); e:0; f:0; o: [10/05/17 20:08:27.751][DEBUG] [Sequence Thread] Created base name for frame capture: Veil_300sec_1x1_Ha_frame1 [10/05/17 20:08:27.751][DEBUG] [Sequence Thread] Created directory for frame capture: C:\Users\Public\Documents\SGP Images\Light\ [10/05/17 20:08:27.751][DEBUG] [Sequence Thread] EventMarker 1 - t:Veil (0); e:0; f:0; o: [10/05/17 20:08:27.751][DEBUG] [Sequence Thread] EventMarker 7 - t:Veil (0); e:0; f:0; o: [10/05/17 20:08:27.751][DEBUG] [Sequence Thread] Resuming auto guiding (settling)... [10/05/17 20:08:27.753][DEBUG] [Sequence Thread] Checking PHD2 state... [10/05/17 20:08:27.753][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/05/17 20:08:27.753][DEBUG] [Sequence Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 20:08:27.853][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Post-Wait: Guiding [10/05/17 20:08:27.853][DEBUG] [Sequence Thread] PHD2: Requested unpause, but PHD2 reports it is not in a paused state [10/05/17 20:08:27.855][DEBUG] [Sequence Thread] {"method": "guide", "params": [{"pixels": 0.6, "time": 0, "timeout": 600}, false ], "id": 1003} [10/05/17 20:08:27.855][DEBUG] [Sequence Thread] Sending to PHD2: {"method": "guide", "params": [{"pixels": 0.6, "time": 0, "timeout": 600}, false ], "id": 1003} [10/05/17 20:08:27.855][DEBUG] [Sequence Thread] Checking PHD2 state... [10/05/17 20:08:27.856][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Pre-Wait : Guiding [10/05/17 20:08:27.856][DEBUG] [Sequence Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 20:08:27.956][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Post-Wait: Guiding [10/05/17 20:08:27.956][DEBUG] [Sequence Thread] User has elected to skip guider settling over time... [10/05/17 20:08:27.956][DEBUG] [Sequence Thread] Waiting for Auto Guider distance to fall below 0.6 [10/05/17 20:08:27.956][DEBUG] [Sequence Thread] PHD2 distance request... [10/05/17 20:08:27.956][DEBUG] [Sequence Thread] PHD2 distance request returned 0.2... [10/05/17 20:08:27.965][DEBUG] [Sequence Thread] Distance is below 0.6, starting timer... [10/05/17 20:08:28.466][DEBUG] [Sequence Thread] PHD2 distance request... [10/05/17 20:08:28.466][DEBUG] [Sequence Thread] PHD2 distance request returned 0.2... [10/05/17 20:08:28.490][DEBUG] [Sequence Thread] Distance stayed below 0.6 for 0 seconds, done settling... [10/05/17 20:08:28.491][DEBUG] [Sequence Thread] Auto guider has settled... [10/05/17 20:08:28.492][DEBUG] [Sequence Thread] EventMarker 8 - t:Veil (0); e:0; f:0; o: [10/05/17 20:08:28.492][DEBUG] [Sequence Thread] EventMarker 9 - t:Veil (0); e:0; f:0; o: [10/05/17 20:08:28.492][DEBUG] [Sequence Thread] EventMarker 10 - t:Veil (0); e:0; f:0; o: [10/05/17 20:08:28.499][DEBUG] [Camera Thread] SGM_CAMERA_CAPTURE message received... [10/05/17 20:08:28.518][DEBUG] [Sequence Thread] Finished sending frame capture. Entering wait mode... [10/05/17 20:08:28.518][DEBUG] [Sequence Thread] EventMarker 11 - t:Veil (0); e:0; f:0; o: [10/05/17 20:08:28.519][DEBUG] [Sequence Thread] EventMarker 12 - t:Veil (0); e:0; f:0; o: [10/05/17 20:08:28.520][DEBUG] [Sequence Thread] EventMarker 13 - t:Veil (0); e:0; f:0; o: [10/05/17 20:08:28.550][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 20:08:28.550][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 20:08:28.623][DEBUG] [PHD2 Listener Thread] PHD2: Settle done received: GOOD [10/05/17 20:13:28.606][DEBUG] [Sequence Thread] EventMarker 15 - t:Veil (0); e:0; f:0; o: [10/05/17 20:13:28.606][DEBUG] [Sequence Thread] Waking from exposure time sleep period... [10/05/17 20:13:28.607][DEBUG] [Sequence Thread] Checking to see if the CCD has a temp... [10/05/17 20:13:28.607][DEBUG] [Sequence Thread] Saving the CCD temp... [10/05/17 20:13:28.640][DEBUG] [Sequence Thread] Checking PHD2 state... [10/05/17 20:13:28.640][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Pre-Wait : Guiding [10/05/17 20:13:28.640][DEBUG] [Sequence Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 20:13:28.740][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Post-Wait: Guiding [10/05/17 20:13:44.281][DEBUG] [Camera Thread] QSI expose and download: 315.6670551s [10/05/17 20:13:44.281][DEBUG] [Camera Thread] SGM_CAMERA_CAPTURE complete... [10/05/17 20:13:44.292][DEBUG] [Sequence Thread] Image ready state detected when waiting for download state, continuing sequence... [10/05/17 20:13:44.292][DEBUG] [Sequence Thread] Entering super dangerous loop to await image completion... [10/05/17 20:13:44.302][DEBUG] [Sequence Thread] EventMarker 16 - t:Veil (0); e:0; f:0; o: [10/05/17 20:13:44.307][DEBUG] [Sequence Thread] EventMarker 17 - t:Veil (0); e:0; f:0; o: [10/05/17 20:13:44.307][DEBUG] [Sequence Thread] Image reported as complete. Continuing... [10/05/17 20:13:44.361][DEBUG] [Sequence Thread] Collecting FITs headers... [10/05/17 20:13:44.364][DEBUG] [Sequence Thread] QSI Camera - Camera reports last image start time as: 2017-10-06T03:08:31.270 UTC [10/05/17 20:13:44.364][DEBUG] [Sequence Thread] DATE-LOC time provided by camera... [10/05/17 20:13:44.654][DEBUG] [Sequence Thread] GatherFitsHeaders: Writing header info from UI... [10/05/17 20:13:44.671][DEBUG] [Sequence Thread] Clearing timed monitoring events... [10/05/17 20:13:44.676][DEBUG] [Sequence Thread] Created full file name (file does not exist): C:\Users\Public\Documents\SGP Images\Light\Veil_300sec_1x1_Ha_frame1.fit [10/05/17 20:13:44.832][DEBUG] [Sequence Thread] SaveFileBmp16: Locking bitmap for disk write... [10/05/17 20:13:44.832][DEBUG] [Sequence Thread] SaveFileBmp16: Opening file stream... [10/05/17 20:13:44.833][DEBUG] [Sequence Thread] SaveFileBmp16: Writing FITS headers... [10/05/17 20:13:44.841][DEBUG] [Sequence Thread] SaveFileBmp16: Traversing and persisting bitmap data... [10/05/17 20:13:45.025][DEBUG] [Sequence Thread] SaveFileBmp16: FITS block fill... [10/05/17 20:13:45.025][DEBUG] [Sequence Thread] SaveFileBmp16: Unlocking bitmap... [10/05/17 20:13:45.025][DEBUG] [Sequence Thread] SaveFileBmp16: Closing file stream [10/05/17 20:13:45.027][DEBUG] [Sequence Thread] Internal Interface: Set Preview... [10/05/17 20:13:45.027][DEBUG] [Sequence Thread] Display image preview using asynch task... [10/05/17 20:13:46.013][DEBUG] [Sequence Thread] =========== Save file took 1339 ms [10/05/17 20:13:46.029][DEBUG] [Sequence Thread] Resuming auto guiding (settling)... [10/05/17 20:13:46.029][DEBUG] [Sequence Thread] Checking PHD2 state... [10/05/17 20:13:46.029][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Pre-Wait : Guiding [10/05/17 20:13:46.029][DEBUG] [Sequence Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 20:13:46.129][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Post-Wait: Guiding [10/05/17 20:13:46.129][DEBUG] [Sequence Thread] PHD2: Requested unpause, but PHD2 reports it is not in a paused state [10/05/17 20:13:46.239][DEBUG] [Sequence Thread] EventMarker 18 - t:Veil (0); e:0; f:0; o: [10/05/17 20:13:46.254][DEBUG] [Sequence Thread] EventMarker 19 - t:Veil (0); e:0; f:1; o: [10/05/17 20:13:46.254][DEBUG] [Sequence Thread] EventMarker 20 - t:Veil (0); e:0; f:1; o: [10/05/17 20:13:46.256][DEBUG] [Sequence Thread] Running post event... [10/05/17 20:13:46.256][DEBUG] [Sequence Thread] EventMarker 21 - t:Veil (0); e:0; f:1; o: [10/05/17 20:13:46.256][DEBUG] [Sequence Thread] Incremented numComplete (1) for event[0]... [10/05/17 20:13:46.261][DEBUG] [Sequence Thread] Attempting to find next event... [10/05/17 20:13:46.261][DEBUG] [Sequence Thread] Current event[0] frame count: 1/15... [10/05/17 20:13:46.261][DEBUG] [Sequence Thread] Current event event[0] has remaining frames. Returning current event. [10/05/17 20:13:46.262][DEBUG] [Sequence Thread] Checking for auto manual focus (post)... [10/05/17 20:13:46.262][DEBUG] [Main Thread] PopulateDataModel: Transferring view to the data model... [10/05/17 20:13:46.274][DEBUG] [Sequence Thread] Attempting to move to next event... [10/05/17 20:13:46.274][DEBUG] [Sequence Thread] Attempting to find next event... [10/05/17 20:13:46.274][DEBUG] [Sequence Thread] Current event[0] frame count: 1/15... [10/05/17 20:13:46.274][DEBUG] [Sequence Thread] Current event event[0] has remaining frames. Returning current event. [10/05/17 20:13:46.274][DEBUG] [Sequence Thread] Running pre event... [10/05/17 20:13:46.295][DEBUG] [Sequence Thread] Running capture event... [10/05/17 20:13:46.297][DEBUG] [Sequence Thread] SetStartTemp: Using focuser for temperature... [10/05/17 20:13:46.297][DEBUG] [Sequence Thread] Set start frame temp to 100... [10/05/17 20:13:46.297][DEBUG] [Sequence Thread] ------------- Starting capture frame for event[0] ------------- [10/05/17 20:13:46.302][DEBUG] [Sequence Thread] Sending commands... [10/05/17 20:13:46.302][DEBUG] [Sequence Thread] SetFlatBox: Frame Type is Light [10/05/17 20:13:46.302][DEBUG] [Sequence Thread] EventMarker 2 - t:Veil (0); e:0; f:1; o: [10/05/17 20:13:46.302][DEBUG] [Sequence Thread] EventMarker 3 - t:Veil (0); e:0; f:1; o: [10/05/17 20:13:46.302][DEBUG] [Sequence Thread] Waiting for AF to complete... [10/05/17 20:13:46.305][DEBUG] [Camera Thread] SGM_FOCUSER_AUTO_FOCUS message received... [10/05/17 20:13:46.305][DEBUG] [Camera Thread] Checking for auto focus... [10/05/17 20:13:46.305][DEBUG] [Camera Thread] SGM_FOCUSER_AUTO_FOCUS complete... [10/05/17 20:13:46.502][DEBUG] [Sequence Thread] AF is complete, continuing sequence... [10/05/17 20:13:46.502][DEBUG] [Sequence Thread] EventMarker 4 - t:Veil (0); e:0; f:1; o: [10/05/17 20:13:46.502][DEBUG] [Sequence Thread] Setting filter position 5... [10/05/17 20:13:46.502][DEBUG] [Sequence Thread] Filter position 5 is already set. Skipping... [10/05/17 20:13:46.502][DEBUG] [Sequence Thread] EventMarker 5 - t:Veil (0); e:0; f:1; o: [10/05/17 20:13:46.502][DEBUG] [Sequence Thread] Checking for auto manual focus (pre)... [10/05/17 20:13:46.502][DEBUG] [Sequence Thread] EventMarker 6 - t:Veil (0); e:0; f:1; o: [10/05/17 20:13:46.576][DEBUG] [Sequence Thread] Created base name for frame capture: Veil_300sec_1x1_Ha_frame2 [10/05/17 20:13:46.577][DEBUG] [Sequence Thread] Created directory for frame capture: C:\Users\Public\Documents\SGP Images\Light\ [10/05/17 20:13:46.577][DEBUG] [Sequence Thread] EventMarker 1 - t:Veil (0); e:0; f:1; o: [10/05/17 20:13:46.577][DEBUG] [Sequence Thread] EventMarker 7 - t:Veil (0); e:0; f:1; o: [10/05/17 20:13:46.577][DEBUG] [Sequence Thread] Resuming auto guiding (settling)... [10/05/17 20:13:46.577][DEBUG] [Sequence Thread] Checking PHD2 state... [10/05/17 20:13:46.577][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Pre-Wait : Guiding [10/05/17 20:13:46.577][DEBUG] [Sequence Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 20:13:46.677][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Post-Wait: Guiding [10/05/17 20:13:46.677][DEBUG] [Sequence Thread] PHD2: Requested unpause, but PHD2 reports it is not in a paused state [10/05/17 20:13:46.677][DEBUG] [Sequence Thread] {"method": "guide", "params": [{"pixels": 0.6, "time": 0, "timeout": 600}, false ], "id": 1003} [10/05/17 20:13:46.677][DEBUG] [Sequence Thread] Sending to PHD2: {"method": "guide", "params": [{"pixels": 0.6, "time": 0, "timeout": 600}, false ], "id": 1003} [10/05/17 20:13:46.677][DEBUG] [Sequence Thread] Checking PHD2 state... [10/05/17 20:13:46.677][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Pre-Wait : Guiding [10/05/17 20:13:46.677][DEBUG] [Sequence Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 20:13:46.777][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Post-Wait: Guiding [10/05/17 20:13:46.777][DEBUG] [Sequence Thread] User has elected to skip guider settling over time... [10/05/17 20:13:46.777][DEBUG] [Sequence Thread] Waiting for Auto Guider distance to fall below 0.6 [10/05/17 20:13:46.777][DEBUG] [Sequence Thread] PHD2 distance request... [10/05/17 20:13:46.777][DEBUG] [Sequence Thread] PHD2 distance request returned 0.4... [10/05/17 20:13:46.785][DEBUG] [Sequence Thread] Distance is below 0.6, starting timer... [10/05/17 20:13:47.285][DEBUG] [Sequence Thread] PHD2 distance request... [10/05/17 20:13:47.285][DEBUG] [Sequence Thread] PHD2 distance request returned 0.4... [10/05/17 20:13:47.294][DEBUG] [Sequence Thread] Distance stayed below 0.6 for 0 seconds, done settling... [10/05/17 20:13:47.295][DEBUG] [Sequence Thread] Auto guider has settled... [10/05/17 20:13:47.295][DEBUG] [Sequence Thread] EventMarker 8 - t:Veil (0); e:0; f:1; o: [10/05/17 20:13:47.295][DEBUG] [Sequence Thread] EventMarker 9 - t:Veil (0); e:0; f:1; o: [10/05/17 20:13:47.295][DEBUG] [Sequence Thread] EventMarker 10 - t:Veil (0); e:0; f:1; o: [10/05/17 20:13:47.295][DEBUG] [Sequence Thread] Finished sending frame capture. Entering wait mode... [10/05/17 20:13:47.295][DEBUG] [Sequence Thread] EventMarker 11 - t:Veil (0); e:0; f:1; o: [10/05/17 20:13:47.295][DEBUG] [Sequence Thread] EventMarker 12 - t:Veil (0); e:0; f:1; o: [10/05/17 20:13:47.296][DEBUG] [Camera Thread] SGM_CAMERA_CAPTURE message received... [10/05/17 20:13:47.296][DEBUG] [Sequence Thread] EventMarker 13 - t:Veil (0); e:0; f:1; o: [10/05/17 20:13:47.330][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 20:13:47.330][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 20:13:48.968][DEBUG] [PHD2 Listener Thread] PHD2: Settle done received: GOOD [10/05/17 20:18:47.308][DEBUG] [Sequence Thread] EventMarker 15 - t:Veil (0); e:0; f:1; o: [10/05/17 20:18:47.309][DEBUG] [Sequence Thread] Waking from exposure time sleep period... [10/05/17 20:18:47.310][DEBUG] [Sequence Thread] Checking to see if the CCD has a temp... [10/05/17 20:18:47.310][DEBUG] [Sequence Thread] Saving the CCD temp... [10/05/17 20:18:47.338][DEBUG] [Sequence Thread] Checking PHD2 state... [10/05/17 20:18:47.338][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Pre-Wait : Guiding [10/05/17 20:18:47.338][DEBUG] [Sequence Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 20:18:47.538][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Post-Wait: Guiding [10/05/17 20:19:03.035][DEBUG] [Camera Thread] QSI expose and download: 315.6410536s [10/05/17 20:19:03.035][DEBUG] [Camera Thread] SGM_CAMERA_CAPTURE complete... [10/05/17 20:19:03.035][DEBUG] [Sequence Thread] Image ready state detected when waiting for download state, continuing sequence... [10/05/17 20:19:03.035][DEBUG] [Sequence Thread] Entering super dangerous loop to await image completion... [10/05/17 20:19:03.041][DEBUG] [Sequence Thread] EventMarker 16 - t:Veil (0); e:0; f:1; o: [10/05/17 20:19:03.052][DEBUG] [Sequence Thread] EventMarker 17 - t:Veil (0); e:0; f:1; o: [10/05/17 20:19:03.052][DEBUG] [Sequence Thread] Image reported as complete. Continuing... [10/05/17 20:19:03.053][DEBUG] [Sequence Thread] Collecting FITs headers... [10/05/17 20:19:03.054][DEBUG] [Sequence Thread] QSI Camera - Camera reports last image start time as: 2017-10-06T03:13:50.050 UTC [10/05/17 20:19:03.054][DEBUG] [Sequence Thread] DATE-LOC time provided by camera... [10/05/17 20:19:03.060][DEBUG] [Sequence Thread] GatherFitsHeaders: Writing header info from UI... [10/05/17 20:19:03.063][DEBUG] [Sequence Thread] Clearing timed monitoring events... [10/05/17 20:19:03.068][DEBUG] [Sequence Thread] Created full file name (file does not exist): C:\Users\Public\Documents\SGP Images\Light\Veil_300sec_1x1_Ha_frame2.fit [10/05/17 20:19:03.222][DEBUG] [Sequence Thread] SaveFileBmp16: Locking bitmap for disk write... [10/05/17 20:19:03.223][DEBUG] [Sequence Thread] SaveFileBmp16: Opening file stream... [10/05/17 20:19:03.223][DEBUG] [Sequence Thread] SaveFileBmp16: Writing FITS headers... [10/05/17 20:19:03.223][DEBUG] [Sequence Thread] SaveFileBmp16: Traversing and persisting bitmap data... [10/05/17 20:19:03.396][DEBUG] [Sequence Thread] SaveFileBmp16: FITS block fill... [10/05/17 20:19:03.396][DEBUG] [Sequence Thread] SaveFileBmp16: Unlocking bitmap... [10/05/17 20:19:03.396][DEBUG] [Sequence Thread] SaveFileBmp16: Closing file stream [10/05/17 20:19:03.396][DEBUG] [Sequence Thread] Internal Interface: Set Preview... [10/05/17 20:19:03.396][DEBUG] [Sequence Thread] Display image preview using asynch task... [10/05/17 20:19:04.471][DEBUG] [Sequence Thread] =========== Save file took 1407 ms [10/05/17 20:19:04.479][DEBUG] [Sequence Thread] Resuming auto guiding (settling)... [10/05/17 20:19:04.479][DEBUG] [Sequence Thread] Checking PHD2 state... [10/05/17 20:19:04.479][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Pre-Wait : Guiding [10/05/17 20:19:04.479][DEBUG] [Sequence Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 20:19:04.579][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Post-Wait: Guiding [10/05/17 20:19:04.579][DEBUG] [Sequence Thread] PHD2: Requested unpause, but PHD2 reports it is not in a paused state [10/05/17 20:19:04.579][DEBUG] [Sequence Thread] EventMarker 18 - t:Veil (0); e:0; f:1; o: [10/05/17 20:19:04.587][DEBUG] [Sequence Thread] EventMarker 19 - t:Veil (0); e:0; f:2; o: [10/05/17 20:19:04.587][DEBUG] [Sequence Thread] EventMarker 20 - t:Veil (0); e:0; f:2; o: [10/05/17 20:19:04.587][DEBUG] [Sequence Thread] Running post event... [10/05/17 20:19:04.587][DEBUG] [Sequence Thread] EventMarker 21 - t:Veil (0); e:0; f:2; o: [10/05/17 20:19:04.587][DEBUG] [Sequence Thread] Incremented numComplete (2) for event[0]... [10/05/17 20:19:04.593][DEBUG] [Sequence Thread] Attempting to find next event... [10/05/17 20:19:04.593][DEBUG] [Sequence Thread] Current event[0] frame count: 2/15... [10/05/17 20:19:04.593][DEBUG] [Sequence Thread] Current event event[0] has remaining frames. Returning current event. [10/05/17 20:19:04.593][DEBUG] [Sequence Thread] Checking for auto manual focus (post)... [10/05/17 20:19:04.593][DEBUG] [Main Thread] PopulateDataModel: Transferring view to the data model... [10/05/17 20:19:04.598][DEBUG] [Sequence Thread] Attempting to move to next event... [10/05/17 20:19:04.598][DEBUG] [Sequence Thread] Attempting to find next event... [10/05/17 20:19:04.598][DEBUG] [Sequence Thread] Current event[0] frame count: 2/15... [10/05/17 20:19:04.598][DEBUG] [Sequence Thread] Current event event[0] has remaining frames. Returning current event. [10/05/17 20:19:04.598][DEBUG] [Sequence Thread] Running pre event... [10/05/17 20:19:04.613][DEBUG] [Sequence Thread] Running capture event... [10/05/17 20:19:04.615][DEBUG] [Sequence Thread] SetStartTemp: Using focuser for temperature... [10/05/17 20:19:04.615][DEBUG] [Sequence Thread] Set start frame temp to 100... [10/05/17 20:19:04.615][DEBUG] [Sequence Thread] ------------- Starting capture frame for event[0] ------------- [10/05/17 20:19:04.619][DEBUG] [Sequence Thread] Sending commands... [10/05/17 20:19:04.619][DEBUG] [Sequence Thread] SetFlatBox: Frame Type is Light [10/05/17 20:19:04.619][DEBUG] [Sequence Thread] EventMarker 2 - t:Veil (0); e:0; f:2; o: [10/05/17 20:19:04.619][DEBUG] [Sequence Thread] EventMarker 3 - t:Veil (0); e:0; f:2; o: [10/05/17 20:19:04.619][DEBUG] [Sequence Thread] Waiting for AF to complete... [10/05/17 20:19:04.620][DEBUG] [Camera Thread] SGM_FOCUSER_AUTO_FOCUS message received... [10/05/17 20:19:04.620][DEBUG] [Camera Thread] Checking for auto focus... [10/05/17 20:19:04.620][DEBUG] [Camera Thread] Auto focus required (frame count trigger)... [10/05/17 20:19:04.629][DEBUG] [Camera Thread] Auto focus: setting filter Ha [10/05/17 20:19:04.629][DEBUG] [Camera Thread] Setting filter position 5... [10/05/17 20:19:04.629][DEBUG] [Camera Thread] Filter position 5 is already set. Skipping... [10/05/17 20:19:04.631][DEBUG] [Camera Thread] Auto focus running... [10/05/17 20:19:04.642][DEBUG] [Camera Thread] Turning temp comp off.. [10/05/17 20:19:04.645][DEBUG] [Camera Thread] Performing Sequence Generator auto focus using Half Flux Radius. AFID: 1 [10/05/17 20:19:04.721][DEBUG] [AfChartThread] Showing AF chart dialog... [10/05/17 20:19:04.817][DEBUG] [Camera Thread] Auto focus data [10/05/17 20:19:04.817][DEBUG] [Camera Thread] - Data Points: 7 [10/05/17 20:19:04.817][DEBUG] [Camera Thread] - Step Size: 10 [10/05/17 20:19:04.817][DEBUG] [Camera Thread] - Current Position: 7822 [10/05/17 20:19:04.817][DEBUG] [Camera Thread] - Initial Move Position: 7852 [10/05/17 20:19:04.823][DEBUG] [Camera Thread] AF Darks exception: The path is not of a legal form. [10/05/17 20:19:04.830][DEBUG] [Camera Thread] Moving focuser to next position (7852)... [10/05/17 20:19:04.830][DEBUG] [Focuser Move Thread] Focuser moving to 7852 [10/05/17 20:19:04.888][DEBUG] [Focuser Move Thread] Focuser move call complete [10/05/17 20:19:05.942][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7852 matches requested position 7852 [10/05/17 20:19:05.942][DEBUG] [Camera Thread] Focuser move complete... [10/05/17 20:19:05.996][DEBUG] [Camera Thread] Focuser position matches requested position (7852), continuing... [10/05/17 20:19:06.051][DEBUG] [Camera Thread] Calculating step metric... [10/05/17 20:19:06.051][DEBUG] [Camera Thread] Taking auto focus frame(s)... [10/05/17 20:19:06.074][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [10/05/17 20:19:06.074][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [10/05/17 20:19:06.074][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [10/05/17 20:19:06.074][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [10/05/17 20:19:06.106][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 20:19:06.106][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 20:19:22.802][DEBUG] [Camera Thread] QSI expose and download: 16.6319513s [10/05/17 20:19:23.063][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 20:19:23.063][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 20:19:23.367][DEBUG] [Main Thread] --> Find stars (normal) [10/05/17 20:19:23.484][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [10/05/17 20:19:29.122][DEBUG] [Main Thread] Star detection using min star size of 4px... [10/05/17 20:19:29.122][DEBUG] [Main Thread] Star detection using max star size of 80px... [10/05/17 20:19:29.224][DEBUG] [Main Thread] Find stars took: 5856 ms... [10/05/17 20:19:29.224][DEBUG] [Main Thread] Star list contains 11 stars... [10/05/17 20:19:29.224][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [10/05/17 20:19:29.539][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.17 [10/05/17 20:19:30.579][DEBUG] [Camera Thread] Moving focuser to next position (7842)... [10/05/17 20:19:30.581][DEBUG] [Focuser Move Thread] Focuser moving to 7842 [10/05/17 20:19:30.644][DEBUG] [Focuser Move Thread] Focuser move call complete [10/05/17 20:19:31.719][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7842 matches requested position 7842 [10/05/17 20:19:31.719][DEBUG] [Camera Thread] Focuser move complete... [10/05/17 20:19:31.780][DEBUG] [Camera Thread] Focuser position matches requested position (7842), continuing... [10/05/17 20:19:31.852][DEBUG] [Camera Thread] Calculating step metric... [10/05/17 20:19:31.853][DEBUG] [Camera Thread] Taking auto focus frame(s)... [10/05/17 20:19:31.861][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [10/05/17 20:19:31.861][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [10/05/17 20:19:31.861][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [10/05/17 20:19:31.861][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [10/05/17 20:19:31.883][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 20:19:31.883][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 20:19:48.594][DEBUG] [Camera Thread] QSI expose and download: 16.6459521s [10/05/17 20:19:48.863][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 20:19:48.863][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 20:19:49.481][DEBUG] [Main Thread] --> Find stars (normal) [10/05/17 20:19:49.591][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [10/05/17 20:19:55.259][DEBUG] [Main Thread] Star detection using min star size of 4px... [10/05/17 20:19:55.259][DEBUG] [Main Thread] Star detection using max star size of 80px... [10/05/17 20:19:55.374][DEBUG] [Main Thread] Find stars took: 5893 ms... [10/05/17 20:19:55.374][DEBUG] [Main Thread] Star list contains 35 stars... [10/05/17 20:19:55.374][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [10/05/17 20:19:55.650][DEBUG] [Camera Thread] Auto focus HFR calculated at: 2.43 [10/05/17 20:19:56.656][DEBUG] [Camera Thread] Moving focuser to next position (7832)... [10/05/17 20:19:56.656][DEBUG] [Focuser Move Thread] Focuser moving to 7832 [10/05/17 20:19:56.713][DEBUG] [Focuser Move Thread] Focuser move call complete [10/05/17 20:19:57.767][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7832 matches requested position 7832 [10/05/17 20:19:57.767][DEBUG] [Camera Thread] Focuser move complete... [10/05/17 20:19:57.822][DEBUG] [Camera Thread] Focuser position matches requested position (7832), continuing... [10/05/17 20:19:57.884][DEBUG] [Camera Thread] Calculating step metric... [10/05/17 20:19:57.884][DEBUG] [Camera Thread] Taking auto focus frame(s)... [10/05/17 20:19:57.889][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [10/05/17 20:19:57.889][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [10/05/17 20:19:57.889][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [10/05/17 20:19:57.889][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [10/05/17 20:19:57.918][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 20:19:57.918][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 20:20:14.652][DEBUG] [Camera Thread] QSI expose and download: 16.6699535s [10/05/17 20:20:14.823][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 20:20:14.823][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 20:20:15.450][DEBUG] [Main Thread] --> Find stars (normal) [10/05/17 20:20:15.582][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [10/05/17 20:20:21.039][DEBUG] [Main Thread] Star detection using min star size of 4px... [10/05/17 20:20:21.039][DEBUG] [Main Thread] Star detection using max star size of 80px... [10/05/17 20:20:21.141][DEBUG] [Main Thread] Find stars took: 5690 ms... [10/05/17 20:20:21.141][DEBUG] [Main Thread] Star list contains 54 stars... [10/05/17 20:20:21.141][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [10/05/17 20:20:21.429][DEBUG] [Camera Thread] Auto focus HFR calculated at: 1.88 [10/05/17 20:20:22.439][DEBUG] [Camera Thread] Moving focuser to next position (7822)... [10/05/17 20:20:22.440][DEBUG] [Focuser Move Thread] Focuser moving to 7822 [10/05/17 20:20:22.556][DEBUG] [Focuser Move Thread] Focuser move call complete [10/05/17 20:20:23.611][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7822 matches requested position 7822 [10/05/17 20:20:23.611][DEBUG] [Camera Thread] Focuser move complete... [10/05/17 20:20:23.667][DEBUG] [Camera Thread] Focuser position matches requested position (7822), continuing... [10/05/17 20:20:23.727][DEBUG] [Camera Thread] Calculating step metric... [10/05/17 20:20:23.727][DEBUG] [Camera Thread] Taking auto focus frame(s)... [10/05/17 20:20:23.791][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [10/05/17 20:20:23.791][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [10/05/17 20:20:23.791][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [10/05/17 20:20:23.791][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [10/05/17 20:20:23.826][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 20:20:23.826][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 20:20:40.610][DEBUG] [Camera Thread] QSI expose and download: 16.7199564s [10/05/17 20:20:40.819][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 20:20:40.819][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 20:20:41.426][DEBUG] [Main Thread] --> Find stars (normal) [10/05/17 20:20:41.561][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [10/05/17 20:20:47.000][DEBUG] [Main Thread] Star detection using min star size of 4px... [10/05/17 20:20:47.000][DEBUG] [Main Thread] Star detection using max star size of 80px... [10/05/17 20:20:47.091][DEBUG] [Main Thread] Find stars took: 5665 ms... [10/05/17 20:20:47.091][DEBUG] [Main Thread] Star list contains 60 stars... [10/05/17 20:20:47.091][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [10/05/17 20:20:47.367][DEBUG] [Camera Thread] Auto focus HFR calculated at: 1.56 [10/05/17 20:20:48.383][DEBUG] [Camera Thread] Moving focuser to next position (7812)... [10/05/17 20:20:48.386][DEBUG] [Focuser Move Thread] Focuser moving to 7812 [10/05/17 20:20:48.447][DEBUG] [Focuser Move Thread] Focuser move call complete [10/05/17 20:20:49.501][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7812 matches requested position 7812 [10/05/17 20:20:49.501][DEBUG] [Camera Thread] Focuser move complete... [10/05/17 20:20:49.556][DEBUG] [Camera Thread] Focuser position matches requested position (7812), continuing... [10/05/17 20:20:49.614][DEBUG] [Camera Thread] Calculating step metric... [10/05/17 20:20:49.614][DEBUG] [Camera Thread] Taking auto focus frame(s)... [10/05/17 20:20:49.618][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [10/05/17 20:20:49.618][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [10/05/17 20:20:49.618][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [10/05/17 20:20:49.618][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [10/05/17 20:20:49.652][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 20:20:49.652][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 20:21:06.375][DEBUG] [Camera Thread] QSI expose and download: 16.6599529s [10/05/17 20:21:06.574][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 20:21:06.574][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 20:21:07.304][DEBUG] [Main Thread] --> Find stars (normal) [10/05/17 20:21:07.430][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [10/05/17 20:21:13.144][DEBUG] [Main Thread] Star detection using min star size of 4px... [10/05/17 20:21:13.144][DEBUG] [Main Thread] Star detection using max star size of 80px... [10/05/17 20:21:13.366][DEBUG] [Main Thread] Find stars took: 6060 ms... [10/05/17 20:21:13.366][DEBUG] [Main Thread] Star list contains 59 stars... [10/05/17 20:21:13.366][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [10/05/17 20:21:13.631][DEBUG] [Camera Thread] Auto focus HFR calculated at: 1.53 [10/05/17 20:21:14.642][DEBUG] [Camera Thread] Moving focuser to next position (7802)... [10/05/17 20:21:14.642][DEBUG] [Focuser Move Thread] Focuser moving to 7802 [10/05/17 20:21:14.704][DEBUG] [Focuser Move Thread] Focuser move call complete [10/05/17 20:21:15.772][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7802 matches requested position 7802 [10/05/17 20:21:15.772][DEBUG] [Camera Thread] Focuser move complete... [10/05/17 20:21:15.831][DEBUG] [Camera Thread] Focuser position matches requested position (7802), continuing... [10/05/17 20:21:15.903][DEBUG] [Camera Thread] Calculating step metric... [10/05/17 20:21:15.903][DEBUG] [Camera Thread] Taking auto focus frame(s)... [10/05/17 20:21:15.906][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [10/05/17 20:21:15.906][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [10/05/17 20:21:15.906][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [10/05/17 20:21:15.906][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [10/05/17 20:21:15.926][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 20:21:15.926][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 20:21:32.621][DEBUG] [Camera Thread] QSI expose and download: 16.6309512s [10/05/17 20:21:32.798][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 20:21:32.798][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 20:21:33.437][DEBUG] [Main Thread] --> Find stars (normal) [10/05/17 20:21:33.578][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [10/05/17 20:21:38.922][DEBUG] [Main Thread] Star detection using min star size of 4px... [10/05/17 20:21:38.922][DEBUG] [Main Thread] Star detection using max star size of 80px... [10/05/17 20:21:39.020][DEBUG] [Main Thread] Find stars took: 5583 ms... [10/05/17 20:21:39.020][DEBUG] [Main Thread] Star list contains 54 stars... [10/05/17 20:21:39.020][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [10/05/17 20:21:39.304][DEBUG] [Camera Thread] Auto focus HFR calculated at: 1.80 [10/05/17 20:21:40.312][DEBUG] [Camera Thread] Moving focuser to next position (7792)... [10/05/17 20:21:40.312][DEBUG] [Focuser Move Thread] Focuser moving to 7792 [10/05/17 20:21:40.374][DEBUG] [Focuser Move Thread] Focuser move call complete [10/05/17 20:21:41.429][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7792 matches requested position 7792 [10/05/17 20:21:41.430][DEBUG] [Camera Thread] Focuser move complete... [10/05/17 20:21:41.484][DEBUG] [Camera Thread] Focuser position matches requested position (7792), continuing... [10/05/17 20:21:41.539][DEBUG] [Camera Thread] Calculating step metric... [10/05/17 20:21:41.539][DEBUG] [Camera Thread] Taking auto focus frame(s)... [10/05/17 20:21:41.606][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [10/05/17 20:21:41.606][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [10/05/17 20:21:41.606][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [10/05/17 20:21:41.606][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [10/05/17 20:21:41.638][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 20:21:41.638][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 20:21:58.330][DEBUG] [Camera Thread] QSI expose and download: 16.627951s [10/05/17 20:21:58.556][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 20:21:58.556][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 20:21:59.158][DEBUG] [Main Thread] --> Find stars (normal) [10/05/17 20:21:59.257][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [10/05/17 20:22:04.316][DEBUG] [Main Thread] Star detection using min star size of 4px... [10/05/17 20:22:04.316][DEBUG] [Main Thread] Star detection using max star size of 80px... [10/05/17 20:22:04.416][DEBUG] [Main Thread] Find stars took: 5257 ms... [10/05/17 20:22:04.416][DEBUG] [Main Thread] Star list contains 33 stars... [10/05/17 20:22:04.416][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [10/05/17 20:22:04.689][DEBUG] [Camera Thread] Auto focus HFR calculated at: 2.34 [10/05/17 20:22:05.697][DEBUG] [Camera Thread] Moving focuser to next position (7782)... [10/05/17 20:22:05.698][DEBUG] [Focuser Move Thread] Focuser moving to 7782 [10/05/17 20:22:05.754][DEBUG] [Focuser Move Thread] Focuser move call complete [10/05/17 20:22:06.811][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7782 matches requested position 7782 [10/05/17 20:22:06.811][DEBUG] [Camera Thread] Focuser move complete... [10/05/17 20:22:06.866][DEBUG] [Camera Thread] Focuser position matches requested position (7782), continuing... [10/05/17 20:22:06.923][DEBUG] [Camera Thread] Calculating step metric... [10/05/17 20:22:06.923][DEBUG] [Camera Thread] Taking auto focus frame(s)... [10/05/17 20:22:06.927][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [10/05/17 20:22:06.927][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [10/05/17 20:22:06.927][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [10/05/17 20:22:06.927][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [10/05/17 20:22:06.953][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 20:22:06.953][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 20:22:23.653][DEBUG] [Camera Thread] QSI expose and download: 16.6359515s [10/05/17 20:22:23.822][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 20:22:23.822][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 20:22:24.550][DEBUG] [Main Thread] --> Find stars (normal) [10/05/17 20:22:24.689][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [10/05/17 20:22:30.473][DEBUG] [Main Thread] Star detection using min star size of 4px... [10/05/17 20:22:30.474][DEBUG] [Main Thread] Star detection using max star size of 80px... [10/05/17 20:22:30.624][DEBUG] [Main Thread] Find stars took: 6073 ms... [10/05/17 20:22:30.624][DEBUG] [Main Thread] Star list contains 11 stars... [10/05/17 20:22:30.624][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [10/05/17 20:22:31.000][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.11 [10/05/17 20:22:32.010][DEBUG] [Camera Thread] New auto focus method calculated focus at: 7816 [10/05/17 20:22:32.010][DEBUG] [Camera Thread] Old auto focus method calculated focus at: 7817 [10/05/17 20:22:32.010][DEBUG] [Camera Thread] Using new auto focus method for focuser position [10/05/17 20:22:32.010][DEBUG] [Camera Thread] New focus position is at 7816 (@100.00C). Moving focuser... [10/05/17 20:22:32.011][DEBUG] [Focuser Move Thread] Focuser moving to 7816 [10/05/17 20:22:32.067][DEBUG] [Focuser Move Thread] Focuser move call complete [10/05/17 20:22:33.128][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7816 matches requested position 7816 [10/05/17 20:22:33.129][DEBUG] [Camera Thread] Focuser move complete... [10/05/17 20:22:33.184][DEBUG] [Camera Thread] Focuser successfully set to focus position (7816) [10/05/17 20:22:33.246][DEBUG] [Camera Thread] Taking validation frame at focus position... [10/05/17 20:22:33.247][DEBUG] [Camera Thread] Taking auto focus frame(s)... [10/05/17 20:22:33.250][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [10/05/17 20:22:33.250][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [10/05/17 20:22:33.250][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [10/05/17 20:22:33.250][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [10/05/17 20:22:33.355][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 20:22:33.355][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 20:22:50.105][DEBUG] [Camera Thread] QSI expose and download: 16.6859544s [10/05/17 20:22:50.307][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/05/17 20:22:50.307][DEBUG] [Camera Thread] Display image preview using asynch task... [10/05/17 20:22:50.960][DEBUG] [Main Thread] --> Find stars (normal) [10/05/17 20:22:51.085][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [10/05/17 20:22:56.797][DEBUG] [Main Thread] Star detection using min star size of 4px... [10/05/17 20:22:56.797][DEBUG] [Main Thread] Star detection using max star size of 80px... [10/05/17 20:22:56.885][DEBUG] [Main Thread] Find stars took: 5924 ms... [10/05/17 20:22:56.885][DEBUG] [Main Thread] Star list contains 50 stars... [10/05/17 20:22:56.885][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [10/05/17 20:22:57.159][DEBUG] [Camera Thread] Auto focus HFR calculated at: 1.37 [10/05/17 20:22:58.164][DEBUG] [Camera Thread] Auto focus is complete (best fit method: 7816; HFR 1.37)... [10/05/17 20:22:58.175][DEBUG] [Camera Thread] Entering chart disposal loop... [10/05/17 20:23:28.179][DEBUG] [Camera Thread] Auto closing chart dialog... [10/05/17 20:23:28.179][DEBUG] [Camera Thread] Auto focus (finally): closing dialog... [10/05/17 20:23:28.232][DEBUG] [AfChartThread] AF chart dialog is closed... [10/05/17 20:23:28.307][DEBUG] [Camera Thread] Auto focus complete... [10/05/17 20:23:28.323][DEBUG] [Camera Thread] SGM_FOCUSER_AUTO_FOCUS complete... [10/05/17 20:23:28.370][DEBUG] [Sequence Thread] AF is complete, continuing sequence... [10/05/17 20:23:28.370][DEBUG] [Sequence Thread] EventMarker 4 - t:Veil (0); e:0; f:2; o: [10/05/17 20:23:28.370][DEBUG] [Sequence Thread] Setting filter position 5... [10/05/17 20:23:28.370][DEBUG] [Sequence Thread] Filter position 5 is already set. Skipping... [10/05/17 20:23:28.370][DEBUG] [Sequence Thread] EventMarker 5 - t:Veil (0); e:0; f:2; o: [10/05/17 20:23:28.370][DEBUG] [Sequence Thread] Checking for auto manual focus (pre)... [10/05/17 20:23:28.370][DEBUG] [Sequence Thread] EventMarker 6 - t:Veil (0); e:0; f:2; o: [10/05/17 20:23:28.433][DEBUG] [Sequence Thread] Created base name for frame capture: Veil_300sec_1x1_Ha_frame3 [10/05/17 20:23:28.433][DEBUG] [Sequence Thread] Created directory for frame capture: C:\Users\Public\Documents\SGP Images\Light\ [10/05/17 20:23:28.433][DEBUG] [Sequence Thread] EventMarker 1 - t:Veil (0); e:0; f:2; o: [10/05/17 20:23:28.433][DEBUG] [Sequence Thread] EventMarker 7 - t:Veil (0); e:0; f:2; o: [10/05/17 20:23:28.433][DEBUG] [Sequence Thread] Resuming auto guiding (settling)... [10/05/17 20:23:28.433][DEBUG] [Sequence Thread] Checking PHD2 state... [10/05/17 20:23:28.433][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Pre-Wait : Guiding [10/05/17 20:23:28.433][DEBUG] [Sequence Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 20:23:28.533][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Post-Wait: Guiding [10/05/17 20:23:28.533][DEBUG] [Sequence Thread] PHD2: Requested unpause, but PHD2 reports it is not in a paused state [10/05/17 20:23:28.533][DEBUG] [Sequence Thread] {"method": "guide", "params": [{"pixels": 0.6, "time": 0, "timeout": 600}, false ], "id": 1003} [10/05/17 20:23:28.533][DEBUG] [Sequence Thread] Sending to PHD2: {"method": "guide", "params": [{"pixels": 0.6, "time": 0, "timeout": 600}, false ], "id": 1003} [10/05/17 20:23:28.533][DEBUG] [Sequence Thread] Checking PHD2 state... [10/05/17 20:23:28.533][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Pre-Wait : Guiding [10/05/17 20:23:28.533][DEBUG] [Sequence Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 20:23:28.633][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Post-Wait: Guiding [10/05/17 20:23:28.633][DEBUG] [Sequence Thread] User has elected to skip guider settling over time... [10/05/17 20:23:28.633][DEBUG] [Sequence Thread] Waiting for Auto Guider distance to fall below 0.6 [10/05/17 20:23:28.633][DEBUG] [Sequence Thread] PHD2 distance request... [10/05/17 20:23:28.633][DEBUG] [Sequence Thread] PHD2 distance request returned 0.6... [10/05/17 20:23:28.642][DEBUG] [Sequence Thread] Distance is below 0.6, starting timer... [10/05/17 20:23:28.803][DEBUG] [PHD2 Listener Thread] PHD2: Settle done received: GOOD [10/05/17 20:23:29.142][DEBUG] [Sequence Thread] PHD2 distance request... [10/05/17 20:23:29.142][DEBUG] [Sequence Thread] PHD2 distance request returned 0.5... [10/05/17 20:23:29.149][DEBUG] [Sequence Thread] Distance stayed below 0.6 for 0 seconds, done settling... [10/05/17 20:23:29.149][DEBUG] [Sequence Thread] Auto guider has settled... [10/05/17 20:23:29.150][DEBUG] [Sequence Thread] EventMarker 8 - t:Veil (0); e:0; f:2; o: [10/05/17 20:23:29.150][DEBUG] [Sequence Thread] EventMarker 9 - t:Veil (0); e:0; f:2; o: [10/05/17 20:23:29.150][DEBUG] [Sequence Thread] EventMarker 10 - t:Veil (0); e:0; f:2; o: [10/05/17 20:23:29.150][DEBUG] [Sequence Thread] Finished sending frame capture. Entering wait mode... [10/05/17 20:23:29.150][DEBUG] [Sequence Thread] EventMarker 11 - t:Veil (0); e:0; f:2; o: [10/05/17 20:23:29.150][DEBUG] [Sequence Thread] EventMarker 12 - t:Veil (0); e:0; f:2; o: [10/05/17 20:23:29.151][DEBUG] [Sequence Thread] EventMarker 13 - t:Veil (0); e:0; f:2; o: [10/05/17 20:23:29.154][DEBUG] [Camera Thread] SGM_CAMERA_CAPTURE message received... [10/05/17 20:23:29.180][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [10/05/17 20:23:29.180][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [10/05/17 20:28:44.906][DEBUG] [Camera Thread] QSI expose and download: 315.6620549s [10/05/17 20:28:44.906][DEBUG] [Camera Thread] SGM_CAMERA_CAPTURE complete... [10/05/17 20:31:29.231][DEBUG] [Monitoring System Timed Message Thread] Adding sequence level notification: Failure while integrating Veil; Event 1; Frame 3 for 300s. Image has not downloaded in alloted time period. [10/05/17 20:36:58.108][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [10/05/17 20:36:58.108][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [10/05/17 20:36:58.108][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Guiding [10/05/17 20:36:58.108][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 20:36:58.108][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Guiding [10/05/17 20:37:08.120][DEBUG] [PHD2 Listener Thread] Attempting to connect to PHD2... [10/05/17 20:37:09.121][DEBUG] [PHD2 Listener Thread] Failed to establish client connection to PHD2 using port 4400: No connection could be made because the target machine actively refused it 127.0.0.1:4400 [10/05/17 20:37:13.121][DEBUG] [PHD2 Listener Thread] Failed to establish client connection to PHD2 using port 4400: No connection could be made because the target machine actively refused it 127.0.0.1:4400 [10/05/17 20:37:16.121][DEBUG] [PHD2 Listener Thread] Could not etablish a connection to PHD2! Aborting... [10/05/17 20:37:16.121][DEBUG] [PHD2 Listener Thread] PHD2 - Could not get a status message in more than 1 minute, attempting to force reconnection... [10/05/17 20:37:16.121][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [10/05/17 20:37:16.121][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : BadConnection [10/05/17 20:37:16.121][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/05/17 20:37:16.121][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: BadConnection [10/05/17 20:37:16.122][DEBUG] [PHD2 Listener Thread] PHD2 connection terminated... [10/05/17 20:37:16.122][DEBUG] [PHD2 Listener Thread] Exiting PHD2 listener...