[08/29/17 08:23:29.823][DEBUG] [Main Thread] Licensing: Machine ID found... [08/29/17 08:23:29.884][DEBUG] [Main Thread] Checking for valid internet connection... [08/29/17 08:23:30.014][DEBUG] [Main Thread] Valid internet connection detected... [08/29/17 08:23:30.791][DEBUG] [Main Thread] Licensing: Request for licenses successful, writing cookie... [08/29/17 08:23:30.801][DEBUG] [Main Thread] Licensing: Applying licenses.. [08/29/17 08:23:30.801][DEBUG] [Main Thread] Licensing: Licenses applied.. [08/29/17 08:23:30.801][DEBUG] [Main Thread] SGPro retail license... [08/29/17 08:23:31.776][DEBUG] [Main Thread] API successfully started on port 59590... [08/29/17 08:23:32.703][DEBUG] [Main Thread] Error loading notification settings! Could not find file 'C:\Users\jones\AppData\Local\SequenceGenerator\Sequence Generator Pro\notifications.sgd'. [08/29/17 08:23:32.707][DEBUG] [Main Thread] Error loading GNS endpoint settings! Could not find file 'C:\Users\jones\AppData\Local\SequenceGenerator\Sequence Generator Pro\gnsSettings.sgd'. [08/29/17 08:23:32.707][DEBUG] [Main Thread] Error loading email endpoint addresses! Could not find file 'C:\Users\jones\AppData\Local\SequenceGenerator\Sequence Generator Pro\1-emailEndpointAddresses.sgd'. [08/29/17 08:23:32.708][DEBUG] [Main Thread] Error loading email endpoint addresses! Could not find file 'C:\Users\jones\AppData\Local\SequenceGenerator\Sequence Generator Pro\2-emailEndpointAddresses.sgd'. [08/29/17 08:23:32.708][DEBUG] [Main Thread] Error loading email endpoint addresses! Could not find file 'C:\Users\jones\AppData\Local\SequenceGenerator\Sequence Generator Pro\3-emailEndpointAddresses.sgd'. [08/29/17 08:23:32.708][DEBUG] [Main Thread] Error loading text file endpoint data! Could not find file 'C:\Users\jones\AppData\Local\SequenceGenerator\Sequence Generator Pro\1-textFileEndpointSettings.sgd'. [08/29/17 08:23:32.708][DEBUG] [Main Thread] Error loading text file endpoint data! Could not find file 'C:\Users\jones\AppData\Local\SequenceGenerator\Sequence Generator Pro\2-textFileEndpointSettings.sgd'. [08/29/17 08:23:32.708][DEBUG] [Main Thread] Error loading text file endpoint data! Could not find file 'C:\Users\jones\AppData\Local\SequenceGenerator\Sequence Generator Pro\3-textFileEndpointSettings.sgd'. [08/29/17 08:23:32.831][DEBUG] [Main Thread] ===== S E Q U E N C E G E N E R A T O R (v2.6.0.23) ===== [08/29/17 08:23:32.896][DEBUG] [Main Thread] OS: Microsoft Windows 10 Home [08/29/17 08:23:33.187][DEBUG] [Safety Thread] Starting safety monitor... [08/29/17 08:23:33.189][DEBUG] [TEC Thread] Entering TEC message dispatch loop... [08/29/17 08:23:33.189][DEBUG] [Filter Wheel Thread] Entering Filter Wheel message dispatch loop... [08/29/17 08:23:33.189][DEBUG] [Camera Thread] Entering camera message dispatch loop... [08/29/17 08:23:33.190][DEBUG] [Focuser Thread] Entering Fouser message dispatch loop... [08/29/17 08:23:33.190][DEBUG] [Auto Guider Error Thread] Entering Auto Guider message dispatch loop... [08/29/17 08:23:33.191][DEBUG] [Auto Guider Thread] Entering Auto Guider message dispatch loop... [08/29/17 08:23:33.191][DEBUG] [Rotator Thread] Entering Rotator message dispatch loop... [08/29/17 08:23:33.192][DEBUG] [Environment Device Thread] Starting environment device monitor... [08/29/17 08:23:33.192][DEBUG] [Dome Thread] Entering Dome message dispatch loop... [08/29/17 08:23:33.202][DEBUG] [Telescope Thread] Entering Telescope message dispatch loop... [08/29/17 08:23:33.234][DEBUG] [Main Thread] Settings changed (or loaded)... [08/29/17 08:23:33.235][DEBUG] [Main Thread] * SETTING: settingMdiAutoStretch = False [08/29/17 08:23:33.235][DEBUG] [Main Thread] * SETTING: settingIgLastFileFolder = [08/29/17 08:23:33.235][DEBUG] [Main Thread] * SETTING: settingFormScaleWarning = False [08/29/17 08:23:33.235][DEBUG] [Main Thread] * SETTING: settingRecoveryDuration = 90 [08/29/17 08:23:33.235][DEBUG] [Main Thread] * SETTING: settingSBIGLpt2Address = 278 [08/29/17 08:23:33.235][DEBUG] [Main Thread] * SETTING: settingFliFlushLow = 0 [08/29/17 08:23:33.235][DEBUG] [Main Thread] * SETTING: settingSBIGLpt1Address = 378 [08/29/17 08:23:33.235][DEBUG] [Main Thread] * SETTING: settingMdiMouseAction = Stretch [08/29/17 08:23:33.235][DEBUG] [Main Thread] * SETTING: settingLastAscomCamera = [08/29/17 08:23:33.235][DEBUG] [Main Thread] * SETTING: settingTemperhumInterval = 300 [08/29/17 08:23:33.235][DEBUG] [Main Thread] * SETTING: settingASCOMWarningShown = False [08/29/17 08:23:33.235][DEBUG] [Main Thread] * SETTING: settingPlateCamScale = 0 [08/29/17 08:23:33.235][DEBUG] [Main Thread] * SETTING: settingCanonMirrorSettle = 0 [08/29/17 08:23:33.235][DEBUG] [Main Thread] * SETTING: settingInterface = [08/29/17 08:23:33.235][DEBUG] [Main Thread] * SETTING: settingLastAscomFilterWheel = [08/29/17 08:23:33.235][DEBUG] [Main Thread] * SETTING: settingAstroArtDisplayInstructions = True [08/29/17 08:23:33.235][DEBUG] [Main Thread] * SETTING: settingMdiPhdZoom = 40 [08/29/17 08:23:33.235][DEBUG] [Main Thread] * SETTING: settingCheckForUpdatesOnStart = True [08/29/17 08:23:33.235][DEBUG] [Main Thread] * SETTING: settingMdiLastImageDir = [08/29/17 08:23:33.235][DEBUG] [Main Thread] * SETTING: settingTemperHumUserTempComp = 0 [08/29/17 08:23:33.235][DEBUG] [Main Thread] * SETTING: settingCaptureCalFramesAlways = False [08/29/17 08:23:33.235][DEBUG] [Main Thread] * SETTING: settingDelayFirst = 0 [08/29/17 08:23:33.235][DEBUG] [Main Thread] * SETTING: settingFliNormalSpeedMode = 0 [08/29/17 08:23:33.235][DEBUG] [Main Thread] * SETTING: settingFliHighSpeedMode = 0 [08/29/17 08:23:33.236][DEBUG] [Main Thread] * SETTING: settingMfPosition = {X=0,Y=0} [08/29/17 08:23:33.236][DEBUG] [Main Thread] * SETTING: settingSoundAttention = [08/29/17 08:23:33.236][DEBUG] [Main Thread] * SETTING: settingPinpointCatalog = [08/29/17 08:23:33.236][DEBUG] [Main Thread] * SETTING: settingCameraOverhead = [08/29/17 08:23:33.236][DEBUG] [Main Thread] * SETTING: settingPlateSolveOn = False [08/29/17 08:23:33.244][DEBUG] [Main Thread] * SETTING: settingAstrometryNetEndpointList = System.Collections.Specialized.StringCollection [08/29/17 08:23:33.244][DEBUG] [Main Thread] * SETTING: settingSBIGGuider = None [08/29/17 08:23:33.244][DEBUG] [Main Thread] * SETTING: settingQSIFanSpeed = 2 [08/29/17 08:23:33.244][DEBUG] [Main Thread] * SETTING: settingNaggerText = [08/29/17 08:23:33.244][DEBUG] [Main Thread] * SETTING: settingMdiPhdDisplay = RA/Dec [08/29/17 08:23:33.244][DEBUG] [Main Thread] * SETTING: settingMdiShowToolStrip = True [08/29/17 08:23:33.244][DEBUG] [Main Thread] * SETTING: settingMdiShowCrosshairs = True [08/29/17 08:23:33.244][DEBUG] [Main Thread] * SETTING: WindowGeometry = [08/29/17 08:23:33.244][DEBUG] [Main Thread] * SETTING: settingIgRenameStringLocation = 2 [08/29/17 08:23:33.244][DEBUG] [Main Thread] * SETTING: settingIgSubFolders = False [08/29/17 08:23:33.244][DEBUG] [Main Thread] * SETTING: WindowGeometrySearch = [08/29/17 08:23:33.244][DEBUG] [Main Thread] * SETTING: settingOffset = -1 [08/29/17 08:23:33.244][DEBUG] [Main Thread] * SETTING: settingAutoStretchLevel = Low [08/29/17 08:23:33.244][DEBUG] [Main Thread] * SETTING: settingTemperHumUnits = C [08/29/17 08:23:33.244][DEBUG] [Main Thread] * SETTING: settingProfileDirectory = [08/29/17 08:23:33.244][DEBUG] [Main Thread] * SETTING: settingBasename = [08/29/17 08:23:33.244][DEBUG] [Main Thread] * SETTING: settingHfrColor = Color [RoyalBlue] [08/29/17 08:23:33.244][DEBUG] [Main Thread] * SETTING: settingNikonAfBinning = 1 [08/29/17 08:23:33.244][DEBUG] [Main Thread] * SETTING: settingSBIGFanOffWithTec = False [08/29/17 08:23:33.244][DEBUG] [Main Thread] * SETTING: settingLastAscomSafetyMonitor = [08/29/17 08:23:33.245][DEBUG] [Main Thread] * SETTING: settingMdiSize = {Width=800, Height=600} [08/29/17 08:23:33.245][DEBUG] [Main Thread] * SETTING: settingIgCopyAndRenameFiles = False [08/29/17 08:23:33.245][DEBUG] [Main Thread] * SETTING: settingSimDownloadTimesAf = False [08/29/17 08:23:33.245][DEBUG] [Main Thread] * SETTING: settingNagger = False [08/29/17 08:23:33.245][DEBUG] [Main Thread] * SETTING: settingImportFromCfw = True [08/29/17 08:23:33.245][DEBUG] [Main Thread] * SETTING: settingMdiShowStatusTrip = True [08/29/17 08:23:33.245][DEBUG] [Main Thread] * SETTING: settingShowTooltipHelp = True [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingLastUpdateCheck = 1/1/2012 12:00:00 AM [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingEventSuffixes = null [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingCpPosition = {X=0,Y=0} [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingSBIGCfwPort = [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingMdiLocation = {X=0,Y=0} [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingIgLastCsvFolder = [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingPlateSolve3Catalogs = [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingSBIGFilterWheel = Auto Detect [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingSBIGConnection = USB [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingDSLRSoftwareBinForAf = False [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingAutoSave = True [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingFirstTime = True [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingAutoRecoveryActive = False [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingMdiState = maximized [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingUseSequenceOptions = False [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingSoundCompleted = [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingTrialExpiredShown = False [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingTemperHumColor = Grey [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingDataDirectory = [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingCanonFileFormat = Single Image (CFA FITS) [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingAlnitakComPort = [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingIgRenameFiles = False [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingPhd2InstallPath = [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingSubFolder = True [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingLastSGSaveFolder = [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingFindStars = Normal (recommended) [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingNikonDataFormat = FITS [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingPlaySounds = False [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingLastNikonType = Auto Connect [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingLastSequenceStart = 1/1/0001 12:00:00 AM [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingLastAscomTelescope = [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingMdiPhdScale = 100 [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingIgRenameType = 0 [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingHelpLanguage = English [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingTemperHumUserHumidityComp = 0 [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingMdiPhdRaColor = Color [Blue] [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingLastImageSaveFolder = [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingIgOverwriteExisting = False [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingFinishFullEvent = True [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingMosaicOverlap = 20 [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingQSIFanOffWithTec = False [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingRecentSequences = [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingDefaultDirectory = [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingMdiPhdDecColor = Color [Red] [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingAstrometryNetEndpoint = Astrometry.NET (Remote) [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingFliFlushHigh = 0 [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingStarsColor = Color [OrangeRed] [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingDefaultMfwFov = 4.0 [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingSBIGLpt3Address = 3BC [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingLastAscomFocuser = [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingIgLastFolder = [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: WindowGeometryOpen = [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingNumEvents = 5 [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingDelayBetween = 0 [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingCanonAfBinning = 1 [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingPlateCamBlank = [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingLogApiCalls = False [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingNikonUseBulbModeAlways = False [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingTempFiles = C:\Temp [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingFliHighSpeedDesc = [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingSBIGCfwUseRS232 = False [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingRecoveryInterval = 10 [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingEnableImageHistory = False [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingSBIGEthernetIP = 0.0.0.0 [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingFliNormalSpeedDesc = [08/29/17 08:23:33.246][DEBUG] [Main Thread] * SETTING: settingIgCopyLocation = [08/29/17 08:23:33.247][DEBUG] [Main Thread] * SETTING: settingSBIGAutoDetectIp = True [08/29/17 08:23:33.247][DEBUG] [Main Thread] * SETTING: settingAstroArtStartApp = True [08/29/17 08:23:33.247][DEBUG] [Main Thread] * SETTING: settingSimDownloadTimesPlate = False [08/29/17 08:23:33.247][DEBUG] [Main Thread] * SETTING: settingMicroTouchLocation = [08/29/17 08:23:33.247][DEBUG] [Main Thread] * SETTING: settingEventAlwaysVisible = False [08/29/17 08:23:33.247][DEBUG] [Main Thread] * SETTING: settingMdiNewWindow = False [08/29/17 08:23:33.247][DEBUG] [Main Thread] * SETTING: settingTrialQuestionsAsked = False [08/29/17 08:23:33.247][DEBUG] [Main Thread] * SETTING: settingFliRbiMitigation = False [08/29/17 08:23:33.247][DEBUG] [Main Thread] * SETTING: settingGain = -1 [08/29/17 08:23:33.247][DEBUG] [Main Thread] * SETTING: settingLastAscomDome = [08/29/17 08:23:33.247][DEBUG] [Main Thread] * SETTING: settingLookForTemperDevices = False [08/29/17 08:23:33.247][DEBUG] [Main Thread] * SETTING: settingLastAscomRotator = [08/29/17 08:23:33.247][DEBUG] [Main Thread] * SETTING: settingAfTestPackPath = [08/29/17 08:23:33.371][DEBUG] [Main Thread] ASCOM Boltwood Observing Conditions [08/29/17 08:23:33.371][DEBUG] [Main Thread] ASCOM Observing Conditions Hub (OCH) [08/29/17 08:23:33.371][DEBUG] [Main Thread] ASCOM Observing Conditions Simulator [08/29/17 08:23:33.371][DEBUG] [Main Thread] OpenWeatherMap ObservingConditions [08/29/17 08:23:33.371][DEBUG] [Main Thread] TEMPerHUM [08/29/17 08:23:33.374][DEBUG] [Main Thread] Performing initialization... [08/29/17 08:23:33.374][DEBUG] [Main Thread] Creating new sequence with default profile: Wes1 [08/29/17 08:23:33.385][DEBUG] [Main Thread] Performing full form reset (with profile Wes1)... [08/29/17 08:23:33.389][DEBUG] [Main Thread] Retreiving new equipment objects... [08/29/17 08:23:33.389][DEBUG] [Main Thread] New camera object (QSI CCD Camera) dispatched... [08/29/17 08:23:33.404][DEBUG] [Main Thread] New filter wheel object (QSI Internal Filter Wheel) dispatched... [08/29/17 08:23:33.405][DEBUG] [Main Thread] New environment device object (ASCOM Boltwood Observing Conditions) dispatched... [08/29/17 08:23:33.408][DEBUG] [Main Thread] New focuser object (RoboFocus) dispatched... [08/29/17 08:23:33.410][DEBUG] [Main Thread] New telescope object (POTH Hub) dispatched... [08/29/17 08:23:33.412][DEBUG] [Main Thread] New rotator object (No Rotator) dispatched... [08/29/17 08:23:33.412][DEBUG] [Main Thread] New dome object (SkyRoofHub) dispatched... [08/29/17 08:23:33.414][DEBUG] [Main Thread] New flat box object (No Flat Box) dispatched... [08/29/17 08:23:33.415][DEBUG] [Main Thread] New safety monitor object (ASCOM Boltwood OK to Image) dispatched... [08/29/17 08:23:33.416][DEBUG] [Main Thread] New auto guider object (PHD2) dispatched... [08/29/17 08:23:33.416][DEBUG] [Main Thread] New plate solver object (No Plate Solver) dispatched... [08/29/17 08:23:33.443][DEBUG] [Main Thread] Populating the form controls... [08/29/17 08:23:33.519][DEBUG] [Main Thread] Added row 0... [08/29/17 08:23:33.559][DEBUG] [Main Thread] Added row 1... [08/29/17 08:23:33.611][DEBUG] [Main Thread] Added row 2... [08/29/17 08:23:33.668][DEBUG] [Main Thread] Added row 3... [08/29/17 08:23:33.736][DEBUG] [Main Thread] Added row 4... [08/29/17 08:23:33.754][DEBUG] [Main Thread] Loading custom filter names... [08/29/17 08:23:33.761][DEBUG] [Main Thread] PopulateDataModel: Transferring view to the data model... [08/29/17 08:23:33.817][DEBUG] [Main Thread] Retreiving new equipment objects... [08/29/17 08:23:33.817][DEBUG] [Main Thread] No change in camera object... [08/29/17 08:23:33.817][DEBUG] [Main Thread] No change in filter wheel object... [08/29/17 08:23:33.817][DEBUG] [Main Thread] No change in environment device object... [08/29/17 08:23:33.817][DEBUG] [Main Thread] No change in focuser object... [08/29/17 08:23:33.818][DEBUG] [Main Thread] MoveFocuserAbs: Error! The focuser is no longer connected! [08/29/17 08:23:33.818][DEBUG] [Sequence Thread] Entering sequence message dispatch loop... [08/29/17 08:23:33.818][DEBUG] [Main Thread] No change in telescope object... [08/29/17 08:23:33.818][DEBUG] [Main Thread] New rotator object (No Rotator) dispatched... [08/29/17 08:23:33.819][DEBUG] [Main Thread] No change in dome object... [08/29/17 08:23:33.819][DEBUG] [Main Thread] New flat box object (No Flat Box) dispatched... [08/29/17 08:23:33.819][DEBUG] [Main Thread] No change in safety monitor object... [08/29/17 08:23:33.819][DEBUG] [Main Thread] New auto guider object (PHD2) dispatched... [08/29/17 08:23:33.820][DEBUG] [Main Thread] New plate solver object (No Plate Solver) dispatched... [08/29/17 08:23:33.822][DEBUG] [Main Thread] Checking if version check is required... [08/29/17 08:23:33.944][DEBUG] [Cleaner Thread] Running cleanup... [08/29/17 08:23:33.945][DEBUG] [Cleaner Thread] Cleaning 0 files in directory C:\Users\jones\AppData\Local\SequenceGenerator\Temp\... [08/29/17 08:23:33.945][DEBUG] [Cleaner Thread] Cleaning multi camera registration file... [08/29/17 08:23:33.945][DEBUG] [Cleaner Thread] Cleaning finished... [08/29/17 08:23:33.947][DEBUG] [Main Thread] UI layout found, loading layout at C:\Users\jones\AppData\Local\SequenceGenerator\sg_ui_config.xml [08/29/17 08:27:39.862][DEBUG] [Main Thread] Settings changed (or loaded)... [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingMdiAutoStretch = False [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingIgLastFileFolder = [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingFormScaleWarning = False [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingRecoveryDuration = 90 [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingSBIGLpt2Address = 278 [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingFliFlushLow = 0 [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingSBIGLpt1Address = 378 [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingMdiMouseAction = Stretch [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingLastAscomCamera = [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingTemperhumInterval = 300 [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingASCOMWarningShown = False [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingPlateCamScale = 0 [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingCanonMirrorSettle = 0 [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingInterface = [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingLastAscomFilterWheel = [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingAstroArtDisplayInstructions = True [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingMdiPhdZoom = 40 [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingCheckForUpdatesOnStart = True [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingMdiLastImageDir = [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingTemperHumUserTempComp = 0 [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingCaptureCalFramesAlways = False [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingDelayFirst = 0 [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingFliNormalSpeedMode = 0 [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingFliHighSpeedMode = 0 [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingMfPosition = {X=0,Y=0} [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingSoundAttention = [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingPinpointCatalog = [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingCameraOverhead = [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingPlateSolveOn = False [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingAstrometryNetEndpointList = System.Collections.Specialized.StringCollection [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingSBIGGuider = None [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingQSIFanSpeed = 2 [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingNaggerText = [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingMdiPhdDisplay = RA/Dec [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingMdiShowToolStrip = True [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingMdiShowCrosshairs = True [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: WindowGeometry = [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingIgRenameStringLocation = 2 [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingIgSubFolders = False [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: WindowGeometrySearch = [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingOffset = -1 [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingAutoStretchLevel = Low [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingTemperHumUnits = C [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingProfileDirectory = [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingBasename = [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingHfrColor = Color [RoyalBlue] [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingNikonAfBinning = 1 [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingSBIGFanOffWithTec = False [08/29/17 08:27:39.862][DEBUG] [Main Thread] * SETTING: settingLastAscomSafetyMonitor = [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingMdiSize = {Width=800, Height=600} [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingIgCopyAndRenameFiles = False [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingSimDownloadTimesAf = False [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingNagger = False [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingImportFromCfw = True [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingMdiShowStatusTrip = True [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingShowTooltipHelp = True [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingLastUpdateCheck = 1/1/2012 12:00:00 AM [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingEventSuffixes = null [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingCpPosition = {X=0,Y=0} [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingSBIGCfwPort = [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingMdiLocation = {X=0,Y=0} [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingIgLastCsvFolder = [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingPlateSolve3Catalogs = [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingSBIGFilterWheel = Auto Detect [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingSBIGConnection = USB [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingDSLRSoftwareBinForAf = False [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingAutoSave = True [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingFirstTime = True [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingAutoRecoveryActive = False [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingMdiState = maximized [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingUseSequenceOptions = False [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingSoundCompleted = [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingTrialExpiredShown = False [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingTemperHumColor = Grey [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingDataDirectory = [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingCanonFileFormat = Single Image (CFA FITS) [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingAlnitakComPort = [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingIgRenameFiles = False [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingPhd2InstallPath = [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingSubFolder = True [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingLastSGSaveFolder = [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingFindStars = Normal (recommended) [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingNikonDataFormat = FITS [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingPlaySounds = False [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingLastNikonType = Auto Connect [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingLastSequenceStart = 1/1/0001 12:00:00 AM [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingLastAscomTelescope = [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingMdiPhdScale = 100 [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingIgRenameType = 0 [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingHelpLanguage = English [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingTemperHumUserHumidityComp = 0 [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingMdiPhdRaColor = Color [Blue] [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingLastImageSaveFolder = [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingIgOverwriteExisting = False [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingFinishFullEvent = True [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingMosaicOverlap = 20 [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingQSIFanOffWithTec = False [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingRecentSequences = [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingDefaultDirectory = [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingMdiPhdDecColor = Color [Red] [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingAstrometryNetEndpoint = Astrometry.NET (Remote) [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingFliFlushHigh = 0 [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingStarsColor = Color [OrangeRed] [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingDefaultMfwFov = 4.0 [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingSBIGLpt3Address = 3BC [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingLastAscomFocuser = [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingIgLastFolder = [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: WindowGeometryOpen = [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingNumEvents = 5 [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingDelayBetween = 0 [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingCanonAfBinning = 1 [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingPlateCamBlank = [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingLogApiCalls = False [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingNikonUseBulbModeAlways = False [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingTempFiles = C:\Temp [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingFliHighSpeedDesc = [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingSBIGCfwUseRS232 = False [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingRecoveryInterval = 10 [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingEnableImageHistory = False [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingSBIGEthernetIP = 0.0.0.0 [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingFliNormalSpeedDesc = [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingIgCopyLocation = [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingSBIGAutoDetectIp = True [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingAstroArtStartApp = True [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingSimDownloadTimesPlate = False [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingMicroTouchLocation = [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingEventAlwaysVisible = False [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingMdiNewWindow = False [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingTrialQuestionsAsked = False [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingFliRbiMitigation = False [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingGain = -1 [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingLastAscomDome = [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingLookForTemperDevices = False [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingLastAscomRotator = [08/29/17 08:27:39.863][DEBUG] [Main Thread] * SETTING: settingAfTestPackPath = [08/29/17 08:28:12.737][DEBUG] [Main Thread] Settings changed (or loaded)... [08/29/17 08:28:12.737][DEBUG] [Main Thread] * SETTING: settingMdiAutoStretch = False [08/29/17 08:28:12.737][DEBUG] [Main Thread] * SETTING: settingIgLastFileFolder = [08/29/17 08:28:12.737][DEBUG] [Main Thread] * SETTING: settingFormScaleWarning = False [08/29/17 08:28:12.737][DEBUG] [Main Thread] * SETTING: settingRecoveryDuration = 90 [08/29/17 08:28:12.737][DEBUG] [Main Thread] * SETTING: settingSBIGLpt2Address = 278 [08/29/17 08:28:12.737][DEBUG] [Main Thread] * SETTING: settingFliFlushLow = 0 [08/29/17 08:28:12.737][DEBUG] [Main Thread] * SETTING: settingSBIGLpt1Address = 378 [08/29/17 08:28:12.737][DEBUG] [Main Thread] * SETTING: settingMdiMouseAction = Stretch [08/29/17 08:28:12.737][DEBUG] [Main Thread] * SETTING: settingLastAscomCamera = [08/29/17 08:28:12.737][DEBUG] [Main Thread] * SETTING: settingTemperhumInterval = 300 [08/29/17 08:28:12.737][DEBUG] [Main Thread] * SETTING: settingASCOMWarningShown = False [08/29/17 08:28:12.737][DEBUG] [Main Thread] * SETTING: settingPlateCamScale = 0 [08/29/17 08:28:12.737][DEBUG] [Main Thread] * SETTING: settingCanonMirrorSettle = 0 [08/29/17 08:28:12.737][DEBUG] [Main Thread] * SETTING: settingInterface = [08/29/17 08:28:12.737][DEBUG] [Main Thread] * SETTING: settingLastAscomFilterWheel = [08/29/17 08:28:12.737][DEBUG] [Main Thread] * SETTING: settingAstroArtDisplayInstructions = True [08/29/17 08:28:12.737][DEBUG] [Main Thread] * SETTING: settingMdiPhdZoom = 40 [08/29/17 08:28:12.737][DEBUG] [Main Thread] * SETTING: settingCheckForUpdatesOnStart = True [08/29/17 08:28:12.737][DEBUG] [Main Thread] * SETTING: settingMdiLastImageDir = [08/29/17 08:28:12.737][DEBUG] [Main Thread] * SETTING: settingTemperHumUserTempComp = 0 [08/29/17 08:28:12.737][DEBUG] [Main Thread] * SETTING: settingCaptureCalFramesAlways = False [08/29/17 08:28:12.737][DEBUG] [Main Thread] * SETTING: settingDelayFirst = 0 [08/29/17 08:28:12.737][DEBUG] [Main Thread] * SETTING: settingFliNormalSpeedMode = 0 [08/29/17 08:28:12.737][DEBUG] [Main Thread] * SETTING: settingFliHighSpeedMode = 0 [08/29/17 08:28:12.737][DEBUG] [Main Thread] * SETTING: settingMfPosition = {X=0,Y=0} [08/29/17 08:28:12.738][DEBUG] [Main Thread] * SETTING: settingSoundAttention = [08/29/17 08:28:12.738][DEBUG] [Main Thread] * SETTING: settingPinpointCatalog = [08/29/17 08:28:12.738][DEBUG] [Main Thread] * SETTING: settingCameraOverhead = [08/29/17 08:28:12.738][DEBUG] [Main Thread] * SETTING: settingPlateSolveOn = False [08/29/17 08:28:12.738][DEBUG] [Main Thread] * SETTING: settingAstrometryNetEndpointList = System.Collections.Specialized.StringCollection [08/29/17 08:28:12.738][DEBUG] [Main Thread] * SETTING: settingSBIGGuider = None [08/29/17 08:28:12.738][DEBUG] [Main Thread] * SETTING: settingQSIFanSpeed = 2 [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingNaggerText = [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingMdiPhdDisplay = RA/Dec [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingMdiShowToolStrip = True [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingMdiShowCrosshairs = True [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: WindowGeometry = [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingIgRenameStringLocation = 2 [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingIgSubFolders = False [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: WindowGeometrySearch = [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingOffset = -1 [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingAutoStretchLevel = Low [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingTemperHumUnits = C [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingProfileDirectory = [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingBasename = [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingHfrColor = Color [RoyalBlue] [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingNikonAfBinning = 1 [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingSBIGFanOffWithTec = False [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingLastAscomSafetyMonitor = [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingMdiSize = {Width=800, Height=600} [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingIgCopyAndRenameFiles = False [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingSimDownloadTimesAf = False [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingNagger = False [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingImportFromCfw = True [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingMdiShowStatusTrip = True [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingShowTooltipHelp = True [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingLastUpdateCheck = 1/1/2012 12:00:00 AM [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingEventSuffixes = null [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingCpPosition = {X=0,Y=0} [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingSBIGCfwPort = [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingMdiLocation = {X=0,Y=0} [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingIgLastCsvFolder = [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingPlateSolve3Catalogs = [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingSBIGFilterWheel = Auto Detect [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingSBIGConnection = USB [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingDSLRSoftwareBinForAf = False [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingAutoSave = True [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingFirstTime = True [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingAutoRecoveryActive = False [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingMdiState = maximized [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingUseSequenceOptions = False [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingSoundCompleted = [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingTrialExpiredShown = False [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingTemperHumColor = Grey [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingDataDirectory = [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingCanonFileFormat = Single Image (CFA FITS) [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingAlnitakComPort = [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingIgRenameFiles = False [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingPhd2InstallPath = [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingSubFolder = True [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingLastSGSaveFolder = [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingFindStars = Normal (recommended) [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingNikonDataFormat = FITS [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingPlaySounds = False [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingLastNikonType = Auto Connect [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingLastSequenceStart = 1/1/0001 12:00:00 AM [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingLastAscomTelescope = [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingMdiPhdScale = 100 [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingIgRenameType = 0 [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingHelpLanguage = English [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingTemperHumUserHumidityComp = 0 [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingMdiPhdRaColor = Color [Blue] [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingLastImageSaveFolder = [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingIgOverwriteExisting = False [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingFinishFullEvent = True [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingMosaicOverlap = 20 [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingQSIFanOffWithTec = False [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingRecentSequences = [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingDefaultDirectory = [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingMdiPhdDecColor = Color [Red] [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingAstrometryNetEndpoint = Astrometry.NET (Remote) [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingFliFlushHigh = 0 [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingStarsColor = Color [OrangeRed] [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingDefaultMfwFov = 4.0 [08/29/17 08:28:12.739][DEBUG] [Main Thread] * SETTING: settingSBIGLpt3Address = 3BC [08/29/17 08:28:12.740][DEBUG] [Main Thread] * SETTING: settingLastAscomFocuser = [08/29/17 08:28:12.740][DEBUG] [Main Thread] * SETTING: settingIgLastFolder = [08/29/17 08:28:12.740][DEBUG] [Main Thread] * SETTING: WindowGeometryOpen = [08/29/17 08:28:12.740][DEBUG] [Main Thread] * SETTING: settingNumEvents = 5 [08/29/17 08:28:12.740][DEBUG] [Main Thread] * SETTING: settingDelayBetween = 0 [08/29/17 08:28:12.740][DEBUG] [Main Thread] * SETTING: settingCanonAfBinning = 1 [08/29/17 08:28:12.740][DEBUG] [Main Thread] * SETTING: settingPlateCamBlank = [08/29/17 08:28:12.740][DEBUG] [Main Thread] * SETTING: settingLogApiCalls = False [08/29/17 08:28:12.740][DEBUG] [Main Thread] * SETTING: settingNikonUseBulbModeAlways = False [08/29/17 08:28:12.740][DEBUG] [Main Thread] * SETTING: settingTempFiles = C:\Temp [08/29/17 08:28:12.740][DEBUG] [Main Thread] * SETTING: settingFliHighSpeedDesc = [08/29/17 08:28:12.740][DEBUG] [Main Thread] * SETTING: settingSBIGCfwUseRS232 = False [08/29/17 08:28:12.740][DEBUG] [Main Thread] * SETTING: settingRecoveryInterval = 10 [08/29/17 08:28:12.740][DEBUG] [Main Thread] * SETTING: settingEnableImageHistory = False [08/29/17 08:28:12.740][DEBUG] [Main Thread] * SETTING: settingSBIGEthernetIP = 0.0.0.0 [08/29/17 08:28:12.740][DEBUG] [Main Thread] * SETTING: settingFliNormalSpeedDesc = [08/29/17 08:28:12.740][DEBUG] [Main Thread] * SETTING: settingIgCopyLocation = [08/29/17 08:28:12.740][DEBUG] [Main Thread] * SETTING: settingSBIGAutoDetectIp = True [08/29/17 08:28:12.740][DEBUG] [Main Thread] * SETTING: settingAstroArtStartApp = True [08/29/17 08:28:12.740][DEBUG] [Main Thread] * SETTING: settingSimDownloadTimesPlate = False [08/29/17 08:28:12.740][DEBUG] [Main Thread] * SETTING: settingMicroTouchLocation = [08/29/17 08:28:12.740][DEBUG] [Main Thread] * SETTING: settingEventAlwaysVisible = False [08/29/17 08:28:12.740][DEBUG] [Main Thread] * SETTING: settingMdiNewWindow = False [08/29/17 08:28:12.740][DEBUG] [Main Thread] * SETTING: settingTrialQuestionsAsked = False [08/29/17 08:28:12.740][DEBUG] [Main Thread] * SETTING: settingFliRbiMitigation = False [08/29/17 08:28:12.740][DEBUG] [Main Thread] * SETTING: settingGain = -1 [08/29/17 08:28:12.740][DEBUG] [Main Thread] * SETTING: settingLastAscomDome = [08/29/17 08:28:12.740][DEBUG] [Main Thread] * SETTING: settingLookForTemperDevices = False [08/29/17 08:28:12.740][DEBUG] [Main Thread] * SETTING: settingLastAscomRotator = [08/29/17 08:28:12.740][DEBUG] [Main Thread] * SETTING: settingAfTestPackPath = [08/29/17 08:28:49.053][DEBUG] [Main Thread] Performing deserialize... [08/29/17 08:28:49.059][DEBUG] [Main Thread] Loading custom filter names... [08/29/17 08:28:49.073][DEBUG] [Main Thread] ReflectDataModel: Transferring data model to the view... [08/29/17 08:28:49.076][DEBUG] [Main Thread] Retreiving new equipment objects... [08/29/17 08:28:49.076][DEBUG] [Main Thread] No change in camera object... [08/29/17 08:28:49.076][DEBUG] [Main Thread] No change in filter wheel object... [08/29/17 08:28:49.077][DEBUG] [Main Thread] No change in environment device object... [08/29/17 08:28:49.077][DEBUG] [Main Thread] No change in focuser object... [08/29/17 08:28:49.077][DEBUG] [Main Thread] MoveFocuserAbs: Error! The focuser is no longer connected! [08/29/17 08:28:49.077][DEBUG] [Main Thread] No change in telescope object... [08/29/17 08:28:49.077][DEBUG] [Main Thread] New rotator object (No Rotator) dispatched... [08/29/17 08:28:49.078][DEBUG] [Main Thread] No change in dome object... [08/29/17 08:28:49.079][DEBUG] [Main Thread] New flat box object (No Flat Box) dispatched... [08/29/17 08:28:49.079][DEBUG] [Main Thread] No change in safety monitor object... [08/29/17 08:28:49.080][DEBUG] [Main Thread] New auto guider object (PHD2) dispatched... [08/29/17 08:28:49.082][DEBUG] [Main Thread] New plate solver object (No Plate Solver) dispatched... [08/29/17 08:28:49.167][DEBUG] [Main Thread] Populating the form controls... [08/29/17 08:28:49.209][DEBUG] [Main Thread] Added row 0... [08/29/17 08:28:49.244][DEBUG] [Main Thread] Added row 1... [08/29/17 08:28:49.287][DEBUG] [Main Thread] Added row 2... [08/29/17 08:28:49.336][DEBUG] [Main Thread] Added row 3... [08/29/17 08:28:49.393][DEBUG] [Main Thread] Added row 4... [08/29/17 08:30:12.914][DEBUG] [Main Thread] PopulateDataModel: Transferring view to the data model... [08/29/17 08:30:12.932][DEBUG] [MF Update Thread] Performing serialize... [08/29/17 20:21:55.833][DEBUG] [Main Thread] PopulateDataModel: Transferring view to the data model... [08/29/17 20:21:55.854][DEBUG] [MF Update Thread] Performing serialize... [08/29/17 20:42:24.762][DEBUG] [Main Thread] Connecting camera in camera thread... [08/29/17 20:42:24.766][DEBUG] [Camera Thread] SGM_CAMERA_CONNECT message received... [08/29/17 20:42:24.768][DEBUG] [Camera Thread] Connecting QSI camera... [08/29/17 20:42:24.930][DEBUG] [Camera Thread] QSI Camera: version check... [08/29/17 20:42:24.933][DEBUG] [Camera Thread] QSI Driver Version: 7.4.1824.0(QSI - Camera not connected) [08/29/17 20:42:26.007][DEBUG] [Camera Thread] Connected to 683ws [08/29/17 20:42:26.007][DEBUG] [Camera Thread] SGM_CAMERA_CONNECT complete... [08/29/17 20:42:26.133][DEBUG] [Main Thread] Camera cooler detected... [08/29/17 20:42:26.182][DEBUG] [Main Thread] Camera cooler is OFF... [08/29/17 20:42:26.183][DEBUG] [Main Thread] Camera cooldown on connect is selected... [08/29/17 20:42:26.183][DEBUG] [Main Thread] Camera cooldown is starting... [08/29/17 20:42:26.194][DEBUG] [TEC Thread] SGM_CHANGE_COOLER_TEMP message received... [08/29/17 20:42:26.199][DEBUG] [TEC Thread] TEC Change: Starting... [08/29/17 20:42:27.203][DEBUG] [Main Thread] Connecting ASCOM focuser... [08/29/17 20:42:27.481][DEBUG] [Main Thread] Focuser supports temperature [08/29/17 20:42:27.481][DEBUG] [Main Thread] Current temperature reports: 100 [08/29/17 20:42:27.482][DEBUG] [Main Thread] Focuser reports Max Increment as: 10000 [08/29/17 20:42:27.482][DEBUG] [Main Thread] Focuser reports max steps as: 10000 [08/29/17 20:42:27.699][DEBUG] [Telescope Thread] Telescope Dispatch loop: Received SGM_TELESCOPE_CONNECT... [08/29/17 20:42:34.230][DEBUG] [Telescope Thread] Telescope can slewing supported... [08/29/17 20:42:34.232][DEBUG] [Telescope Thread] Telescope: Does not implement MoveAxis... [08/29/17 20:42:34.259][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() [08/29/17 20:42:34.260][DEBUG] [Telescope Thread] Telescope: CanSetSideOfPier returned False [08/29/17 20:42:34.261][DEBUG] [Telescope Thread] Telescope: CanPark returned True [08/29/17 20:42:34.262][DEBUG] [Telescope Thread] Telescope equatorial system is JNOW... [08/29/17 20:42:34.263][DEBUG] [Telescope Thread] SGM_TELESCOPE_CONNECT complete... [08/29/17 20:42:34.566][DEBUG] [Main Thread] Telescope: Setting sync behavior to: Sync [08/29/17 20:42:34.579][DEBUG] [Main Thread] Connecting ASCOM dome... [08/29/17 20:42:34.632][DEBUG] [Main Thread] Dome: CanSetAzimuth is false [08/29/17 20:42:34.668][DEBUG] [Main Thread] Connecting ASCOM Safety Monitor... [08/29/17 20:42:35.052][DEBUG] [Main Thread] ASCOM EnvironmentDevice: Connected to ASCOM Boltwood Observing Conditions [08/29/17 20:42:35.055][DEBUG] [Main Thread] ASCOM EnvironmentDevice: Device Name - Boltwood ObservingConditions [08/29/17 20:42:35.058][DEBUG] [Main Thread] ASCOM EnvironmentDevice: Description - ASCOM Boltwood Observing Conditions [08/29/17 20:42:35.063][DEBUG] [Main Thread] ASCOM EnvironmentDevice: Driver Info Boltwood obseving conditions driver. Version: 6.2.5823.16903 [08/29/17 20:42:35.064][DEBUG] [Main Thread] ASCOM EnvironmentDevice: Driver Version 6.2 [08/29/17 20:42:35.082][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() [08/29/17 20:42:35.090][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() [08/29/17 20:42:35.093][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() [08/29/17 20:42:35.096][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() [08/29/17 20:42:35.106][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() [08/29/17 20:42:35.110][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() [08/29/17 20:42:35.139][DEBUG] [TEC Thread] TEC Change: After camera connect change, waiting ~5 seconds... [08/29/17 20:42:36.153][DEBUG] [Auto Guider Equipment Connection Thread] Connecting to PHD2... [08/29/17 20:42:36.154][DEBUG] [Auto Guider Equipment Connection Thread] Waiting for socket connection to PHD2... [08/29/17 20:42:36.157][DEBUG] [PHD2 Listener Thread] Attempting to connect to PHD2... [08/29/17 20:42:37.154][DEBUG] [Auto Guider Equipment Connection Thread] Successfully connected to PHD2... [08/29/17 20:42:37.156][DEBUG] [Auto Guider Equipment Connection Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [08/29/17 20:42:37.156][DEBUG] [Auto Guider Equipment Connection Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [08/29/17 20:42:37.256][DEBUG] [Auto Guider Equipment Connection Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [08/29/17 20:42:37.260][DEBUG] [Auto Guider Equipment Connection Thread] PHD2: PHD2 is already open... [08/29/17 20:42:37.260][DEBUG] [Auto Guider Equipment Connection Thread] Sending to PHD2: {"method": "get_profiles", "id": 1009} [08/29/17 20:42:37.761][DEBUG] [Auto Guider Equipment Connection Thread] Sending to PHD2: {"method":"set_connected","params":[false],"id":1007} [08/29/17 20:42:37.761][DEBUG] [Auto Guider Equipment Connection Thread] Sending to PHD2: {"method":"set_profile","params":[2],"id":1008} [08/29/17 20:42:37.761][DEBUG] [Auto Guider Equipment Connection Thread] Sending to PHD2: {"method":"set_connected","params":[true],"id":1007} [08/29/17 20:42:40.146][DEBUG] [TEC Thread] TEC Change: Attempting to turn the camera cooler on... [08/29/17 20:42:40.150][DEBUG] [Main Thread] Turning camera cooler on... [08/29/17 20:42:40.317][DEBUG] [Main Thread] Turning camera cooler on... [08/29/17 20:42:45.239][DEBUG] [TEC Thread] TEC Change: Changing temp from 19.00 to -10.00 in 360 seconds... [08/29/17 20:42:50.660][DEBUG] [Filter Wheel Thread] SGM_FW_SET_POSITION message received... [08/29/17 20:42:52.197][DEBUG] [Filter Wheel Thread] SGM_FW_SET_POSITION complete... [08/29/17 20:42:55.211][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS message received... [08/29/17 20:42:55.235][DEBUG] [Camera Thread] ASCOM (QSI) camera: frame and focus... [08/29/17 20:42:55.271][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 20:42:55.271][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 20:43:06.713][DEBUG] [Camera Thread] QSI expose and download: 11.3783566s [08/29/17 20:43:06.772][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 20:43:06.773][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 20:43:07.111][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS complete... [08/29/17 20:43:24.432][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS message received... [08/29/17 20:43:24.456][DEBUG] [Camera Thread] ASCOM (QSI) camera: frame and focus... [08/29/17 20:43:24.479][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 20:43:24.479][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 20:43:34.825][DEBUG] [Camera Thread] QSI expose and download: 10.2820604s [08/29/17 20:43:34.903][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 20:43:34.903][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 20:43:35.215][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS complete... [08/29/17 20:43:38.671][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [08/29/17 20:43:38.671][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [08/29/17 20:43:38.672][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [08/29/17 20:43:38.672][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [08/29/17 20:43:38.672][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [08/29/17 20:44:13.987][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS message received... [08/29/17 20:44:14.001][DEBUG] [Camera Thread] ASCOM (QSI) camera: frame and focus... [08/29/17 20:44:14.037][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 20:44:14.037][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 20:44:24.418][DEBUG] [Camera Thread] QSI expose and download: 10.3177739s [08/29/17 20:44:24.498][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 20:44:24.498][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 20:44:24.861][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS complete... [08/29/17 20:44:36.856][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS message received... [08/29/17 20:44:36.864][DEBUG] [Camera Thread] ASCOM (QSI) camera: frame and focus... [08/29/17 20:44:36.890][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 20:44:36.890][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 20:44:38.791][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [08/29/17 20:44:38.791][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [08/29/17 20:44:38.791][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [08/29/17 20:44:38.791][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [08/29/17 20:44:38.791][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [08/29/17 20:44:47.309][DEBUG] [Camera Thread] QSI expose and download: 10.3538032s [08/29/17 20:44:47.379][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 20:44:47.379][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 20:44:47.743][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS complete... [08/29/17 20:45:06.144][DEBUG] [Camera Thread] SGM_FOCUSER_AUTO_FOCUS message received... [08/29/17 20:45:06.152][DEBUG] [Camera Thread] Checking for auto focus... [08/29/17 20:45:06.152][DEBUG] [Camera Thread] Auto focus required (frame count trigger)... [08/29/17 20:45:06.160][DEBUG] [Camera Thread] Auto focus: setting filter Blue [08/29/17 20:45:06.162][DEBUG] [Camera Thread] Setting filter position 4... [08/29/17 20:45:06.162][DEBUG] [Camera Thread] Filter position 4 is already set. Skipping... [08/29/17 20:45:06.165][DEBUG] [Camera Thread] Auto focus running... [08/29/17 20:45:06.170][DEBUG] [Camera Thread] Turning temp comp off.. [08/29/17 20:45:06.190][DEBUG] [Camera Thread] Performing Sequence Generator auto focus using Half Flux Radius. AFID: 0 [08/29/17 20:45:06.316][DEBUG] [AfChartThread] Showing AF chart dialog... [08/29/17 20:45:06.411][DEBUG] [Camera Thread] Auto focus data [08/29/17 20:45:06.411][DEBUG] [Camera Thread] - Data Points: 7 [08/29/17 20:45:06.411][DEBUG] [Camera Thread] - Step Size: 10 [08/29/17 20:45:06.411][DEBUG] [Camera Thread] - Current Position: 7922 [08/29/17 20:45:06.411][DEBUG] [Camera Thread] - Initial Move Position: 7952 [08/29/17 20:45:06.456][DEBUG] [Camera Thread] AF Darks exception: The path is not of a legal form. [08/29/17 20:45:06.458][DEBUG] [Camera Thread] Moving focuser to next position (7952)... [08/29/17 20:45:06.462][DEBUG] [Focuser Move Thread] Focuser moving to 7952 [08/29/17 20:45:06.519][DEBUG] [Focuser Move Thread] Focuser move call complete [08/29/17 20:45:07.574][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7952 matches requested position 7952 [08/29/17 20:45:07.574][DEBUG] [Camera Thread] Focuser move complete... [08/29/17 20:45:07.631][DEBUG] [Camera Thread] Focuser position matches requested position (7952), continuing... [08/29/17 20:45:07.686][DEBUG] [Camera Thread] Calculating step metric... [08/29/17 20:45:07.689][DEBUG] [Camera Thread] Taking auto focus frame(s)... [08/29/17 20:45:07.695][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [08/29/17 20:45:07.695][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [08/29/17 20:45:07.695][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [08/29/17 20:45:07.695][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [08/29/17 20:45:07.729][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 20:45:07.729][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 20:45:25.073][DEBUG] [Camera Thread] QSI expose and download: 17.2797084s [08/29/17 20:45:25.156][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 20:45:25.156][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 20:45:25.428][DEBUG] [Main Thread] --> Find stars (normal) [08/29/17 20:45:25.576][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [08/29/17 20:45:27.797][DEBUG] [Main Thread] Star detection using min star size of 4px... [08/29/17 20:45:27.797][DEBUG] [Main Thread] Star detection using max star size of 80px... [08/29/17 20:45:27.868][DEBUG] [Main Thread] Find stars took: 2439 ms... [08/29/17 20:45:27.870][DEBUG] [Main Thread] Star list contains 17 stars... [08/29/17 20:45:27.870][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [08/29/17 20:45:28.207][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.75 [08/29/17 20:45:29.270][DEBUG] [Camera Thread] Moving focuser to next position (7942)... [08/29/17 20:45:29.270][DEBUG] [Focuser Move Thread] Focuser moving to 7942 [08/29/17 20:45:29.325][DEBUG] [Focuser Move Thread] Focuser move call complete [08/29/17 20:45:30.380][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7942 matches requested position 7942 [08/29/17 20:45:30.380][DEBUG] [Camera Thread] Focuser move complete... [08/29/17 20:45:30.436][DEBUG] [Camera Thread] Focuser position matches requested position (7942), continuing... [08/29/17 20:45:30.491][DEBUG] [Camera Thread] Calculating step metric... [08/29/17 20:45:30.491][DEBUG] [Camera Thread] Taking auto focus frame(s)... [08/29/17 20:45:30.493][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [08/29/17 20:45:30.493][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [08/29/17 20:45:30.493][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [08/29/17 20:45:30.493][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [08/29/17 20:45:30.530][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 20:45:30.530][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 20:45:38.911][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [08/29/17 20:45:38.911][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [08/29/17 20:45:38.911][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [08/29/17 20:45:38.911][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [08/29/17 20:45:38.911][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [08/29/17 20:45:47.089][DEBUG] [Camera Thread] QSI expose and download: 16.4943262s [08/29/17 20:45:47.201][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 20:45:47.201][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 20:45:47.460][DEBUG] [Main Thread] --> Find stars (normal) [08/29/17 20:45:47.538][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [08/29/17 20:45:49.990][DEBUG] [Main Thread] Star detection using min star size of 4px... [08/29/17 20:45:49.990][DEBUG] [Main Thread] Star detection using max star size of 80px... [08/29/17 20:45:50.044][DEBUG] [Main Thread] Find stars took: 2583 ms... [08/29/17 20:45:50.044][DEBUG] [Main Thread] Star list contains 16 stars... [08/29/17 20:45:50.044][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [08/29/17 20:45:50.426][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.98 [08/29/17 20:45:51.443][DEBUG] [Camera Thread] Moving focuser to next position (7932)... [08/29/17 20:45:51.444][DEBUG] [Focuser Move Thread] Focuser moving to 7932 [08/29/17 20:45:51.501][DEBUG] [Focuser Move Thread] Focuser move call complete [08/29/17 20:45:52.556][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7932 matches requested position 7932 [08/29/17 20:45:52.556][DEBUG] [Camera Thread] Focuser move complete... [08/29/17 20:45:52.611][DEBUG] [Camera Thread] Focuser position matches requested position (7932), continuing... [08/29/17 20:45:52.718][DEBUG] [Camera Thread] Calculating step metric... [08/29/17 20:45:52.718][DEBUG] [Camera Thread] Taking auto focus frame(s)... [08/29/17 20:45:52.720][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [08/29/17 20:45:52.720][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [08/29/17 20:45:52.720][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [08/29/17 20:45:52.720][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [08/29/17 20:45:52.741][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 20:45:52.741][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 20:46:09.336][DEBUG] [Camera Thread] QSI expose and download: 16.5317186s [08/29/17 20:46:09.423][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 20:46:09.423][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 20:46:09.718][DEBUG] [Main Thread] --> Find stars (normal) [08/29/17 20:46:09.786][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [08/29/17 20:46:12.029][DEBUG] [Main Thread] Star detection using min star size of 4px... [08/29/17 20:46:12.029][DEBUG] [Main Thread] Star detection using max star size of 80px... [08/29/17 20:46:12.088][DEBUG] [Main Thread] Find stars took: 2369 ms... [08/29/17 20:46:12.088][DEBUG] [Main Thread] Star list contains 19 stars... [08/29/17 20:46:12.088][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [08/29/17 20:46:12.410][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.52 [08/29/17 20:46:13.417][DEBUG] [Camera Thread] Moving focuser to next position (7922)... [08/29/17 20:46:13.418][DEBUG] [Focuser Move Thread] Focuser moving to 7922 [08/29/17 20:46:13.473][DEBUG] [Focuser Move Thread] Focuser move call complete [08/29/17 20:46:14.528][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7922 matches requested position 7922 [08/29/17 20:46:14.528][DEBUG] [Camera Thread] Focuser move complete... [08/29/17 20:46:14.583][DEBUG] [Camera Thread] Focuser position matches requested position (7922), continuing... [08/29/17 20:46:14.638][DEBUG] [Camera Thread] Calculating step metric... [08/29/17 20:46:14.638][DEBUG] [Camera Thread] Taking auto focus frame(s)... [08/29/17 20:46:14.642][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [08/29/17 20:46:14.642][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [08/29/17 20:46:14.642][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [08/29/17 20:46:14.642][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [08/29/17 20:46:14.676][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 20:46:14.676][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 20:46:31.239][DEBUG] [Camera Thread] QSI expose and download: 16.4993145s [08/29/17 20:46:31.328][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 20:46:31.328][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 20:46:31.608][DEBUG] [Main Thread] --> Find stars (normal) [08/29/17 20:46:31.662][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [08/29/17 20:46:33.959][DEBUG] [Main Thread] Star detection using min star size of 4px... [08/29/17 20:46:33.959][DEBUG] [Main Thread] Star detection using max star size of 80px... [08/29/17 20:46:34.008][DEBUG] [Main Thread] Find stars took: 2399 ms... [08/29/17 20:46:34.008][DEBUG] [Main Thread] Star list contains 24 stars... [08/29/17 20:46:34.008][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [08/29/17 20:46:34.321][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.19 [08/29/17 20:46:35.344][DEBUG] [Camera Thread] Moving focuser to next position (7912)... [08/29/17 20:46:35.345][DEBUG] [Focuser Move Thread] Focuser moving to 7912 [08/29/17 20:46:35.402][DEBUG] [Focuser Move Thread] Focuser move call complete [08/29/17 20:46:36.456][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7912 matches requested position 7912 [08/29/17 20:46:36.456][DEBUG] [Camera Thread] Focuser move complete... [08/29/17 20:46:36.511][DEBUG] [Camera Thread] Focuser position matches requested position (7912), continuing... [08/29/17 20:46:36.568][DEBUG] [Camera Thread] Calculating step metric... [08/29/17 20:46:36.568][DEBUG] [Camera Thread] Taking auto focus frame(s)... [08/29/17 20:46:36.571][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [08/29/17 20:46:36.571][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [08/29/17 20:46:36.571][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [08/29/17 20:46:36.571][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [08/29/17 20:46:36.596][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 20:46:36.596][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 20:46:39.041][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [08/29/17 20:46:39.041][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [08/29/17 20:46:39.041][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [08/29/17 20:46:39.041][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [08/29/17 20:46:39.041][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [08/29/17 20:46:53.145][DEBUG] [Camera Thread] QSI expose and download: 16.4847357s [08/29/17 20:46:53.229][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 20:46:53.229][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 20:46:53.502][DEBUG] [Main Thread] --> Find stars (normal) [08/29/17 20:46:53.570][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [08/29/17 20:46:55.898][DEBUG] [Main Thread] Star detection using min star size of 4px... [08/29/17 20:46:55.898][DEBUG] [Main Thread] Star detection using max star size of 80px... [08/29/17 20:46:55.945][DEBUG] [Main Thread] Find stars took: 2441 ms... [08/29/17 20:46:55.945][DEBUG] [Main Thread] Star list contains 22 stars... [08/29/17 20:46:55.945][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [08/29/17 20:46:56.263][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.49 [08/29/17 20:46:57.272][DEBUG] [Camera Thread] Moving focuser to next position (7902)... [08/29/17 20:46:57.273][DEBUG] [Focuser Move Thread] Focuser moving to 7902 [08/29/17 20:46:57.329][DEBUG] [Focuser Move Thread] Focuser move call complete [08/29/17 20:46:58.383][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7902 matches requested position 7902 [08/29/17 20:46:58.383][DEBUG] [Camera Thread] Focuser move complete... [08/29/17 20:46:58.437][DEBUG] [Camera Thread] Focuser position matches requested position (7902), continuing... [08/29/17 20:46:58.493][DEBUG] [Camera Thread] Calculating step metric... [08/29/17 20:46:58.493][DEBUG] [Camera Thread] Taking auto focus frame(s)... [08/29/17 20:46:58.496][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [08/29/17 20:46:58.496][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [08/29/17 20:46:58.496][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [08/29/17 20:46:58.496][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [08/29/17 20:46:58.531][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 20:46:58.531][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 20:47:15.083][DEBUG] [Camera Thread] QSI expose and download: 16.4886237s [08/29/17 20:47:15.177][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 20:47:15.177][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 20:47:15.466][DEBUG] [Main Thread] --> Find stars (normal) [08/29/17 20:47:15.532][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [08/29/17 20:47:17.860][DEBUG] [Main Thread] Star detection using min star size of 4px... [08/29/17 20:47:17.860][DEBUG] [Main Thread] Star detection using max star size of 80px... [08/29/17 20:47:17.917][DEBUG] [Main Thread] Find stars took: 2451 ms... [08/29/17 20:47:17.917][DEBUG] [Main Thread] Star list contains 25 stars... [08/29/17 20:47:17.917][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [08/29/17 20:47:18.262][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.21 [08/29/17 20:47:19.270][DEBUG] [Camera Thread] Moving focuser to next position (7892)... [08/29/17 20:47:19.271][DEBUG] [Focuser Move Thread] Focuser moving to 7892 [08/29/17 20:47:19.329][DEBUG] [Focuser Move Thread] Focuser move call complete [08/29/17 20:47:20.383][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7892 matches requested position 7892 [08/29/17 20:47:20.383][DEBUG] [Camera Thread] Focuser move complete... [08/29/17 20:47:20.438][DEBUG] [Camera Thread] Focuser position matches requested position (7892), continuing... [08/29/17 20:47:20.494][DEBUG] [Camera Thread] Calculating step metric... [08/29/17 20:47:20.494][DEBUG] [Camera Thread] Taking auto focus frame(s)... [08/29/17 20:47:20.497][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [08/29/17 20:47:20.502][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [08/29/17 20:47:20.502][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [08/29/17 20:47:20.502][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [08/29/17 20:47:20.530][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 20:47:20.530][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 20:47:37.124][DEBUG] [Camera Thread] QSI expose and download: 16.530075s [08/29/17 20:47:37.224][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 20:47:37.224][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 20:47:37.497][DEBUG] [Main Thread] --> Find stars (normal) [08/29/17 20:47:37.570][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [08/29/17 20:47:39.164][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [08/29/17 20:47:39.164][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [08/29/17 20:47:39.164][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [08/29/17 20:47:39.164][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [08/29/17 20:47:39.164][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [08/29/17 20:47:39.797][DEBUG] [Main Thread] Star detection using min star size of 4px... [08/29/17 20:47:39.797][DEBUG] [Main Thread] Star detection using max star size of 80px... [08/29/17 20:47:39.850][DEBUG] [Main Thread] Find stars took: 2352 ms... [08/29/17 20:47:39.850][DEBUG] [Main Thread] Star list contains 20 stars... [08/29/17 20:47:39.850][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [08/29/17 20:47:40.174][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.52 [08/29/17 20:47:41.183][DEBUG] [Camera Thread] Moving focuser to next position (7882)... [08/29/17 20:47:41.183][DEBUG] [Focuser Move Thread] Focuser moving to 7882 [08/29/17 20:47:41.240][DEBUG] [Focuser Move Thread] Focuser move call complete [08/29/17 20:47:42.294][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7882 matches requested position 7882 [08/29/17 20:47:42.294][DEBUG] [Camera Thread] Focuser move complete... [08/29/17 20:47:42.402][DEBUG] [Camera Thread] Focuser position matches requested position (7882), continuing... [08/29/17 20:47:42.458][DEBUG] [Camera Thread] Calculating step metric... [08/29/17 20:47:42.459][DEBUG] [Camera Thread] Taking auto focus frame(s)... [08/29/17 20:47:42.461][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [08/29/17 20:47:42.461][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [08/29/17 20:47:42.461][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [08/29/17 20:47:42.461][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [08/29/17 20:47:42.499][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 20:47:42.499][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 20:47:59.085][DEBUG] [Camera Thread] QSI expose and download: 16.4900268s [08/29/17 20:47:59.172][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 20:47:59.172][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 20:47:59.446][DEBUG] [Main Thread] --> Find stars (normal) [08/29/17 20:47:59.504][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [08/29/17 20:48:01.654][DEBUG] [Main Thread] Star detection using min star size of 4px... [08/29/17 20:48:01.654][DEBUG] [Main Thread] Star detection using max star size of 80px... [08/29/17 20:48:01.729][DEBUG] [Main Thread] Find stars took: 2282 ms... [08/29/17 20:48:01.729][DEBUG] [Main Thread] Star list contains 16 stars... [08/29/17 20:48:01.729][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [08/29/17 20:48:02.050][DEBUG] [Camera Thread] Auto focus HFR calculated at: 4.23 [08/29/17 20:48:03.061][DEBUG] [Camera Thread] AF has detected >= 3 left slope and >= 3 right slope but an asymmetrical fit (Ratio: 47.6). Something isn't right here! Defaulting to lowest weighted HFR method [08/29/17 20:48:03.067][DEBUG] [Camera Thread] New auto focus method calculated focus at: 7911 [08/29/17 20:48:03.067][DEBUG] [Camera Thread] Old auto focus method calculated focus at: 7912 [08/29/17 20:48:03.067][DEBUG] [Camera Thread] Using old auto focus method for focuser position [08/29/17 20:48:03.067][DEBUG] [Camera Thread] New focus position is at 7912 (@100.00C). Moving focuser... [08/29/17 20:48:03.068][DEBUG] [Focuser Move Thread] Focuser moving to 7912 [08/29/17 20:48:03.124][DEBUG] [Focuser Move Thread] Focuser move call complete [08/29/17 20:48:04.179][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7912 matches requested position 7912 [08/29/17 20:48:04.179][DEBUG] [Camera Thread] Focuser move complete... [08/29/17 20:48:04.287][DEBUG] [Camera Thread] Focuser successfully set to focus position (7912) [08/29/17 20:48:04.347][DEBUG] [Camera Thread] Taking validation frame at focus position... [08/29/17 20:48:04.349][DEBUG] [Camera Thread] Taking auto focus frame(s)... [08/29/17 20:48:04.352][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [08/29/17 20:48:04.352][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [08/29/17 20:48:04.353][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [08/29/17 20:48:04.353][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [08/29/17 20:48:04.452][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 20:48:04.452][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 20:48:21.020][DEBUG] [Camera Thread] QSI expose and download: 16.5032357s [08/29/17 20:48:21.102][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 20:48:21.102][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 20:48:21.346][DEBUG] [Main Thread] --> Find stars (normal) [08/29/17 20:48:21.398][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [08/29/17 20:48:23.283][DEBUG] [Main Thread] Star detection using min star size of 4px... [08/29/17 20:48:23.283][DEBUG] [Main Thread] Star detection using max star size of 80px... [08/29/17 20:48:23.330][DEBUG] [Main Thread] Find stars took: 1984 ms... [08/29/17 20:48:23.330][DEBUG] [Main Thread] Star list contains 32 stars... [08/29/17 20:48:23.330][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [08/29/17 20:48:23.621][DEBUG] [Camera Thread] Auto focus HFR calculated at: 2.85 [08/29/17 20:48:24.630][DEBUG] [Camera Thread] Auto focus is complete (weighted avg method: 7912; HFR 2.85)... [08/29/17 20:48:24.635][DEBUG] [Camera Thread] Entering chart disposal loop... [08/29/17 20:48:39.302][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [08/29/17 20:48:39.302][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [08/29/17 20:48:39.302][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [08/29/17 20:48:39.302][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [08/29/17 20:48:39.302][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [08/29/17 20:48:45.243][DEBUG] [TEC Thread] TEC Change: Complete... [08/29/17 20:48:45.286][DEBUG] [TEC Thread] SGM_CHANGE_COOLER_TEMP complete... [08/29/17 20:48:56.138][DEBUG] [Camera Thread] Chart disposal loop is complete... [08/29/17 20:48:56.138][DEBUG] [Camera Thread] Auto focus is done... [08/29/17 20:48:56.151][DEBUG] [AfChartThread] AF chart dialog is closed... [08/29/17 20:48:56.199][DEBUG] [Camera Thread] Auto focus complete... [08/29/17 20:48:56.223][DEBUG] [Camera Thread] SGM_FOCUSER_AUTO_FOCUS complete... [08/29/17 20:49:39.408][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [08/29/17 20:49:39.408][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [08/29/17 20:49:39.408][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [08/29/17 20:49:39.408][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [08/29/17 20:49:39.408][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [08/29/17 20:50:35.936][DEBUG] [Camera Thread] SGM_FOCUSER_AUTO_FOCUS message received... [08/29/17 20:50:35.936][DEBUG] [Camera Thread] Checking for auto focus... [08/29/17 20:50:35.939][DEBUG] [Camera Thread] Auto focus: setting filter Blue [08/29/17 20:50:35.939][DEBUG] [Camera Thread] Setting filter position 4... [08/29/17 20:50:35.939][DEBUG] [Camera Thread] Filter position 4 is already set. Skipping... [08/29/17 20:50:35.941][DEBUG] [Camera Thread] Auto focus running... [08/29/17 20:50:35.944][DEBUG] [Camera Thread] Turning temp comp off.. [08/29/17 20:50:35.946][DEBUG] [Camera Thread] Performing Sequence Generator auto focus using Half Flux Radius. AFID: 1 [08/29/17 20:50:36.009][DEBUG] [AfChartThread] Showing AF chart dialog... [08/29/17 20:50:36.116][DEBUG] [Camera Thread] Auto focus data [08/29/17 20:50:36.116][DEBUG] [Camera Thread] - Data Points: 7 [08/29/17 20:50:36.116][DEBUG] [Camera Thread] - Step Size: 10 [08/29/17 20:50:36.116][DEBUG] [Camera Thread] - Current Position: 7912 [08/29/17 20:50:36.116][DEBUG] [Camera Thread] - Initial Move Position: 7942 [08/29/17 20:50:36.120][DEBUG] [Camera Thread] AF Darks exception: The path is not of a legal form. [08/29/17 20:50:36.122][DEBUG] [Camera Thread] Moving focuser to next position (7942)... [08/29/17 20:50:36.123][DEBUG] [Focuser Move Thread] Focuser moving to 7942 [08/29/17 20:50:36.179][DEBUG] [Focuser Move Thread] Focuser move call complete [08/29/17 20:50:36.951][DEBUG] [Main Thread] PopulateDataModel: Transferring view to the data model... [08/29/17 20:50:36.957][DEBUG] [MF Update Thread] Performing serialize... [08/29/17 20:50:37.233][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7942 matches requested position 7942 [08/29/17 20:50:37.233][DEBUG] [Camera Thread] Focuser move complete... [08/29/17 20:50:37.289][DEBUG] [Camera Thread] Focuser position matches requested position (7942), continuing... [08/29/17 20:50:37.345][DEBUG] [Camera Thread] Calculating step metric... [08/29/17 20:50:37.345][DEBUG] [Camera Thread] Taking auto focus frame(s)... [08/29/17 20:50:37.348][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [08/29/17 20:50:37.348][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [08/29/17 20:50:37.348][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [08/29/17 20:50:37.348][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [08/29/17 20:50:37.372][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 20:50:37.372][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 20:50:39.511][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [08/29/17 20:50:39.511][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [08/29/17 20:50:39.511][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [08/29/17 20:50:39.511][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [08/29/17 20:50:39.511][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [08/29/17 20:50:53.928][DEBUG] [Camera Thread] QSI expose and download: 16.4931765s [08/29/17 20:50:54.003][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 20:50:54.003][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 20:50:54.147][DEBUG] [Main Thread] --> Find stars (normal) [08/29/17 20:50:54.234][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [08/29/17 20:50:56.474][DEBUG] [Main Thread] Star detection using min star size of 4px... [08/29/17 20:50:56.474][DEBUG] [Main Thread] Star detection using max star size of 80px... [08/29/17 20:50:56.521][DEBUG] [Main Thread] Find stars took: 2374 ms... [08/29/17 20:50:56.521][DEBUG] [Main Thread] Star list contains 24 stars... [08/29/17 20:50:56.521][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [08/29/17 20:50:56.677][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.24 [08/29/17 20:50:57.685][DEBUG] [Camera Thread] Moving focuser to next position (7932)... [08/29/17 20:50:57.686][DEBUG] [Focuser Move Thread] Focuser moving to 7932 [08/29/17 20:50:57.743][DEBUG] [Focuser Move Thread] Focuser move call complete [08/29/17 20:50:58.798][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7932 matches requested position 7932 [08/29/17 20:50:58.798][DEBUG] [Camera Thread] Focuser move complete... [08/29/17 20:50:58.853][DEBUG] [Camera Thread] Focuser position matches requested position (7932), continuing... [08/29/17 20:50:58.908][DEBUG] [Camera Thread] Calculating step metric... [08/29/17 20:50:58.908][DEBUG] [Camera Thread] Taking auto focus frame(s)... [08/29/17 20:50:58.911][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [08/29/17 20:50:58.911][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [08/29/17 20:50:58.911][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [08/29/17 20:50:58.911][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [08/29/17 20:50:58.941][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 20:50:58.941][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 20:51:15.503][DEBUG] [Camera Thread] QSI expose and download: 16.4982641s [08/29/17 20:51:15.600][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 20:51:15.600][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 20:51:15.784][DEBUG] [Main Thread] --> Find stars (normal) [08/29/17 20:51:15.853][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [08/29/17 20:51:18.214][DEBUG] [Main Thread] Star detection using min star size of 4px... [08/29/17 20:51:18.214][DEBUG] [Main Thread] Star detection using max star size of 80px... [08/29/17 20:51:18.274][DEBUG] [Main Thread] Find stars took: 2490 ms... [08/29/17 20:51:18.274][DEBUG] [Main Thread] Star list contains 19 stars... [08/29/17 20:51:18.274][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [08/29/17 20:51:18.476][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.66 [08/29/17 20:51:19.481][DEBUG] [Camera Thread] Moving focuser to next position (7922)... [08/29/17 20:51:19.481][DEBUG] [Focuser Move Thread] Focuser moving to 7922 [08/29/17 20:51:19.537][DEBUG] [Focuser Move Thread] Focuser move call complete [08/29/17 20:51:20.591][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7922 matches requested position 7922 [08/29/17 20:51:20.591][DEBUG] [Camera Thread] Focuser move complete... [08/29/17 20:51:20.646][DEBUG] [Camera Thread] Focuser position matches requested position (7922), continuing... [08/29/17 20:51:20.754][DEBUG] [Camera Thread] Calculating step metric... [08/29/17 20:51:20.754][DEBUG] [Camera Thread] Taking auto focus frame(s)... [08/29/17 20:51:20.755][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [08/29/17 20:51:20.755][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [08/29/17 20:51:20.755][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [08/29/17 20:51:20.755][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [08/29/17 20:51:20.781][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 20:51:20.781][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 20:51:37.391][DEBUG] [Camera Thread] QSI expose and download: 16.5145273s [08/29/17 20:51:37.484][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 20:51:37.484][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 20:51:37.635][DEBUG] [Main Thread] --> Find stars (normal) [08/29/17 20:51:37.720][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [08/29/17 20:51:39.661][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [08/29/17 20:51:39.661][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [08/29/17 20:51:39.661][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [08/29/17 20:51:39.661][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [08/29/17 20:51:39.661][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [08/29/17 20:51:39.894][DEBUG] [Main Thread] Star detection using min star size of 4px... [08/29/17 20:51:39.894][DEBUG] [Main Thread] Star detection using max star size of 80px... [08/29/17 20:51:39.940][DEBUG] [Main Thread] Find stars took: 2304 ms... [08/29/17 20:51:39.941][DEBUG] [Main Thread] Star list contains 24 stars... [08/29/17 20:51:39.941][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [08/29/17 20:51:40.103][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.11 [08/29/17 20:51:41.113][DEBUG] [Camera Thread] Moving focuser to next position (7912)... [08/29/17 20:51:41.113][DEBUG] [Focuser Move Thread] Focuser moving to 7912 [08/29/17 20:51:41.170][DEBUG] [Focuser Move Thread] Focuser move call complete [08/29/17 20:51:42.224][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7912 matches requested position 7912 [08/29/17 20:51:42.225][DEBUG] [Camera Thread] Focuser move complete... [08/29/17 20:51:42.279][DEBUG] [Camera Thread] Focuser position matches requested position (7912), continuing... [08/29/17 20:51:42.390][DEBUG] [Camera Thread] Calculating step metric... [08/29/17 20:51:42.390][DEBUG] [Camera Thread] Taking auto focus frame(s)... [08/29/17 20:51:42.393][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [08/29/17 20:51:42.393][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [08/29/17 20:51:42.393][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [08/29/17 20:51:42.393][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [08/29/17 20:51:42.414][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 20:51:42.414][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 20:51:59.002][DEBUG] [Camera Thread] QSI expose and download: 16.4912913s [08/29/17 20:51:59.078][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 20:51:59.078][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 20:51:59.234][DEBUG] [Main Thread] --> Find stars (normal) [08/29/17 20:51:59.305][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [08/29/17 20:52:01.549][DEBUG] [Main Thread] Star detection using min star size of 4px... [08/29/17 20:52:01.549][DEBUG] [Main Thread] Star detection using max star size of 80px... [08/29/17 20:52:01.599][DEBUG] [Main Thread] Find stars took: 2364 ms... [08/29/17 20:52:01.599][DEBUG] [Main Thread] Star list contains 22 stars... [08/29/17 20:52:01.599][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [08/29/17 20:52:01.768][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.54 [08/29/17 20:52:02.778][DEBUG] [Camera Thread] Moving focuser to next position (7902)... [08/29/17 20:52:02.779][DEBUG] [Focuser Move Thread] Focuser moving to 7902 [08/29/17 20:52:02.834][DEBUG] [Focuser Move Thread] Focuser move call complete [08/29/17 20:52:03.943][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7902 matches requested position 7902 [08/29/17 20:52:03.943][DEBUG] [Camera Thread] Focuser move complete... [08/29/17 20:52:03.999][DEBUG] [Camera Thread] Focuser position matches requested position (7902), continuing... [08/29/17 20:52:04.054][DEBUG] [Camera Thread] Calculating step metric... [08/29/17 20:52:04.054][DEBUG] [Camera Thread] Taking auto focus frame(s)... [08/29/17 20:52:04.057][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [08/29/17 20:52:04.057][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [08/29/17 20:52:04.057][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [08/29/17 20:52:04.057][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [08/29/17 20:52:04.078][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 20:52:04.078][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 20:52:20.649][DEBUG] [Camera Thread] QSI expose and download: 16.50823s [08/29/17 20:52:20.730][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 20:52:20.730][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 20:52:20.885][DEBUG] [Main Thread] --> Find stars (normal) [08/29/17 20:52:20.959][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [08/29/17 20:52:23.118][DEBUG] [Main Thread] Star detection using min star size of 4px... [08/29/17 20:52:23.118][DEBUG] [Main Thread] Star detection using max star size of 80px... [08/29/17 20:52:23.173][DEBUG] [Main Thread] Find stars took: 2287 ms... [08/29/17 20:52:23.173][DEBUG] [Main Thread] Star list contains 29 stars... [08/29/17 20:52:23.173][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [08/29/17 20:52:23.329][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.02 [08/29/17 20:52:24.344][DEBUG] [Camera Thread] Moving focuser to next position (7892)... [08/29/17 20:52:24.344][DEBUG] [Focuser Move Thread] Focuser moving to 7892 [08/29/17 20:52:24.399][DEBUG] [Focuser Move Thread] Focuser move call complete [08/29/17 20:52:25.455][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7892 matches requested position 7892 [08/29/17 20:52:25.455][DEBUG] [Camera Thread] Focuser move complete... [08/29/17 20:52:25.562][DEBUG] [Camera Thread] Focuser position matches requested position (7892), continuing... [08/29/17 20:52:25.619][DEBUG] [Camera Thread] Calculating step metric... [08/29/17 20:52:25.619][DEBUG] [Camera Thread] Taking auto focus frame(s)... [08/29/17 20:52:25.622][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [08/29/17 20:52:25.622][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [08/29/17 20:52:25.622][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [08/29/17 20:52:25.622][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [08/29/17 20:52:25.646][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 20:52:25.646][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 20:52:39.775][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [08/29/17 20:52:39.775][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [08/29/17 20:52:39.775][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [08/29/17 20:52:39.775][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [08/29/17 20:52:39.775][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [08/29/17 20:52:42.268][DEBUG] [Camera Thread] QSI expose and download: 16.5256938s [08/29/17 20:52:42.322][DEBUG] [Camera Thread] HFR value from auto focuser is 0!, trying again... [08/29/17 20:52:42.322][DEBUG] [Camera Thread] AF abort requested [08/29/17 20:52:42.327][DEBUG] [Focuser Thread] SGM_FOCUSER_MOVE_ABS message received... [08/29/17 20:52:42.327][DEBUG] [Focuser Thread] Focuser moving to 7912 [08/29/17 20:52:42.328][DEBUG] [Camera Thread] Auto focus (finally): closing dialog... [08/29/17 20:52:42.353][DEBUG] [AfChartThread] AF chart dialog is closed... [08/29/17 20:52:42.406][DEBUG] [Camera Thread] Auto focus complete... [08/29/17 20:52:42.469][DEBUG] [Focuser Thread] Focuser move call complete [08/29/17 20:52:42.474][DEBUG] [Camera Thread] SGM_FOCUSER_AUTO_FOCUS complete... [08/29/17 20:52:43.712][DEBUG] [Focuser Thread] SGM_FOCUSER_MOVE_ABS complete... [08/29/17 20:53:16.261][DEBUG] [Camera Thread] SGM_FOCUSER_AUTO_FOCUS message received... [08/29/17 20:53:16.261][DEBUG] [Camera Thread] Checking for auto focus... [08/29/17 20:53:16.272][DEBUG] [Camera Thread] Auto focus: setting filter Blue [08/29/17 20:53:16.272][DEBUG] [Camera Thread] Setting filter position 4... [08/29/17 20:53:16.272][DEBUG] [Camera Thread] Filter position 4 is already set. Skipping... [08/29/17 20:53:16.276][DEBUG] [Camera Thread] Auto focus running... [08/29/17 20:53:16.285][DEBUG] [Camera Thread] Turning temp comp off.. [08/29/17 20:53:16.291][DEBUG] [Camera Thread] Performing Sequence Generator auto focus using Half Flux Radius. AFID: 2 [08/29/17 20:53:16.359][DEBUG] [AfChartThread] Showing AF chart dialog... [08/29/17 20:53:16.449][DEBUG] [Camera Thread] Auto focus data [08/29/17 20:53:16.450][DEBUG] [Camera Thread] - Data Points: 7 [08/29/17 20:53:16.450][DEBUG] [Camera Thread] - Step Size: 10 [08/29/17 20:53:16.450][DEBUG] [Camera Thread] - Current Position: 7912 [08/29/17 20:53:16.450][DEBUG] [Camera Thread] - Initial Move Position: 7942 [08/29/17 20:53:16.453][DEBUG] [Camera Thread] AF Darks exception: The path is not of a legal form. [08/29/17 20:53:16.455][DEBUG] [Camera Thread] Moving focuser to next position (7942)... [08/29/17 20:53:16.455][DEBUG] [Focuser Move Thread] Focuser moving to 7942 [08/29/17 20:53:16.511][DEBUG] [Focuser Move Thread] Focuser move call complete [08/29/17 20:53:17.605][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7942 matches requested position 7942 [08/29/17 20:53:17.605][DEBUG] [Camera Thread] Focuser move complete... [08/29/17 20:53:17.660][DEBUG] [Camera Thread] Focuser position matches requested position (7942), continuing... [08/29/17 20:53:17.718][DEBUG] [Camera Thread] Calculating step metric... [08/29/17 20:53:17.718][DEBUG] [Camera Thread] Taking auto focus frame(s)... [08/29/17 20:53:17.722][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [08/29/17 20:53:17.722][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [08/29/17 20:53:17.722][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 2 seconds... [08/29/17 20:53:17.722][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [08/29/17 20:53:17.757][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 20:53:17.757][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 20:53:32.680][DEBUG] [Main Thread] PopulateDataModel: Transferring view to the data model... [08/29/17 20:53:32.686][DEBUG] [MF Update Thread] Performing serialize... [08/29/17 20:53:35.341][DEBUG] [Camera Thread] QSI expose and download: 17.5199233s [08/29/17 20:53:35.437][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 20:53:35.437][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 20:53:35.592][DEBUG] [Main Thread] --> Find stars (normal) [08/29/17 20:53:35.666][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [08/29/17 20:53:37.885][DEBUG] [Main Thread] Star detection using min star size of 4px... [08/29/17 20:53:37.885][DEBUG] [Main Thread] Star detection using max star size of 80px... [08/29/17 20:53:37.935][DEBUG] [Main Thread] Find stars took: 2343 ms... [08/29/17 20:53:37.935][DEBUG] [Main Thread] Star list contains 30 stars... [08/29/17 20:53:37.935][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [08/29/17 20:53:38.099][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.72 [08/29/17 20:53:39.109][DEBUG] [Camera Thread] Moving focuser to next position (7932)... [08/29/17 20:53:39.109][DEBUG] [Focuser Move Thread] Focuser moving to 7932 [08/29/17 20:53:39.167][DEBUG] [Focuser Move Thread] Focuser move call complete [08/29/17 20:53:39.925][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [08/29/17 20:53:39.925][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [08/29/17 20:53:39.925][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [08/29/17 20:53:39.925][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [08/29/17 20:53:39.925][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [08/29/17 20:53:40.221][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7932 matches requested position 7932 [08/29/17 20:53:40.222][DEBUG] [Camera Thread] Focuser move complete... [08/29/17 20:53:40.277][DEBUG] [Camera Thread] Focuser position matches requested position (7932), continuing... [08/29/17 20:53:40.387][DEBUG] [Camera Thread] Calculating step metric... [08/29/17 20:53:40.387][DEBUG] [Camera Thread] Taking auto focus frame(s)... [08/29/17 20:53:40.390][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [08/29/17 20:53:40.390][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [08/29/17 20:53:40.390][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 2 seconds... [08/29/17 20:53:40.390][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [08/29/17 20:53:40.413][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 20:53:40.413][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 20:53:58.025][DEBUG] [Camera Thread] QSI expose and download: 17.5161527s [08/29/17 20:53:58.194][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 20:53:58.194][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 20:53:58.352][DEBUG] [Main Thread] --> Find stars (normal) [08/29/17 20:53:58.437][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [08/29/17 20:54:00.949][DEBUG] [Main Thread] Star detection using min star size of 4px... [08/29/17 20:54:00.949][DEBUG] [Main Thread] Star detection using max star size of 80px... [08/29/17 20:54:01.008][DEBUG] [Main Thread] Find stars took: 2655 ms... [08/29/17 20:54:01.008][DEBUG] [Main Thread] Star list contains 48 stars... [08/29/17 20:54:01.008][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [08/29/17 20:54:01.193][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.12 [08/29/17 20:54:02.201][DEBUG] [Camera Thread] Moving focuser to next position (7922)... [08/29/17 20:54:02.202][DEBUG] [Focuser Move Thread] Focuser moving to 7922 [08/29/17 20:54:02.258][DEBUG] [Focuser Move Thread] Focuser move call complete [08/29/17 20:54:03.313][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7922 matches requested position 7922 [08/29/17 20:54:03.313][DEBUG] [Camera Thread] Focuser move complete... [08/29/17 20:54:03.422][DEBUG] [Camera Thread] Focuser position matches requested position (7922), continuing... [08/29/17 20:54:03.478][DEBUG] [Camera Thread] Calculating step metric... [08/29/17 20:54:03.478][DEBUG] [Camera Thread] Taking auto focus frame(s)... [08/29/17 20:54:03.481][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [08/29/17 20:54:03.481][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [08/29/17 20:54:03.481][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 2 seconds... [08/29/17 20:54:03.481][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [08/29/17 20:54:03.517][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 20:54:03.517][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 20:54:21.103][DEBUG] [Camera Thread] QSI expose and download: 17.5216537s [08/29/17 20:54:21.198][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 20:54:21.198][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 20:54:21.326][DEBUG] [Main Thread] --> Find stars (normal) [08/29/17 20:54:21.408][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [08/29/17 20:54:23.573][DEBUG] [Main Thread] Star detection using min star size of 4px... [08/29/17 20:54:23.573][DEBUG] [Main Thread] Star detection using max star size of 80px... [08/29/17 20:54:23.623][DEBUG] [Main Thread] Find stars took: 2296 ms... [08/29/17 20:54:23.623][DEBUG] [Main Thread] Star list contains 26 stars... [08/29/17 20:54:23.623][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [08/29/17 20:54:23.793][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.96 [08/29/17 20:54:24.804][DEBUG] [Camera Thread] Moving focuser to next position (7912)... [08/29/17 20:54:24.804][DEBUG] [Focuser Move Thread] Focuser moving to 7912 [08/29/17 20:54:24.860][DEBUG] [Focuser Move Thread] Focuser move call complete [08/29/17 20:54:25.914][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7912 matches requested position 7912 [08/29/17 20:54:25.914][DEBUG] [Camera Thread] Focuser move complete... [08/29/17 20:54:25.968][DEBUG] [Camera Thread] Focuser position matches requested position (7912), continuing... [08/29/17 20:54:26.079][DEBUG] [Camera Thread] Calculating step metric... [08/29/17 20:54:26.079][DEBUG] [Camera Thread] Taking auto focus frame(s)... [08/29/17 20:54:26.081][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [08/29/17 20:54:26.082][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [08/29/17 20:54:26.082][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 2 seconds... [08/29/17 20:54:26.082][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [08/29/17 20:54:26.110][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 20:54:26.110][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 20:54:40.074][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [08/29/17 20:54:40.074][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [08/29/17 20:54:40.074][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [08/29/17 20:54:40.074][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [08/29/17 20:54:40.074][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [08/29/17 20:54:43.727][DEBUG] [Camera Thread] QSI expose and download: 17.5210549s [08/29/17 20:54:43.886][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 20:54:43.886][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 20:54:44.036][DEBUG] [Main Thread] --> Find stars (normal) [08/29/17 20:54:44.115][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [08/29/17 20:54:46.233][DEBUG] [Main Thread] Star detection using min star size of 4px... [08/29/17 20:54:46.233][DEBUG] [Main Thread] Star detection using max star size of 80px... [08/29/17 20:54:46.280][DEBUG] [Main Thread] Find stars took: 2243 ms... [08/29/17 20:54:46.280][DEBUG] [Main Thread] Star list contains 27 stars... [08/29/17 20:54:46.280][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [08/29/17 20:54:46.444][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.95 [08/29/17 20:54:47.455][DEBUG] [Camera Thread] Moving focuser to next position (7902)... [08/29/17 20:54:47.456][DEBUG] [Focuser Move Thread] Focuser moving to 7902 [08/29/17 20:54:47.514][DEBUG] [Focuser Move Thread] Focuser move call complete [08/29/17 20:54:48.568][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7902 matches requested position 7902 [08/29/17 20:54:48.568][DEBUG] [Camera Thread] Focuser move complete... [08/29/17 20:54:48.676][DEBUG] [Camera Thread] Focuser position matches requested position (7902), continuing... [08/29/17 20:54:48.732][DEBUG] [Camera Thread] Calculating step metric... [08/29/17 20:54:48.733][DEBUG] [Camera Thread] Taking auto focus frame(s)... [08/29/17 20:54:48.736][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [08/29/17 20:54:48.736][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [08/29/17 20:54:48.736][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 2 seconds... [08/29/17 20:54:48.736][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [08/29/17 20:54:48.766][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 20:54:48.766][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 20:55:06.365][DEBUG] [Camera Thread] QSI expose and download: 17.535278s [08/29/17 20:55:06.487][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 20:55:06.487][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 20:55:06.697][DEBUG] [Main Thread] --> Find stars (normal) [08/29/17 20:55:06.784][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [08/29/17 20:55:09.006][DEBUG] [Main Thread] Star detection using min star size of 4px... [08/29/17 20:55:09.006][DEBUG] [Main Thread] Star detection using max star size of 80px... [08/29/17 20:55:09.058][DEBUG] [Main Thread] Find stars took: 2361 ms... [08/29/17 20:55:09.058][DEBUG] [Main Thread] Star list contains 44 stars... [08/29/17 20:55:09.058][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [08/29/17 20:55:09.236][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.14 [08/29/17 20:55:10.245][DEBUG] [Camera Thread] Moving focuser to next position (7892)... [08/29/17 20:55:10.245][DEBUG] [Focuser Move Thread] Focuser moving to 7892 [08/29/17 20:55:10.302][DEBUG] [Focuser Move Thread] Focuser move call complete [08/29/17 20:55:11.357][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7892 matches requested position 7892 [08/29/17 20:55:11.357][DEBUG] [Camera Thread] Focuser move complete... [08/29/17 20:55:11.465][DEBUG] [Camera Thread] Focuser position matches requested position (7892), continuing... [08/29/17 20:55:11.520][DEBUG] [Camera Thread] Calculating step metric... [08/29/17 20:55:11.520][DEBUG] [Camera Thread] Taking auto focus frame(s)... [08/29/17 20:55:11.524][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [08/29/17 20:55:11.524][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [08/29/17 20:55:11.525][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 2 seconds... [08/29/17 20:55:11.525][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [08/29/17 20:55:11.551][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 20:55:11.551][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 20:55:29.131][DEBUG] [Camera Thread] QSI expose and download: 17.5160742s [08/29/17 20:55:29.319][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 20:55:29.319][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 20:55:29.480][DEBUG] [Main Thread] --> Find stars (normal) [08/29/17 20:55:29.547][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [08/29/17 20:55:32.085][DEBUG] [Main Thread] Star detection using min star size of 4px... [08/29/17 20:55:32.085][DEBUG] [Main Thread] Star detection using max star size of 80px... [08/29/17 20:55:32.138][DEBUG] [Main Thread] Find stars took: 2657 ms... [08/29/17 20:55:32.138][DEBUG] [Main Thread] Star list contains 45 stars... [08/29/17 20:55:32.138][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [08/29/17 20:55:32.329][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.19 [08/29/17 20:55:33.336][DEBUG] [Camera Thread] Detected focus getting worse! Shifting auto focus range [08/29/17 20:55:33.337][DEBUG] [Focuser Move Thread] Focuser moving to 7957 [08/29/17 20:55:33.393][DEBUG] [Focuser Move Thread] Focuser move call complete [08/29/17 20:55:34.448][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7957 matches requested position 7957 [08/29/17 20:55:34.448][DEBUG] [Camera Thread] Focuser move complete... [08/29/17 20:55:34.614][DEBUG] [Camera Thread] Auto focus data [08/29/17 20:55:34.614][DEBUG] [Camera Thread] - Data Points: 7 [08/29/17 20:55:34.614][DEBUG] [Camera Thread] - Step Size: 10 [08/29/17 20:55:34.614][DEBUG] [Camera Thread] - Current Position: 7957 [08/29/17 20:55:34.614][DEBUG] [Camera Thread] - Initial Move Position: 7987 [08/29/17 20:55:34.618][DEBUG] [Camera Thread] AF Darks exception: The path is not of a legal form. [08/29/17 20:55:34.620][DEBUG] [Camera Thread] Moving focuser to next position (7987)... [08/29/17 20:55:34.621][DEBUG] [Focuser Move Thread] Focuser moving to 7987 [08/29/17 20:55:34.677][DEBUG] [Focuser Move Thread] Focuser move call complete [08/29/17 20:55:35.731][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7987 matches requested position 7987 [08/29/17 20:55:35.731][DEBUG] [Camera Thread] Focuser move complete... [08/29/17 20:55:35.786][DEBUG] [Camera Thread] Focuser position matches requested position (7987), continuing... [08/29/17 20:55:35.840][DEBUG] [Camera Thread] Calculating step metric... [08/29/17 20:55:35.840][DEBUG] [Camera Thread] Taking auto focus frame(s)... [08/29/17 20:55:35.841][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [08/29/17 20:55:35.841][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [08/29/17 20:55:35.841][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 2 seconds... [08/29/17 20:55:35.841][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [08/29/17 20:55:35.871][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 20:55:35.871][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 20:55:40.210][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [08/29/17 20:55:40.210][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [08/29/17 20:55:40.210][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [08/29/17 20:55:40.210][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [08/29/17 20:55:40.210][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [08/29/17 20:55:53.458][DEBUG] [Camera Thread] QSI expose and download: 17.5221386s [08/29/17 20:55:53.547][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 20:55:53.547][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 20:55:53.735][DEBUG] [Main Thread] --> Find stars (normal) [08/29/17 20:55:53.813][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [08/29/17 20:55:56.009][DEBUG] [Main Thread] Star detection using min star size of 4px... [08/29/17 20:55:56.009][DEBUG] [Main Thread] Star detection using max star size of 80px... [08/29/17 20:55:56.058][DEBUG] [Main Thread] Find stars took: 2323 ms... [08/29/17 20:55:56.058][DEBUG] [Main Thread] Star list contains 11 stars... [08/29/17 20:55:56.058][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [08/29/17 20:55:56.217][DEBUG] [Camera Thread] Auto focus HFR calculated at: 6.49 [08/29/17 20:55:57.225][DEBUG] [Camera Thread] Moving focuser to next position (7977)... [08/29/17 20:55:57.226][DEBUG] [Focuser Move Thread] Focuser moving to 7977 [08/29/17 20:55:57.282][DEBUG] [Focuser Move Thread] Focuser move call complete [08/29/17 20:55:58.337][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7977 matches requested position 7977 [08/29/17 20:55:58.337][DEBUG] [Camera Thread] Focuser move complete... [08/29/17 20:55:58.392][DEBUG] [Camera Thread] Focuser position matches requested position (7977), continuing... [08/29/17 20:55:58.447][DEBUG] [Camera Thread] Calculating step metric... [08/29/17 20:55:58.447][DEBUG] [Camera Thread] Taking auto focus frame(s)... [08/29/17 20:55:58.451][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [08/29/17 20:55:58.451][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [08/29/17 20:55:58.451][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 2 seconds... [08/29/17 20:55:58.451][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [08/29/17 20:55:58.480][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 20:55:58.480][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 20:56:16.053][DEBUG] [Camera Thread] QSI expose and download: 17.5087118s [08/29/17 20:56:16.142][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 20:56:16.142][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 20:56:16.293][DEBUG] [Main Thread] --> Find stars (normal) [08/29/17 20:56:16.367][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [08/29/17 20:56:18.554][DEBUG] [Main Thread] Star detection using min star size of 4px... [08/29/17 20:56:18.554][DEBUG] [Main Thread] Star detection using max star size of 80px... [08/29/17 20:56:18.607][DEBUG] [Main Thread] Find stars took: 2313 ms... [08/29/17 20:56:18.607][DEBUG] [Main Thread] Star list contains 14 stars... [08/29/17 20:56:18.607][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [08/29/17 20:56:18.765][DEBUG] [Camera Thread] Auto focus HFR calculated at: 5.77 [08/29/17 20:56:19.773][DEBUG] [Camera Thread] Moving focuser to next position (7967)... [08/29/17 20:56:19.774][DEBUG] [Focuser Move Thread] Focuser moving to 7967 [08/29/17 20:56:19.830][DEBUG] [Focuser Move Thread] Focuser move call complete [08/29/17 20:56:20.885][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7967 matches requested position 7967 [08/29/17 20:56:20.885][DEBUG] [Camera Thread] Focuser move complete... [08/29/17 20:56:20.940][DEBUG] [Camera Thread] Focuser position matches requested position (7967), continuing... [08/29/17 20:56:21.050][DEBUG] [Camera Thread] Calculating step metric... [08/29/17 20:56:21.050][DEBUG] [Camera Thread] Taking auto focus frame(s)... [08/29/17 20:56:21.052][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [08/29/17 20:56:21.053][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [08/29/17 20:56:21.053][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 2 seconds... [08/29/17 20:56:21.053][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [08/29/17 20:56:21.088][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 20:56:21.088][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 20:56:38.713][DEBUG] [Camera Thread] QSI expose and download: 17.5299749s [08/29/17 20:56:38.841][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 20:56:38.841][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 20:56:38.982][DEBUG] [Main Thread] --> Find stars (normal) [08/29/17 20:56:39.063][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [08/29/17 20:56:40.361][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [08/29/17 20:56:40.361][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [08/29/17 20:56:40.361][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [08/29/17 20:56:40.361][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [08/29/17 20:56:40.361][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [08/29/17 20:56:41.222][DEBUG] [Main Thread] Star detection using min star size of 4px... [08/29/17 20:56:41.222][DEBUG] [Main Thread] Star detection using max star size of 80px... [08/29/17 20:56:41.272][DEBUG] [Main Thread] Find stars took: 2290 ms... [08/29/17 20:56:41.272][DEBUG] [Main Thread] Star list contains 16 stars... [08/29/17 20:56:41.272][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [08/29/17 20:56:41.414][DEBUG] [Camera Thread] Auto focus HFR calculated at: 5.20 [08/29/17 20:56:42.424][DEBUG] [Camera Thread] Moving focuser to next position (7957)... [08/29/17 20:56:42.425][DEBUG] [Focuser Move Thread] Focuser moving to 7957 [08/29/17 20:56:42.481][DEBUG] [Focuser Move Thread] Focuser move call complete [08/29/17 20:56:43.537][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7957 matches requested position 7957 [08/29/17 20:56:43.537][DEBUG] [Camera Thread] Focuser move complete... [08/29/17 20:56:43.645][DEBUG] [Camera Thread] Focuser position matches requested position (7957), continuing... [08/29/17 20:56:43.754][DEBUG] [Camera Thread] Calculating step metric... [08/29/17 20:56:43.755][DEBUG] [Camera Thread] Taking auto focus frame(s)... [08/29/17 20:56:43.758][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [08/29/17 20:56:43.758][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [08/29/17 20:56:43.758][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 2 seconds... [08/29/17 20:56:43.758][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [08/29/17 20:56:43.793][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 20:56:43.793][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 20:57:01.387][DEBUG] [Camera Thread] QSI expose and download: 17.5301255s [08/29/17 20:57:01.482][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 20:57:01.482][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 20:57:01.623][DEBUG] [Main Thread] --> Find stars (normal) [08/29/17 20:57:01.705][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [08/29/17 20:57:04.203][DEBUG] [Main Thread] Star detection using min star size of 4px... [08/29/17 20:57:04.203][DEBUG] [Main Thread] Star detection using max star size of 80px... [08/29/17 20:57:04.287][DEBUG] [Main Thread] Find stars took: 2663 ms... [08/29/17 20:57:04.287][DEBUG] [Main Thread] Star list contains 24 stars... [08/29/17 20:57:04.287][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [08/29/17 20:57:04.482][DEBUG] [Camera Thread] Auto focus HFR calculated at: 4.31 [08/29/17 20:57:05.498][DEBUG] [Camera Thread] Moving focuser to next position (7947)... [08/29/17 20:57:05.498][DEBUG] [Focuser Move Thread] Focuser moving to 7947 [08/29/17 20:57:05.555][DEBUG] [Focuser Move Thread] Focuser move call complete [08/29/17 20:57:06.609][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7947 matches requested position 7947 [08/29/17 20:57:06.609][DEBUG] [Camera Thread] Focuser move complete... [08/29/17 20:57:06.718][DEBUG] [Camera Thread] Focuser position matches requested position (7947), continuing... [08/29/17 20:57:06.826][DEBUG] [Camera Thread] Calculating step metric... [08/29/17 20:57:06.827][DEBUG] [Camera Thread] Taking auto focus frame(s)... [08/29/17 20:57:06.830][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [08/29/17 20:57:06.830][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [08/29/17 20:57:06.830][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 2 seconds... [08/29/17 20:57:06.830][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [08/29/17 20:57:06.866][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 20:57:06.866][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 20:57:24.461][DEBUG] [Camera Thread] QSI expose and download: 17.5316823s [08/29/17 20:57:24.539][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 20:57:24.539][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 20:57:24.677][DEBUG] [Main Thread] --> Find stars (normal) [08/29/17 20:57:24.750][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [08/29/17 20:57:26.891][DEBUG] [Main Thread] Star detection using min star size of 4px... [08/29/17 20:57:26.891][DEBUG] [Main Thread] Star detection using max star size of 80px... [08/29/17 20:57:26.943][DEBUG] [Main Thread] Find stars took: 2266 ms... [08/29/17 20:57:26.943][DEBUG] [Main Thread] Star list contains 31 stars... [08/29/17 20:57:26.943][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [08/29/17 20:57:27.115][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.64 [08/29/17 20:57:28.123][DEBUG] [Camera Thread] Moving focuser to next position (7937)... [08/29/17 20:57:28.123][DEBUG] [Focuser Move Thread] Focuser moving to 7937 [08/29/17 20:57:28.180][DEBUG] [Focuser Move Thread] Focuser move call complete [08/29/17 20:57:29.235][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7937 matches requested position 7937 [08/29/17 20:57:29.235][DEBUG] [Camera Thread] Focuser move complete... [08/29/17 20:57:29.289][DEBUG] [Camera Thread] Focuser position matches requested position (7937), continuing... [08/29/17 20:57:29.404][DEBUG] [Camera Thread] Calculating step metric... [08/29/17 20:57:29.405][DEBUG] [Camera Thread] Taking auto focus frame(s)... [08/29/17 20:57:29.407][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [08/29/17 20:57:29.407][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [08/29/17 20:57:29.407][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 2 seconds... [08/29/17 20:57:29.407][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [08/29/17 20:57:29.441][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 20:57:29.441][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 20:57:40.486][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [08/29/17 20:57:40.486][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [08/29/17 20:57:40.486][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [08/29/17 20:57:40.486][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [08/29/17 20:57:40.486][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [08/29/17 20:57:47.066][DEBUG] [Camera Thread] QSI expose and download: 17.528753s [08/29/17 20:57:47.219][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 20:57:47.219][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 20:57:47.370][DEBUG] [Main Thread] --> Find stars (normal) [08/29/17 20:57:47.463][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [08/29/17 20:57:50.020][DEBUG] [Main Thread] Star detection using min star size of 4px... [08/29/17 20:57:50.020][DEBUG] [Main Thread] Star detection using max star size of 80px... [08/29/17 20:57:50.077][DEBUG] [Main Thread] Find stars took: 2705 ms... [08/29/17 20:57:50.077][DEBUG] [Main Thread] Star list contains 39 stars... [08/29/17 20:57:50.077][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [08/29/17 20:57:50.252][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.34 [08/29/17 20:57:51.262][DEBUG] [Camera Thread] Moving focuser to next position (7927)... [08/29/17 20:57:51.262][DEBUG] [Focuser Move Thread] Focuser moving to 7927 [08/29/17 20:57:51.321][DEBUG] [Focuser Move Thread] Focuser move call complete [08/29/17 20:57:52.376][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7927 matches requested position 7927 [08/29/17 20:57:52.376][DEBUG] [Camera Thread] Focuser move complete... [08/29/17 20:57:52.484][DEBUG] [Camera Thread] Focuser position matches requested position (7927), continuing... [08/29/17 20:57:52.540][DEBUG] [Camera Thread] Calculating step metric... [08/29/17 20:57:52.540][DEBUG] [Camera Thread] Taking auto focus frame(s)... [08/29/17 20:57:52.541][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [08/29/17 20:57:52.541][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [08/29/17 20:57:52.541][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 2 seconds... [08/29/17 20:57:52.541][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [08/29/17 20:57:52.561][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 20:57:52.561][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 20:58:10.182][DEBUG] [Camera Thread] QSI expose and download: 17.5249357s [08/29/17 20:58:10.274][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 20:58:10.274][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 20:58:10.424][DEBUG] [Main Thread] --> Find stars (normal) [08/29/17 20:58:10.510][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [08/29/17 20:58:12.703][DEBUG] [Main Thread] Star detection using min star size of 4px... [08/29/17 20:58:12.703][DEBUG] [Main Thread] Star detection using max star size of 80px... [08/29/17 20:58:12.761][DEBUG] [Main Thread] Find stars took: 2336 ms... [08/29/17 20:58:12.761][DEBUG] [Main Thread] Star list contains 49 stars... [08/29/17 20:58:12.761][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [08/29/17 20:58:12.969][DEBUG] [Camera Thread] Auto focus HFR calculated at: 2.93 [08/29/17 20:58:13.978][DEBUG] [Camera Thread] Moving focuser to next position (7917)... [08/29/17 20:58:13.979][DEBUG] [Focuser Move Thread] Focuser moving to 7917 [08/29/17 20:58:14.035][DEBUG] [Focuser Move Thread] Focuser move call complete [08/29/17 20:58:15.089][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7917 matches requested position 7917 [08/29/17 20:58:15.089][DEBUG] [Camera Thread] Focuser move complete... [08/29/17 20:58:15.146][DEBUG] [Camera Thread] Focuser position matches requested position (7917), continuing... [08/29/17 20:58:15.201][DEBUG] [Camera Thread] Calculating step metric... [08/29/17 20:58:15.201][DEBUG] [Camera Thread] Taking auto focus frame(s)... [08/29/17 20:58:15.204][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [08/29/17 20:58:15.204][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [08/29/17 20:58:15.204][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 2 seconds... [08/29/17 20:58:15.205][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [08/29/17 20:58:15.233][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 20:58:15.233][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 20:58:32.823][DEBUG] [Camera Thread] QSI expose and download: 17.5253133s [08/29/17 20:58:32.951][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 20:58:32.951][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 20:58:33.093][DEBUG] [Main Thread] --> Find stars (normal) [08/29/17 20:58:33.150][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [08/29/17 20:58:35.327][DEBUG] [Main Thread] Star detection using min star size of 4px... [08/29/17 20:58:35.327][DEBUG] [Main Thread] Star detection using max star size of 80px... [08/29/17 20:58:35.383][DEBUG] [Main Thread] Find stars took: 2289 ms... [08/29/17 20:58:35.383][DEBUG] [Main Thread] Star list contains 53 stars... [08/29/17 20:58:35.383][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [08/29/17 20:58:35.560][DEBUG] [Camera Thread] Auto focus HFR calculated at: 2.90 [08/29/17 20:58:36.568][DEBUG] [Camera Thread] Moving focuser to next position (7907)... [08/29/17 20:58:36.570][DEBUG] [Focuser Move Thread] Focuser moving to 7907 [08/29/17 20:58:36.627][DEBUG] [Focuser Move Thread] Focuser move call complete [08/29/17 20:58:37.682][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7907 matches requested position 7907 [08/29/17 20:58:37.682][DEBUG] [Camera Thread] Focuser move complete... [08/29/17 20:58:37.737][DEBUG] [Camera Thread] Focuser position matches requested position (7907), continuing... [08/29/17 20:58:37.792][DEBUG] [Camera Thread] Calculating step metric... [08/29/17 20:58:37.792][DEBUG] [Camera Thread] Taking auto focus frame(s)... [08/29/17 20:58:37.794][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [08/29/17 20:58:37.794][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [08/29/17 20:58:37.794][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 2 seconds... [08/29/17 20:58:37.794][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [08/29/17 20:58:37.827][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 20:58:37.827][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 20:58:40.604][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [08/29/17 20:58:40.604][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [08/29/17 20:58:40.604][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [08/29/17 20:58:40.604][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [08/29/17 20:58:40.604][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [08/29/17 20:58:55.416][DEBUG] [Camera Thread] QSI expose and download: 17.525863s [08/29/17 20:58:55.572][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 20:58:55.572][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 20:58:55.742][DEBUG] [Main Thread] --> Find stars (normal) [08/29/17 20:58:55.836][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [08/29/17 20:58:58.411][DEBUG] [Main Thread] Star detection using min star size of 4px... [08/29/17 20:58:58.411][DEBUG] [Main Thread] Star detection using max star size of 80px... [08/29/17 20:58:58.473][DEBUG] [Main Thread] Find stars took: 2730 ms... [08/29/17 20:58:58.473][DEBUG] [Main Thread] Star list contains 50 stars... [08/29/17 20:58:58.473][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [08/29/17 20:58:58.647][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.00 [08/29/17 20:58:59.657][DEBUG] [Camera Thread] Moving focuser to next position (7897)... [08/29/17 20:58:59.657][DEBUG] [Focuser Move Thread] Focuser moving to 7897 [08/29/17 20:58:59.712][DEBUG] [Focuser Move Thread] Focuser move call complete [08/29/17 20:59:00.767][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7897 matches requested position 7897 [08/29/17 20:59:00.767][DEBUG] [Camera Thread] Focuser move complete... [08/29/17 20:59:00.876][DEBUG] [Camera Thread] Focuser position matches requested position (7897), continuing... [08/29/17 20:59:00.931][DEBUG] [Camera Thread] Calculating step metric... [08/29/17 20:59:00.931][DEBUG] [Camera Thread] Taking auto focus frame(s)... [08/29/17 20:59:00.934][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [08/29/17 20:59:00.934][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [08/29/17 20:59:00.934][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 2 seconds... [08/29/17 20:59:00.934][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [08/29/17 20:59:00.963][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 20:59:00.963][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 20:59:18.588][DEBUG] [Camera Thread] QSI expose and download: 17.528432s [08/29/17 20:59:18.673][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 20:59:18.673][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 20:59:18.806][DEBUG] [Main Thread] --> Find stars (normal) [08/29/17 20:59:18.861][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [08/29/17 20:59:21.097][DEBUG] [Main Thread] Star detection using min star size of 4px... [08/29/17 20:59:21.097][DEBUG] [Main Thread] Star detection using max star size of 80px... [08/29/17 20:59:21.168][DEBUG] [Main Thread] Find stars took: 2362 ms... [08/29/17 20:59:21.168][DEBUG] [Main Thread] Star list contains 39 stars... [08/29/17 20:59:21.168][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [08/29/17 20:59:21.338][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.44 [08/29/17 20:59:22.350][DEBUG] [Camera Thread] Moving focuser to next position (7887)... [08/29/17 20:59:22.350][DEBUG] [Focuser Move Thread] Focuser moving to 7887 [08/29/17 20:59:22.408][DEBUG] [Focuser Move Thread] Focuser move call complete [08/29/17 20:59:23.462][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7887 matches requested position 7887 [08/29/17 20:59:23.462][DEBUG] [Camera Thread] Focuser move complete... [08/29/17 20:59:23.517][DEBUG] [Camera Thread] Focuser position matches requested position (7887), continuing... [08/29/17 20:59:23.627][DEBUG] [Camera Thread] Calculating step metric... [08/29/17 20:59:23.627][DEBUG] [Camera Thread] Taking auto focus frame(s)... [08/29/17 20:59:23.629][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [08/29/17 20:59:23.629][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [08/29/17 20:59:23.629][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 2 seconds... [08/29/17 20:59:23.629][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [08/29/17 20:59:23.652][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 20:59:23.652][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 20:59:40.745][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [08/29/17 20:59:40.745][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [08/29/17 20:59:40.745][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [08/29/17 20:59:40.745][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [08/29/17 20:59:40.745][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [08/29/17 20:59:41.282][DEBUG] [Camera Thread] QSI expose and download: 17.5337269s [08/29/17 20:59:41.423][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 20:59:41.423][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 20:59:41.607][DEBUG] [Main Thread] --> Find stars (normal) [08/29/17 20:59:41.674][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [08/29/17 20:59:44.184][DEBUG] [Main Thread] Star detection using min star size of 4px... [08/29/17 20:59:44.184][DEBUG] [Main Thread] Star detection using max star size of 80px... [08/29/17 20:59:44.243][DEBUG] [Main Thread] Find stars took: 2636 ms... [08/29/17 20:59:44.244][DEBUG] [Main Thread] Star list contains 33 stars... [08/29/17 20:59:44.244][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [08/29/17 20:59:44.442][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.70 [08/29/17 20:59:45.450][DEBUG] [Camera Thread] Moving focuser to next position (7877)... [08/29/17 20:59:45.450][DEBUG] [Focuser Move Thread] Focuser moving to 7877 [08/29/17 20:59:45.506][DEBUG] [Focuser Move Thread] Focuser move call complete [08/29/17 20:59:46.561][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7877 matches requested position 7877 [08/29/17 20:59:46.561][DEBUG] [Camera Thread] Focuser move complete... [08/29/17 20:59:46.616][DEBUG] [Camera Thread] Focuser position matches requested position (7877), continuing... [08/29/17 20:59:46.670][DEBUG] [Camera Thread] Calculating step metric... [08/29/17 20:59:46.670][DEBUG] [Camera Thread] Taking auto focus frame(s)... [08/29/17 20:59:46.727][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [08/29/17 20:59:46.727][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [08/29/17 20:59:46.727][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 2 seconds... [08/29/17 20:59:46.727][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [08/29/17 20:59:46.756][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 20:59:46.756][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 21:00:04.346][DEBUG] [Camera Thread] QSI expose and download: 17.5255636s [08/29/17 21:00:04.435][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 21:00:04.435][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 21:00:04.570][DEBUG] [Main Thread] --> Find stars (normal) [08/29/17 21:00:04.644][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [08/29/17 21:00:06.816][DEBUG] [Main Thread] Star detection using min star size of 4px... [08/29/17 21:00:06.816][DEBUG] [Main Thread] Star detection using max star size of 80px... [08/29/17 21:00:06.866][DEBUG] [Main Thread] Find stars took: 2295 ms... [08/29/17 21:00:06.866][DEBUG] [Main Thread] Star list contains 25 stars... [08/29/17 21:00:06.866][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [08/29/17 21:00:07.023][DEBUG] [Camera Thread] Auto focus HFR calculated at: 4.21 [08/29/17 21:00:08.031][DEBUG] [Camera Thread] AF has detected >= 3 left slope and >= 3 right slope but an asymmetrical fit (Ratio: 41.6). Something isn't right here! Defaulting to lowest weighted HFR method [08/29/17 21:00:08.031][DEBUG] [Camera Thread] New auto focus method calculated focus at: 7925 [08/29/17 21:00:08.031][DEBUG] [Camera Thread] Old auto focus method calculated focus at: 7918 [08/29/17 21:00:08.031][DEBUG] [Camera Thread] Using old auto focus method for focuser position [08/29/17 21:00:08.031][DEBUG] [Camera Thread] New focus position is at 7918 (@100.00C). Moving focuser... [08/29/17 21:00:08.032][DEBUG] [Focuser Move Thread] Focuser moving to 7918 [08/29/17 21:00:08.088][DEBUG] [Focuser Move Thread] Focuser move call complete [08/29/17 21:00:09.143][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7918 matches requested position 7918 [08/29/17 21:00:09.143][DEBUG] [Camera Thread] Focuser move complete... [08/29/17 21:00:09.198][DEBUG] [Camera Thread] Focuser successfully set to focus position (7918) [08/29/17 21:00:09.308][DEBUG] [Camera Thread] Taking validation frame at focus position... [08/29/17 21:00:09.310][DEBUG] [Camera Thread] Taking auto focus frame(s)... [08/29/17 21:00:09.313][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [08/29/17 21:00:09.313][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [08/29/17 21:00:09.313][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 2 seconds... [08/29/17 21:00:09.313][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [08/29/17 21:00:09.350][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 21:00:09.350][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 21:00:26.975][DEBUG] [Camera Thread] QSI expose and download: 17.5288297s [08/29/17 21:00:27.104][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 21:00:27.104][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 21:00:27.244][DEBUG] [Main Thread] --> Find stars (normal) [08/29/17 21:00:27.328][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [08/29/17 21:00:29.519][DEBUG] [Main Thread] Star detection using min star size of 4px... [08/29/17 21:00:29.519][DEBUG] [Main Thread] Star detection using max star size of 80px... [08/29/17 21:00:29.566][DEBUG] [Main Thread] Find stars took: 2322 ms... [08/29/17 21:00:29.566][DEBUG] [Main Thread] Star list contains 44 stars... [08/29/17 21:00:29.566][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [08/29/17 21:00:29.726][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.19 [08/29/17 21:00:30.730][DEBUG] [Camera Thread] Auto focus is complete (weighted avg method: 7918; HFR 3.19)... [08/29/17 21:00:30.735][DEBUG] [Camera Thread] Entering chart disposal loop... [08/29/17 21:00:40.887][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [08/29/17 21:00:40.887][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [08/29/17 21:00:40.887][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [08/29/17 21:00:40.887][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [08/29/17 21:00:40.887][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [08/29/17 21:01:41.037][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [08/29/17 21:01:41.037][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [08/29/17 21:01:41.037][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [08/29/17 21:01:41.037][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [08/29/17 21:01:41.037][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [08/29/17 21:02:12.030][DEBUG] [AfChartThread] AF chart dialog is closed... [08/29/17 21:02:12.040][DEBUG] [Camera Thread] Chart disposal loop is complete... [08/29/17 21:02:12.040][DEBUG] [Camera Thread] Auto focus is done... [08/29/17 21:02:12.100][DEBUG] [Camera Thread] Auto focus complete... [08/29/17 21:02:12.108][DEBUG] [Camera Thread] SGM_FOCUSER_AUTO_FOCUS complete... [08/29/17 21:02:41.153][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [08/29/17 21:02:41.153][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [08/29/17 21:02:41.153][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [08/29/17 21:02:41.153][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [08/29/17 21:02:41.153][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [08/29/17 21:02:45.876][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS message received... [08/29/17 21:02:45.895][DEBUG] [Camera Thread] ASCOM (QSI) camera: frame and focus... [08/29/17 21:02:45.918][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 21:02:45.919][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 21:03:06.399][DEBUG] [Camera Thread] QSI expose and download: 20.4171659s [08/29/17 21:03:06.440][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 21:03:06.440][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 21:03:06.684][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS complete... [08/29/17 21:03:41.283][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [08/29/17 21:03:41.283][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [08/29/17 21:03:41.283][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [08/29/17 21:03:41.283][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [08/29/17 21:03:41.283][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [08/29/17 21:03:48.472][DEBUG] [Main Thread] PopulateDataModel: Transferring view to the data model... [08/29/17 21:03:48.484][DEBUG] [MF Update Thread] Performing serialize... [08/29/17 21:06:31.904][DEBUG] [Main Thread] PopulateDataModel: Transferring view to the data model... [08/29/17 21:06:31.917][DEBUG] [MF Update Thread] Performing serialize... [08/29/17 21:06:34.419][DEBUG] [Main Thread] Filter wheel is required... [08/29/17 21:06:34.419][DEBUG] [Main Thread] Filter wheel is still connected. Ignoring connect step... [08/29/17 21:06:34.420][DEBUG] [Main Thread] Focuser is required... [08/29/17 21:06:34.420][DEBUG] [Main Thread] Focuser is still connected. Ignoring connect step... [08/29/17 21:06:34.420][DEBUG] [Main Thread] Telescope is required... [08/29/17 21:06:34.420][DEBUG] [Main Thread] Telescope is still connected. Ignoring connect step... [08/29/17 21:06:34.429][DEBUG] [Main Thread] Observatory connection might be required... [08/29/17 21:06:34.429][DEBUG] [Main Thread] Observatory is still connected. Ignoring connect step... [08/29/17 21:06:34.429][DEBUG] [Main Thread] Safety device might be required... [08/29/17 21:06:34.429][DEBUG] [Main Thread] Safety device is still connected. Ignoring connect step... [08/29/17 21:06:34.429][DEBUG] [Main Thread] Environment device required... [08/29/17 21:06:34.429][DEBUG] [Main Thread] Environment device is still connected. Ignoring connect step... [08/29/17 21:06:34.505][DEBUG] [Main Thread] PopulateDataModel: Transferring view to the data model... [08/29/17 21:06:34.510][DEBUG] [Main Thread] GNS Startup... [08/29/17 21:06:34.521][DEBUG] [Sequence Thread] Dispatch loop: Received SGM_RUN_SEQUENCE... [08/29/17 21:06:34.534][DEBUG] [Sequence Thread] GuiderCheck: Checking auto guider status... [08/29/17 21:06:34.534][DEBUG] [Sequence Thread] GuiderCheck: Auto guider reports connected... [08/29/17 21:06:34.534][DEBUG] [Sequence Thread] Checking auto guider version... [08/29/17 21:06:34.535][DEBUG] [Sequence Thread] PHD2: Performing version check... [08/29/17 21:06:34.535][DEBUG] [Sequence Thread] PHD2: Min version is 2.6.0 [08/29/17 21:06:34.535][DEBUG] [Sequence Thread] PHD2: User version is 2.6.3 [08/29/17 21:06:34.536][DEBUG] [Sequence Thread] Finding first active group... [08/29/17 21:06:34.536][DEBUG] [Sequence Thread] Looking at target M13... [08/29/17 21:06:34.536][DEBUG] [Sequence Thread] Target is active: True [08/29/17 21:06:34.536][DEBUG] [Sequence Thread] Target is complete: False [08/29/17 21:06:34.536][DEBUG] [Sequence Thread] Target marked as past end time: False [08/29/17 21:06:34.536][DEBUG] [Sequence Thread] Target has end time: False [08/29/17 21:06:34.536][DEBUG] [Sequence Thread] Target end time: 8/28/2017 9:18:00 PM [08/29/17 21:06:34.536][DEBUG] [Sequence Thread] Active group found... [08/29/17 21:06:34.536][DEBUG] [Sequence Thread] Sequence should find starting target; Found target: 0 [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] ************** SEQUENCE DUMP ************** [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] sDirectory: C:\Users\Public\Documents\SGP Images [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] sBaseName: [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] nDelayFirst: 0 [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] nDelayBetween: 0 [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] bFinishFullEventFirst: False [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] arEventGroups: System.Collections.Generic.List`1[SequenceGenerator.EventGroup] [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] nCurrentEventIndex: 0 [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] dtElapsedTime: 1/1/0001 12:00:00 AM +00:00 [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] bStarted: False [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] bIsSequenceRunning: False [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] sFileNamePattern: %ft\%tn_%el_%bi_%su_%fn [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] sVersion: 2.6.0.23 [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] tsElapsedTime: 00:00:00 [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] bCaptureUntil: False [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] dtCaptureUntil: 8/29/2017 9:05:00 PM [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] userProfile: SequenceGenerator.UserProfile [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] nAfNebulosityRejection: 0.85 [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] sequenceFiles: System.Collections.Generic.Dictionary`2[System.String,System.String] [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] sequenceFileList: System.Collections.Generic.List`1[SequenceGenerator.SequenceFile] [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] imageHistory: SequenceGenerator.ImageHistoryManager [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] nAfSampleSize: 100 [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] bCaptureCalFramesIfSequenceFails: False [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] notificationsList: System.Collections.Generic.List`1[SequenceGenerator.NotoficationInfo] [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] bRunEndOfSequenceScript: True [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] sEndOfSequenceScript: C:\Users\Public\Documents\MyObservatoryScripts\ShutDownfor_SGP_Boot.vbs [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] bDisconnectAllGearOnSequenceEnd: True [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] ************** PROFILE DUMP ************** [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] sForImagingInstance: Sequence Generator Pro [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] sProfileName: Wes1 [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] bDefault: True [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] sCameraName: QSI CCD Camera [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] nGain1x1: 0 [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] nOffset1x1: 100 [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] nGain2x2: 0 [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] nOffset2x2: 100 [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] nGain3x3: 0 [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] nOffset3x3: 100 [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] nGain4x4: 0 [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] nOffset4x4: 100 [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] nCooldownTemp: -10 [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] nCooldownTime: 6 [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] nWarmupTemp: 20 [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] nWarmupTime: 5 [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] bAutoCoolDown: True [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] bAutoWarmUp: True [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] sFilterWheelName: QSI Internal Filter Wheel [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] nFilterDelay: 0 [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] sFocuserName: RoboFocus [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] bReverseFocuser: False [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] nFineFocusStepSize: 10 [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] nCoarseFocusStepSize: 30 [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] bAutoAdjustFocusPerFilter: False [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] bAutoFocusForFilterChangeOnly: True [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] bUseAutoFocus: True [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] bAutoFocusPerFrame: True [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] bAutoFocusOverTime: False [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] nAutoFocusFramerInterval: 3 [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] nAutoFocusTimerInterval: 10 [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] bAutoFocusForceForFilter: False [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] bAutoFocusBeforeFirstFrame: False [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] bAutoFocusManualStar: False [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] bAutoManualFocusForFilterChangeOnly: True [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] bUseAutoManualFocus: False [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] bAutoManualFocusPerFrame: False [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] bAutoManualFocusOverTime: False [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] nAutoManualFocusFramerInterval: 1 [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] nAutoManualFocusTimerInterval: 10 [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] bAutoManualFocusForceForFilter: False [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] bAutoManualFocusBeforeFirstFrame: True [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] bOverrideMaxSteps: False [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] nOverrideMaxSteps: 1 [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] bTempComp: False [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] nStepsPerDegree: 0 [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] bPhdDither: False [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] nPhdDitherIndex: 0 [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] nPhdSettleValue: 0.6 [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] bRecalGuiderOnSlew: False [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] nDecRecalValue: 0 [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] sTelescopeName: POTH Hub [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] bAutoSlew: False [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] tpFocusFrame: [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] tpTargetFrame: [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] bPausePhdDuringDownload: True [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] bShutdownPhdWhenDone: True [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] bParkTelescopeWhenDone: True [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] sReferenceImagePath: [08/29/17 21:06:34.539][DEBUG] [Sequence Thread] bAutoCenterFrame: False [08/29/17 21:06:34.540][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 [08/29/17 21:06:34.540][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 [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] nSolverExposure1x1: 1 [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] nSolverExposure2x2: 1 [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] nSolverExposure3x3: 1 [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] nSolverExposure4x4: 1 [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] nArcsec1x1: 0 [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] nArcsec2x2: 0 [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] nArcsec3x3: 0 [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] nArcsec4x4: 0 [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] sDithertext: Small Dither [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] sAutoGuider: PHD2 [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] nFFBinning: 3 [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] nFFExposure: 1 [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] nAFExposure: 1 [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] nAFBinning: 0 [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] nAFStepSize: 10 [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] nAFDataPoints: 7 [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] sPlateSolver: No Plate Solver [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] sAutoFocuser: Half Flux Radius [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] nAutoFocusDialogCloseDelay: 30 [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] bAutoFocusDegree: False [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] bAutoManualFocusDegree: False [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] nAutoFocusDegreeInterval: 1 [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] nAutoManualFocusDegreeInterval: 1 [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] bAutoFocusAutoContinue: False [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] nNudgeSpeed: 0 [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] bReverseNs: False [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] bReverseEw: False [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] nHiSpeedRate: 1 [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] nLoSpeedRate: 0.5 [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] sCameraGain1x1: High [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] sCameraGain2x2: High [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] sCameraGain3x3: High [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] sCameraGain4x4: High [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] sCameraOffset1x1: [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] sCameraOffset2x2: [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] sCameraOffset3x3: [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] sCameraOffset4x4: [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] bPauseAutoGuiderDuringAutoFocus: False [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] bUseSameStarsForAf: False [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] bSaveAfData: False [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] sSaveAfDataPath: C:\Users\Public\Documents\SGP Images [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] bAllowExternalScopeControl: False [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] sFFBinning: 2x2 [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] sAFBinning: 1x1 [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] nMountSettling: 0 [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] ffExposure: 10 [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] noFilter: SequenceGenerator.Filter [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] bAutoFocusOnResume: False [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] sFlatBoxName: No Flat Box [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] flatBoxBrightness: 0 [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] bFocuserBacklash: False [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] nFocuserBacklashStepSize: 0 [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] bFocuserBacklashMovesIn: True [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] nGuiderSettlingTime: 0 [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] nAutoCenterAttempts: 1 [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] nAutoCenterError: 50 [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] bFrameRestart: False [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] nFrameRestartError: 0 [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] bDoAutoMeridianFlip: False [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] autoMeridianFlipVals: SequenceGenerator.AutoMeridianFlipValues [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] bUseAfDarks: False [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] sAfDarksPath: [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] bUseAutoCenterFilter: False [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] sAutoCenterFilter: Lum [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] bUseAutoFocusFilter: False [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] sAutoFocusFilter: Lum [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] bSettleAgBeforeAf: False [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] bUseHighSpeedDl: False [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] bBlindSolveFailover: False [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] nReadoutNoise: 0 [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] ccdArraySize: {Width=0, Height=0} [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] nCameraGain1x1: 0 [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] nCameraGain2x2: 0 [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] nCameraGain3x3: 0 [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] nCameraGain4x4: 0 [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] sRotatorName: No Rotator [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] nRotationError: 3 [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] bUseTemperDevice: False [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] bAutoFocusAfterFlip: False [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] bReverseManualRotatorCorrections: False [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] sObservatoryName: SkyRoofHub [08/29/17 21:06:34.540][DEBUG] [Sequence Thread] sObsType: Roll Off Roof [08/29/17 21:06:34.541][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 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] bSlaveObsToScope: False [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] bPromptToPlaceLightBox: False [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nDownloadTime1x1: 0 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nDownloadTime2x2: 0 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nDownloadTime3x3: 0 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nDownloadTime4x4: 0 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] bAfCropFrames: False [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nAfCropFramesPercent: 0 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] sFilePattern: [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] sWeatherStationName: ASCOM Boltwood OK to Image [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nScopeFocalLength: 0 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] bCameraCooldownOnSequenceStart: False [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] sRtfNotes: [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] bStopTracking: False [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] bHasCentralObstruction: False [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] sCustomVariables: [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] sPhd2Profile: Tak [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] bAutoConnectPhd2Gear: True [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] bAutoDisconnectPhd2Gear: True [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] bPauseGuiderDuringBacklashComp: False [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] bSetAbsPositiononFirstFilterChange: False [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] sEnvironmentDeviceName: ASCOM Boltwood Observing Conditions [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] bSettleGuiderTime: False [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] equipmentData: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.EquipmentData] [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nMinStarSize: 6 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] telescopeSyncOption: Sync [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nAutoguiderDitherEveryXFrameCount: 1 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] ************** FILTER DUMP ************** [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] >> FILTER 1: [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] bActive: True [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] sName: None [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nAfExposureTime: 1 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nFocusPosition: 0 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nFocusTemp: 0 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nFlat1: 0 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nFlat2: 0 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nFlat3: 0 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nFlat4: 0 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nNumFlats: 0 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] flatsData: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] flatsDataBin: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] flatsDataIso: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] sFocusWith: Default [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] arFocPositions: System.Collections.Generic.List`1[SequenceGenerator.FocusPosData] [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] >> FILTER 2: [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] bActive: True [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] sName: Lum [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nAfExposureTime: 1 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nFocusPosition: 0 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nFocusTemp: 0 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nFlat1: 0 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nFlat2: 0 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nFlat3: 0 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nFlat4: 0 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nNumFlats: 0 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] flatsData: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] flatsDataBin: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] flatsDataIso: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] sFocusWith: Default [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] arFocPositions: System.Collections.Generic.List`1[SequenceGenerator.FocusPosData] [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] >> FILTER 3: [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] bActive: True [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] sName: Red [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nAfExposureTime: 1 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nFocusPosition: 0 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nFocusTemp: 0 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nFlat1: 0 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nFlat2: 0 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nFlat3: 0 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nFlat4: 0 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nNumFlats: 0 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] flatsData: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] flatsDataBin: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] flatsDataIso: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] sFocusWith: Default [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] arFocPositions: System.Collections.Generic.List`1[SequenceGenerator.FocusPosData] [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] >> FILTER 4: [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] bActive: True [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] sName: Green [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nAfExposureTime: 1 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nFocusPosition: 0 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nFocusTemp: 0 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nFlat1: 0 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nFlat2: 0 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nFlat3: 0 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nFlat4: 0 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nNumFlats: 0 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] flatsData: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] flatsDataBin: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] flatsDataIso: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] sFocusWith: Default [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] arFocPositions: System.Collections.Generic.List`1[SequenceGenerator.FocusPosData] [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] >> FILTER 5: [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] bActive: True [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] sName: Blue [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nAfExposureTime: 2 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nFocusPosition: 0 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nFocusTemp: 0 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nFlat1: 0 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nFlat2: 0 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nFlat3: 0 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nFlat4: 0 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nNumFlats: 0 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] flatsData: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] flatsDataBin: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] flatsDataIso: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] sFocusWith: Default [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] arFocPositions: System.Collections.Generic.List`1[SequenceGenerator.FocusPosData] [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] >> FILTER 6: [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] bActive: True [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] sName: Ha [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nAfExposureTime: 1 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nFocusPosition: 0 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nFocusTemp: 0 [08/29/17 21:06:34.541][DEBUG] [Sequence Thread] nFlat1: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFlat2: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFlat3: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFlat4: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nNumFlats: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] flatsData: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] flatsDataBin: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] flatsDataIso: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] sFocusWith: Default [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] arFocPositions: System.Collections.Generic.List`1[SequenceGenerator.FocusPosData] [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] >> FILTER 7: [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] bActive: True [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] sName: O3 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nAfExposureTime: 1 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFocusPosition: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFocusTemp: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFlat1: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFlat2: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFlat3: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFlat4: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nNumFlats: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] flatsData: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] flatsDataBin: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] flatsDataIso: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] sFocusWith: Default [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] arFocPositions: System.Collections.Generic.List`1[SequenceGenerator.FocusPosData] [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] >> FILTER 8: [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] bActive: True [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] sName: S2 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nAfExposureTime: 1 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFocusPosition: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFocusTemp: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFlat1: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFlat2: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFlat3: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFlat4: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nNumFlats: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] flatsData: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] flatsDataBin: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] flatsDataIso: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] sFocusWith: Default [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] arFocPositions: System.Collections.Generic.List`1[SequenceGenerator.FocusPosData] [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] >> FILTER 9: [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] bActive: False [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] sName: [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nAfExposureTime: 1 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFocusPosition: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFocusTemp: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFlat1: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFlat2: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFlat3: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFlat4: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nNumFlats: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] flatsData: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] flatsDataBin: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] flatsDataIso: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] sFocusWith: Default [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] arFocPositions: System.Collections.Generic.List`1[SequenceGenerator.FocusPosData] [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] >> FILTER 10: [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] bActive: False [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] sName: [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nAfExposureTime: 1 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFocusPosition: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFocusTemp: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFlat1: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFlat2: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFlat3: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFlat4: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nNumFlats: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] flatsData: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] flatsDataBin: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] flatsDataIso: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] sFocusWith: Default [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] arFocPositions: System.Collections.Generic.List`1[SequenceGenerator.FocusPosData] [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] >> FILTER 11: [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] bActive: False [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] sName: [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nAfExposureTime: 1 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFocusPosition: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFocusTemp: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFlat1: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFlat2: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFlat3: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFlat4: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nNumFlats: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] flatsData: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] flatsDataBin: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] flatsDataIso: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] sFocusWith: Default [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] arFocPositions: System.Collections.Generic.List`1[SequenceGenerator.FocusPosData] [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] >> FILTER 12: [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] bActive: False [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] sName: [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nAfExposureTime: 1 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFocusPosition: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFocusTemp: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFlat1: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFlat2: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFlat3: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFlat4: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nNumFlats: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] flatsData: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] flatsDataBin: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] flatsDataIso: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] sFocusWith: Default [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] arFocPositions: System.Collections.Generic.List`1[SequenceGenerator.FocusPosData] [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] >> FILTER 13: [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] bActive: False [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] sName: [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nAfExposureTime: 1 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFocusPosition: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFocusTemp: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFlat1: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFlat2: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFlat3: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nFlat4: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] nNumFlats: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] flatsData: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] flatsDataBin: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] flatsDataIso: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.FlatData] [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] sFocusWith: Default [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] arFocPositions: System.Collections.Generic.List`1[SequenceGenerator.FocusPosData] [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] ************** EVENT DUMP ************** [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] * Target M13 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] -Active: True [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] -Start Time: False [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] -Start: 8/28/2017 9:18:00 PM [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] -End Time: False [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] -End: 8/28/2017 9:18:00 PM [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] -Auto Rotate: False [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] -Auto Center: False [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] -Location set: False [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] -RA: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] -DEC: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] -Finish Full Event: True [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] -Completed: False [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] -After End Time: False [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] -Rotate Camera: False [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] -Rotation: 0 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] ** Event 1 [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] -Active: True [08/29/17 21:06:34.542][DEBUG] [Sequence Thread] -Frame Type: Light [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Filter: 4 [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Suffix: B [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Exposure Time: 300 [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Binning: 1x1 [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Repeat: 6 [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Gain: 0 [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Offset: 100 [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Num Complete: 0 [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] ** Event 2 [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Active: False [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Frame Type: Light [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Filter: 0 [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Suffix: [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Exposure Time: 0 [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Binning: 1x1 [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Repeat: 1 [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Gain: 0 [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Offset: 100 [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Num Complete: 0 [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] ** Event 3 [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Active: False [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Frame Type: Light [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Filter: 0 [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Suffix: [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Exposure Time: 0 [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Binning: 1x1 [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Repeat: 1 [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Gain: 0 [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Offset: 100 [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Num Complete: 0 [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] ** Event 4 [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Active: False [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Frame Type: Light [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Filter: 0 [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Suffix: [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Exposure Time: 0 [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Binning: 1x1 [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Repeat: 1 [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Gain: 0 [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Offset: 100 [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Num Complete: 0 [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] ** Event 5 [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Active: False [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Frame Type: Light [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Filter: 0 [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Suffix: [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Exposure Time: 0 [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Binning: 1x1 [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Repeat: 1 [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Gain: 0 [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Offset: 100 [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] -Num Complete: 0 [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] ************** END SEQUENCE DUMP ************** [08/29/17 21:06:34.543][DEBUG] [Sequence Thread] ********** Run sequence started ********** [08/29/17 21:06:34.604][DEBUG] [Sequence Thread] Checking if observatory requires auto slaving... [08/29/17 21:06:34.604][DEBUG] [Sequence Thread] Observatory does not require auto slave... [08/29/17 21:06:34.607][DEBUG] [Sequence Thread] DoEventGroupChange: Changing to event group: M13 [08/29/17 21:06:34.609][DEBUG] [Sequence Thread] Checking for start delay... [08/29/17 21:06:34.609][DEBUG] [Sequence Thread] Delay found... waiting 2 seconds... [08/29/17 21:06:34.609][DEBUG] [Sequence Thread] Delayed start. Commencing sequence in 2 seconds... [08/29/17 21:06:36.717][DEBUG] [Sequence Thread] DoEventGroupChange: Complete [08/29/17 21:06:36.717][DEBUG] [Sequence Thread] Attempting to find next event... [08/29/17 21:06:36.718][DEBUG] [Sequence Thread] Current event[0] frame count: 0/6... [08/29/17 21:06:36.718][DEBUG] [Sequence Thread] Looking at event[0]... [08/29/17 21:06:36.718][DEBUG] [Sequence Thread] Found event event[0] with remaining frames. [08/29/17 21:06:36.719][DEBUG] [Sequence Thread] Event[0] frame count: 0/6... [08/29/17 21:06:36.719][DEBUG] [Sequence Thread] Getting first event (0)... [08/29/17 21:06:36.719][DEBUG] [Main Thread] PopulateDataModel: Transferring view to the data model... [08/29/17 21:06:36.727][DEBUG] [Sequence Thread] Running pre event... [08/29/17 21:06:36.747][DEBUG] [Sequence Thread] Running capture event... [08/29/17 21:06:36.749][DEBUG] [Sequence Thread] SetStartTemp: Using focuser for temperature... [08/29/17 21:06:36.749][DEBUG] [Sequence Thread] Set start frame temp to 100... [08/29/17 21:06:36.752][DEBUG] [Sequence Thread] ------------- Starting capture frame for event[0] ------------- [08/29/17 21:06:36.755][DEBUG] [Sequence Thread] Sending commands... [08/29/17 21:06:36.756][DEBUG] [Sequence Thread] SetFlatBox: Frame Type is Light [08/29/17 21:06:36.757][DEBUG] [Sequence Thread] EventMarker 2 - t:M13 (0); e:0; f:0; o: [08/29/17 21:06:36.758][DEBUG] [Sequence Thread] EventMarker 3 - t:M13 (0); e:0; f:0; o: [08/29/17 21:06:36.758][DEBUG] [Sequence Thread] Waiting for AF to complete... [08/29/17 21:06:36.763][DEBUG] [Camera Thread] SGM_FOCUSER_AUTO_FOCUS message received... [08/29/17 21:06:36.764][DEBUG] [Camera Thread] Checking for auto focus... [08/29/17 21:06:36.764][DEBUG] [Camera Thread] SGM_FOCUSER_AUTO_FOCUS complete... [08/29/17 21:06:36.958][DEBUG] [Sequence Thread] AF is complete, continuing sequence... [08/29/17 21:06:36.958][DEBUG] [Sequence Thread] EventMarker 4 - t:M13 (0); e:0; f:0; o: [08/29/17 21:06:36.958][DEBUG] [Sequence Thread] Setting filter position 4... [08/29/17 21:06:36.958][DEBUG] [Sequence Thread] Filter position 4 is already set. Skipping... [08/29/17 21:06:36.958][DEBUG] [Sequence Thread] EventMarker 5 - t:M13 (0); e:0; f:0; o: [08/29/17 21:06:36.960][DEBUG] [Sequence Thread] Checking for auto manual focus (pre)... [08/29/17 21:06:36.960][DEBUG] [Sequence Thread] EventMarker 6 - t:M13 (0); e:0; f:0; o: [08/29/17 21:06:37.036][DEBUG] [Sequence Thread] Created base name for frame capture: M13_300sec_1x1_B_frame1 [08/29/17 21:06:37.037][DEBUG] [Sequence Thread] Created directory for frame capture: C:\Users\Public\Documents\SGP Images\Light\ [08/29/17 21:06:37.037][DEBUG] [Sequence Thread] EventMarker 1 - t:M13 (0); e:0; f:0; o: [08/29/17 21:06:37.038][DEBUG] [Sequence Thread] EventMarker 7 - t:M13 (0); e:0; f:0; o: [08/29/17 21:06:37.038][DEBUG] [Sequence Thread] Resuming auto guiding (settling)... [08/29/17 21:06:37.039][DEBUG] [Sequence Thread] Checking PHD2 state... [08/29/17 21:06:37.040][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [08/29/17 21:06:37.040][DEBUG] [Sequence Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [08/29/17 21:06:37.140][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Post-Wait: Guiding [08/29/17 21:06:37.140][DEBUG] [Sequence Thread] PHD2: Requested unpause, but PHD2 reports it is not in a paused state [08/29/17 21:06:37.142][DEBUG] [Sequence Thread] {"method": "guide", "params": [{"pixels": 0.6, "time": 0, "timeout": 600}, false ], "id": 1003} [08/29/17 21:06:37.142][DEBUG] [Sequence Thread] Sending to PHD2: {"method": "guide", "params": [{"pixels": 0.6, "time": 0, "timeout": 600}, false ], "id": 1003} [08/29/17 21:06:37.142][DEBUG] [Sequence Thread] Checking PHD2 state... [08/29/17 21:06:37.142][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Pre-Wait : Guiding [08/29/17 21:06:37.142][DEBUG] [Sequence Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [08/29/17 21:06:37.244][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Post-Wait: Guiding [08/29/17 21:06:37.244][DEBUG] [Sequence Thread] User has elected to skip guider settling over time... [08/29/17 21:06:37.245][DEBUG] [Sequence Thread] Waiting for Auto Guider distance to fall below 0.6 [08/29/17 21:06:37.245][DEBUG] [Sequence Thread] PHD2 distance request... [08/29/17 21:06:37.245][DEBUG] [Sequence Thread] PHD2 distance request returned 0.3... [08/29/17 21:06:37.252][DEBUG] [Sequence Thread] Distance is below 0.6, starting timer... [08/29/17 21:06:37.587][DEBUG] [PHD2 Listener Thread] PHD2: Settle done received: GOOD [08/29/17 21:06:37.754][DEBUG] [Sequence Thread] PHD2 distance request... [08/29/17 21:06:37.754][DEBUG] [Sequence Thread] PHD2 distance request returned 0.2... [08/29/17 21:06:37.762][DEBUG] [Sequence Thread] Distance stayed below 0.6 for 0 seconds, done settling... [08/29/17 21:06:37.763][DEBUG] [Sequence Thread] Auto guider has settled... [08/29/17 21:06:37.764][DEBUG] [Sequence Thread] EventMarker 8 - t:M13 (0); e:0; f:0; o: [08/29/17 21:06:37.764][DEBUG] [Sequence Thread] EventMarker 9 - t:M13 (0); e:0; f:0; o: [08/29/17 21:06:37.764][DEBUG] [Sequence Thread] EventMarker 10 - t:M13 (0); e:0; f:0; o: [08/29/17 21:06:37.764][DEBUG] [Sequence Thread] Finished sending frame capture. Entering wait mode... [08/29/17 21:06:37.765][DEBUG] [Sequence Thread] EventMarker 11 - t:M13 (0); e:0; f:0; o: [08/29/17 21:06:37.767][DEBUG] [Sequence Thread] EventMarker 12 - t:M13 (0); e:0; f:0; o: [08/29/17 21:06:37.768][DEBUG] [Sequence Thread] EventMarker 13 - t:M13 (0); e:0; f:0; o: [08/29/17 21:06:37.774][DEBUG] [Camera Thread] SGM_CAMERA_CAPTURE message received... [08/29/17 21:06:37.803][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 21:06:37.803][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 21:11:37.839][DEBUG] [Sequence Thread] EventMarker 15 - t:M13 (0); e:0; f:0; o: [08/29/17 21:11:37.839][DEBUG] [Sequence Thread] Waking from exposure time sleep period... [08/29/17 21:11:37.839][DEBUG] [Sequence Thread] Checking to see if the CCD has a temp... [08/29/17 21:11:37.839][DEBUG] [Sequence Thread] Saving the CCD temp... [08/29/17 21:11:37.907][DEBUG] [Sequence Thread] Checking PHD2 state... [08/29/17 21:11:37.907][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Pre-Wait : Guiding [08/29/17 21:11:37.907][DEBUG] [Sequence Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [08/29/17 21:11:38.008][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Post-Wait: Guiding [08/29/17 21:11:54.212][DEBUG] [Camera Thread] QSI expose and download: 316.3455606s [08/29/17 21:11:54.212][DEBUG] [Camera Thread] SGM_CAMERA_CAPTURE complete... [08/29/17 21:11:54.332][DEBUG] [Sequence Thread] Image ready state detected when waiting for download state, continuing sequence... [08/29/17 21:11:54.332][DEBUG] [Sequence Thread] Entering super dangerous loop to await image completion... [08/29/17 21:11:54.341][DEBUG] [Sequence Thread] EventMarker 16 - t:M13 (0); e:0; f:0; o: [08/29/17 21:11:54.353][DEBUG] [Sequence Thread] EventMarker 17 - t:M13 (0); e:0; f:0; o: [08/29/17 21:11:54.353][DEBUG] [Sequence Thread] Image reported as complete. Continuing... [08/29/17 21:11:54.363][DEBUG] [Sequence Thread] Collecting FITs headers... [08/29/17 21:11:54.371][DEBUG] [Sequence Thread] QSI Camera - Camera reports last image start time as: 2017-08-30T04:06:41.307 UTC [08/29/17 21:11:54.371][DEBUG] [Sequence Thread] DATE-LOC time provided by camera... [08/29/17 21:11:54.390][DEBUG] [Sequence Thread] GatherFitsHeaders: Writing header info from UI... [08/29/17 21:11:54.404][DEBUG] [Sequence Thread] Clearing timed monitoring events... [08/29/17 21:11:54.410][DEBUG] [Sequence Thread] Created full file name (file does not exist): C:\Users\Public\Documents\SGP Images\Light\M13_300sec_1x1_B_frame1.fit [08/29/17 21:11:54.488][DEBUG] [Sequence Thread] SaveFileBmp16: Locking bitmap for disk write... [08/29/17 21:11:54.488][DEBUG] [Sequence Thread] SaveFileBmp16: Opening file stream... [08/29/17 21:11:54.489][DEBUG] [Sequence Thread] SaveFileBmp16: Writing FITS headers... [08/29/17 21:11:54.493][DEBUG] [Sequence Thread] SaveFileBmp16: Traversing and persisting bitmap data... [08/29/17 21:11:54.587][DEBUG] [Sequence Thread] SaveFileBmp16: FITS block fill... [08/29/17 21:11:54.587][DEBUG] [Sequence Thread] SaveFileBmp16: Unlocking bitmap... [08/29/17 21:11:54.587][DEBUG] [Sequence Thread] SaveFileBmp16: Closing file stream [08/29/17 21:11:54.593][DEBUG] [Sequence Thread] Internal Interface: Set Preview... [08/29/17 21:11:54.593][DEBUG] [Sequence Thread] Display image preview using asynch task... [08/29/17 21:11:55.184][DEBUG] [Sequence Thread] =========== Save file took 777 ms [08/29/17 21:11:55.206][DEBUG] [Sequence Thread] Resuming auto guiding (settling)... [08/29/17 21:11:55.206][DEBUG] [Sequence Thread] Checking PHD2 state... [08/29/17 21:11:55.206][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Pre-Wait : Guiding [08/29/17 21:11:55.206][DEBUG] [Sequence Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [08/29/17 21:11:55.307][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Post-Wait: Guiding [08/29/17 21:11:55.307][DEBUG] [Sequence Thread] PHD2: Requested unpause, but PHD2 reports it is not in a paused state [08/29/17 21:11:55.307][DEBUG] [Sequence Thread] EventMarker 18 - t:M13 (0); e:0; f:0; o: [08/29/17 21:11:55.311][DEBUG] [Sequence Thread] EventMarker 19 - t:M13 (0); e:0; f:1; o: [08/29/17 21:11:55.312][DEBUG] [Sequence Thread] EventMarker 20 - t:M13 (0); e:0; f:1; o: [08/29/17 21:11:55.313][DEBUG] [Sequence Thread] Running post event... [08/29/17 21:11:55.313][DEBUG] [Sequence Thread] EventMarker 21 - t:M13 (0); e:0; f:1; o: [08/29/17 21:11:55.315][DEBUG] [Sequence Thread] Incremented numComplete (1) for event[0]... [08/29/17 21:11:55.319][DEBUG] [Sequence Thread] Attempting to find next event... [08/29/17 21:11:55.319][DEBUG] [Sequence Thread] Current event[0] frame count: 1/6... [08/29/17 21:11:55.320][DEBUG] [Sequence Thread] Current event event[0] has remaining frames. Returning current event. [08/29/17 21:11:55.320][DEBUG] [Sequence Thread] Checking for auto manual focus (post)... [08/29/17 21:11:55.320][DEBUG] [Main Thread] PopulateDataModel: Transferring view to the data model... [08/29/17 21:11:55.326][DEBUG] [Sequence Thread] Attempting to move to next event... [08/29/17 21:11:55.326][DEBUG] [Sequence Thread] Attempting to find next event... [08/29/17 21:11:55.326][DEBUG] [Sequence Thread] Current event[0] frame count: 1/6... [08/29/17 21:11:55.326][DEBUG] [Sequence Thread] Current event event[0] has remaining frames. Returning current event. [08/29/17 21:11:55.326][DEBUG] [Sequence Thread] Running pre event... [08/29/17 21:11:55.400][DEBUG] [Sequence Thread] Running capture event... [08/29/17 21:11:55.402][DEBUG] [Sequence Thread] SetStartTemp: Using focuser for temperature... [08/29/17 21:11:55.402][DEBUG] [Sequence Thread] Set start frame temp to 100... [08/29/17 21:11:55.403][DEBUG] [Sequence Thread] ------------- Starting capture frame for event[0] ------------- [08/29/17 21:11:55.405][DEBUG] [Sequence Thread] Sending commands... [08/29/17 21:11:55.405][DEBUG] [Sequence Thread] SetFlatBox: Frame Type is Light [08/29/17 21:11:55.405][DEBUG] [Sequence Thread] EventMarker 2 - t:M13 (0); e:0; f:1; o: [08/29/17 21:11:55.405][DEBUG] [Sequence Thread] EventMarker 3 - t:M13 (0); e:0; f:1; o: [08/29/17 21:11:55.405][DEBUG] [Sequence Thread] Waiting for AF to complete... [08/29/17 21:11:55.415][DEBUG] [Camera Thread] SGM_FOCUSER_AUTO_FOCUS message received... [08/29/17 21:11:55.415][DEBUG] [Camera Thread] Checking for auto focus... [08/29/17 21:11:55.415][DEBUG] [Camera Thread] SGM_FOCUSER_AUTO_FOCUS complete... [08/29/17 21:11:55.607][DEBUG] [Sequence Thread] AF is complete, continuing sequence... [08/29/17 21:11:55.607][DEBUG] [Sequence Thread] EventMarker 4 - t:M13 (0); e:0; f:1; o: [08/29/17 21:11:55.607][DEBUG] [Sequence Thread] Setting filter position 4... [08/29/17 21:11:55.607][DEBUG] [Sequence Thread] Filter position 4 is already set. Skipping... [08/29/17 21:11:55.607][DEBUG] [Sequence Thread] EventMarker 5 - t:M13 (0); e:0; f:1; o: [08/29/17 21:11:55.607][DEBUG] [Sequence Thread] Checking for auto manual focus (pre)... [08/29/17 21:11:55.607][DEBUG] [Sequence Thread] EventMarker 6 - t:M13 (0); e:0; f:1; o: [08/29/17 21:11:55.665][DEBUG] [Sequence Thread] Between frame delay found. Sleeping for 2 seconds... [08/29/17 21:11:57.692][DEBUG] [Sequence Thread] Created base name for frame capture: M13_300sec_1x1_B_frame2 [08/29/17 21:11:57.692][DEBUG] [Sequence Thread] Created directory for frame capture: C:\Users\Public\Documents\SGP Images\Light\ [08/29/17 21:11:57.692][DEBUG] [Sequence Thread] EventMarker 1 - t:M13 (0); e:0; f:1; o: [08/29/17 21:11:57.694][DEBUG] [Sequence Thread] EventMarker 7 - t:M13 (0); e:0; f:1; o: [08/29/17 21:11:57.694][DEBUG] [Sequence Thread] Resuming auto guiding (settling)... [08/29/17 21:11:57.694][DEBUG] [Sequence Thread] Checking PHD2 state... [08/29/17 21:11:57.695][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Pre-Wait : Guiding [08/29/17 21:11:57.695][DEBUG] [Sequence Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [08/29/17 21:11:57.795][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Post-Wait: Guiding [08/29/17 21:11:57.795][DEBUG] [Sequence Thread] PHD2: Requested unpause, but PHD2 reports it is not in a paused state [08/29/17 21:11:57.796][DEBUG] [Sequence Thread] {"method": "guide", "params": [{"pixels": 0.6, "time": 0, "timeout": 600}, false ], "id": 1003} [08/29/17 21:11:57.796][DEBUG] [Sequence Thread] Sending to PHD2: {"method": "guide", "params": [{"pixels": 0.6, "time": 0, "timeout": 600}, false ], "id": 1003} [08/29/17 21:11:57.796][DEBUG] [Sequence Thread] Checking PHD2 state... [08/29/17 21:11:57.796][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Pre-Wait : Guiding [08/29/17 21:11:57.796][DEBUG] [Sequence Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [08/29/17 21:11:57.896][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Post-Wait: Guiding [08/29/17 21:11:57.896][DEBUG] [Sequence Thread] User has elected to skip guider settling over time... [08/29/17 21:11:57.896][DEBUG] [Sequence Thread] Waiting for Auto Guider distance to fall below 0.6 [08/29/17 21:11:57.896][DEBUG] [Sequence Thread] PHD2 distance request... [08/29/17 21:11:57.896][DEBUG] [Sequence Thread] PHD2 distance request returned 0.3... [08/29/17 21:11:57.905][DEBUG] [Sequence Thread] Distance is below 0.6, starting timer... [08/29/17 21:11:58.405][DEBUG] [Sequence Thread] PHD2 distance request... [08/29/17 21:11:58.405][DEBUG] [Sequence Thread] PHD2 distance request returned 0.3... [08/29/17 21:11:58.411][DEBUG] [Sequence Thread] Distance stayed below 0.6 for 0 seconds, done settling... [08/29/17 21:11:58.412][DEBUG] [Sequence Thread] Auto guider has settled... [08/29/17 21:11:58.412][DEBUG] [Sequence Thread] EventMarker 8 - t:M13 (0); e:0; f:1; o: [08/29/17 21:11:58.412][DEBUG] [Sequence Thread] EventMarker 9 - t:M13 (0); e:0; f:1; o: [08/29/17 21:11:58.412][DEBUG] [Sequence Thread] EventMarker 10 - t:M13 (0); e:0; f:1; o: [08/29/17 21:11:58.412][DEBUG] [Sequence Thread] Finished sending frame capture. Entering wait mode... [08/29/17 21:11:58.412][DEBUG] [Sequence Thread] EventMarker 11 - t:M13 (0); e:0; f:1; o: [08/29/17 21:11:58.413][DEBUG] [Sequence Thread] EventMarker 12 - t:M13 (0); e:0; f:1; o: [08/29/17 21:11:58.414][DEBUG] [Sequence Thread] EventMarker 13 - t:M13 (0); e:0; f:1; o: [08/29/17 21:11:58.423][DEBUG] [Camera Thread] SGM_CAMERA_CAPTURE message received... [08/29/17 21:11:58.444][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 21:11:58.444][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 21:11:59.242][DEBUG] [PHD2 Listener Thread] PHD2: Settle done received: GOOD [08/29/17 21:13:15.004][DEBUG] [Main Thread] PopulateDataModel: Transferring view to the data model... [08/29/17 21:13:15.018][DEBUG] [MF Update Thread] Performing serialize... [08/29/17 21:16:58.571][DEBUG] [Sequence Thread] EventMarker 15 - t:M13 (0); e:0; f:1; o: [08/29/17 21:16:58.573][DEBUG] [Sequence Thread] Waking from exposure time sleep period... [08/29/17 21:16:58.574][DEBUG] [Sequence Thread] Checking to see if the CCD has a temp... [08/29/17 21:16:58.574][DEBUG] [Sequence Thread] Saving the CCD temp... [08/29/17 21:16:58.593][DEBUG] [Sequence Thread] Checking PHD2 state... [08/29/17 21:16:58.593][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Pre-Wait : Guiding [08/29/17 21:16:58.593][DEBUG] [Sequence Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [08/29/17 21:16:58.695][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Post-Wait: Guiding [08/29/17 21:17:14.058][DEBUG] [Camera Thread] QSI expose and download: 315.5494087s [08/29/17 21:17:14.058][DEBUG] [Camera Thread] SGM_CAMERA_CAPTURE complete... [08/29/17 21:17:14.189][DEBUG] [Sequence Thread] Image ready state detected when waiting for download state, continuing sequence... [08/29/17 21:17:14.189][DEBUG] [Sequence Thread] Entering super dangerous loop to await image completion... [08/29/17 21:17:14.196][DEBUG] [Sequence Thread] EventMarker 16 - t:M13 (0); e:0; f:1; o: [08/29/17 21:17:14.213][DEBUG] [Sequence Thread] EventMarker 17 - t:M13 (0); e:0; f:1; o: [08/29/17 21:17:14.213][DEBUG] [Sequence Thread] Image reported as complete. Continuing... [08/29/17 21:17:14.215][DEBUG] [Sequence Thread] Collecting FITs headers... [08/29/17 21:17:14.216][DEBUG] [Sequence Thread] QSI Camera - Camera reports last image start time as: 2017-08-30T04:12:01.164 UTC [08/29/17 21:17:14.216][DEBUG] [Sequence Thread] DATE-LOC time provided by camera... [08/29/17 21:17:14.223][DEBUG] [Sequence Thread] GatherFitsHeaders: Writing header info from UI... [08/29/17 21:17:14.232][DEBUG] [Sequence Thread] Clearing timed monitoring events... [08/29/17 21:17:14.237][DEBUG] [Sequence Thread] Created full file name (file does not exist): C:\Users\Public\Documents\SGP Images\Light\M13_300sec_1x1_B_frame2.fit [08/29/17 21:17:14.293][DEBUG] [Sequence Thread] SaveFileBmp16: Locking bitmap for disk write... [08/29/17 21:17:14.293][DEBUG] [Sequence Thread] SaveFileBmp16: Opening file stream... [08/29/17 21:17:14.293][DEBUG] [Sequence Thread] SaveFileBmp16: Writing FITS headers... [08/29/17 21:17:14.294][DEBUG] [Sequence Thread] SaveFileBmp16: Traversing and persisting bitmap data... [08/29/17 21:17:14.375][DEBUG] [Sequence Thread] SaveFileBmp16: FITS block fill... [08/29/17 21:17:14.375][DEBUG] [Sequence Thread] SaveFileBmp16: Unlocking bitmap... [08/29/17 21:17:14.375][DEBUG] [Sequence Thread] SaveFileBmp16: Closing file stream [08/29/17 21:17:14.380][DEBUG] [Sequence Thread] Internal Interface: Set Preview... [08/29/17 21:17:14.380][DEBUG] [Sequence Thread] Display image preview using asynch task... [08/29/17 21:17:14.799][DEBUG] [Sequence Thread] =========== Save file took 566 ms [08/29/17 21:17:14.805][DEBUG] [Sequence Thread] Resuming auto guiding (settling)... [08/29/17 21:17:14.805][DEBUG] [Sequence Thread] Checking PHD2 state... [08/29/17 21:17:14.805][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Pre-Wait : Guiding [08/29/17 21:17:14.805][DEBUG] [Sequence Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [08/29/17 21:17:14.906][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Post-Wait: Guiding [08/29/17 21:17:14.906][DEBUG] [Sequence Thread] PHD2: Requested unpause, but PHD2 reports it is not in a paused state [08/29/17 21:17:14.906][DEBUG] [Sequence Thread] EventMarker 18 - t:M13 (0); e:0; f:1; o: [08/29/17 21:17:14.910][DEBUG] [Sequence Thread] EventMarker 19 - t:M13 (0); e:0; f:2; o: [08/29/17 21:17:14.910][DEBUG] [Sequence Thread] EventMarker 20 - t:M13 (0); e:0; f:2; o: [08/29/17 21:17:14.910][DEBUG] [Sequence Thread] Running post event... [08/29/17 21:17:14.910][DEBUG] [Sequence Thread] EventMarker 21 - t:M13 (0); e:0; f:2; o: [08/29/17 21:17:14.911][DEBUG] [Sequence Thread] Incremented numComplete (2) for event[0]... [08/29/17 21:17:14.914][DEBUG] [Sequence Thread] Attempting to find next event... [08/29/17 21:17:14.914][DEBUG] [Sequence Thread] Current event[0] frame count: 2/6... [08/29/17 21:17:14.914][DEBUG] [Sequence Thread] Current event event[0] has remaining frames. Returning current event. [08/29/17 21:17:14.914][DEBUG] [Sequence Thread] Checking for auto manual focus (post)... [08/29/17 21:17:14.914][DEBUG] [Main Thread] PopulateDataModel: Transferring view to the data model... [08/29/17 21:17:14.921][DEBUG] [Sequence Thread] Attempting to move to next event... [08/29/17 21:17:14.921][DEBUG] [Sequence Thread] Attempting to find next event... [08/29/17 21:17:14.921][DEBUG] [Sequence Thread] Current event[0] frame count: 2/6... [08/29/17 21:17:14.921][DEBUG] [Sequence Thread] Current event event[0] has remaining frames. Returning current event. [08/29/17 21:17:14.921][DEBUG] [Sequence Thread] Running pre event... [08/29/17 21:17:14.963][DEBUG] [Sequence Thread] Running capture event... [08/29/17 21:17:14.969][DEBUG] [Sequence Thread] SetStartTemp: Using focuser for temperature... [08/29/17 21:17:14.969][DEBUG] [Sequence Thread] Set start frame temp to 100... [08/29/17 21:17:14.970][DEBUG] [Sequence Thread] ------------- Starting capture frame for event[0] ------------- [08/29/17 21:17:14.974][DEBUG] [Sequence Thread] Sending commands... [08/29/17 21:17:14.974][DEBUG] [Sequence Thread] SetFlatBox: Frame Type is Light [08/29/17 21:17:14.974][DEBUG] [Sequence Thread] EventMarker 2 - t:M13 (0); e:0; f:2; o: [08/29/17 21:17:14.974][DEBUG] [Sequence Thread] EventMarker 3 - t:M13 (0); e:0; f:2; o: [08/29/17 21:17:14.974][DEBUG] [Sequence Thread] Waiting for AF to complete... [08/29/17 21:17:14.983][DEBUG] [Camera Thread] SGM_FOCUSER_AUTO_FOCUS message received... [08/29/17 21:17:14.983][DEBUG] [Camera Thread] Checking for auto focus... [08/29/17 21:17:14.983][DEBUG] [Camera Thread] Auto focus required (frame count trigger)... [08/29/17 21:17:14.988][DEBUG] [Camera Thread] Auto focus: setting filter Blue [08/29/17 21:17:14.988][DEBUG] [Camera Thread] Setting filter position 4... [08/29/17 21:17:14.988][DEBUG] [Camera Thread] Filter position 4 is already set. Skipping... [08/29/17 21:17:14.990][DEBUG] [Camera Thread] Auto focus running... [08/29/17 21:17:14.994][DEBUG] [Camera Thread] Turning temp comp off.. [08/29/17 21:17:15.008][DEBUG] [Camera Thread] Performing Sequence Generator auto focus using Half Flux Radius. AFID: 3 [08/29/17 21:17:15.080][DEBUG] [AfChartThread] Showing AF chart dialog... [08/29/17 21:17:15.168][DEBUG] [Camera Thread] Auto focus data [08/29/17 21:17:15.168][DEBUG] [Camera Thread] - Data Points: 7 [08/29/17 21:17:15.168][DEBUG] [Camera Thread] - Step Size: 10 [08/29/17 21:17:15.168][DEBUG] [Camera Thread] - Current Position: 7918 [08/29/17 21:17:15.168][DEBUG] [Camera Thread] - Initial Move Position: 7948 [08/29/17 21:17:15.171][DEBUG] [Camera Thread] AF Darks exception: The path is not of a legal form. [08/29/17 21:17:15.172][DEBUG] [Camera Thread] Moving focuser to next position (7948)... [08/29/17 21:17:15.173][DEBUG] [Focuser Move Thread] Focuser moving to 7948 [08/29/17 21:17:15.229][DEBUG] [Focuser Move Thread] Focuser move call complete [08/29/17 21:17:16.284][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7948 matches requested position 7948 [08/29/17 21:17:16.284][DEBUG] [Camera Thread] Focuser move complete... [08/29/17 21:17:16.338][DEBUG] [Camera Thread] Focuser position matches requested position (7948), continuing... [08/29/17 21:17:16.393][DEBUG] [Camera Thread] Calculating step metric... [08/29/17 21:17:16.393][DEBUG] [Camera Thread] Taking auto focus frame(s)... [08/29/17 21:17:16.395][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [08/29/17 21:17:16.395][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [08/29/17 21:17:16.395][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 2 seconds... [08/29/17 21:17:16.395][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [08/29/17 21:17:16.429][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 21:17:16.429][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 21:17:32.759][DEBUG] [Main Thread] PopulateDataModel: Transferring view to the data model... [08/29/17 21:17:32.772][DEBUG] [MF Update Thread] Performing serialize... [08/29/17 21:17:34.062][DEBUG] [Camera Thread] QSI expose and download: 17.5687862s [08/29/17 21:17:34.247][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 21:17:34.247][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 21:17:34.391][DEBUG] [Main Thread] --> Find stars (normal) [08/29/17 21:17:34.451][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [08/29/17 21:17:37.163][DEBUG] [Main Thread] Star detection using min star size of 4px... [08/29/17 21:17:37.163][DEBUG] [Main Thread] Star detection using max star size of 80px... [08/29/17 21:17:37.224][DEBUG] [Main Thread] Find stars took: 2832 ms... [08/29/17 21:17:37.224][DEBUG] [Main Thread] Star list contains 34 stars... [08/29/17 21:17:37.224][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [08/29/17 21:17:37.381][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.54 [08/29/17 21:17:38.392][DEBUG] [Camera Thread] Moving focuser to next position (7938)... [08/29/17 21:17:38.392][DEBUG] [Focuser Move Thread] Focuser moving to 7938 [08/29/17 21:17:38.454][DEBUG] [Focuser Move Thread] Focuser move call complete [08/29/17 21:17:39.509][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7938 matches requested position 7938 [08/29/17 21:17:39.509][DEBUG] [Camera Thread] Focuser move complete... [08/29/17 21:17:39.564][DEBUG] [Camera Thread] Focuser position matches requested position (7938), continuing... [08/29/17 21:17:39.618][DEBUG] [Camera Thread] Calculating step metric... [08/29/17 21:17:39.618][DEBUG] [Camera Thread] Taking auto focus frame(s)... [08/29/17 21:17:39.677][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [08/29/17 21:17:39.677][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [08/29/17 21:17:39.677][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 2 seconds... [08/29/17 21:17:39.677][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [08/29/17 21:17:39.710][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 21:17:39.710][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 21:17:57.329][DEBUG] [Camera Thread] QSI expose and download: 17.5542745s [08/29/17 21:17:57.525][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 21:17:57.525][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 21:17:57.740][DEBUG] [Main Thread] --> Find stars (normal) [08/29/17 21:17:57.836][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [08/29/17 21:18:00.472][DEBUG] [Main Thread] Star detection using min star size of 4px... [08/29/17 21:18:00.472][DEBUG] [Main Thread] Star detection using max star size of 80px... [08/29/17 21:18:00.537][DEBUG] [Main Thread] Find stars took: 2796 ms... [08/29/17 21:18:00.537][DEBUG] [Main Thread] Star list contains 50 stars... [08/29/17 21:18:00.537][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [08/29/17 21:18:00.837][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.01 [08/29/17 21:18:01.845][DEBUG] [Camera Thread] Moving focuser to next position (7928)... [08/29/17 21:18:01.846][DEBUG] [Focuser Move Thread] Focuser moving to 7928 [08/29/17 21:18:01.903][DEBUG] [Focuser Move Thread] Focuser move call complete [08/29/17 21:18:02.958][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7928 matches requested position 7928 [08/29/17 21:18:02.958][DEBUG] [Camera Thread] Focuser move complete... [08/29/17 21:18:03.014][DEBUG] [Camera Thread] Focuser position matches requested position (7928), continuing... [08/29/17 21:18:03.069][DEBUG] [Camera Thread] Calculating step metric... [08/29/17 21:18:03.069][DEBUG] [Camera Thread] Taking auto focus frame(s)... [08/29/17 21:18:03.070][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [08/29/17 21:18:03.070][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [08/29/17 21:18:03.070][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 2 seconds... [08/29/17 21:18:03.070][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [08/29/17 21:18:03.104][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 21:18:03.104][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 21:18:20.705][DEBUG] [Camera Thread] QSI expose and download: 17.5373856s [08/29/17 21:18:20.801][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 21:18:20.801][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 21:18:21.027][DEBUG] [Main Thread] --> Find stars (normal) [08/29/17 21:18:21.131][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [08/29/17 21:18:23.343][DEBUG] [Main Thread] Star detection using min star size of 4px... [08/29/17 21:18:23.343][DEBUG] [Main Thread] Star detection using max star size of 80px... [08/29/17 21:18:23.391][DEBUG] [Main Thread] Find stars took: 2365 ms... [08/29/17 21:18:23.391][DEBUG] [Main Thread] Star list contains 71 stars... [08/29/17 21:18:23.391][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [08/29/17 21:18:23.649][DEBUG] [Camera Thread] Auto focus HFR calculated at: 2.35 [08/29/17 21:18:24.656][DEBUG] [Camera Thread] Moving focuser to next position (7918)... [08/29/17 21:18:24.656][DEBUG] [Focuser Move Thread] Focuser moving to 7918 [08/29/17 21:18:24.713][DEBUG] [Focuser Move Thread] Focuser move call complete [08/29/17 21:18:25.768][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7918 matches requested position 7918 [08/29/17 21:18:25.768][DEBUG] [Camera Thread] Focuser move complete... [08/29/17 21:18:25.824][DEBUG] [Camera Thread] Focuser position matches requested position (7918), continuing... [08/29/17 21:18:25.879][DEBUG] [Camera Thread] Calculating step metric... [08/29/17 21:18:25.879][DEBUG] [Camera Thread] Taking auto focus frame(s)... [08/29/17 21:18:25.883][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [08/29/17 21:18:25.883][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [08/29/17 21:18:25.883][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 2 seconds... [08/29/17 21:18:25.883][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [08/29/17 21:18:25.920][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 21:18:25.920][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 21:18:43.511][DEBUG] [Camera Thread] QSI expose and download: 17.5257966s [08/29/17 21:18:43.606][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 21:18:43.606][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 21:18:43.826][DEBUG] [Main Thread] --> Find stars (normal) [08/29/17 21:18:43.919][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [08/29/17 21:18:46.142][DEBUG] [Main Thread] Star detection using min star size of 4px... [08/29/17 21:18:46.142][DEBUG] [Main Thread] Star detection using max star size of 80px... [08/29/17 21:18:46.190][DEBUG] [Main Thread] Find stars took: 2364 ms... [08/29/17 21:18:46.190][DEBUG] [Main Thread] Star list contains 89 stars... [08/29/17 21:18:46.190][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [08/29/17 21:18:46.458][DEBUG] [Camera Thread] Auto focus HFR calculated at: 2.13 [08/29/17 21:18:47.466][DEBUG] [Camera Thread] Moving focuser to next position (7908)... [08/29/17 21:18:47.467][DEBUG] [Focuser Move Thread] Focuser moving to 7908 [08/29/17 21:18:47.524][DEBUG] [Focuser Move Thread] Focuser move call complete [08/29/17 21:18:48.579][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7908 matches requested position 7908 [08/29/17 21:18:48.579][DEBUG] [Camera Thread] Focuser move complete... [08/29/17 21:18:48.634][DEBUG] [Camera Thread] Focuser position matches requested position (7908), continuing... [08/29/17 21:18:48.690][DEBUG] [Camera Thread] Calculating step metric... [08/29/17 21:18:48.690][DEBUG] [Camera Thread] Taking auto focus frame(s)... [08/29/17 21:18:48.693][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [08/29/17 21:18:48.693][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [08/29/17 21:18:48.693][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 2 seconds... [08/29/17 21:18:48.693][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [08/29/17 21:18:48.722][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 21:18:48.722][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 21:19:06.309][DEBUG] [Camera Thread] QSI expose and download: 17.5232052s [08/29/17 21:19:06.450][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 21:19:06.450][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 21:19:06.700][DEBUG] [Main Thread] --> Find stars (normal) [08/29/17 21:19:06.796][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [08/29/17 21:19:09.437][DEBUG] [Main Thread] Star detection using min star size of 4px... [08/29/17 21:19:09.437][DEBUG] [Main Thread] Star detection using max star size of 80px... [08/29/17 21:19:09.523][DEBUG] [Main Thread] Find stars took: 2823 ms... [08/29/17 21:19:09.523][DEBUG] [Main Thread] Star list contains 93 stars... [08/29/17 21:19:09.523][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [08/29/17 21:19:09.820][DEBUG] [Camera Thread] Auto focus HFR calculated at: 1.88 [08/29/17 21:19:10.828][DEBUG] [Camera Thread] Moving focuser to next position (7898)... [08/29/17 21:19:10.829][DEBUG] [Focuser Move Thread] Focuser moving to 7898 [08/29/17 21:19:10.885][DEBUG] [Focuser Move Thread] Focuser move call complete [08/29/17 21:19:11.993][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7898 matches requested position 7898 [08/29/17 21:19:11.993][DEBUG] [Camera Thread] Focuser move complete... [08/29/17 21:19:12.101][DEBUG] [Camera Thread] Focuser position matches requested position (7898), continuing... [08/29/17 21:19:12.155][DEBUG] [Camera Thread] Calculating step metric... [08/29/17 21:19:12.155][DEBUG] [Camera Thread] Taking auto focus frame(s)... [08/29/17 21:19:12.159][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [08/29/17 21:19:12.159][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [08/29/17 21:19:12.159][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 2 seconds... [08/29/17 21:19:12.159][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [08/29/17 21:19:12.191][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 21:19:12.191][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 21:19:29.791][DEBUG] [Camera Thread] QSI expose and download: 17.5364874s [08/29/17 21:19:29.963][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 21:19:29.963][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 21:19:30.172][DEBUG] [Main Thread] --> Find stars (normal) [08/29/17 21:19:30.271][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [08/29/17 21:19:32.564][DEBUG] [Main Thread] Star detection using min star size of 4px... [08/29/17 21:19:32.564][DEBUG] [Main Thread] Star detection using max star size of 80px... [08/29/17 21:19:32.619][DEBUG] [Main Thread] Find stars took: 2446 ms... [08/29/17 21:19:32.619][DEBUG] [Main Thread] Star list contains 82 stars... [08/29/17 21:19:32.619][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [08/29/17 21:19:32.878][DEBUG] [Camera Thread] Auto focus HFR calculated at: 2.19 [08/29/17 21:19:33.889][DEBUG] [Camera Thread] Moving focuser to next position (7888)... [08/29/17 21:19:33.889][DEBUG] [Focuser Move Thread] Focuser moving to 7888 [08/29/17 21:19:33.946][DEBUG] [Focuser Move Thread] Focuser move call complete [08/29/17 21:19:35.001][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7888 matches requested position 7888 [08/29/17 21:19:35.001][DEBUG] [Camera Thread] Focuser move complete... [08/29/17 21:19:35.056][DEBUG] [Camera Thread] Focuser position matches requested position (7888), continuing... [08/29/17 21:19:35.166][DEBUG] [Camera Thread] Calculating step metric... [08/29/17 21:19:35.166][DEBUG] [Camera Thread] Taking auto focus frame(s)... [08/29/17 21:19:35.171][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [08/29/17 21:19:35.171][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [08/29/17 21:19:35.171][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 2 seconds... [08/29/17 21:19:35.171][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [08/29/17 21:19:35.199][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 21:19:35.199][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 21:19:52.830][DEBUG] [Camera Thread] QSI expose and download: 17.5351934s [08/29/17 21:19:53.036][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 21:19:53.036][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 21:19:53.266][DEBUG] [Main Thread] --> Find stars (normal) [08/29/17 21:19:53.367][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [08/29/17 21:19:55.633][DEBUG] [Main Thread] Star detection using min star size of 4px... [08/29/17 21:19:55.633][DEBUG] [Main Thread] Star detection using max star size of 80px... [08/29/17 21:19:55.691][DEBUG] [Main Thread] Find stars took: 2425 ms... [08/29/17 21:19:55.691][DEBUG] [Main Thread] Star list contains 64 stars... [08/29/17 21:19:55.691][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [08/29/17 21:19:55.991][DEBUG] [Camera Thread] Auto focus HFR calculated at: 2.64 [08/29/17 21:19:57.003][DEBUG] [Camera Thread] Moving focuser to next position (7878)... [08/29/17 21:19:57.003][DEBUG] [Focuser Move Thread] Focuser moving to 7878 [08/29/17 21:19:57.060][DEBUG] [Focuser Move Thread] Focuser move call complete [08/29/17 21:19:58.114][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7878 matches requested position 7878 [08/29/17 21:19:58.114][DEBUG] [Camera Thread] Focuser move complete... [08/29/17 21:19:58.169][DEBUG] [Camera Thread] Focuser position matches requested position (7878), continuing... [08/29/17 21:19:58.225][DEBUG] [Camera Thread] Calculating step metric... [08/29/17 21:19:58.225][DEBUG] [Camera Thread] Taking auto focus frame(s)... [08/29/17 21:19:58.226][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [08/29/17 21:19:58.226][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [08/29/17 21:19:58.227][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 2 seconds... [08/29/17 21:19:58.227][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [08/29/17 21:19:58.254][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 21:19:58.254][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 21:20:15.855][DEBUG] [Camera Thread] QSI expose and download: 17.5375511s [08/29/17 21:20:16.043][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 21:20:16.043][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 21:20:16.327][DEBUG] [Main Thread] --> Find stars (normal) [08/29/17 21:20:16.406][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [08/29/17 21:20:19.037][DEBUG] [Main Thread] Star detection using min star size of 4px... [08/29/17 21:20:19.037][DEBUG] [Main Thread] Star detection using max star size of 80px... [08/29/17 21:20:19.099][DEBUG] [Main Thread] Find stars took: 2771 ms... [08/29/17 21:20:19.099][DEBUG] [Main Thread] Star list contains 45 stars... [08/29/17 21:20:19.099][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [08/29/17 21:20:19.367][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.25 [08/29/17 21:20:20.374][DEBUG] [Camera Thread] New auto focus method calculated focus at: 7909 [08/29/17 21:20:20.374][DEBUG] [Camera Thread] Old auto focus method calculated focus at: 7908 [08/29/17 21:20:20.374][DEBUG] [Camera Thread] Using new auto focus method for focuser position [08/29/17 21:20:20.374][DEBUG] [Camera Thread] New focus position is at 7909 (@100.00C). Moving focuser... [08/29/17 21:20:20.375][DEBUG] [Focuser Move Thread] Focuser moving to 7909 [08/29/17 21:20:20.431][DEBUG] [Focuser Move Thread] Focuser move call complete [08/29/17 21:20:21.486][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7909 matches requested position 7909 [08/29/17 21:20:21.486][DEBUG] [Camera Thread] Focuser move complete... [08/29/17 21:20:21.542][DEBUG] [Camera Thread] Focuser successfully set to focus position (7909) [08/29/17 21:20:21.597][DEBUG] [Camera Thread] Taking validation frame at focus position... [08/29/17 21:20:21.599][DEBUG] [Camera Thread] Taking auto focus frame(s)... [08/29/17 21:20:21.601][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [08/29/17 21:20:21.601][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [08/29/17 21:20:21.601][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 2 seconds... [08/29/17 21:20:21.602][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [08/29/17 21:20:21.631][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 21:20:21.631][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 21:20:36.291][DEBUG] [PHD2 Listener Thread] Unknown object from PHD2: {"Event":"GuideParamChange","Timestamp":1504066836.240,"Host":"OBSERVATORY-PC","Inst":1,"Name":"Dec Max Duration","Value":2924} [08/29/17 21:20:39.215][DEBUG] [Camera Thread] QSI expose and download: 17.5210591s [08/29/17 21:20:39.325][DEBUG] [Camera Thread] Internal Interface: Set Preview... [08/29/17 21:20:39.325][DEBUG] [Camera Thread] Display image preview using asynch task... [08/29/17 21:20:39.530][DEBUG] [Main Thread] --> Find stars (normal) [08/29/17 21:20:39.617][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [08/29/17 21:20:41.833][DEBUG] [Main Thread] Star detection using min star size of 4px... [08/29/17 21:20:41.833][DEBUG] [Main Thread] Star detection using max star size of 80px... [08/29/17 21:20:41.888][DEBUG] [Main Thread] Find stars took: 2357 ms... [08/29/17 21:20:41.888][DEBUG] [Main Thread] Star list contains 84 stars... [08/29/17 21:20:41.888][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [08/29/17 21:20:42.147][DEBUG] [Camera Thread] Auto focus HFR calculated at: 2.12 [08/29/17 21:20:43.154][DEBUG] [Camera Thread] Auto focus is complete (best fit method: 7909; HFR 2.11)... [08/29/17 21:20:43.160][DEBUG] [Camera Thread] Entering chart disposal loop... [08/29/17 21:21:13.189][DEBUG] [Camera Thread] Auto closing chart dialog... [08/29/17 21:21:13.189][DEBUG] [Camera Thread] Auto focus (finally): closing dialog... [08/29/17 21:21:13.207][DEBUG] [AfChartThread] AF chart dialog is closed... [08/29/17 21:21:13.263][DEBUG] [Camera Thread] Auto focus complete... [08/29/17 21:21:13.295][DEBUG] [Camera Thread] SGM_FOCUSER_AUTO_FOCUS complete... [08/29/17 21:21:13.340][DEBUG] [Sequence Thread] AF is complete, continuing sequence... [08/29/17 21:21:13.350][DEBUG] [Sequence Thread] EventMarker 4 - t:M13 (0); e:0; f:2; o: [08/29/17 21:21:13.350][DEBUG] [Sequence Thread] Setting filter position 4... [08/29/17 21:21:13.350][DEBUG] [Sequence Thread] Filter position 4 is already set. Skipping... [08/29/17 21:21:13.350][DEBUG] [Sequence Thread] EventMarker 5 - t:M13 (0); e:0; f:2; o: [08/29/17 21:21:13.350][DEBUG] [Sequence Thread] Checking for auto manual focus (pre)... [08/29/17 21:21:13.350][DEBUG] [Sequence Thread] EventMarker 6 - t:M13 (0); e:0; f:2; o: [08/29/17 21:21:13.409][DEBUG] [Sequence Thread] Between frame delay found. Sleeping for 2 seconds... [08/29/17 21:21:15.421][DEBUG] [Sequence Thread] Created base name for frame capture: M13_300sec_1x1_B_frame3 [08/29/17 21:21:15.421][DEBUG] [Sequence Thread] Created directory for frame capture: C:\Users\Public\Documents\SGP Images\Light\ [08/29/17 21:21:15.421][DEBUG] [Sequence Thread] EventMarker 1 - t:M13 (0); e:0; f:2; o: [08/29/17 21:21:15.421][DEBUG] [Sequence Thread] EventMarker 7 - t:M13 (0); e:0; f:2; o: [08/29/17 21:21:15.421][DEBUG] [Sequence Thread] Resuming auto guiding (settling)... [08/29/17 21:21:15.421][DEBUG] [Sequence Thread] Checking PHD2 state... [08/29/17 21:21:15.421][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Pre-Wait : Guiding [08/29/17 21:21:15.421][DEBUG] [Sequence Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [08/29/17 21:21:15.523][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Post-Wait: Guiding [08/29/17 21:21:15.523][DEBUG] [Sequence Thread] PHD2: Requested unpause, but PHD2 reports it is not in a paused state [08/29/17 21:21:15.553][DEBUG] [Sequence Thread] {"method": "guide", "params": [{"pixels": 0.6, "time": 0, "timeout": 600}, false ], "id": 1003} [08/29/17 21:21:15.553][DEBUG] [Sequence Thread] Sending to PHD2: {"method": "guide", "params": [{"pixels": 0.6, "time": 0, "timeout": 600}, false ], "id": 1003} [08/29/17 21:21:15.553][DEBUG] [Sequence Thread] Checking PHD2 state... [08/29/17 21:21:15.553][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Pre-Wait : Guiding [08/29/17 21:21:15.553][DEBUG] [Sequence Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [08/29/17 21:21:15.655][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Post-Wait: Guiding [08/29/17 21:21:15.655][DEBUG] [Sequence Thread] User has elected to skip guider settling over time... [08/29/17 21:21:15.655][DEBUG] [Sequence Thread] Waiting for Auto Guider distance to fall below 0.6 [08/29/17 21:21:15.655][DEBUG] [Sequence Thread] PHD2 distance request... [08/29/17 21:21:15.655][DEBUG] [Sequence Thread] PHD2 distance request returned 0.6... [08/29/17 21:21:15.658][DEBUG] [Sequence Thread] Distance is below 0.6, starting timer... [08/29/17 21:21:16.159][DEBUG] [Sequence Thread] PHD2 distance request... [08/29/17 21:21:16.159][DEBUG] [Sequence Thread] PHD2 distance request returned 0.6... [08/29/17 21:21:16.166][DEBUG] [Sequence Thread] Distance stayed below 0.6 for 0 seconds, done settling... [08/29/17 21:21:16.167][DEBUG] [Sequence Thread] Auto guider has settled... [08/29/17 21:21:16.167][DEBUG] [Sequence Thread] EventMarker 8 - t:M13 (0); e:0; f:2; o: [08/29/17 21:21:16.167][DEBUG] [Sequence Thread] EventMarker 9 - t:M13 (0); e:0; f:2; o: [08/29/17 21:21:16.167][DEBUG] [Sequence Thread] EventMarker 10 - t:M13 (0); e:0; f:2; o: [08/29/17 21:21:16.167][DEBUG] [Sequence Thread] Finished sending frame capture. Entering wait mode... [08/29/17 21:21:16.167][DEBUG] [Sequence Thread] EventMarker 11 - t:M13 (0); e:0; f:2; o: [08/29/17 21:21:16.169][DEBUG] [Sequence Thread] EventMarker 12 - t:M13 (0); e:0; f:2; o: [08/29/17 21:21:16.170][DEBUG] [Sequence Thread] EventMarker 13 - t:M13 (0); e:0; f:2; o: [08/29/17 21:21:16.171][DEBUG] [Camera Thread] SGM_CAMERA_CAPTURE message received... [08/29/17 21:21:16.206][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [08/29/17 21:21:16.206][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [08/29/17 21:21:17.347][DEBUG] [PHD2 Listener Thread] PHD2: Settle done received: GOOD [08/29/17 21:24:58.390][DEBUG] [PHD2 Listener Thread] Unknown object from PHD2: {"Event":"GuideParamChange","Timestamp":1504067098.357,"Host":"OBSERVATORY-PC","Inst":1,"Name":"Dec Max Duration","Value":3045} [08/29/17 21:25:45.591][DEBUG] [PHD2 Listener Thread] Unknown object from PHD2: {"Event":"GuideParamChange","Timestamp":1504067145.578,"Host":"OBSERVATORY-PC","Inst":1,"Name":"Dec Max Duration","Value":3138} [08/29/17 21:26:31.830][DEBUG] [Camera Thread] QSI expose and download: 315.5602113s [08/29/17 21:26:31.830][DEBUG] [Camera Thread] SGM_CAMERA_CAPTURE complete... [08/29/17 21:29:16.207][DEBUG] [Monitoring System Timed Message Thread] Adding sequence level notification: Failure while integrating M13; Event 1; Frame 3 for 300s. Image has not downloaded in alloted time period. [08/29/17 21:29:50.038][DEBUG] [PHD2 Listener Thread] Unknown object from PHD2: {"Event":"GuideParamChange","Timestamp":1504067390.019,"Host":"OBSERVATORY-PC","Inst":1,"Name":"DEC Resist switch aggression","Value":65} [08/29/17 21:29:50.795][DEBUG] [PHD2 Listener Thread] Unknown object from PHD2: {"Event":"GuideParamChange","Timestamp":1504067390.761,"Host":"OBSERVATORY-PC","Inst":1,"Name":"DEC Resist switch aggression","Value":60} [08/29/17 21:39:54.113][DEBUG] [PHD2 Listener Thread] Unknown object from PHD2: {"Event":"GuideParamChange","Timestamp":1504067994.066,"Host":"OBSERVATORY-PC","Inst":1,"Name":"Dec Max Duration","Value":3219}