[09/09/17 20:45:13.423][DEBUG] [Main Thread] Licensing: Machine ID found... [09/09/17 20:45:13.479][DEBUG] [Main Thread] Checking for valid internet connection... [09/09/17 20:45:13.608][DEBUG] [Main Thread] Valid internet connection detected... [09/09/17 20:45:14.630][DEBUG] [Main Thread] Licensing: Request for licenses successful, writing cookie... [09/09/17 20:45:14.640][DEBUG] [Main Thread] Licensing: Applying licenses.. [09/09/17 20:45:14.640][DEBUG] [Main Thread] Licensing: Licenses applied.. [09/09/17 20:45:14.640][DEBUG] [Main Thread] SGPro retail license... [09/09/17 20:45:15.577][DEBUG] [Main Thread] API successfully started on port 59590... [09/09/17 20:45:16.145][DEBUG] [Main Thread] Error loading notification settings! Could not find file 'C:\Users\jones\AppData\Local\SequenceGenerator\Sequence Generator Pro\notifications.sgd'. [09/09/17 20:45:16.148][DEBUG] [Main Thread] Error loading GNS endpoint settings! Could not find file 'C:\Users\jones\AppData\Local\SequenceGenerator\Sequence Generator Pro\gnsSettings.sgd'. [09/09/17 20:45:16.149][DEBUG] [Main Thread] Error loading email endpoint addresses! Could not find file 'C:\Users\jones\AppData\Local\SequenceGenerator\Sequence Generator Pro\1-emailEndpointAddresses.sgd'. [09/09/17 20:45:16.149][DEBUG] [Main Thread] Error loading email endpoint addresses! Could not find file 'C:\Users\jones\AppData\Local\SequenceGenerator\Sequence Generator Pro\2-emailEndpointAddresses.sgd'. [09/09/17 20:45:16.150][DEBUG] [Main Thread] Error loading email endpoint addresses! Could not find file 'C:\Users\jones\AppData\Local\SequenceGenerator\Sequence Generator Pro\3-emailEndpointAddresses.sgd'. [09/09/17 20:45:16.150][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'. [09/09/17 20:45:16.150][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'. [09/09/17 20:45:16.150][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'. [09/09/17 20:45:16.254][DEBUG] [Main Thread] ===== S E Q U E N C E G E N E R A T O R (v2.6.0.23) ===== [09/09/17 20:45:16.291][DEBUG] [Main Thread] OS: Microsoft Windows 10 Home [09/09/17 20:45:16.576][DEBUG] [Safety Thread] Starting safety monitor... [09/09/17 20:45:16.578][DEBUG] [Camera Thread] Entering camera message dispatch loop... [09/09/17 20:45:16.578][DEBUG] [Filter Wheel Thread] Entering Filter Wheel message dispatch loop... [09/09/17 20:45:16.579][DEBUG] [TEC Thread] Entering TEC message dispatch loop... [09/09/17 20:45:16.579][DEBUG] [Focuser Thread] Entering Fouser message dispatch loop... [09/09/17 20:45:16.579][DEBUG] [Auto Guider Error Thread] Entering Auto Guider message dispatch loop... [09/09/17 20:45:16.580][DEBUG] [Auto Guider Thread] Entering Auto Guider message dispatch loop... [09/09/17 20:45:16.580][DEBUG] [Dome Thread] Entering Dome message dispatch loop... [09/09/17 20:45:16.580][DEBUG] [Rotator Thread] Entering Rotator message dispatch loop... [09/09/17 20:45:16.581][DEBUG] [Environment Device Thread] Starting environment device monitor... [09/09/17 20:45:16.596][DEBUG] [Telescope Thread] Entering Telescope message dispatch loop... [09/09/17 20:45:16.621][DEBUG] [Main Thread] Settings changed (or loaded)... [09/09/17 20:45:16.623][DEBUG] [Main Thread] * SETTING: settingMdiAutoStretch = False [09/09/17 20:45:16.623][DEBUG] [Main Thread] * SETTING: settingIgLastFileFolder = [09/09/17 20:45:16.623][DEBUG] [Main Thread] * SETTING: settingFormScaleWarning = False [09/09/17 20:45:16.623][DEBUG] [Main Thread] * SETTING: settingRecoveryDuration = 90 [09/09/17 20:45:16.623][DEBUG] [Main Thread] * SETTING: settingSBIGLpt2Address = 278 [09/09/17 20:45:16.623][DEBUG] [Main Thread] * SETTING: settingFliFlushLow = 0 [09/09/17 20:45:16.623][DEBUG] [Main Thread] * SETTING: settingSBIGLpt1Address = 378 [09/09/17 20:45:16.623][DEBUG] [Main Thread] * SETTING: settingMdiMouseAction = Stretch [09/09/17 20:45:16.623][DEBUG] [Main Thread] * SETTING: settingLastAscomCamera = [09/09/17 20:45:16.623][DEBUG] [Main Thread] * SETTING: settingTemperhumInterval = 300 [09/09/17 20:45:16.623][DEBUG] [Main Thread] * SETTING: settingASCOMWarningShown = False [09/09/17 20:45:16.623][DEBUG] [Main Thread] * SETTING: settingPlateCamScale = 0 [09/09/17 20:45:16.623][DEBUG] [Main Thread] * SETTING: settingCanonMirrorSettle = 0 [09/09/17 20:45:16.623][DEBUG] [Main Thread] * SETTING: settingInterface = [09/09/17 20:45:16.623][DEBUG] [Main Thread] * SETTING: settingLastAscomFilterWheel = [09/09/17 20:45:16.623][DEBUG] [Main Thread] * SETTING: settingAstroArtDisplayInstructions = True [09/09/17 20:45:16.623][DEBUG] [Main Thread] * SETTING: settingMdiPhdZoom = 40 [09/09/17 20:45:16.623][DEBUG] [Main Thread] * SETTING: settingCheckForUpdatesOnStart = True [09/09/17 20:45:16.623][DEBUG] [Main Thread] * SETTING: settingMdiLastImageDir = [09/09/17 20:45:16.624][DEBUG] [Main Thread] * SETTING: settingTemperHumUserTempComp = 0 [09/09/17 20:45:16.624][DEBUG] [Main Thread] * SETTING: settingCaptureCalFramesAlways = False [09/09/17 20:45:16.624][DEBUG] [Main Thread] * SETTING: settingDelayFirst = 0 [09/09/17 20:45:16.624][DEBUG] [Main Thread] * SETTING: settingFliNormalSpeedMode = 0 [09/09/17 20:45:16.624][DEBUG] [Main Thread] * SETTING: settingFliHighSpeedMode = 0 [09/09/17 20:45:16.624][DEBUG] [Main Thread] * SETTING: settingMfPosition = {X=0,Y=0} [09/09/17 20:45:16.624][DEBUG] [Main Thread] * SETTING: settingSoundAttention = [09/09/17 20:45:16.624][DEBUG] [Main Thread] * SETTING: settingPinpointCatalog = [09/09/17 20:45:16.624][DEBUG] [Main Thread] * SETTING: settingCameraOverhead = [09/09/17 20:45:16.624][DEBUG] [Main Thread] * SETTING: settingPlateSolveOn = False [09/09/17 20:45:16.632][DEBUG] [Main Thread] * SETTING: settingAstrometryNetEndpointList = System.Collections.Specialized.StringCollection [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingSBIGGuider = None [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingQSIFanSpeed = 2 [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingNaggerText = [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingMdiPhdDisplay = RA/Dec [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingMdiShowToolStrip = True [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingMdiShowCrosshairs = True [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: WindowGeometry = [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingIgRenameStringLocation = 2 [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingIgSubFolders = False [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: WindowGeometrySearch = [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingOffset = -1 [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingAutoStretchLevel = Low [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingTemperHumUnits = C [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingProfileDirectory = [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingBasename = [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingHfrColor = Color [RoyalBlue] [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingNikonAfBinning = 1 [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingSBIGFanOffWithTec = False [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingLastAscomSafetyMonitor = [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingMdiSize = {Width=800, Height=600} [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingIgCopyAndRenameFiles = False [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingSimDownloadTimesAf = False [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingNagger = False [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingImportFromCfw = True [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingMdiShowStatusTrip = True [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingShowTooltipHelp = True [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingLastUpdateCheck = 1/1/2012 12:00:00 AM [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingEventSuffixes = null [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingCpPosition = {X=0,Y=0} [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingSBIGCfwPort = [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingMdiLocation = {X=0,Y=0} [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingIgLastCsvFolder = [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingPlateSolve3Catalogs = [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingSBIGFilterWheel = Auto Detect [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingSBIGConnection = USB [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingDSLRSoftwareBinForAf = False [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingAutoSave = True [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingFirstTime = True [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingAutoRecoveryActive = False [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingMdiState = maximized [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingUseSequenceOptions = False [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingSoundCompleted = [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingTrialExpiredShown = False [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingTemperHumColor = Grey [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingDataDirectory = [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingCanonFileFormat = Single Image (CFA FITS) [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingAlnitakComPort = [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingIgRenameFiles = False [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingPhd2InstallPath = [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingSubFolder = True [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingLastSGSaveFolder = [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingFindStars = Normal (recommended) [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingNikonDataFormat = FITS [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingPlaySounds = False [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingLastNikonType = Auto Connect [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingLastSequenceStart = 1/1/0001 12:00:00 AM [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingLastAscomTelescope = [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingMdiPhdScale = 100 [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingIgRenameType = 0 [09/09/17 20:45:16.633][DEBUG] [Main Thread] * SETTING: settingHelpLanguage = English [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: settingTemperHumUserHumidityComp = 0 [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: settingMdiPhdRaColor = Color [Blue] [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: settingLastImageSaveFolder = [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: settingIgOverwriteExisting = False [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: settingFinishFullEvent = True [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: settingMosaicOverlap = 20 [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: settingQSIFanOffWithTec = False [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: settingRecentSequences = [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: settingDefaultDirectory = [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: settingMdiPhdDecColor = Color [Red] [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: settingAstrometryNetEndpoint = Astrometry.NET (Remote) [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: settingFliFlushHigh = 0 [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: settingStarsColor = Color [OrangeRed] [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: settingDefaultMfwFov = 4.0 [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: settingSBIGLpt3Address = 3BC [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: settingLastAscomFocuser = [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: settingIgLastFolder = [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: WindowGeometryOpen = [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: settingNumEvents = 5 [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: settingDelayBetween = 0 [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: settingCanonAfBinning = 1 [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: settingPlateCamBlank = [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: settingLogApiCalls = False [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: settingNikonUseBulbModeAlways = False [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: settingTempFiles = C:\Temp [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: settingFliHighSpeedDesc = [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: settingSBIGCfwUseRS232 = False [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: settingRecoveryInterval = 10 [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: settingEnableImageHistory = False [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: settingSBIGEthernetIP = 0.0.0.0 [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: settingFliNormalSpeedDesc = [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: settingIgCopyLocation = [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: settingSBIGAutoDetectIp = True [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: settingAstroArtStartApp = True [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: settingSimDownloadTimesPlate = False [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: settingMicroTouchLocation = [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: settingEventAlwaysVisible = False [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: settingMdiNewWindow = False [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: settingTrialQuestionsAsked = False [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: settingFliRbiMitigation = False [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: settingGain = -1 [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: settingLastAscomDome = [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: settingLookForTemperDevices = False [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: settingLastAscomRotator = [09/09/17 20:45:16.634][DEBUG] [Main Thread] * SETTING: settingAfTestPackPath = [09/09/17 20:45:16.755][DEBUG] [Main Thread] ASCOM Boltwood Observing Conditions [09/09/17 20:45:16.755][DEBUG] [Main Thread] ASCOM Observing Conditions Hub (OCH) [09/09/17 20:45:16.755][DEBUG] [Main Thread] ASCOM Observing Conditions Simulator [09/09/17 20:45:16.755][DEBUG] [Main Thread] OpenWeatherMap ObservingConditions [09/09/17 20:45:16.755][DEBUG] [Main Thread] TEMPerHUM [09/09/17 20:45:16.757][DEBUG] [Main Thread] Performing initialization... [09/09/17 20:45:16.757][DEBUG] [Main Thread] Creating new sequence with default profile: Wes1 [09/09/17 20:45:16.769][DEBUG] [Main Thread] Performing full form reset (with profile Wes1)... [09/09/17 20:45:16.773][DEBUG] [Main Thread] Retreiving new equipment objects... [09/09/17 20:45:16.773][DEBUG] [Main Thread] New camera object (QSI CCD Camera) dispatched... [09/09/17 20:45:16.787][DEBUG] [Main Thread] New filter wheel object (QSI Internal Filter Wheel) dispatched... [09/09/17 20:45:16.788][DEBUG] [Main Thread] New environment device object (ASCOM Boltwood Observing Conditions) dispatched... [09/09/17 20:45:16.811][DEBUG] [Main Thread] New focuser object (RoboFocus) dispatched... [09/09/17 20:45:16.813][DEBUG] [Main Thread] New telescope object (POTH Hub) dispatched... [09/09/17 20:45:16.814][DEBUG] [Main Thread] New rotator object (No Rotator) dispatched... [09/09/17 20:45:16.815][DEBUG] [Main Thread] New dome object (SkyRoofHub) dispatched... [09/09/17 20:45:16.816][DEBUG] [Main Thread] New flat box object (No Flat Box) dispatched... [09/09/17 20:45:16.816][DEBUG] [Main Thread] New safety monitor object (ASCOM Boltwood OK to Image) dispatched... [09/09/17 20:45:16.817][DEBUG] [Main Thread] New auto guider object (PHD2) dispatched... [09/09/17 20:45:16.817][DEBUG] [Main Thread] New plate solver object (No Plate Solver) dispatched... [09/09/17 20:45:16.850][DEBUG] [Main Thread] Populating the form controls... [09/09/17 20:45:16.924][DEBUG] [Main Thread] Added row 0... [09/09/17 20:45:16.988][DEBUG] [Main Thread] Added row 1... [09/09/17 20:45:17.056][DEBUG] [Main Thread] Added row 2... [09/09/17 20:45:17.128][DEBUG] [Main Thread] Added row 3... [09/09/17 20:45:17.215][DEBUG] [Main Thread] Added row 4... [09/09/17 20:45:17.229][DEBUG] [Main Thread] Loading custom filter names... [09/09/17 20:45:17.234][DEBUG] [Main Thread] PopulateDataModel: Transferring view to the data model... [09/09/17 20:45:17.275][DEBUG] [Main Thread] Retreiving new equipment objects... [09/09/17 20:45:17.275][DEBUG] [Main Thread] No change in camera object... [09/09/17 20:45:17.275][DEBUG] [Main Thread] No change in filter wheel object... [09/09/17 20:45:17.275][DEBUG] [Main Thread] No change in environment device object... [09/09/17 20:45:17.275][DEBUG] [Main Thread] No change in focuser object... [09/09/17 20:45:17.276][DEBUG] [Main Thread] MoveFocuserAbs: Error! The focuser is no longer connected! [09/09/17 20:45:17.276][DEBUG] [Main Thread] No change in telescope object... [09/09/17 20:45:17.276][DEBUG] [Main Thread] New rotator object (No Rotator) dispatched... [09/09/17 20:45:17.277][DEBUG] [Sequence Thread] Entering sequence message dispatch loop... [09/09/17 20:45:17.278][DEBUG] [Main Thread] No change in dome object... [09/09/17 20:45:17.278][DEBUG] [Main Thread] New flat box object (No Flat Box) dispatched... [09/09/17 20:45:17.279][DEBUG] [Main Thread] No change in safety monitor object... [09/09/17 20:45:17.280][DEBUG] [Main Thread] New auto guider object (PHD2) dispatched... [09/09/17 20:45:17.283][DEBUG] [Main Thread] New plate solver object (No Plate Solver) dispatched... [09/09/17 20:45:17.289][DEBUG] [Main Thread] Checking if version check is required... [09/09/17 20:45:17.291][DEBUG] [Main Thread] Checking for updated version... [09/09/17 20:45:17.522][DEBUG] [Cleaner Thread] Running cleanup... [09/09/17 20:45:17.523][DEBUG] [Cleaner Thread] Cleaning 0 files in directory C:\Users\jones\AppData\Local\SequenceGenerator\Temp\... [09/09/17 20:45:17.523][DEBUG] [Cleaner Thread] Cleaning multi camera registration file... [09/09/17 20:45:17.523][DEBUG] [Cleaner Thread] Cleaning finished... [09/09/17 20:45:17.523][DEBUG] [Main Thread] UI layout found, loading layout at C:\Users\jones\AppData\Local\SequenceGenerator\sg_ui_config.xml [09/09/17 20:45:37.290][DEBUG] [Main Thread] Connecting camera in camera thread... [09/09/17 20:45:37.296][DEBUG] [Camera Thread] SGM_CAMERA_CONNECT message received... [09/09/17 20:45:37.296][DEBUG] [Camera Thread] Connecting QSI camera... [09/09/17 20:45:37.343][DEBUG] [Camera Thread] QSI Camera: version check... [09/09/17 20:45:37.346][DEBUG] [Camera Thread] QSI Driver Version: 7.4.1824.0(QSI - Camera not connected) [09/09/17 20:45:38.296][DEBUG] [Camera Thread] Connected to 683ws [09/09/17 20:45:38.296][DEBUG] [Camera Thread] SGM_CAMERA_CONNECT complete... [09/09/17 20:45:38.451][DEBUG] [Main Thread] Camera cooler detected... [09/09/17 20:45:38.488][DEBUG] [Main Thread] Camera cooler is OFF... [09/09/17 20:45:38.489][DEBUG] [Main Thread] Camera cooldown on connect is selected... [09/09/17 20:45:38.489][DEBUG] [Main Thread] Camera cooldown is starting... [09/09/17 20:45:38.494][DEBUG] [TEC Thread] SGM_CHANGE_COOLER_TEMP message received... [09/09/17 20:45:38.497][DEBUG] [TEC Thread] TEC Change: Starting... [09/09/17 20:45:38.497][DEBUG] [TEC Thread] TEC Change: After camera connect change, waiting ~5 seconds... [09/09/17 20:45:39.482][DEBUG] [Main Thread] Connecting ASCOM focuser... [09/09/17 20:45:39.721][DEBUG] [Main Thread] Focuser supports temperature [09/09/17 20:45:39.721][DEBUG] [Main Thread] Current temperature reports: 100 [09/09/17 20:45:39.722][DEBUG] [Main Thread] Focuser reports Max Increment as: 10000 [09/09/17 20:45:39.722][DEBUG] [Main Thread] Focuser reports max steps as: 10000 [09/09/17 20:45:39.932][DEBUG] [Telescope Thread] Telescope Dispatch loop: Received SGM_TELESCOPE_CONNECT... [09/09/17 20:45:39.994][DEBUG] [Telescope Thread] Telescope can slewing supported... [09/09/17 20:45:39.994][DEBUG] [Telescope Thread] Telescope: Does not implement MoveAxis... [09/09/17 20:45:40.004][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() [09/09/17 20:45:40.004][DEBUG] [Telescope Thread] Telescope: CanSetSideOfPier returned False [09/09/17 20:45:40.005][DEBUG] [Telescope Thread] Telescope: CanPark returned True [09/09/17 20:45:40.005][DEBUG] [Telescope Thread] Telescope equatorial system is JNOW... [09/09/17 20:45:40.006][DEBUG] [Telescope Thread] SGM_TELESCOPE_CONNECT complete... [09/09/17 20:45:40.034][DEBUG] [Main Thread] Telescope: Setting sync behavior to: Sync [09/09/17 20:45:40.041][DEBUG] [Main Thread] Connecting ASCOM dome... [09/09/17 20:45:40.082][DEBUG] [Main Thread] Dome: CanSetAzimuth is false [09/09/17 20:45:40.112][DEBUG] [Main Thread] Connecting ASCOM Safety Monitor... [09/09/17 20:45:40.331][DEBUG] [Main Thread] ASCOM EnvironmentDevice: Connected to ASCOM Boltwood Observing Conditions [09/09/17 20:45:40.332][DEBUG] [Main Thread] ASCOM EnvironmentDevice: Device Name - Boltwood ObservingConditions [09/09/17 20:45:40.334][DEBUG] [Main Thread] ASCOM EnvironmentDevice: Description - ASCOM Boltwood Observing Conditions [09/09/17 20:45:40.335][DEBUG] [Main Thread] ASCOM EnvironmentDevice: Driver Info Boltwood obseving conditions driver. Version: 6.2.5823.16903 [09/09/17 20:45:40.336][DEBUG] [Main Thread] ASCOM EnvironmentDevice: Driver Version 6.2 [09/09/17 20:45:40.346][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() [09/09/17 20:45:40.348][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() [09/09/17 20:45:40.350][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() [09/09/17 20:45:40.351][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() [09/09/17 20:45:40.356][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() [09/09/17 20:45:40.358][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() [09/09/17 20:45:42.124][DEBUG] [Auto Guider Equipment Connection Thread] Connecting to PHD2... [09/09/17 20:45:42.124][DEBUG] [Auto Guider Equipment Connection Thread] Waiting for socket connection to PHD2... [09/09/17 20:45:42.128][DEBUG] [PHD2 Listener Thread] Attempting to connect to PHD2... [09/09/17 20:45:43.125][DEBUG] [Auto Guider Equipment Connection Thread] Successfully connected to PHD2... [09/09/17 20:45:43.126][DEBUG] [Auto Guider Equipment Connection Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [09/09/17 20:45:43.126][DEBUG] [Auto Guider Equipment Connection Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [09/09/17 20:45:43.228][DEBUG] [Auto Guider Equipment Connection Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [09/09/17 20:45:43.231][DEBUG] [Auto Guider Equipment Connection Thread] PHD2: PHD2 is already open... [09/09/17 20:45:43.231][DEBUG] [Auto Guider Equipment Connection Thread] Sending to PHD2: {"method": "get_profiles", "id": 1009} [09/09/17 20:45:43.505][DEBUG] [TEC Thread] TEC Change: Attempting to turn the camera cooler on... [09/09/17 20:45:43.505][DEBUG] [TEC Thread] Turning camera cooler on... [09/09/17 20:45:43.731][DEBUG] [Auto Guider Equipment Connection Thread] Sending to PHD2: {"method":"set_connected","params":[false],"id":1007} [09/09/17 20:45:43.731][DEBUG] [Auto Guider Equipment Connection Thread] Sending to PHD2: {"method":"set_profile","params":[2],"id":1008} [09/09/17 20:45:43.731][DEBUG] [Auto Guider Equipment Connection Thread] Sending to PHD2: {"method":"set_connected","params":[true],"id":1007} [09/09/17 20:45:43.838][DEBUG] [Main Thread] Turning camera cooler on... [09/09/17 20:45:48.602][DEBUG] [TEC Thread] TEC Change: Changing temp from 16.10 to -10.00 in 360 seconds... [09/09/17 20:46:44.907][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [09/09/17 20:46:44.907][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [09/09/17 20:46:44.907][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [09/09/17 20:46:44.907][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [09/09/17 20:46:44.907][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [09/09/17 20:47:45.035][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [09/09/17 20:47:45.035][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [09/09/17 20:47:45.035][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [09/09/17 20:47:45.035][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [09/09/17 20:47:45.035][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [09/09/17 20:48:15.918][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS message received... [09/09/17 20:48:15.931][DEBUG] [Camera Thread] ASCOM (QSI) camera: frame and focus... [09/09/17 20:48:15.968][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [09/09/17 20:48:15.968][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [09/09/17 20:48:27.368][DEBUG] [Camera Thread] QSI expose and download: 11.3354678s [09/09/17 20:48:27.470][DEBUG] [Camera Thread] Internal Interface: Set Preview... [09/09/17 20:48:27.471][DEBUG] [Camera Thread] Display image preview using asynch task... [09/09/17 20:48:27.827][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS complete... [09/09/17 20:48:45.141][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [09/09/17 20:48:45.141][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [09/09/17 20:48:45.141][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [09/09/17 20:48:45.141][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [09/09/17 20:48:45.141][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [09/09/17 20:49:38.468][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS message received... [09/09/17 20:49:38.471][DEBUG] [Camera Thread] ASCOM (QSI) camera: frame and focus... [09/09/17 20:49:38.518][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [09/09/17 20:49:38.518][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [09/09/17 20:49:45.273][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [09/09/17 20:49:45.273][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [09/09/17 20:49:45.273][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [09/09/17 20:49:45.273][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [09/09/17 20:49:45.273][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [09/09/17 20:49:48.875][DEBUG] [Camera Thread] QSI expose and download: 10.292885s [09/09/17 20:49:48.993][DEBUG] [Camera Thread] Internal Interface: Set Preview... [09/09/17 20:49:48.993][DEBUG] [Camera Thread] Display image preview using asynch task... [09/09/17 20:49:49.240][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS complete... [09/09/17 20:50:10.459][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS message received... [09/09/17 20:50:10.462][DEBUG] [Camera Thread] ASCOM (QSI) camera: frame and focus... [09/09/17 20:50:10.491][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [09/09/17 20:50:10.491][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [09/09/17 20:50:29.871][DEBUG] [Camera Thread] QSI expose and download: 19.315971s [09/09/17 20:50:29.920][DEBUG] [Camera Thread] Internal Interface: Set Preview... [09/09/17 20:50:29.921][DEBUG] [Camera Thread] Display image preview using asynch task... [09/09/17 20:50:30.174][DEBUG] [Camera Thread] ASCOM (QSI) camera: frame and focus... [09/09/17 20:50:30.210][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [09/09/17 20:50:30.210][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [09/09/17 20:50:45.420][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [09/09/17 20:50:45.420][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [09/09/17 20:50:45.420][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [09/09/17 20:50:45.420][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [09/09/17 20:50:45.420][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [09/09/17 20:50:49.581][DEBUG] [Camera Thread] QSI expose and download: 19.3074063s [09/09/17 20:50:49.658][DEBUG] [Camera Thread] Internal Interface: Set Preview... [09/09/17 20:50:49.658][DEBUG] [Camera Thread] Display image preview using asynch task... [09/09/17 20:50:49.941][DEBUG] [Camera Thread] ASCOM (QSI) camera: frame and focus... [09/09/17 20:50:49.962][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [09/09/17 20:50:49.962][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [09/09/17 20:51:09.340][DEBUG] [Camera Thread] QSI expose and download: 19.313445s [09/09/17 20:51:09.411][DEBUG] [Camera Thread] Internal Interface: Set Preview... [09/09/17 20:51:09.412][DEBUG] [Camera Thread] Display image preview using asynch task... [09/09/17 20:51:09.616][DEBUG] [Camera Thread] ASCOM (QSI) camera: frame and focus... [09/09/17 20:51:09.636][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [09/09/17 20:51:09.636][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [09/09/17 20:51:29.022][DEBUG] [Camera Thread] QSI expose and download: 19.3218995s [09/09/17 20:51:29.068][DEBUG] [Camera Thread] Internal Interface: Set Preview... [09/09/17 20:51:29.068][DEBUG] [Camera Thread] Display image preview using asynch task... [09/09/17 20:51:29.305][DEBUG] [Camera Thread] ASCOM (QSI) camera: frame and focus... [09/09/17 20:51:29.339][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [09/09/17 20:51:29.339][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [09/09/17 20:51:32.059][DEBUG] [Camera Thread] ASCOM (QSI) Camera: abort message received... [09/09/17 20:51:32.107][DEBUG] [Camera Thread] ASCOM (QSI) Camera: Attempting to abort exposure... [09/09/17 20:51:32.427][DEBUG] [Camera Thread] ASCOM (QSI) Camera: Exposure aborted... [09/09/17 20:51:32.506][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS complete... [09/09/17 20:51:45.565][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [09/09/17 20:51:45.565][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [09/09/17 20:51:45.565][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [09/09/17 20:51:45.565][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [09/09/17 20:51:45.565][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [09/09/17 20:51:48.729][DEBUG] [TEC Thread] TEC Change: Complete... [09/09/17 20:51:48.810][DEBUG] [TEC Thread] SGM_CHANGE_COOLER_TEMP complete... [09/09/17 20:52:17.413][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS message received... [09/09/17 20:52:17.417][DEBUG] [Camera Thread] ASCOM (QSI) camera: frame and focus... [09/09/17 20:52:17.448][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [09/09/17 20:52:17.448][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [09/09/17 20:52:45.707][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [09/09/17 20:52:45.707][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [09/09/17 20:52:45.707][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [09/09/17 20:52:45.707][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [09/09/17 20:52:45.707][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [09/09/17 20:52:56.914][DEBUG] [Camera Thread] QSI expose and download: 39.4016415s [09/09/17 20:52:56.977][DEBUG] [Camera Thread] Internal Interface: Set Preview... [09/09/17 20:52:56.977][DEBUG] [Camera Thread] Display image preview using asynch task... [09/09/17 20:52:57.257][DEBUG] [Camera Thread] ASCOM (QSI) camera: frame and focus... [09/09/17 20:52:57.278][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [09/09/17 20:52:57.278][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [09/09/17 20:52:59.998][DEBUG] [Camera Thread] ASCOM (QSI) Camera: abort message received... [09/09/17 20:53:00.030][DEBUG] [Camera Thread] ASCOM (QSI) Camera: Attempting to abort exposure... [09/09/17 20:53:00.350][DEBUG] [Camera Thread] ASCOM (QSI) Camera: Exposure aborted... [09/09/17 20:53:00.444][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS complete... [09/09/17 20:53:45.857][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [09/09/17 20:53:45.857][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [09/09/17 20:53:45.857][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [09/09/17 20:53:45.857][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [09/09/17 20:53:45.857][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [09/09/17 20:54:38.388][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS message received... [09/09/17 20:54:38.393][DEBUG] [Camera Thread] ASCOM (QSI) camera: frame and focus... [09/09/17 20:54:38.424][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [09/09/17 20:54:38.424][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [09/09/17 20:54:45.997][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [09/09/17 20:54:45.997][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [09/09/17 20:54:45.997][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [09/09/17 20:54:45.997][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [09/09/17 20:54:45.997][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [09/09/17 20:55:46.149][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [09/09/17 20:55:46.149][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [09/09/17 20:55:46.149][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [09/09/17 20:55:46.149][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [09/09/17 20:55:46.149][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [09/09/17 20:55:47.742][DEBUG] [Camera Thread] QSI expose and download: 69.2534901s [09/09/17 20:55:47.818][DEBUG] [Camera Thread] Internal Interface: Set Preview... [09/09/17 20:55:47.818][DEBUG] [Camera Thread] Display image preview using asynch task... [09/09/17 20:55:48.080][DEBUG] [Camera Thread] ASCOM (QSI) camera: frame and focus... [09/09/17 20:55:48.108][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [09/09/17 20:55:48.108][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [09/09/17 20:55:50.828][DEBUG] [Camera Thread] ASCOM (QSI) Camera: abort message received... [09/09/17 20:55:50.876][DEBUG] [Camera Thread] ASCOM (QSI) Camera: Attempting to abort exposure... [09/09/17 20:55:51.163][DEBUG] [Camera Thread] ASCOM (QSI) Camera: Exposure aborted... [09/09/17 20:55:51.227][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS complete... [09/09/17 20:56:42.372][DEBUG] [Image Plate Solve Thread] Collecting FITs headers... [09/09/17 20:56:42.373][DEBUG] [Image Plate Solve Thread] QSI Camera - Camera reports last image start time as: 2017-09-10T03:55:50.828 UTC [09/09/17 20:56:42.373][DEBUG] [Image Plate Solve Thread] DATE-LOC time provided by camera... [09/09/17 20:56:42.382][DEBUG] [Image Plate Solve Thread] GatherFitsHeaders: Writing header info from UI... [09/09/17 20:56:42.389][DEBUG] [Image Plate Solve Thread] SaveFileBmp16: Locking bitmap for disk write... [09/09/17 20:56:42.392][DEBUG] [Image Plate Solve Thread] SaveFileBmp16: Opening file stream... [09/09/17 20:56:42.392][DEBUG] [Image Plate Solve Thread] SaveFileBmp16: Writing FITS headers... [09/09/17 20:56:42.397][DEBUG] [Image Plate Solve Thread] SaveFileBmp16: Traversing and persisting bitmap data... [09/09/17 20:56:42.425][DEBUG] [Image Plate Solve Thread] SaveFileBmp16: FITS block fill... [09/09/17 20:56:42.425][DEBUG] [Image Plate Solve Thread] SaveFileBmp16: Unlocking bitmap... [09/09/17 20:56:42.425][DEBUG] [Image Plate Solve Thread] SaveFileBmp16: Closing file stream [09/09/17 20:56:42.431][DEBUG] [Image Plate Solve Thread] Astrometry.NET: Sequence endpoint data: Astrometry.NET (Remote) [09/09/17 20:56:42.434][DEBUG] [Image Plate Solve Thread] ************* SOLVE HINTS **************** [09/09/17 20:56:42.434][DEBUG] [Image Plate Solve Thread] SOLVER: Astrometry.NET [09/09/17 20:56:42.434][DEBUG] [Image Plate Solve Thread] BLIND: True [09/09/17 20:56:42.434][DEBUG] [Image Plate Solve Thread] METHOD: No plate solver selected [09/09/17 20:56:42.434][DEBUG] [Image Plate Solve Thread] RA: 15.2716833333333 [09/09/17 20:56:42.434][DEBUG] [Image Plate Solve Thread] DEC: -31.1154166666667 [09/09/17 20:56:42.434][DEBUG] [Image Plate Solve Thread] SCALE: 0 [09/09/17 20:56:42.434][DEBUG] [Image Plate Solve Thread] ****************************************** [09/09/17 20:56:42.434][DEBUG] [Image Plate Solve Thread] Astrometry.NET: Sequence endpoint data: http://nova.astrometry.net/api [09/09/17 20:56:42.550][DEBUG] [Image Plate Solve Thread] Astrometry.NET convertedAstrometry.fits path: C:\Users\jones\AppData\Local\SequenceGenerator\Temp\convertedAstometry.fits [09/09/17 20:56:42.550][DEBUG] [Image Plate Solve Thread] Astrometry.NET - File is too large, resizing [09/09/17 20:56:42.574][DEBUG] [Image Plate Solve Thread] Astrometry.NET - Saving file [09/09/17 20:56:42.574][DEBUG] [Image Plate Solve Thread] SaveFileBmp16: Locking bitmap for disk write... [09/09/17 20:56:42.574][DEBUG] [Image Plate Solve Thread] SaveFileBmp16: Opening file stream... [09/09/17 20:56:42.575][DEBUG] [Image Plate Solve Thread] SaveFileBmp16: Writing FITS headers... [09/09/17 20:56:42.575][DEBUG] [Image Plate Solve Thread] SaveFileBmp16: Traversing and persisting bitmap data... [09/09/17 20:56:42.584][DEBUG] [Image Plate Solve Thread] SaveFileBmp16: FITS block fill... [09/09/17 20:56:42.584][DEBUG] [Image Plate Solve Thread] SaveFileBmp16: Unlocking bitmap... [09/09/17 20:56:42.584][DEBUG] [Image Plate Solve Thread] SaveFileBmp16: Closing file stream [09/09/17 20:56:42.588][DEBUG] [Image Plate Solve Thread] Astrometry.NET using endpoint: http://nova.astrometry.net/api/ [09/09/17 20:56:42.588][DEBUG] [Image Plate Solve Thread] Astrometry.NET - Calling Async Solve [09/09/17 20:56:43.070][DEBUG] [Unknown] Astrometry.NET uploading file: C:\Users\jones\AppData\Local\SequenceGenerator\Temp\convertedAstometry.fits [09/09/17 20:56:46.279][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [09/09/17 20:56:46.279][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [09/09/17 20:56:46.279][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [09/09/17 20:56:46.279][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [09/09/17 20:56:46.279][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [09/09/17 20:56:47.188][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/1722965 [09/09/17 20:56:47.188][DEBUG] [Unknown] Astrometry.NET returned: {"processing_started": "None", "job_calibrations": [], "jobs": [], "processing_finished": "None", "user": 794, "user_images": []} [09/09/17 20:56:48.291][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/1722965 [09/09/17 20:56:48.291][DEBUG] [Unknown] Astrometry.NET returned: {"processing_started": "None", "job_calibrations": [], "jobs": [], "processing_finished": "None", "user": 794, "user_images": []} [09/09/17 20:56:49.397][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/1722965 [09/09/17 20:56:49.397][DEBUG] [Unknown] Astrometry.NET returned: {"processing_started": "None", "job_calibrations": [], "jobs": [], "processing_finished": "None", "user": 794, "user_images": []} [09/09/17 20:56:50.718][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/1722965 [09/09/17 20:56:50.718][DEBUG] [Unknown] Astrometry.NET returned: {"processing_started": "None", "job_calibrations": [], "jobs": [], "processing_finished": "None", "user": 794, "user_images": []} [09/09/17 20:56:51.820][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/1722965 [09/09/17 20:56:51.820][DEBUG] [Unknown] Astrometry.NET returned: {"processing_started": "None", "job_calibrations": [], "jobs": [], "processing_finished": "None", "user": 794, "user_images": []} [09/09/17 20:56:52.921][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/1722965 [09/09/17 20:56:52.921][DEBUG] [Unknown] Astrometry.NET returned: {"processing_started": "None", "job_calibrations": [], "jobs": [], "processing_finished": "None", "user": 794, "user_images": []} [09/09/17 20:56:54.017][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/1722965 [09/09/17 20:56:54.017][DEBUG] [Unknown] Astrometry.NET returned: {"processing_started": "None", "job_calibrations": [], "jobs": [], "processing_finished": "None", "user": 794, "user_images": []} [09/09/17 20:56:55.123][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/1722965 [09/09/17 20:56:55.123][DEBUG] [Unknown] Astrometry.NET returned: {"processing_started": "2017-09-09 23:56:54.663638", "job_calibrations": [], "jobs": [null], "processing_finished": "2017-09-09 23:56:55.016966", "user": 794, "user_images": [1774784]} [09/09/17 20:56:55.141][DEBUG] [Unknown] Caught exception while Checking Astrometry.NET solve - CheckSubmission: Error converting value {null} to type 'System.Int32'. Path 'jobs[0]', line 1, position 90. [09/09/17 20:56:56.250][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/1722965 [09/09/17 20:56:56.250][DEBUG] [Unknown] Astrometry.NET returned: {"processing_started": "2017-09-09 23:56:54.663638", "job_calibrations": [], "jobs": [null], "processing_finished": "2017-09-09 23:56:55.016966", "user": 794, "user_images": [1774784]} [09/09/17 20:56:56.250][DEBUG] [Unknown] Caught exception while Checking Astrometry.NET solve - CheckSubmission: Error converting value {null} to type 'System.Int32'. Path 'jobs[0]', line 1, position 90. [09/09/17 20:56:57.346][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/1722965 [09/09/17 20:56:57.346][DEBUG] [Unknown] Astrometry.NET returned: {"processing_started": "2017-09-09 23:56:54.663638", "job_calibrations": [], "jobs": [null], "processing_finished": "2017-09-09 23:56:55.016966", "user": 794, "user_images": [1774784]} [09/09/17 20:56:57.347][DEBUG] [Unknown] Caught exception while Checking Astrometry.NET solve - CheckSubmission: Error converting value {null} to type 'System.Int32'. Path 'jobs[0]', line 1, position 90. [09/09/17 20:56:58.443][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/1722965 [09/09/17 20:56:58.443][DEBUG] [Unknown] Astrometry.NET returned: {"processing_started": "2017-09-09 23:56:54.663638", "job_calibrations": [], "jobs": [null], "processing_finished": "2017-09-09 23:56:55.016966", "user": 794, "user_images": [1774784]} [09/09/17 20:56:58.443][DEBUG] [Unknown] Caught exception while Checking Astrometry.NET solve - CheckSubmission: Error converting value {null} to type 'System.Int32'. Path 'jobs[0]', line 1, position 90. [09/09/17 20:56:59.558][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/1722965 [09/09/17 20:56:59.558][DEBUG] [Unknown] Astrometry.NET returned: {"processing_started": "2017-09-09 23:56:54.663638", "job_calibrations": [], "jobs": [null], "processing_finished": "2017-09-09 23:56:55.016966", "user": 794, "user_images": [1774784]} [09/09/17 20:56:59.558][DEBUG] [Unknown] Caught exception while Checking Astrometry.NET solve - CheckSubmission: Error converting value {null} to type 'System.Int32'. Path 'jobs[0]', line 1, position 90. [09/09/17 20:57:00.660][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/1722965 [09/09/17 20:57:00.660][DEBUG] [Unknown] Astrometry.NET returned: {"processing_started": "2017-09-09 23:56:54.663638", "job_calibrations": [], "jobs": [null], "processing_finished": "2017-09-09 23:56:55.016966", "user": 794, "user_images": [1774784]} [09/09/17 20:57:00.661][DEBUG] [Unknown] Caught exception while Checking Astrometry.NET solve - CheckSubmission: Error converting value {null} to type 'System.Int32'. Path 'jobs[0]', line 1, position 90. [09/09/17 20:57:01.823][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/1722965 [09/09/17 20:57:01.823][DEBUG] [Unknown] Astrometry.NET returned: {"processing_started": "2017-09-09 23:56:54.663638", "job_calibrations": [], "jobs": [null], "processing_finished": "2017-09-09 23:56:55.016966", "user": 794, "user_images": [1774784]} [09/09/17 20:57:01.823][DEBUG] [Unknown] Caught exception while Checking Astrometry.NET solve - CheckSubmission: Error converting value {null} to type 'System.Int32'. Path 'jobs[0]', line 1, position 90. [09/09/17 20:57:02.918][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/1722965 [09/09/17 20:57:02.919][DEBUG] [Unknown] Astrometry.NET returned: {"processing_started": "2017-09-09 23:56:54.663638", "job_calibrations": [], "jobs": [null], "processing_finished": "2017-09-09 23:56:55.016966", "user": 794, "user_images": [1774784]} [09/09/17 20:57:02.919][DEBUG] [Unknown] Caught exception while Checking Astrometry.NET solve - CheckSubmission: Error converting value {null} to type 'System.Int32'. Path 'jobs[0]', line 1, position 90. [09/09/17 20:57:04.013][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/1722965 [09/09/17 20:57:04.013][DEBUG] [Unknown] Astrometry.NET returned: {"processing_started": "2017-09-09 23:56:54.663638", "job_calibrations": [], "jobs": [null], "processing_finished": "2017-09-09 23:56:55.016966", "user": 794, "user_images": [1774784]} [09/09/17 20:57:04.013][DEBUG] [Unknown] Caught exception while Checking Astrometry.NET solve - CheckSubmission: Error converting value {null} to type 'System.Int32'. Path 'jobs[0]', line 1, position 90. [09/09/17 20:57:05.124][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/1722965 [09/09/17 20:57:05.124][DEBUG] [Unknown] Astrometry.NET returned: {"processing_started": "2017-09-09 23:56:54.663638", "job_calibrations": [], "jobs": [null], "processing_finished": "2017-09-09 23:56:55.016966", "user": 794, "user_images": [1774784]} [09/09/17 20:57:05.125][DEBUG] [Unknown] Caught exception while Checking Astrometry.NET solve - CheckSubmission: Error converting value {null} to type 'System.Int32'. Path 'jobs[0]', line 1, position 90. [09/09/17 20:57:06.224][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/1722965 [09/09/17 20:57:06.224][DEBUG] [Unknown] Astrometry.NET returned: {"processing_started": "2017-09-09 23:56:54.663638", "job_calibrations": [], "jobs": [null], "processing_finished": "2017-09-09 23:56:55.016966", "user": 794, "user_images": [1774784]} [09/09/17 20:57:06.225][DEBUG] [Unknown] Caught exception while Checking Astrometry.NET solve - CheckSubmission: Error converting value {null} to type 'System.Int32'. Path 'jobs[0]', line 1, position 90. [09/09/17 20:57:07.339][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/1722965 [09/09/17 20:57:07.340][DEBUG] [Unknown] Astrometry.NET returned: {"processing_started": "2017-09-09 23:56:54.663638", "job_calibrations": [], "jobs": [null], "processing_finished": "2017-09-09 23:56:55.016966", "user": 794, "user_images": [1774784]} [09/09/17 20:57:07.340][DEBUG] [Unknown] Caught exception while Checking Astrometry.NET solve - CheckSubmission: Error converting value {null} to type 'System.Int32'. Path 'jobs[0]', line 1, position 90. [09/09/17 20:57:08.451][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/1722965 [09/09/17 20:57:08.451][DEBUG] [Unknown] Astrometry.NET returned: {"processing_started": "2017-09-09 23:56:54.663638", "job_calibrations": [], "jobs": [null], "processing_finished": "2017-09-09 23:56:55.016966", "user": 794, "user_images": [1774784]} [09/09/17 20:57:08.451][DEBUG] [Unknown] Caught exception while Checking Astrometry.NET solve - CheckSubmission: Error converting value {null} to type 'System.Int32'. Path 'jobs[0]', line 1, position 90. [09/09/17 20:57:09.560][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/1722965 [09/09/17 20:57:09.560][DEBUG] [Unknown] Astrometry.NET returned: {"processing_started": "2017-09-09 23:56:54.663638", "job_calibrations": [], "jobs": [null], "processing_finished": "2017-09-09 23:56:55.016966", "user": 794, "user_images": [1774784]} [09/09/17 20:57:09.560][DEBUG] [Unknown] Caught exception while Checking Astrometry.NET solve - CheckSubmission: Error converting value {null} to type 'System.Int32'. Path 'jobs[0]', line 1, position 90. [09/09/17 20:57:10.677][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/1722965 [09/09/17 20:57:10.677][DEBUG] [Unknown] Astrometry.NET returned: {"processing_started": "2017-09-09 23:56:54.663638", "job_calibrations": [], "jobs": [null], "processing_finished": "2017-09-09 23:56:55.016966", "user": 794, "user_images": [1774784]} [09/09/17 20:57:10.677][DEBUG] [Unknown] Caught exception while Checking Astrometry.NET solve - CheckSubmission: Error converting value {null} to type 'System.Int32'. Path 'jobs[0]', line 1, position 90. [09/09/17 20:57:11.786][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/1722965 [09/09/17 20:57:11.786][DEBUG] [Unknown] Astrometry.NET returned: {"processing_started": "2017-09-09 23:56:54.663638", "job_calibrations": [], "jobs": [null], "processing_finished": "2017-09-09 23:56:55.016966", "user": 794, "user_images": [1774784]} [09/09/17 20:57:11.786][DEBUG] [Unknown] Caught exception while Checking Astrometry.NET solve - CheckSubmission: Error converting value {null} to type 'System.Int32'. Path 'jobs[0]', line 1, position 90. [09/09/17 20:57:12.894][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/1722965 [09/09/17 20:57:12.894][DEBUG] [Unknown] Astrometry.NET returned: {"processing_started": "2017-09-09 23:56:54.663638", "job_calibrations": [], "jobs": [null], "processing_finished": "2017-09-09 23:56:55.016966", "user": 794, "user_images": [1774784]} [09/09/17 20:57:12.895][DEBUG] [Unknown] Caught exception while Checking Astrometry.NET solve - CheckSubmission: Error converting value {null} to type 'System.Int32'. Path 'jobs[0]', line 1, position 90. [09/09/17 20:57:14.002][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/1722965 [09/09/17 20:57:14.002][DEBUG] [Unknown] Astrometry.NET returned: {"processing_started": "2017-09-09 23:56:54.663638", "job_calibrations": [], "jobs": [null], "processing_finished": "2017-09-09 23:56:55.016966", "user": 794, "user_images": [1774784]} [09/09/17 20:57:14.002][DEBUG] [Unknown] Caught exception while Checking Astrometry.NET solve - CheckSubmission: Error converting value {null} to type 'System.Int32'. Path 'jobs[0]', line 1, position 90. [09/09/17 20:57:15.102][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/1722965 [09/09/17 20:57:15.102][DEBUG] [Unknown] Astrometry.NET returned: {"processing_started": "2017-09-09 23:56:54.663638", "job_calibrations": [], "jobs": [null], "processing_finished": "2017-09-09 23:56:55.016966", "user": 794, "user_images": [1774784]} [09/09/17 20:57:15.102][DEBUG] [Unknown] Caught exception while Checking Astrometry.NET solve - CheckSubmission: Error converting value {null} to type 'System.Int32'. Path 'jobs[0]', line 1, position 90. [09/09/17 20:57:16.206][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/1722965 [09/09/17 20:57:16.206][DEBUG] [Unknown] Astrometry.NET returned: {"processing_started": "2017-09-09 23:56:54.663638", "job_calibrations": [], "jobs": [null], "processing_finished": "2017-09-09 23:56:55.016966", "user": 794, "user_images": [1774784]} [09/09/17 20:57:16.206][DEBUG] [Unknown] Caught exception while Checking Astrometry.NET solve - CheckSubmission: Error converting value {null} to type 'System.Int32'. Path 'jobs[0]', line 1, position 90. [09/09/17 20:57:17.312][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/1722965 [09/09/17 20:57:17.312][DEBUG] [Unknown] Astrometry.NET returned: {"processing_started": "2017-09-09 23:56:54.663638", "job_calibrations": [], "jobs": [null], "processing_finished": "2017-09-09 23:56:55.016966", "user": 794, "user_images": [1774784]} [09/09/17 20:57:17.312][DEBUG] [Unknown] Caught exception while Checking Astrometry.NET solve - CheckSubmission: Error converting value {null} to type 'System.Int32'. Path 'jobs[0]', line 1, position 90. [09/09/17 20:57:18.414][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/1722965 [09/09/17 20:57:18.414][DEBUG] [Unknown] Astrometry.NET returned: {"processing_started": "2017-09-09 23:56:54.663638", "job_calibrations": [], "jobs": [null], "processing_finished": "2017-09-09 23:56:55.016966", "user": 794, "user_images": [1774784]} [09/09/17 20:57:18.414][DEBUG] [Unknown] Caught exception while Checking Astrometry.NET solve - CheckSubmission: Error converting value {null} to type 'System.Int32'. Path 'jobs[0]', line 1, position 90. [09/09/17 20:57:19.539][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/1722965 [09/09/17 20:57:19.539][DEBUG] [Unknown] Astrometry.NET returned: {"processing_started": "2017-09-09 23:56:54.663638", "job_calibrations": [], "jobs": [null], "processing_finished": "2017-09-09 23:56:55.016966", "user": 794, "user_images": [1774784]} [09/09/17 20:57:19.539][DEBUG] [Unknown] Caught exception while Checking Astrometry.NET solve - CheckSubmission: Error converting value {null} to type 'System.Int32'. Path 'jobs[0]', line 1, position 90. [09/09/17 20:57:20.651][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/1722965 [09/09/17 20:57:20.651][DEBUG] [Unknown] Astrometry.NET returned: {"processing_started": "2017-09-09 23:56:54.663638", "job_calibrations": [], "jobs": [2222343], "processing_finished": "2017-09-09 23:56:55.016966", "user": 794, "user_images": [1774784]} [09/09/17 20:57:20.651][DEBUG] [Unknown] Astrometry.NET - Upload complete [09/09/17 20:57:20.651][DEBUG] [Unknown] Astrometry.NET - Waiting for solve to complete [09/09/17 20:57:21.241][DEBUG] [Unknown] Astrometry.NET request: /api/jobs/2222343 [09/09/17 20:57:21.241][DEBUG] [Unknown] Astrometry.NET returned: {"status": "solving"} [09/09/17 20:57:21.829][DEBUG] [Unknown] Astrometry.NET request: /api/jobs/2222343 [09/09/17 20:57:21.829][DEBUG] [Unknown] Astrometry.NET returned: {"status": "solving"} [09/09/17 20:57:22.419][DEBUG] [Unknown] Astrometry.NET request: /api/jobs/2222343 [09/09/17 20:57:22.419][DEBUG] [Unknown] Astrometry.NET returned: {"status": "solving"} [09/09/17 20:57:23.006][DEBUG] [Unknown] Astrometry.NET request: /api/jobs/2222343 [09/09/17 20:57:23.006][DEBUG] [Unknown] Astrometry.NET returned: {"status": "solving"} [09/09/17 20:57:23.612][DEBUG] [Unknown] Astrometry.NET request: /api/jobs/2222343 [09/09/17 20:57:23.612][DEBUG] [Unknown] Astrometry.NET returned: {"status": "solving"} [09/09/17 20:57:24.208][DEBUG] [Unknown] Astrometry.NET request: /api/jobs/2222343 [09/09/17 20:57:24.208][DEBUG] [Unknown] Astrometry.NET returned: {"status": "success"} [09/09/17 20:57:24.310][DEBUG] [Unknown] Astrometry.NET request: /api/jobs/2222343/calibration [09/09/17 20:57:24.310][DEBUG] [Unknown] Astrometry.NET returned: {"parity": 1.0, "orientation": -179.7438867437153, "pixscale": 5.84445644229617, "radius": 1.2190577629083492, "ra": 295.2937108674385, "dec": 23.439828759833585} [09/09/17 20:57:24.315][DEBUG] [Unknown] ------------ Begin CalibrationResponse ------------- [09/09/17 20:57:24.315][DEBUG] [Unknown] dec 23.4398287598336 [09/09/17 20:57:24.315][DEBUG] [Unknown] ra 295.293710867439 [09/09/17 20:57:24.315][DEBUG] [Unknown] radius 1.21905776290835 [09/09/17 20:57:24.315][DEBUG] [Unknown] orientation -179.743886743715 [09/09/17 20:57:24.315][DEBUG] [Unknown] pixscale 5.84445644229617 [09/09/17 20:57:24.315][DEBUG] [Unknown] epoch [09/09/17 20:57:24.315][DEBUG] [Unknown] time NULL [09/09/17 20:57:24.315][DEBUG] [Unknown] parity 5.84445644229617 [09/09/17 20:57:24.315][DEBUG] [Unknown] ------------ End CalibrationResponse --------------- [09/09/17 20:57:24.315][DEBUG] [Unknown] Astrometry.NET - Job successfully solved [09/09/17 20:57:24.315][DEBUG] [Unknown] Astrometry.NET solve done in 38 seconds. [09/09/17 20:57:24.611][DEBUG] [Image Plate Solve Thread] Astrometry.NET - Solve Completed [09/09/17 20:57:24.612][DEBUG] [Image Plate Solve Thread] *********** SUCCESSFUL SOLVE ************* [09/09/17 20:57:24.612][DEBUG] [Image Plate Solve Thread] SOLVER: True [09/09/17 20:57:24.612][DEBUG] [Image Plate Solve Thread] SUCCESS: True [09/09/17 20:57:24.612][DEBUG] [Image Plate Solve Thread] CONF: 100 [09/09/17 20:57:24.612][DEBUG] [Image Plate Solve Thread] BLIND: True [09/09/17 20:57:24.612][DEBUG] [Image Plate Solve Thread] RA: 19.6862473911626 [09/09/17 20:57:24.612][DEBUG] [Image Plate Solve Thread] DEC: 23.4398287598336 [09/09/17 20:57:24.612][DEBUG] [Image Plate Solve Thread] SCALE: 4.21728666912532 [09/09/17 20:57:24.612][DEBUG] [Image Plate Solve Thread] FLIPPED: False [09/09/17 20:57:24.612][DEBUG] [Image Plate Solve Thread] ANGLE (EON): 0.256113256284692 [09/09/17 20:57:24.612][DEBUG] [Image Plate Solve Thread] MSG: Solve took 38.225 seconds [09/09/17 20:57:24.612][DEBUG] [Image Plate Solve Thread] ****************************************** [09/09/17 20:57:24.613][DEBUG] [Image Plate Solve Thread] Attempting to write fits header info for [09/09/17 20:57:24.630][DEBUG] [Ps Status Thread] Aborting solve... [09/09/17 20:57:46.420][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [09/09/17 20:57:46.420][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [09/09/17 20:57:46.420][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [09/09/17 20:57:46.420][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [09/09/17 20:57:46.420][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [09/09/17 20:58:03.282][DEBUG] [Main Thread] Collecting FITs headers... [09/09/17 20:58:03.284][DEBUG] [Main Thread] QSI Camera - Camera reports last image start time as: 2017-09-10T03:55:50.828 UTC [09/09/17 20:58:03.285][DEBUG] [Main Thread] DATE-LOC time provided by camera... [09/09/17 20:58:03.339][DEBUG] [Main Thread] GatherFitsHeaders: Writing header info from UI... [09/09/17 20:58:03.351][DEBUG] [Main Thread] SaveFileBmp16: Locking bitmap for disk write... [09/09/17 20:58:03.354][DEBUG] [Main Thread] SaveFileBmp16: Opening file stream... [09/09/17 20:58:03.355][DEBUG] [Main Thread] SaveFileBmp16: Writing FITS headers... [09/09/17 20:58:03.355][DEBUG] [Main Thread] SaveFileBmp16: Traversing and persisting bitmap data... [09/09/17 20:58:03.382][DEBUG] [Main Thread] SaveFileBmp16: FITS block fill... [09/09/17 20:58:03.382][DEBUG] [Main Thread] SaveFileBmp16: Unlocking bitmap... [09/09/17 20:58:03.382][DEBUG] [Main Thread] SaveFileBmp16: Closing file stream [09/09/17 20:58:46.551][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [09/09/17 20:58:46.551][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [09/09/17 20:58:46.551][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [09/09/17 20:58:46.551][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [09/09/17 20:58:46.551][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [09/09/17 20:59:05.351][DEBUG] [Main Thread] FitsFileHeaderData: Angle - 0 [09/09/17 20:59:05.351][DEBUG] [Main Thread] FitsFileHeaderData: Scale - 0 [09/09/17 20:59:05.352][DEBUG] [Main Thread] FitsFileHeaderData: RA - 15.2716828865187 [09/09/17 20:59:05.352][DEBUG] [Main Thread] FitsFileHeaderData: DEC - -31.1154154562694 [09/09/17 20:59:05.434][DEBUG] [Main Thread] Display image preview using asynch task... [09/09/17 20:59:46.695][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [09/09/17 20:59:46.695][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [09/09/17 20:59:46.695][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [09/09/17 20:59:46.695][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [09/09/17 20:59:46.695][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [09/09/17 21:00:46.798][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [09/09/17 21:00:46.798][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [09/09/17 21:00:46.798][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [09/09/17 21:00:46.798][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [09/09/17 21:00:46.798][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [09/09/17 21:01:46.947][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [09/09/17 21:01:46.947][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [09/09/17 21:01:46.947][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [09/09/17 21:01:46.947][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [09/09/17 21:01:46.947][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [09/09/17 21:02:47.078][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [09/09/17 21:02:47.078][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [09/09/17 21:02:47.078][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [09/09/17 21:02:47.078][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [09/09/17 21:02:47.078][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [09/09/17 21:03:09.444][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS message received... [09/09/17 21:03:09.447][DEBUG] [Camera Thread] ASCOM (QSI) camera: frame and focus... [09/09/17 21:03:09.472][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [09/09/17 21:03:09.472][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [09/09/17 21:03:47.205][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [09/09/17 21:03:47.205][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [09/09/17 21:03:47.205][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [09/09/17 21:03:47.205][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [09/09/17 21:03:47.205][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [09/09/17 21:04:18.793][DEBUG] [Camera Thread] QSI expose and download: 69.2565903s [09/09/17 21:04:18.882][DEBUG] [Camera Thread] Internal Interface: Set Preview... [09/09/17 21:04:18.882][DEBUG] [Camera Thread] Display image preview using asynch task... [09/09/17 21:04:19.167][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS complete... [09/09/17 21:04:47.344][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [09/09/17 21:04:47.344][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [09/09/17 21:04:47.344][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [09/09/17 21:04:47.344][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [09/09/17 21:04:47.344][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [09/09/17 21:05:47.471][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [09/09/17 21:05:47.471][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [09/09/17 21:05:47.471][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [09/09/17 21:05:47.471][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [09/09/17 21:05:47.471][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [09/09/17 21:06:47.601][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [09/09/17 21:06:47.601][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [09/09/17 21:06:47.601][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [09/09/17 21:06:47.601][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [09/09/17 21:06:47.601][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [09/09/17 21:07:47.741][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [09/09/17 21:07:47.741][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [09/09/17 21:07:47.741][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [09/09/17 21:07:47.741][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [09/09/17 21:07:47.741][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [09/09/17 21:08:47.879][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [09/09/17 21:08:47.879][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [09/09/17 21:08:47.879][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [09/09/17 21:08:47.879][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [09/09/17 21:08:47.879][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [09/09/17 21:09:20.602][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS message received... [09/09/17 21:09:20.605][DEBUG] [Camera Thread] ASCOM (QSI) camera: frame and focus... [09/09/17 21:09:20.628][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [09/09/17 21:09:20.628][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [09/09/17 21:09:47.980][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [09/09/17 21:09:47.980][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [09/09/17 21:09:47.980][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [09/09/17 21:09:47.980][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [09/09/17 21:09:47.980][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [09/09/17 21:10:29.956][DEBUG] [Camera Thread] QSI expose and download: 69.264682s [09/09/17 21:10:30.028][DEBUG] [Camera Thread] Internal Interface: Set Preview... [09/09/17 21:10:30.028][DEBUG] [Camera Thread] Display image preview using asynch task... [09/09/17 21:10:30.307][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS complete... [09/09/17 21:10:48.084][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [09/09/17 21:10:48.084][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [09/09/17 21:10:48.084][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [09/09/17 21:10:48.084][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [09/09/17 21:10:48.084][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [09/09/17 21:11:48.206][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [09/09/17 21:11:48.206][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [09/09/17 21:11:48.206][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [09/09/17 21:11:48.206][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [09/09/17 21:11:48.206][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [09/09/17 21:12:48.336][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [09/09/17 21:12:48.336][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [09/09/17 21:12:48.336][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [09/09/17 21:12:48.336][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [09/09/17 21:12:48.336][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [09/09/17 21:12:50.078][DEBUG] [Camera Thread] SGM_FOCUSER_AUTO_FOCUS message received... [09/09/17 21:12:50.083][DEBUG] [Camera Thread] Checking for auto focus... [09/09/17 21:12:50.083][DEBUG] [Camera Thread] Auto focus required (frame count trigger)... [09/09/17 21:12:50.090][DEBUG] [Camera Thread] Auto focus: setting filter Lum [09/09/17 21:12:50.093][DEBUG] [Camera Thread] Setting filter position 1... [09/09/17 21:12:50.093][DEBUG] [Camera Thread] Filter position 1 is already set. Skipping... [09/09/17 21:12:50.095][DEBUG] [Camera Thread] Auto focus running... [09/09/17 21:12:50.102][DEBUG] [Camera Thread] Turning temp comp off.. [09/09/17 21:12:50.121][DEBUG] [Camera Thread] Performing Sequence Generator auto focus using Half Flux Radius. AFID: 0 [09/09/17 21:12:50.197][DEBUG] [AfChartThread] Showing AF chart dialog... [09/09/17 21:12:50.282][DEBUG] [Camera Thread] Auto focus data [09/09/17 21:12:50.282][DEBUG] [Camera Thread] - Data Points: 7 [09/09/17 21:12:50.282][DEBUG] [Camera Thread] - Step Size: 10 [09/09/17 21:12:50.282][DEBUG] [Camera Thread] - Current Position: 7907 [09/09/17 21:12:50.282][DEBUG] [Camera Thread] - Initial Move Position: 7937 [09/09/17 21:12:50.292][DEBUG] [Camera Thread] AF Darks exception: The path is not of a legal form. [09/09/17 21:12:50.294][DEBUG] [Camera Thread] Moving focuser to next position (7937)... [09/09/17 21:12:50.298][DEBUG] [Focuser Move Thread] Focuser moving to 7937 [09/09/17 21:12:50.355][DEBUG] [Focuser Move Thread] Focuser move call complete [09/09/17 21:12:51.409][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7937 matches requested position 7937 [09/09/17 21:12:51.410][DEBUG] [Camera Thread] Focuser move complete... [09/09/17 21:12:51.464][DEBUG] [Camera Thread] Focuser position matches requested position (7937), continuing... [09/09/17 21:12:51.519][DEBUG] [Camera Thread] Calculating step metric... [09/09/17 21:12:51.523][DEBUG] [Camera Thread] Taking auto focus frame(s)... [09/09/17 21:12:51.529][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [09/09/17 21:12:51.530][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [09/09/17 21:12:51.530][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [09/09/17 21:12:51.530][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [09/09/17 21:12:51.551][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [09/09/17 21:12:51.552][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [09/09/17 21:13:08.902][DEBUG] [Camera Thread] QSI expose and download: 17.2862844s [09/09/17 21:13:09.000][DEBUG] [Camera Thread] Internal Interface: Set Preview... [09/09/17 21:13:09.000][DEBUG] [Camera Thread] Display image preview using asynch task... [09/09/17 21:13:09.262][DEBUG] [Main Thread] --> Find stars (normal) [09/09/17 21:13:09.427][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [09/09/17 21:13:11.652][DEBUG] [Main Thread] Star detection using min star size of 4px... [09/09/17 21:13:11.652][DEBUG] [Main Thread] Star detection using max star size of 80px... [09/09/17 21:13:11.720][DEBUG] [Main Thread] Find stars took: 2458 ms... [09/09/17 21:13:11.721][DEBUG] [Main Thread] Star list contains 258 stars... [09/09/17 21:13:11.721][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [09/09/17 21:13:12.087][DEBUG] [Camera Thread] Auto focus HFR calculated at: 1.16 [09/09/17 21:13:13.119][DEBUG] [Camera Thread] Moving focuser to next position (7927)... [09/09/17 21:13:13.120][DEBUG] [Focuser Move Thread] Focuser moving to 7927 [09/09/17 21:13:13.177][DEBUG] [Focuser Move Thread] Focuser move call complete [09/09/17 21:13:14.232][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7927 matches requested position 7927 [09/09/17 21:13:14.232][DEBUG] [Camera Thread] Focuser move complete... [09/09/17 21:13:14.287][DEBUG] [Camera Thread] Focuser position matches requested position (7927), continuing... [09/09/17 21:13:14.343][DEBUG] [Camera Thread] Calculating step metric... [09/09/17 21:13:14.343][DEBUG] [Camera Thread] Taking auto focus frame(s)... [09/09/17 21:13:14.345][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [09/09/17 21:13:14.345][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [09/09/17 21:13:14.345][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [09/09/17 21:13:14.346][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [09/09/17 21:13:14.369][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [09/09/17 21:13:14.369][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [09/09/17 21:13:30.916][DEBUG] [Camera Thread] QSI expose and download: 16.4837159s [09/09/17 21:13:31.008][DEBUG] [Camera Thread] Internal Interface: Set Preview... [09/09/17 21:13:31.008][DEBUG] [Camera Thread] Display image preview using asynch task... [09/09/17 21:13:31.301][DEBUG] [Main Thread] --> Find stars (normal) [09/09/17 21:13:31.372][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [09/09/17 21:13:33.702][DEBUG] [Main Thread] Star detection using min star size of 4px... [09/09/17 21:13:33.702][DEBUG] [Main Thread] Star detection using max star size of 80px... [09/09/17 21:13:33.754][DEBUG] [Main Thread] Find stars took: 2452 ms... [09/09/17 21:13:33.754][DEBUG] [Main Thread] Star list contains 265 stars... [09/09/17 21:13:33.754][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [09/09/17 21:13:34.141][DEBUG] [Camera Thread] Auto focus HFR calculated at: 1.31 [09/09/17 21:13:35.145][DEBUG] [Camera Thread] Moving focuser to next position (7917)... [09/09/17 21:13:35.146][DEBUG] [Focuser Move Thread] Focuser moving to 7917 [09/09/17 21:13:35.203][DEBUG] [Focuser Move Thread] Focuser move call complete [09/09/17 21:13:36.258][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7917 matches requested position 7917 [09/09/17 21:13:36.258][DEBUG] [Camera Thread] Focuser move complete... [09/09/17 21:13:36.313][DEBUG] [Camera Thread] Focuser position matches requested position (7917), continuing... [09/09/17 21:13:36.368][DEBUG] [Camera Thread] Calculating step metric... [09/09/17 21:13:36.368][DEBUG] [Camera Thread] Taking auto focus frame(s)... [09/09/17 21:13:36.370][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [09/09/17 21:13:36.370][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [09/09/17 21:13:36.370][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [09/09/17 21:13:36.370][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [09/09/17 21:13:36.398][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [09/09/17 21:13:36.398][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [09/09/17 21:13:48.445][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [09/09/17 21:13:48.445][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [09/09/17 21:13:48.445][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [09/09/17 21:13:48.445][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [09/09/17 21:13:48.445][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [09/09/17 21:13:52.958][DEBUG] [Camera Thread] QSI expose and download: 16.4962025s [09/09/17 21:13:53.136][DEBUG] [Camera Thread] Internal Interface: Set Preview... [09/09/17 21:13:53.136][DEBUG] [Camera Thread] Display image preview using asynch task... [09/09/17 21:13:53.488][DEBUG] [Main Thread] --> Find stars (normal) [09/09/17 21:13:53.588][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [09/09/17 21:13:56.163][DEBUG] [Main Thread] Star detection using min star size of 4px... [09/09/17 21:13:56.163][DEBUG] [Main Thread] Star detection using max star size of 80px... [09/09/17 21:13:56.227][DEBUG] [Main Thread] Find stars took: 2738 ms... [09/09/17 21:13:56.227][DEBUG] [Main Thread] Star list contains 177 stars... [09/09/17 21:13:56.227][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [09/09/17 21:13:56.729][DEBUG] [Camera Thread] Auto focus HFR calculated at: 1.65 [09/09/17 21:13:57.739][DEBUG] [Camera Thread] Moving focuser to next position (7907)... [09/09/17 21:13:57.740][DEBUG] [Focuser Move Thread] Focuser moving to 7907 [09/09/17 21:13:57.849][DEBUG] [Focuser Move Thread] Focuser move call complete [09/09/17 21:13:58.905][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7907 matches requested position 7907 [09/09/17 21:13:58.905][DEBUG] [Camera Thread] Focuser move complete... [09/09/17 21:13:58.960][DEBUG] [Camera Thread] Focuser position matches requested position (7907), continuing... [09/09/17 21:13:59.015][DEBUG] [Camera Thread] Calculating step metric... [09/09/17 21:13:59.016][DEBUG] [Camera Thread] Taking auto focus frame(s)... [09/09/17 21:13:59.019][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [09/09/17 21:13:59.019][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [09/09/17 21:13:59.019][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [09/09/17 21:13:59.019][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [09/09/17 21:13:59.054][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [09/09/17 21:13:59.054][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [09/09/17 21:14:15.618][DEBUG] [Camera Thread] QSI expose and download: 16.5001841s [09/09/17 21:14:15.780][DEBUG] [Camera Thread] Internal Interface: Set Preview... [09/09/17 21:14:15.780][DEBUG] [Camera Thread] Display image preview using asynch task... [09/09/17 21:14:16.130][DEBUG] [Main Thread] --> Find stars (normal) [09/09/17 21:14:16.199][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [09/09/17 21:14:18.723][DEBUG] [Main Thread] Star detection using min star size of 4px... [09/09/17 21:14:18.723][DEBUG] [Main Thread] Star detection using max star size of 80px... [09/09/17 21:14:18.780][DEBUG] [Main Thread] Find stars took: 2650 ms... [09/09/17 21:14:18.780][DEBUG] [Main Thread] Star list contains 96 stars... [09/09/17 21:14:18.780][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [09/09/17 21:14:19.208][DEBUG] [Camera Thread] Auto focus HFR calculated at: 2.29 [09/09/17 21:14:20.226][DEBUG] [Camera Thread] Detected focus getting worse! Shifting auto focus range [09/09/17 21:14:20.227][DEBUG] [Focuser Move Thread] Focuser moving to 7952 [09/09/17 21:14:20.337][DEBUG] [Focuser Move Thread] Focuser move call complete [09/09/17 21:14:21.392][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7952 matches requested position 7952 [09/09/17 21:14:21.392][DEBUG] [Camera Thread] Focuser move complete... [09/09/17 21:14:21.450][DEBUG] [Camera Thread] Auto focus data [09/09/17 21:14:21.450][DEBUG] [Camera Thread] - Data Points: 7 [09/09/17 21:14:21.450][DEBUG] [Camera Thread] - Step Size: 10 [09/09/17 21:14:21.450][DEBUG] [Camera Thread] - Current Position: 7952 [09/09/17 21:14:21.450][DEBUG] [Camera Thread] - Initial Move Position: 7982 [09/09/17 21:14:21.455][DEBUG] [Camera Thread] AF Darks exception: The path is not of a legal form. [09/09/17 21:14:21.456][DEBUG] [Camera Thread] Moving focuser to next position (7982)... [09/09/17 21:14:21.457][DEBUG] [Focuser Move Thread] Focuser moving to 7982 [09/09/17 21:14:21.513][DEBUG] [Focuser Move Thread] Focuser move call complete [09/09/17 21:14:22.608][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7982 matches requested position 7982 [09/09/17 21:14:22.608][DEBUG] [Camera Thread] Focuser move complete... [09/09/17 21:14:22.716][DEBUG] [Camera Thread] Focuser position matches requested position (7982), continuing... [09/09/17 21:14:22.770][DEBUG] [Camera Thread] Calculating step metric... [09/09/17 21:14:22.770][DEBUG] [Camera Thread] Taking auto focus frame(s)... [09/09/17 21:14:22.772][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [09/09/17 21:14:22.772][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [09/09/17 21:14:22.773][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [09/09/17 21:14:22.773][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [09/09/17 21:14:22.800][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [09/09/17 21:14:22.800][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [09/09/17 21:14:39.342][DEBUG] [Camera Thread] QSI expose and download: 16.4787186s [09/09/17 21:14:39.426][DEBUG] [Camera Thread] Internal Interface: Set Preview... [09/09/17 21:14:39.426][DEBUG] [Camera Thread] Display image preview using asynch task... [09/09/17 21:14:39.668][DEBUG] [Main Thread] --> Find stars (normal) [09/09/17 21:14:39.732][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [09/09/17 21:14:41.977][DEBUG] [Main Thread] Star detection using min star size of 4px... [09/09/17 21:14:41.977][DEBUG] [Main Thread] Star detection using max star size of 80px... [09/09/17 21:14:42.066][DEBUG] [Main Thread] Find stars took: 2398 ms... [09/09/17 21:14:42.066][DEBUG] [Main Thread] Star list contains 50 stars... [09/09/17 21:14:42.066][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [09/09/17 21:14:42.451][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.81 [09/09/17 21:14:43.457][DEBUG] [Camera Thread] Moving focuser to next position (7972)... [09/09/17 21:14:43.458][DEBUG] [Focuser Move Thread] Focuser moving to 7972 [09/09/17 21:14:43.513][DEBUG] [Focuser Move Thread] Focuser move call complete [09/09/17 21:14:44.568][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7972 matches requested position 7972 [09/09/17 21:14:44.568][DEBUG] [Camera Thread] Focuser move complete... [09/09/17 21:14:44.623][DEBUG] [Camera Thread] Focuser position matches requested position (7972), continuing... [09/09/17 21:14:44.679][DEBUG] [Camera Thread] Calculating step metric... [09/09/17 21:14:44.679][DEBUG] [Camera Thread] Taking auto focus frame(s)... [09/09/17 21:14:44.682][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [09/09/17 21:14:44.682][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [09/09/17 21:14:44.682][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [09/09/17 21:14:44.682][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [09/09/17 21:14:44.704][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [09/09/17 21:14:44.704][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [09/09/17 21:14:48.575][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [09/09/17 21:14:48.575][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [09/09/17 21:14:48.575][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [09/09/17 21:14:48.575][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [09/09/17 21:14:48.575][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [09/09/17 21:15:01.263][DEBUG] [Camera Thread] QSI expose and download: 16.494176s [09/09/17 21:15:01.348][DEBUG] [Camera Thread] Internal Interface: Set Preview... [09/09/17 21:15:01.348][DEBUG] [Camera Thread] Display image preview using asynch task... [09/09/17 21:15:01.578][DEBUG] [Main Thread] --> Find stars (normal) [09/09/17 21:15:01.632][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [09/09/17 21:15:04.108][DEBUG] [Main Thread] Star detection using min star size of 4px... [09/09/17 21:15:04.108][DEBUG] [Main Thread] Star detection using max star size of 80px... [09/09/17 21:15:04.160][DEBUG] [Main Thread] Find stars took: 2582 ms... [09/09/17 21:15:04.160][DEBUG] [Main Thread] Star list contains 68 stars... [09/09/17 21:15:04.160][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [09/09/17 21:15:04.686][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3.02 [09/09/17 21:15:05.697][DEBUG] [Camera Thread] Moving focuser to next position (7962)... [09/09/17 21:15:05.698][DEBUG] [Focuser Move Thread] Focuser moving to 7962 [09/09/17 21:15:05.754][DEBUG] [Focuser Move Thread] Focuser move call complete [09/09/17 21:15:06.809][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7962 matches requested position 7962 [09/09/17 21:15:06.809][DEBUG] [Camera Thread] Focuser move complete... [09/09/17 21:15:06.864][DEBUG] [Camera Thread] Focuser position matches requested position (7962), continuing... [09/09/17 21:15:06.920][DEBUG] [Camera Thread] Calculating step metric... [09/09/17 21:15:06.920][DEBUG] [Camera Thread] Taking auto focus frame(s)... [09/09/17 21:15:06.923][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [09/09/17 21:15:06.923][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [09/09/17 21:15:06.923][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [09/09/17 21:15:06.923][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [09/09/17 21:15:06.946][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [09/09/17 21:15:06.946][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [09/09/17 21:15:23.502][DEBUG] [Camera Thread] QSI expose and download: 16.4916473s [09/09/17 21:15:23.590][DEBUG] [Camera Thread] Internal Interface: Set Preview... [09/09/17 21:15:23.590][DEBUG] [Camera Thread] Display image preview using asynch task... [09/09/17 21:15:23.861][DEBUG] [Main Thread] --> Find stars (normal) [09/09/17 21:15:23.914][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [09/09/17 21:15:26.182][DEBUG] [Main Thread] Star detection using min star size of 4px... [09/09/17 21:15:26.182][DEBUG] [Main Thread] Star detection using max star size of 80px... [09/09/17 21:15:26.228][DEBUG] [Main Thread] Find stars took: 2367 ms... [09/09/17 21:15:26.228][DEBUG] [Main Thread] Star list contains 88 stars... [09/09/17 21:15:26.228][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [09/09/17 21:15:26.620][DEBUG] [Camera Thread] Auto focus HFR calculated at: 2.36 [09/09/17 21:15:27.631][DEBUG] [Camera Thread] Moving focuser to next position (7952)... [09/09/17 21:15:27.631][DEBUG] [Focuser Move Thread] Focuser moving to 7952 [09/09/17 21:15:27.687][DEBUG] [Focuser Move Thread] Focuser move call complete [09/09/17 21:15:28.744][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7952 matches requested position 7952 [09/09/17 21:15:28.744][DEBUG] [Camera Thread] Focuser move complete... [09/09/17 21:15:28.799][DEBUG] [Camera Thread] Focuser position matches requested position (7952), continuing... [09/09/17 21:15:28.854][DEBUG] [Camera Thread] Calculating step metric... [09/09/17 21:15:28.854][DEBUG] [Camera Thread] Taking auto focus frame(s)... [09/09/17 21:15:28.858][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [09/09/17 21:15:28.859][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [09/09/17 21:15:28.859][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [09/09/17 21:15:28.859][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [09/09/17 21:15:28.883][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [09/09/17 21:15:28.883][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [09/09/17 21:15:45.436][DEBUG] [Camera Thread] QSI expose and download: 16.4880207s [09/09/17 21:15:45.532][DEBUG] [Camera Thread] Internal Interface: Set Preview... [09/09/17 21:15:45.532][DEBUG] [Camera Thread] Display image preview using asynch task... [09/09/17 21:15:45.778][DEBUG] [Main Thread] --> Find stars (normal) [09/09/17 21:15:45.844][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [09/09/17 21:15:48.102][DEBUG] [Main Thread] Star detection using min star size of 4px... [09/09/17 21:15:48.102][DEBUG] [Main Thread] Star detection using max star size of 80px... [09/09/17 21:15:48.169][DEBUG] [Main Thread] Find stars took: 2390 ms... [09/09/17 21:15:48.169][DEBUG] [Main Thread] Star list contains 170 stars... [09/09/17 21:15:48.169][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [09/09/17 21:15:48.710][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [09/09/17 21:15:48.710][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [09/09/17 21:15:48.710][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [09/09/17 21:15:48.710][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [09/09/17 21:15:48.710][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [09/09/17 21:15:48.711][DEBUG] [Camera Thread] Auto focus HFR calculated at: 1.63 [09/09/17 21:15:49.721][DEBUG] [Camera Thread] Moving focuser to next position (7942)... [09/09/17 21:15:49.722][DEBUG] [Focuser Move Thread] Focuser moving to 7942 [09/09/17 21:15:49.780][DEBUG] [Focuser Move Thread] Focuser move call complete [09/09/17 21:15:50.836][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7942 matches requested position 7942 [09/09/17 21:15:50.836][DEBUG] [Camera Thread] Focuser move complete... [09/09/17 21:15:50.890][DEBUG] [Camera Thread] Focuser position matches requested position (7942), continuing... [09/09/17 21:15:50.946][DEBUG] [Camera Thread] Calculating step metric... [09/09/17 21:15:50.946][DEBUG] [Camera Thread] Taking auto focus frame(s)... [09/09/17 21:15:50.948][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [09/09/17 21:15:50.948][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [09/09/17 21:15:50.948][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [09/09/17 21:15:50.948][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [09/09/17 21:15:50.976][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [09/09/17 21:15:50.976][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [09/09/17 21:16:07.532][DEBUG] [Camera Thread] QSI expose and download: 16.4923931s [09/09/17 21:16:07.614][DEBUG] [Camera Thread] Internal Interface: Set Preview... [09/09/17 21:16:07.614][DEBUG] [Camera Thread] Display image preview using asynch task... [09/09/17 21:16:07.854][DEBUG] [Main Thread] --> Find stars (normal) [09/09/17 21:16:07.920][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [09/09/17 21:16:09.834][DEBUG] [Main Thread] Star detection using min star size of 4px... [09/09/17 21:16:09.834][DEBUG] [Main Thread] Star detection using max star size of 80px... [09/09/17 21:16:09.877][DEBUG] [Main Thread] Find stars took: 2023 ms... [09/09/17 21:16:09.877][DEBUG] [Main Thread] Star list contains 255 stars... [09/09/17 21:16:09.877][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [09/09/17 21:16:10.239][DEBUG] [Camera Thread] Auto focus HFR calculated at: 1.20 [09/09/17 21:16:11.250][DEBUG] [Camera Thread] Moving focuser to next position (7932)... [09/09/17 21:16:11.251][DEBUG] [Focuser Move Thread] Focuser moving to 7932 [09/09/17 21:16:11.313][DEBUG] [Focuser Move Thread] Focuser move call complete [09/09/17 21:16:12.367][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7932 matches requested position 7932 [09/09/17 21:16:12.367][DEBUG] [Camera Thread] Focuser move complete... [09/09/17 21:16:12.421][DEBUG] [Camera Thread] Focuser position matches requested position (7932), continuing... [09/09/17 21:16:12.477][DEBUG] [Camera Thread] Calculating step metric... [09/09/17 21:16:12.477][DEBUG] [Camera Thread] Taking auto focus frame(s)... [09/09/17 21:16:12.479][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [09/09/17 21:16:12.479][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [09/09/17 21:16:12.479][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [09/09/17 21:16:12.479][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [09/09/17 21:16:12.514][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [09/09/17 21:16:12.514][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [09/09/17 21:16:29.071][DEBUG] [Camera Thread] QSI expose and download: 16.4934171s [09/09/17 21:16:29.150][DEBUG] [Camera Thread] Internal Interface: Set Preview... [09/09/17 21:16:29.150][DEBUG] [Camera Thread] Display image preview using asynch task... [09/09/17 21:16:29.416][DEBUG] [Main Thread] --> Find stars (normal) [09/09/17 21:16:29.487][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [09/09/17 21:16:31.835][DEBUG] [Main Thread] Star detection using min star size of 4px... [09/09/17 21:16:31.835][DEBUG] [Main Thread] Star detection using max star size of 80px... [09/09/17 21:16:31.883][DEBUG] [Main Thread] Find stars took: 2467 ms... [09/09/17 21:16:31.883][DEBUG] [Main Thread] Star list contains 280 stars... [09/09/17 21:16:31.883][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [09/09/17 21:16:32.316][DEBUG] [Camera Thread] Auto focus HFR calculated at: 1.13 [09/09/17 21:16:33.327][DEBUG] [Camera Thread] Moving focuser to next position (7922)... [09/09/17 21:16:33.328][DEBUG] [Focuser Move Thread] Focuser moving to 7922 [09/09/17 21:16:33.386][DEBUG] [Focuser Move Thread] Focuser move call complete [09/09/17 21:16:34.440][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7922 matches requested position 7922 [09/09/17 21:16:34.440][DEBUG] [Camera Thread] Focuser move complete... [09/09/17 21:16:34.495][DEBUG] [Camera Thread] Focuser position matches requested position (7922), continuing... [09/09/17 21:16:34.551][DEBUG] [Camera Thread] Calculating step metric... [09/09/17 21:16:34.551][DEBUG] [Camera Thread] Taking auto focus frame(s)... [09/09/17 21:16:34.553][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [09/09/17 21:16:34.553][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [09/09/17 21:16:34.553][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [09/09/17 21:16:34.553][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [09/09/17 21:16:34.578][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [09/09/17 21:16:34.578][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [09/09/17 21:16:48.851][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [09/09/17 21:16:48.851][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [09/09/17 21:16:48.851][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [09/09/17 21:16:48.851][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [09/09/17 21:16:48.851][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [09/09/17 21:16:51.147][DEBUG] [Camera Thread] QSI expose and download: 16.5046879s [09/09/17 21:16:51.237][DEBUG] [Camera Thread] Internal Interface: Set Preview... [09/09/17 21:16:51.237][DEBUG] [Camera Thread] Display image preview using asynch task... [09/09/17 21:16:51.543][DEBUG] [Main Thread] --> Find stars (normal) [09/09/17 21:16:51.615][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [09/09/17 21:16:53.866][DEBUG] [Main Thread] Star detection using min star size of 4px... [09/09/17 21:16:53.866][DEBUG] [Main Thread] Star detection using max star size of 80px... [09/09/17 21:16:53.929][DEBUG] [Main Thread] Find stars took: 2385 ms... [09/09/17 21:16:53.929][DEBUG] [Main Thread] Star list contains 251 stars... [09/09/17 21:16:53.929][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [09/09/17 21:16:54.360][DEBUG] [Camera Thread] Auto focus HFR calculated at: 1.30 [09/09/17 21:16:55.370][DEBUG] [Camera Thread] Moving focuser to next position (7912)... [09/09/17 21:16:55.370][DEBUG] [Focuser Move Thread] Focuser moving to 7912 [09/09/17 21:16:55.426][DEBUG] [Focuser Move Thread] Focuser move call complete [09/09/17 21:16:56.480][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7912 matches requested position 7912 [09/09/17 21:16:56.480][DEBUG] [Camera Thread] Focuser move complete... [09/09/17 21:16:56.536][DEBUG] [Camera Thread] Focuser position matches requested position (7912), continuing... [09/09/17 21:16:56.593][DEBUG] [Camera Thread] Calculating step metric... [09/09/17 21:16:56.593][DEBUG] [Camera Thread] Taking auto focus frame(s)... [09/09/17 21:16:56.596][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [09/09/17 21:16:56.596][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [09/09/17 21:16:56.596][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [09/09/17 21:16:56.596][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [09/09/17 21:16:56.628][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [09/09/17 21:16:56.628][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [09/09/17 21:17:13.196][DEBUG] [Camera Thread] QSI expose and download: 16.5038156s [09/09/17 21:17:13.299][DEBUG] [Camera Thread] Internal Interface: Set Preview... [09/09/17 21:17:13.299][DEBUG] [Camera Thread] Display image preview using asynch task... [09/09/17 21:17:13.582][DEBUG] [Main Thread] --> Find stars (normal) [09/09/17 21:17:13.648][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [09/09/17 21:17:15.970][DEBUG] [Main Thread] Star detection using min star size of 4px... [09/09/17 21:17:15.970][DEBUG] [Main Thread] Star detection using max star size of 80px... [09/09/17 21:17:16.024][DEBUG] [Main Thread] Find stars took: 2441 ms... [09/09/17 21:17:16.024][DEBUG] [Main Thread] Star list contains 126 stars... [09/09/17 21:17:16.024][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [09/09/17 21:17:16.423][DEBUG] [Camera Thread] Auto focus HFR calculated at: 1.88 [09/09/17 21:17:17.431][DEBUG] [Camera Thread] Moving focuser to next position (7902)... [09/09/17 21:17:17.432][DEBUG] [Focuser Move Thread] Focuser moving to 7902 [09/09/17 21:17:17.487][DEBUG] [Focuser Move Thread] Focuser move call complete [09/09/17 21:17:18.543][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7902 matches requested position 7902 [09/09/17 21:17:18.543][DEBUG] [Camera Thread] Focuser move complete... [09/09/17 21:17:18.598][DEBUG] [Camera Thread] Focuser position matches requested position (7902), continuing... [09/09/17 21:17:18.653][DEBUG] [Camera Thread] Calculating step metric... [09/09/17 21:17:18.653][DEBUG] [Camera Thread] Taking auto focus frame(s)... [09/09/17 21:17:18.657][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [09/09/17 21:17:18.657][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [09/09/17 21:17:18.657][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [09/09/17 21:17:18.657][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [09/09/17 21:17:18.692][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [09/09/17 21:17:18.692][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [09/09/17 21:17:35.254][DEBUG] [Camera Thread] QSI expose and download: 16.4987428s [09/09/17 21:17:35.346][DEBUG] [Camera Thread] Internal Interface: Set Preview... [09/09/17 21:17:35.346][DEBUG] [Camera Thread] Display image preview using asynch task... [09/09/17 21:17:35.606][DEBUG] [Main Thread] --> Find stars (normal) [09/09/17 21:17:35.663][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [09/09/17 21:17:37.959][DEBUG] [Main Thread] Star detection using min star size of 4px... [09/09/17 21:17:37.959][DEBUG] [Main Thread] Star detection using max star size of 80px... [09/09/17 21:17:38.037][DEBUG] [Main Thread] Find stars took: 2430 ms... [09/09/17 21:17:38.037][DEBUG] [Main Thread] Star list contains 81 stars... [09/09/17 21:17:38.037][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [09/09/17 21:17:38.428][DEBUG] [Camera Thread] Auto focus HFR calculated at: 2.56 [09/09/17 21:17:39.445][DEBUG] [Camera Thread] New auto focus method calculated focus at: 7935 [09/09/17 21:17:39.445][DEBUG] [Camera Thread] Old auto focus method calculated focus at: 7935 [09/09/17 21:17:39.445][DEBUG] [Camera Thread] Using new auto focus method for focuser position [09/09/17 21:17:39.445][DEBUG] [Camera Thread] New focus position is at 7935 (@100.00C). Moving focuser... [09/09/17 21:17:39.446][DEBUG] [Focuser Move Thread] Focuser moving to 7935 [09/09/17 21:17:39.501][DEBUG] [Focuser Move Thread] Focuser move call complete [09/09/17 21:17:40.557][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7935 matches requested position 7935 [09/09/17 21:17:40.557][DEBUG] [Camera Thread] Focuser move complete... [09/09/17 21:17:40.612][DEBUG] [Camera Thread] Focuser successfully set to focus position (7935) [09/09/17 21:17:40.722][DEBUG] [Camera Thread] Taking validation frame at focus position... [09/09/17 21:17:40.723][DEBUG] [Camera Thread] Taking auto focus frame(s)... [09/09/17 21:17:40.725][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [09/09/17 21:17:40.725][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [09/09/17 21:17:40.725][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [09/09/17 21:17:40.725][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [09/09/17 21:17:40.756][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [09/09/17 21:17:40.756][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [09/09/17 21:17:48.983][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [09/09/17 21:17:48.983][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [09/09/17 21:17:48.983][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [09/09/17 21:17:48.983][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [09/09/17 21:17:48.983][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [09/09/17 21:17:57.313][DEBUG] [Camera Thread] QSI expose and download: 16.4924297s [09/09/17 21:17:57.396][DEBUG] [Camera Thread] Internal Interface: Set Preview... [09/09/17 21:17:57.396][DEBUG] [Camera Thread] Display image preview using asynch task... [09/09/17 21:17:57.626][DEBUG] [Main Thread] --> Find stars (normal) [09/09/17 21:17:57.695][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75... [09/09/17 21:17:59.921][DEBUG] [Main Thread] Star detection using min star size of 4px... [09/09/17 21:17:59.921][DEBUG] [Main Thread] Star detection using max star size of 80px... [09/09/17 21:17:59.974][DEBUG] [Main Thread] Find stars took: 2347 ms... [09/09/17 21:17:59.974][DEBUG] [Main Thread] Star list contains 260 stars... [09/09/17 21:17:59.974][DEBUG] [Main Thread] Calculating HFR with sample size: 6 [09/09/17 21:18:00.541][DEBUG] [Camera Thread] Auto focus HFR calculated at: 1.17 [09/09/17 21:18:01.548][DEBUG] [Camera Thread] Auto focus is complete (best fit method: 7935; HFR 1.17)... [09/09/17 21:18:01.553][DEBUG] [Camera Thread] Entering chart disposal loop... [09/09/17 21:18:15.721][DEBUG] [AfChartThread] AF chart dialog is closed... [09/09/17 21:18:15.743][DEBUG] [Camera Thread] Chart disposal loop is complete... [09/09/17 21:18:15.743][DEBUG] [Camera Thread] Auto focus is done... [09/09/17 21:18:15.747][DEBUG] [Camera Thread] Auto focus complete... [09/09/17 21:18:15.755][DEBUG] [Camera Thread] SGM_FOCUSER_AUTO_FOCUS complete... [09/09/17 21:18:24.182][DEBUG] [Filter Wheel Thread] SGM_FW_SET_POSITION message received... [09/09/17 21:18:26.067][DEBUG] [Filter Wheel Thread] SGM_FW_SET_POSITION complete... [09/09/17 21:18:35.943][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS message received... [09/09/17 21:18:35.952][DEBUG] [Camera Thread] ASCOM (QSI) camera: frame and focus... [09/09/17 21:18:35.987][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [09/09/17 21:18:35.987][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [09/09/17 21:18:49.090][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [09/09/17 21:18:49.090][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [09/09/17 21:18:49.090][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [09/09/17 21:18:49.090][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [09/09/17 21:18:49.090][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [09/09/17 21:19:46.363][DEBUG] [Camera Thread] QSI expose and download: 70.3116824s [09/09/17 21:19:46.437][DEBUG] [Camera Thread] Internal Interface: Set Preview... [09/09/17 21:19:46.437][DEBUG] [Camera Thread] Display image preview using asynch task... [09/09/17 21:19:46.803][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS complete... [09/09/17 21:19:49.239][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [09/09/17 21:19:49.239][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [09/09/17 21:19:49.239][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [09/09/17 21:19:49.239][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [09/09/17 21:19:49.239][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [09/09/17 21:20:06.655][DEBUG] [Camera Thread] SGM_FOCUSER_AUTO_FOCUS message received... [09/09/17 21:20:06.655][DEBUG] [Camera Thread] Checking for auto focus... [09/09/17 21:20:06.665][DEBUG] [Camera Thread] Auto focus: setting filter Ha [09/09/17 21:20:06.665][DEBUG] [Camera Thread] Setting filter position 5... [09/09/17 21:20:06.665][DEBUG] [Camera Thread] Filter position 5 is already set. Skipping... [09/09/17 21:20:06.668][DEBUG] [Camera Thread] Auto focus running... [09/09/17 21:20:06.675][DEBUG] [Camera Thread] Turning temp comp off.. [09/09/17 21:20:06.677][DEBUG] [Camera Thread] Performing Sequence Generator auto focus using Half Flux Radius. AFID: 1 [09/09/17 21:20:06.736][DEBUG] [AfChartThread] Showing AF chart dialog... [09/09/17 21:20:06.818][DEBUG] [Camera Thread] Auto focus data [09/09/17 21:20:06.818][DEBUG] [Camera Thread] - Data Points: 7 [09/09/17 21:20:06.818][DEBUG] [Camera Thread] - Step Size: 10 [09/09/17 21:20:06.818][DEBUG] [Camera Thread] - Current Position: 7935 [09/09/17 21:20:06.818][DEBUG] [Camera Thread] - Initial Move Position: 7965 [09/09/17 21:20:06.822][DEBUG] [Camera Thread] AF Darks exception: The path is not of a legal form. [09/09/17 21:20:06.823][DEBUG] [Camera Thread] Moving focuser to next position (7965)... [09/09/17 21:20:06.824][DEBUG] [Focuser Move Thread] Focuser moving to 7965 [09/09/17 21:20:06.879][DEBUG] [Focuser Move Thread] Focuser move call complete [09/09/17 21:20:07.948][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 7965 matches requested position 7965 [09/09/17 21:20:07.948][DEBUG] [Camera Thread] Focuser move complete... [09/09/17 21:20:08.005][DEBUG] [Camera Thread] Focuser position matches requested position (7965), continuing... [09/09/17 21:20:08.060][DEBUG] [Camera Thread] Calculating step metric... [09/09/17 21:20:08.060][DEBUG] [Camera Thread] Taking auto focus frame(s)... [09/09/17 21:20:08.063][DEBUG] [Camera Thread] ASCOM (QSI) camera: Capturing auto focus frame... [09/09/17 21:20:08.063][DEBUG] [Camera Thread] ASCOM (QSI): AF Binning: 1x1... [09/09/17 21:20:08.063][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure length: 1 seconds... [09/09/17 21:20:08.063][DEBUG] [Camera Thread] ASCOM (QSI): AF Exposure DL speed: b [09/09/17 21:20:08.085][DEBUG] [Camera Thread] ASCOM (QSI) Camera: setting gain to HIGH... [09/09/17 21:20:08.085][DEBUG] [Camera Thread] ASCOM (QSI) camera: setting normal speed readout... [09/09/17 21:20:25.443][DEBUG] [Camera Thread] QSI expose and download: 17.293824s [09/09/17 21:20:25.529][DEBUG] [Camera Thread] Internal Interface: Set Preview... [09/09/17 21:20:25.529][DEBUG] [Camera Thread] Display image preview using asynch task... [09/09/17 21:20:25.779][DEBUG] [Main Thread] --> Find stars (normal) [09/09/17 21:20:25.827][DEBUG] [Main Thread] AF frame was too large... downsample = 0.75...