[03-24-18 20:06:36.756][DEBUG] [Main Thread] Licensing: Machine ID found... [03-24-18 20:06:37.006][DEBUG] [Main Thread] Checking for valid internet connection... [03-24-18 20:06:39.670][DEBUG] [Main Thread] Valid internet connection detected... [03-24-18 20:06:47.573][DEBUG] [Main Thread] Error in MakeSecureLicenseCall: El relleno entre caracteres no es válido y no se puede quitar. [03-24-18 20:06:47.573][DEBUG] [Main Thread] Error getting licenses! El relleno entre caracteres no es válido y no se puede quitar. [03-24-18 20:06:47.573][DEBUG] [Main Thread] Licensing: Request for licenses failed, looking for cookie.. [03-24-18 20:06:48.135][DEBUG] [Main Thread] Licensing: Cookie found.. [03-24-18 20:06:48.135][DEBUG] [Main Thread] Licensing: Applying licenses.. [03-24-18 20:06:48.135][DEBUG] [Main Thread] Licensing: Licenses applied.. [03-24-18 20:06:48.135][DEBUG] [Main Thread] SGPro retail license... [03-24-18 20:06:53.540][DEBUG] [Main Thread] API successfully started on port 59590... [03-24-18 20:06:58.134][DEBUG] [Main Thread] Error loading email endpoint addresses! No se pudo encontrar el archivo 'C:\Users\Leandro\AppData\Local\SequenceGenerator\Sequence Generator Pro\1-emailEndpointAddresses.sgd'. [03-24-18 20:06:58.135][DEBUG] [Main Thread] Error loading email endpoint addresses! No se pudo encontrar el archivo 'C:\Users\Leandro\AppData\Local\SequenceGenerator\Sequence Generator Pro\2-emailEndpointAddresses.sgd'. [03-24-18 20:06:58.135][DEBUG] [Main Thread] Error loading email endpoint addresses! No se pudo encontrar el archivo 'C:\Users\Leandro\AppData\Local\SequenceGenerator\Sequence Generator Pro\3-emailEndpointAddresses.sgd'. [03-24-18 20:06:58.137][DEBUG] [Main Thread] Error loading text file endpoint data! No se pudo encontrar el archivo 'C:\Users\Leandro\AppData\Local\SequenceGenerator\Sequence Generator Pro\1-textFileEndpointSettings.sgd'. [03-24-18 20:06:58.137][DEBUG] [Main Thread] Error loading text file endpoint data! No se pudo encontrar el archivo 'C:\Users\Leandro\AppData\Local\SequenceGenerator\Sequence Generator Pro\2-textFileEndpointSettings.sgd'. [03-24-18 20:06:58.138][DEBUG] [Main Thread] Error loading text file endpoint data! No se pudo encontrar el archivo 'C:\Users\Leandro\AppData\Local\SequenceGenerator\Sequence Generator Pro\3-textFileEndpointSettings.sgd'. [03-24-18 20:06:58.752][DEBUG] [Main Thread] ===== S E Q U E N C E G E N E R A T O R (v3.0.1.0) ===== [03-24-18 20:07:00.193][DEBUG] [Main Thread] OS: Microsoft Windows 10 Home Single Language [03-24-18 20:07:01.666][DEBUG] [Rotator Thread] Entering Rotator message dispatch loop... [03-24-18 20:07:01.670][DEBUG] [Dome Thread] Entering Dome message dispatch loop... [03-24-18 20:07:01.673][DEBUG] [Environment Device Thread] Starting environment device monitor... [03-24-18 20:07:01.696][DEBUG] [Safety Thread] Starting safety monitor... [03-24-18 20:07:01.700][DEBUG] [Filter Wheel Thread] Entering Filter Wheel message dispatch loop... [03-24-18 20:07:01.701][DEBUG] [TEC Thread] Entering TEC message dispatch loop... [03-24-18 20:07:01.703][DEBUG] [Focuser Thread] Entering Fouser message dispatch loop... [03-24-18 20:07:01.704][DEBUG] [Auto Guider Error Thread] Entering Auto Guider message dispatch loop... [03-24-18 20:07:01.705][DEBUG] [Auto Guider Thread] Entering Auto Guider message dispatch loop... [03-24-18 20:07:01.707][DEBUG] [Camera Thread] Entering camera message dispatch loop... [03-24-18 20:07:01.786][DEBUG] [Telescope Thread] Entering Telescope message dispatch loop... [03-24-18 20:07:01.943][DEBUG] [Main Thread] Settings changed (or loaded)... [03-24-18 20:07:01.985][DEBUG] [Main Thread] * SETTING: settingMdiAutoStretch = False [03-24-18 20:07:01.985][DEBUG] [Main Thread] * SETTING: settingIgLastFileFolder = [03-24-18 20:07:01.985][DEBUG] [Main Thread] * SETTING: settingFormScaleWarning = False [03-24-18 20:07:01.985][DEBUG] [Main Thread] * SETTING: settingRecoveryDuration = 90 [03-24-18 20:07:01.985][DEBUG] [Main Thread] * SETTING: settingSBIGLpt2Address = 278 [03-24-18 20:07:01.985][DEBUG] [Main Thread] * SETTING: settingFliFlushLow = 0 [03-24-18 20:07:01.985][DEBUG] [Main Thread] * SETTING: settingSBIGLpt1Address = 378 [03-24-18 20:07:01.985][DEBUG] [Main Thread] * SETTING: settingShowBetas = False [03-24-18 20:07:01.985][DEBUG] [Main Thread] * SETTING: settingMdiMouseAction = Stretch [03-24-18 20:07:01.985][DEBUG] [Main Thread] * SETTING: settingLastAscomCamera = [03-24-18 20:07:01.985][DEBUG] [Main Thread] * SETTING: settingTemperhumInterval = 300 [03-24-18 20:07:01.986][DEBUG] [Main Thread] * SETTING: settingASCOMWarningShown = False [03-24-18 20:07:01.986][DEBUG] [Main Thread] * SETTING: settingPlateCamScale = 0 [03-24-18 20:07:01.986][DEBUG] [Main Thread] * SETTING: settingCanonMirrorSettle = 0 [03-24-18 20:07:01.986][DEBUG] [Main Thread] * SETTING: settingInterface = [03-24-18 20:07:01.986][DEBUG] [Main Thread] * SETTING: settingLastAscomFilterWheel = [03-24-18 20:07:01.986][DEBUG] [Main Thread] * SETTING: settingAstroArtDisplayInstructions = True [03-24-18 20:07:01.986][DEBUG] [Main Thread] * SETTING: settingMdiPhdZoom = 40 [03-24-18 20:07:01.986][DEBUG] [Main Thread] * SETTING: settingCheckForUpdatesOnStart = True [03-24-18 20:07:01.986][DEBUG] [Main Thread] * SETTING: settingMdiLastImageDir = [03-24-18 20:07:01.986][DEBUG] [Main Thread] * SETTING: settingTemperHumUserTempComp = 0 [03-24-18 20:07:01.986][DEBUG] [Main Thread] * SETTING: settingCaptureCalFramesAlways = False [03-24-18 20:07:01.986][DEBUG] [Main Thread] * SETTING: settingDelayFirst = 0 [03-24-18 20:07:01.986][DEBUG] [Main Thread] * SETTING: settingFliNormalSpeedMode = 0 [03-24-18 20:07:01.986][DEBUG] [Main Thread] * SETTING: settingFliHighSpeedMode = 0 [03-24-18 20:07:01.986][DEBUG] [Main Thread] * SETTING: settingMfPosition = {X=0,Y=0} [03-24-18 20:07:01.986][DEBUG] [Main Thread] * SETTING: settingSoundAttention = [03-24-18 20:07:01.986][DEBUG] [Main Thread] * SETTING: settingPinpointCatalog = [03-24-18 20:07:01.986][DEBUG] [Main Thread] * SETTING: settingCameraOverhead = [03-24-18 20:07:01.986][DEBUG] [Main Thread] * SETTING: settingPlateSolveOn = False [03-24-18 20:07:02.031][DEBUG] [Main Thread] * SETTING: settingAstrometryNetEndpointList = System.Collections.Specialized.StringCollection [03-24-18 20:07:02.031][DEBUG] [Main Thread] * SETTING: settingSBIGGuider = None [03-24-18 20:07:02.031][DEBUG] [Main Thread] * SETTING: settingFocusLockDirectory = [03-24-18 20:07:02.031][DEBUG] [Main Thread] * SETTING: settingQSIFanSpeed = 2 [03-24-18 20:07:02.031][DEBUG] [Main Thread] * SETTING: settingNaggerText = [03-24-18 20:07:02.031][DEBUG] [Main Thread] * SETTING: settingMdiPhdDisplay = RA/Dec [03-24-18 20:07:02.031][DEBUG] [Main Thread] * SETTING: settingMdiShowToolStrip = True [03-24-18 20:07:02.031][DEBUG] [Main Thread] * SETTING: settingMdiShowCrosshairs = True [03-24-18 20:07:02.031][DEBUG] [Main Thread] * SETTING: WindowGeometry = [03-24-18 20:07:02.032][DEBUG] [Main Thread] * SETTING: settingIgRenameStringLocation = 2 [03-24-18 20:07:02.032][DEBUG] [Main Thread] * SETTING: settingIgSubFolders = False [03-24-18 20:07:02.032][DEBUG] [Main Thread] * SETTING: WindowGeometrySearch = [03-24-18 20:07:02.032][DEBUG] [Main Thread] * SETTING: settingOffset = -1 [03-24-18 20:07:02.032][DEBUG] [Main Thread] * SETTING: settingAutoStretchLevel = Low [03-24-18 20:07:02.032][DEBUG] [Main Thread] * SETTING: settingTemperHumUnits = C [03-24-18 20:07:02.032][DEBUG] [Main Thread] * SETTING: settingProfileDirectory = [03-24-18 20:07:02.032][DEBUG] [Main Thread] * SETTING: settingBasename = [03-24-18 20:07:02.032][DEBUG] [Main Thread] * SETTING: settingHfrColor = Color [RoyalBlue] [03-24-18 20:07:02.032][DEBUG] [Main Thread] * SETTING: settingNikonAfBinning = 1 [03-24-18 20:07:02.032][DEBUG] [Main Thread] * SETTING: settingSBIGFanOffWithTec = False [03-24-18 20:07:02.032][DEBUG] [Main Thread] * SETTING: settingLastAscomSafetyMonitor = [03-24-18 20:07:02.032][DEBUG] [Main Thread] * SETTING: settingMdiSize = {Width=800, Height=600} [03-24-18 20:07:02.032][DEBUG] [Main Thread] * SETTING: settingIgCopyAndRenameFiles = False [03-24-18 20:07:02.032][DEBUG] [Main Thread] * SETTING: settingSimDownloadTimesAf = False [03-24-18 20:07:02.032][DEBUG] [Main Thread] * SETTING: settingNagger = False [03-24-18 20:07:02.032][DEBUG] [Main Thread] * SETTING: settingImportFromCfw = True [03-24-18 20:07:02.032][DEBUG] [Main Thread] * SETTING: settingMdiShowStatusTrip = True [03-24-18 20:07:02.032][DEBUG] [Main Thread] * SETTING: settingShowTooltipHelp = True [03-24-18 20:07:02.048][DEBUG] [Main Thread] * SETTING: settingLastUpdateCheck = 01-01-2012 0:00:00 [03-24-18 20:07:02.048][DEBUG] [Main Thread] * SETTING: settingEventSuffixes = null [03-24-18 20:07:02.048][DEBUG] [Main Thread] * SETTING: settingCpPosition = {X=0,Y=0} [03-24-18 20:07:02.048][DEBUG] [Main Thread] * SETTING: settingSBIGCfwPort = [03-24-18 20:07:02.048][DEBUG] [Main Thread] * SETTING: settingMdiLocation = {X=0,Y=0} [03-24-18 20:07:02.048][DEBUG] [Main Thread] * SETTING: settingIgLastCsvFolder = [03-24-18 20:07:02.048][DEBUG] [Main Thread] * SETTING: settingPlateSolve3Catalogs = [03-24-18 20:07:02.048][DEBUG] [Main Thread] * SETTING: settingSBIGFilterWheel = Auto Detect [03-24-18 20:07:02.048][DEBUG] [Main Thread] * SETTING: settingSBIGConnection = USB [03-24-18 20:07:02.048][DEBUG] [Main Thread] * SETTING: settingDSLRSoftwareBinForAf = False [03-24-18 20:07:02.048][DEBUG] [Main Thread] * SETTING: settingAutoSave = True [03-24-18 20:07:02.048][DEBUG] [Main Thread] * SETTING: settingFirstTime = True [03-24-18 20:07:02.048][DEBUG] [Main Thread] * SETTING: settingAutoRecoveryActive = False [03-24-18 20:07:02.048][DEBUG] [Main Thread] * SETTING: settingMdiState = maximized [03-24-18 20:07:02.048][DEBUG] [Main Thread] * SETTING: settingUseSequenceOptions = False [03-24-18 20:07:02.048][DEBUG] [Main Thread] * SETTING: settingSoundCompleted = [03-24-18 20:07:02.048][DEBUG] [Main Thread] * SETTING: settingTrialExpiredShown = False [03-24-18 20:07:02.048][DEBUG] [Main Thread] * SETTING: settingTemperHumColor = Grey [03-24-18 20:07:02.049][DEBUG] [Main Thread] * SETTING: settingDataDirectory = [03-24-18 20:07:02.049][DEBUG] [Main Thread] * SETTING: settingCanonFileFormat = Single Image (CFA FITS) [03-24-18 20:07:02.049][DEBUG] [Main Thread] * SETTING: settingAlnitakComPort = [03-24-18 20:07:02.049][DEBUG] [Main Thread] * SETTING: settingIgRenameFiles = False [03-24-18 20:07:02.049][DEBUG] [Main Thread] * SETTING: settingPhd2InstallPath = [03-24-18 20:07:02.049][DEBUG] [Main Thread] * SETTING: settingSubFolder = True [03-24-18 20:07:02.049][DEBUG] [Main Thread] * SETTING: settingLastSGSaveFolder = [03-24-18 20:07:02.049][DEBUG] [Main Thread] * SETTING: settingFindStars = Normal (recommended) [03-24-18 20:07:02.049][DEBUG] [Main Thread] * SETTING: settingNikonDataFormat = FITS [03-24-18 20:07:02.049][DEBUG] [Main Thread] * SETTING: settingPlaySounds = False [03-24-18 20:07:02.049][DEBUG] [Main Thread] * SETTING: settingLastNikonType = Auto Connect [03-24-18 20:07:02.049][DEBUG] [Main Thread] * SETTING: settingLastSequenceStart = 01-01-0001 0:00:00 [03-24-18 20:07:02.049][DEBUG] [Main Thread] * SETTING: settingLastAscomTelescope = [03-24-18 20:07:02.049][DEBUG] [Main Thread] * SETTING: settingMdiPhdScale = 100 [03-24-18 20:07:02.049][DEBUG] [Main Thread] * SETTING: settingIgRenameType = 0 [03-24-18 20:07:02.049][DEBUG] [Main Thread] * SETTING: settingHelpLanguage = English [03-24-18 20:07:02.049][DEBUG] [Main Thread] * SETTING: settingTemperHumUserHumidityComp = 0 [03-24-18 20:07:02.049][DEBUG] [Main Thread] * SETTING: settingMdiPhdRaColor = Color [Blue] [03-24-18 20:07:02.049][DEBUG] [Main Thread] * SETTING: settingLastImageSaveFolder = [03-24-18 20:07:02.049][DEBUG] [Main Thread] * SETTING: settingIgOverwriteExisting = False [03-24-18 20:07:02.049][DEBUG] [Main Thread] * SETTING: settingFinishFullEvent = True [03-24-18 20:07:02.050][DEBUG] [Main Thread] * SETTING: settingMosaicOverlap = 20 [03-24-18 20:07:02.050][DEBUG] [Main Thread] * SETTING: settingQSIFanOffWithTec = False [03-24-18 20:07:02.050][DEBUG] [Main Thread] * SETTING: settingRecentSequences = [03-24-18 20:07:02.050][DEBUG] [Main Thread] * SETTING: settingDefaultDirectory = [03-24-18 20:07:02.050][DEBUG] [Main Thread] * SETTING: settingMdiPhdDecColor = Color [Red] [03-24-18 20:07:02.050][DEBUG] [Main Thread] * SETTING: settingAstrometryNetEndpoint = Astrometry.NET (Remote) [03-24-18 20:07:02.050][DEBUG] [Main Thread] * SETTING: settingFliFlushHigh = 0 [03-24-18 20:07:02.050][DEBUG] [Main Thread] * SETTING: settingStarsColor = Color [OrangeRed] [03-24-18 20:07:02.050][DEBUG] [Main Thread] * SETTING: settingDefaultMfwFov = 4,0 [03-24-18 20:07:02.050][DEBUG] [Main Thread] * SETTING: settingSBIGLpt3Address = 3BC [03-24-18 20:07:02.050][DEBUG] [Main Thread] * SETTING: settingLastAscomFocuser = [03-24-18 20:07:02.050][DEBUG] [Main Thread] * SETTING: settingIgLastFolder = [03-24-18 20:07:02.050][DEBUG] [Main Thread] * SETTING: WindowGeometryOpen = [03-24-18 20:07:02.050][DEBUG] [Main Thread] * SETTING: settingNumEvents = 5 [03-24-18 20:07:02.050][DEBUG] [Main Thread] * SETTING: settingDelayBetween = 0 [03-24-18 20:07:02.050][DEBUG] [Main Thread] * SETTING: settingCanonAfBinning = 1 [03-24-18 20:07:02.050][DEBUG] [Main Thread] * SETTING: settingPlateCamBlank = [03-24-18 20:07:02.050][DEBUG] [Main Thread] * SETTING: settingLogApiCalls = False [03-24-18 20:07:02.050][DEBUG] [Main Thread] * SETTING: settingNikonUseBulbModeAlways = False [03-24-18 20:07:02.050][DEBUG] [Main Thread] * SETTING: settingTempFiles = C:\Temp [03-24-18 20:07:02.050][DEBUG] [Main Thread] * SETTING: settingFliHighSpeedDesc = [03-24-18 20:07:02.050][DEBUG] [Main Thread] * SETTING: settingSBIGCfwUseRS232 = False [03-24-18 20:07:02.050][DEBUG] [Main Thread] * SETTING: settingRecoveryInterval = 10 [03-24-18 20:07:02.050][DEBUG] [Main Thread] * SETTING: settingEnableImageHistory = False [03-24-18 20:07:02.050][DEBUG] [Main Thread] * SETTING: settingSBIGEthernetIP = 0.0.0.0 [03-24-18 20:07:02.050][DEBUG] [Main Thread] * SETTING: settingFliNormalSpeedDesc = [03-24-18 20:07:02.051][DEBUG] [Main Thread] * SETTING: settingIgCopyLocation = [03-24-18 20:07:02.051][DEBUG] [Main Thread] * SETTING: settingSBIGAutoDetectIp = True [03-24-18 20:07:02.051][DEBUG] [Main Thread] * SETTING: settingAstroArtStartApp = True [03-24-18 20:07:02.051][DEBUG] [Main Thread] * SETTING: settingSimDownloadTimesPlate = False [03-24-18 20:07:02.051][DEBUG] [Main Thread] * SETTING: settingMicroTouchLocation = [03-24-18 20:07:02.051][DEBUG] [Main Thread] * SETTING: settingEventAlwaysVisible = False [03-24-18 20:07:02.051][DEBUG] [Main Thread] * SETTING: settingMdiNewWindow = False [03-24-18 20:07:02.051][DEBUG] [Main Thread] * SETTING: settingTrialQuestionsAsked = False [03-24-18 20:07:02.051][DEBUG] [Main Thread] * SETTING: settingFliRbiMitigation = False [03-24-18 20:07:02.051][DEBUG] [Main Thread] * SETTING: settingGain = -1 [03-24-18 20:07:02.051][DEBUG] [Main Thread] * SETTING: settingLastAscomDome = [03-24-18 20:07:02.051][DEBUG] [Main Thread] * SETTING: settingLookForTemperDevices = False [03-24-18 20:07:02.051][DEBUG] [Main Thread] * SETTING: settingLastAscomRotator = [03-24-18 20:07:02.051][DEBUG] [Main Thread] * SETTING: settingAfTestPackPath = [03-24-18 20:07:03.271][DEBUG] [Main Thread] ASCOM Observing Conditions Hub (OCH) [03-24-18 20:07:03.271][DEBUG] [Main Thread] ASCOM Observing Conditions Simulator [03-24-18 20:07:03.271][DEBUG] [Main Thread] OpenWeatherMap ObservingConditions [03-24-18 20:07:03.271][DEBUG] [Main Thread] TEMPerHUM [03-24-18 20:07:03.276][DEBUG] [Main Thread] Performing initialization... [03-24-18 20:07:03.276][DEBUG] [Main Thread] Creating new sequence with default profile: POCHOCO [03-24-18 20:07:03.348][DEBUG] [Main Thread] Performing full form reset (with profile POCHOCO)... [03-24-18 20:07:03.362][DEBUG] [Main Thread] Retreiving new equipment objects... [03-24-18 20:07:03.362][DEBUG] [Main Thread] New camera object (QHY10-StarSenseSci) dispatched... [03-24-18 20:07:03.464][DEBUG] [Main Thread] New filter wheel object (No Filter Wheel) dispatched... [03-24-18 20:07:03.465][DEBUG] [Main Thread] New environment device object (OpenWeatherMap ObservingConditions) dispatched... [03-24-18 20:07:03.470][DEBUG] [Main Thread] New focuser object (Seletek Focuser (MAIN port)) dispatched... [03-24-18 20:07:03.487][DEBUG] [Main Thread] New telescope object (Celestron Telescope Driver) dispatched... [03-24-18 20:07:03.490][DEBUG] [Main Thread] New rotator object (Manual Rotator) dispatched... [03-24-18 20:07:03.492][DEBUG] [Main Thread] New dome object (No Observatory) dispatched... [03-24-18 20:07:03.495][DEBUG] [Main Thread] New flat box object (Manual Flat Box) dispatched... [03-24-18 20:07:03.499][DEBUG] [Main Thread] New safety monitor object (No Safety Monitor) dispatched... [03-24-18 20:07:03.500][DEBUG] [Main Thread] New auto guider object (PHD2) dispatched... [03-24-18 20:07:03.501][DEBUG] [Main Thread] New plate solver object (PlateSolve2) dispatched... [03-24-18 20:07:03.957][DEBUG] [Main Thread] Populating the form controls... [03-24-18 20:07:04.250][DEBUG] [Main Thread] Added row 0... [03-24-18 20:07:04.400][DEBUG] [Main Thread] Added row 1... [03-24-18 20:07:04.655][DEBUG] [Main Thread] Loading custom filter names... [03-24-18 20:07:04.676][DEBUG] [Main Thread] PopulateDataModel: Transferring view to the data model... [03-24-18 20:07:04.840][DEBUG] [Main Thread] Retreiving new equipment objects... [03-24-18 20:07:04.840][DEBUG] [Main Thread] No change in camera object... [03-24-18 20:07:04.840][DEBUG] [Main Thread] New filter wheel object (No Filter Wheel) dispatched... [03-24-18 20:07:04.842][DEBUG] [Main Thread] No change in environment device object... [03-24-18 20:07:04.842][DEBUG] [Main Thread] No change in focuser object... [03-24-18 20:07:04.843][DEBUG] [Sequence Thread] Entering sequence message dispatch loop... [03-24-18 20:07:04.844][DEBUG] [Main Thread] MoveFocuserAbs: Error! The focuser is no longer connected! [03-24-18 20:07:04.845][DEBUG] [Main Thread] No change in telescope object... [03-24-18 20:07:04.845][DEBUG] [Main Thread] No change in rotator object... [03-24-18 20:07:04.845][DEBUG] [Main Thread] New dome object (No Observatory) dispatched... [03-24-18 20:07:04.846][DEBUG] [Main Thread] No change in flat box object... [03-24-18 20:07:04.846][DEBUG] [Main Thread] New safety monitor object (No Safety Monitor) dispatched... [03-24-18 20:07:04.847][DEBUG] [Main Thread] New auto guider object (PHD2) dispatched... [03-24-18 20:07:04.848][DEBUG] [Main Thread] No change in plate solver object... [03-24-18 20:07:04.854][DEBUG] [Main Thread] Checking if version check is required... [03-24-18 20:07:05.515][DEBUG] [Cleaner Thread] Running cleanup... [03-24-18 20:07:05.518][DEBUG] [Cleaner Thread] Cleaning 0 files in directory C:\Users\Leandro\AppData\Local\SequenceGenerator\Temp\... [03-24-18 20:07:05.519][DEBUG] [Cleaner Thread] Cleaning multi camera registration file... [03-24-18 20:07:05.519][DEBUG] [Cleaner Thread] Cleaning finished... [03-24-18 20:07:05.531][DEBUG] [Main Thread] UI layout found, loading layout at C:\Users\Leandro\AppData\Local\SequenceGenerator\sg_ui_config.xml [03-24-18 20:08:05.404][DEBUG] [Main Thread] Performing deserialize... [03-24-18 20:08:05.467][DEBUG] [Main Thread] Loading custom filter names... [03-24-18 20:08:05.494][DEBUG] [Main Thread] ReflectDataModel: Transferring data model to the view... [03-24-18 20:08:05.578][DEBUG] [Main Thread] Retreiving new equipment objects... [03-24-18 20:08:05.578][DEBUG] [Main Thread] No change in camera object... [03-24-18 20:08:05.579][DEBUG] [Main Thread] New filter wheel object (No Filter Wheel) dispatched... [03-24-18 20:08:05.581][DEBUG] [Main Thread] No change in environment device object... [03-24-18 20:08:05.581][DEBUG] [Main Thread] No change in focuser object... [03-24-18 20:08:05.582][DEBUG] [Main Thread] MoveFocuserAbs: Error! The focuser is no longer connected! [03-24-18 20:08:05.582][DEBUG] [Main Thread] No change in telescope object... [03-24-18 20:08:05.583][DEBUG] [Main Thread] No change in rotator object... [03-24-18 20:08:05.583][DEBUG] [Main Thread] New dome object (No Observatory) dispatched... [03-24-18 20:08:05.586][DEBUG] [Main Thread] No change in flat box object... [03-24-18 20:08:05.586][DEBUG] [Main Thread] New safety monitor object (No Safety Monitor) dispatched... [03-24-18 20:08:05.588][DEBUG] [Main Thread] New auto guider object (PHD2) dispatched... [03-24-18 20:08:05.588][DEBUG] [Main Thread] No change in plate solver object... [03-24-18 20:08:05.758][DEBUG] [Main Thread] Populating the form controls... [03-24-18 20:08:05.871][DEBUG] [Main Thread] Added row 0... [03-24-18 20:08:05.999][DEBUG] [Main Thread] Added row 1... [03-24-18 20:08:06.018][DEBUG] [Main Thread] Populating the form controls... [03-24-18 20:08:06.105][DEBUG] [Main Thread] Added row 0... [03-24-18 20:08:06.224][DEBUG] [Main Thread] Added row 1... [03-24-18 20:08:06.239][DEBUG] [Main Thread] Populating the form controls... [03-24-18 20:08:06.318][DEBUG] [Main Thread] Added row 0... [03-24-18 20:08:06.458][DEBUG] [Main Thread] Added row 1... [03-24-18 20:08:06.473][DEBUG] [Main Thread] Populating the form controls... [03-24-18 20:08:06.558][DEBUG] [Main Thread] Added row 0... [03-24-18 20:08:06.574][DEBUG] [Main Thread] Populating the form controls... [03-24-18 20:08:06.655][DEBUG] [Main Thread] Added row 0... [03-24-18 20:08:06.667][DEBUG] [Main Thread] Populating the form controls... [03-24-18 20:08:06.743][DEBUG] [Main Thread] Added row 0... [03-24-18 20:08:06.755][DEBUG] [Main Thread] Populating the form controls... [03-24-18 20:08:06.835][DEBUG] [Main Thread] Added row 0... [03-24-18 20:09:14.314][DEBUG] [Main Thread] FitsFileHeaderData: Angle - 359,24 [03-24-18 20:09:14.315][DEBUG] [Main Thread] FitsFileHeaderData: Scale - 1,914455 [03-24-18 20:09:14.331][DEBUG] [Main Thread] FitsFileHeaderData: RA - 10,7527663556315 [03-24-18 20:09:14.333][DEBUG] [Main Thread] FitsFileHeaderData: DEC - -58,9844921567905 [03-24-18 20:09:16.288][DEBUG] [Main Thread] Display image preview using asynch task... [03-24-18 20:11:54.449][DEBUG] [Main Thread] PopulateDataModel: Transferring view to the data model... [03-24-18 20:11:54.484][DEBUG] [MF Update Thread] Performing serialize... [03-24-18 20:13:15.402][DEBUG] [Main Thread] PopulateDataModel: Transferring view to the data model... [03-24-18 20:13:15.414][DEBUG] [MF Update Thread] Performing serialize... [03-24-18 20:22:33.281][DEBUG] [Main Thread] Connecting ASCOM focuser... [03-24-18 20:22:37.516][DEBUG] [Main Thread] Focuser supports temperature [03-24-18 20:22:37.516][DEBUG] [Main Thread] Current temperature reports: 20,7 [03-24-18 20:22:37.526][DEBUG] [Main Thread] Focuser reports Max Increment as: 3000 [03-24-18 20:22:37.526][DEBUG] [Main Thread] Focuser reports max steps as: 3000 [03-24-18 20:22:37.676][DEBUG] [Telescope Thread] Telescope Dispatch loop: Received SGM_TELESCOPE_CONNECT... [03-24-18 20:22:40.742][DEBUG] [Telescope Thread] Disconnecting ASCOM Telescope: ASCOM.Celestron.Telescope [03-24-18 20:22:40.752][DEBUG] [Telescope Thread] Failed to connect to telescope. : CheckDotNetExceptions ASCOM.Celestron.Telescope ConnectedSet System.IO.IOException: El puerto 'COM6' no existe. (See Inner Exception for details) (System.IO.IOException: El puerto 'COM6' no existe.) en ASCOM.DriverAccess.MemberFactory.CheckDotNetExceptions(String memberName, Exception e) en c:\ASCOM Build\Export\ASCOM.DriverAccess\MemberFactory.cs:línea 630 en ASCOM.DriverAccess.MemberFactory.SetTargetInvocationExceptionHandler(String memberName, Exception e) en c:\ASCOM Build\Export\ASCOM.DriverAccess\MemberFactory.cs:línea 647 en ASCOM.DriverAccess.MemberFactory.CallMember(Int32 memberCode, String memberName, Type[] parameterTypes, Object[] parms) en c:\ASCOM Build\Export\ASCOM.DriverAccess\MemberFactory.cs:línea 373 en ASCOM.DriverAccess.AscomDriver.set_Connected(Boolean value) en c:\ASCOM Build\Export\ASCOM.DriverAccess\AscomDriver.cs:línea 145 en qp.jq() [03-24-18 20:22:40.752][DEBUG] [Telescope Thread] SGM_TELESCOPE_CONNECT complete... [03-24-18 20:22:54.374][DEBUG] [Telescope Thread] Telescope Dispatch loop: Received SGM_TELESCOPE_CONNECT... [03-24-18 20:22:55.376][DEBUG] [Telescope Thread] Telescope can slewing supported... [03-24-18 20:22:55.378][DEBUG] [Telescope Thread] Telescope: Does not implement MoveAxis... [03-24-18 20:22:55.597][DEBUG] [Telescope Thread] Telescope: CanSetSideOfPier returned False [03-24-18 20:22:55.599][DEBUG] [Telescope Thread] Telescope: CanPark returned True [03-24-18 20:22:55.601][DEBUG] [Telescope Thread] Telescope equatorial system is JNOW... [03-24-18 20:22:55.601][DEBUG] [Telescope Thread] SGM_TELESCOPE_CONNECT complete... [03-24-18 20:22:55.993][DEBUG] [Main Thread] Telescope: Setting sync behavior to: Sync [03-24-18 20:23:00.932][DEBUG] [Main Thread] Connecting Manual rotator... [03-24-18 20:23:03.953][DEBUG] [Main Thread] ASCOM EnvironmentDevice: Connected to OpenWeatherMap ObservingConditions [03-24-18 20:23:03.955][DEBUG] [Main Thread] ASCOM EnvironmentDevice: Device Name - Short driver name - please customise [03-24-18 20:23:03.958][DEBUG] [Main Thread] ASCOM EnvironmentDevice: Description - OpenWeatherMap ObservingConditions [03-24-18 20:23:03.961][DEBUG] [Main Thread] ASCOM EnvironmentDevice: Driver Info Information about the driver itself. Version: 6.2 [03-24-18 20:23:03.963][DEBUG] [Main Thread] ASCOM EnvironmentDevice: Driver Version 6.2 [03-24-18 20:23:06.642][DEBUG] [Main Thread] ASCOM EnvironmentDevice: Failed to get Seeing! : Property read StarFWHM is not implemented in this driver. (ASCOM.PropertyNotImplementedException: Property read StarFWHM is not implemented in this driver. en ASCOM.OpenWeatherMap.ObservingConditions.get_StarFWHM() en c:\ASCOM Build\Export\Drivers and Simulators\OpenWeatherMap\OpenWeatherMap\Driver.cs:línea 415) en ASCOM.DriverAccess.MemberFactory.GetTargetInvocationExceptionHandler(String memberName, Exception e) en c:\ASCOM Build\Export\ASCOM.DriverAccess\MemberFactory.cs:línea 661 en ASCOM.DriverAccess.MemberFactory.CallMember(Int32 memberCode, String memberName, Type[] parameterTypes, Object[] parms) en c:\ASCOM Build\Export\ASCOM.DriverAccess\MemberFactory.cs:línea 231 en ASCOM.DriverAccess.ObservingConditions.get_StarFWHM() en c:\ASCOM Build\Export\ASCOM.DriverAccess\ObservingConditions.cs:línea 232 en jr.br() [03-24-18 20:23:06.645][DEBUG] [Main Thread] ASCOM EnvironmentDevice: Failed to get SkyBrightness! : Property read SkyBrightness is not implemented in this driver. (ASCOM.PropertyNotImplementedException: Property read SkyBrightness is not implemented in this driver. en ASCOM.OpenWeatherMap.ObservingConditions.get_SkyBrightness() en c:\ASCOM Build\Export\Drivers and Simulators\OpenWeatherMap\OpenWeatherMap\Driver.cs:línea 391) en ASCOM.DriverAccess.MemberFactory.GetTargetInvocationExceptionHandler(String memberName, Exception e) en c:\ASCOM Build\Export\ASCOM.DriverAccess\MemberFactory.cs:línea 661 en ASCOM.DriverAccess.MemberFactory.CallMember(Int32 memberCode, String memberName, Type[] parameterTypes, Object[] parms) en c:\ASCOM Build\Export\ASCOM.DriverAccess\MemberFactory.cs:línea 231 en ASCOM.DriverAccess.ObservingConditions.get_SkyBrightness() en c:\ASCOM Build\Export\ASCOM.DriverAccess\ObservingConditions.cs:línea 204 en jr.br() [03-24-18 20:23:06.648][DEBUG] [Main Thread] ASCOM EnvironmentDevice: Failed to get SkyQuality! : Property read SkyQuality is not implemented in this driver. (ASCOM.PropertyNotImplementedException: Property read SkyQuality is not implemented in this driver. en ASCOM.OpenWeatherMap.ObservingConditions.get_SkyQuality() en c:\ASCOM Build\Export\Drivers and Simulators\OpenWeatherMap\OpenWeatherMap\Driver.cs:línea 403) en ASCOM.DriverAccess.MemberFactory.GetTargetInvocationExceptionHandler(String memberName, Exception e) en c:\ASCOM Build\Export\ASCOM.DriverAccess\MemberFactory.cs:línea 661 en ASCOM.DriverAccess.MemberFactory.CallMember(Int32 memberCode, String memberName, Type[] parameterTypes, Object[] parms) en c:\ASCOM Build\Export\ASCOM.DriverAccess\MemberFactory.cs:línea 231 en ASCOM.DriverAccess.ObservingConditions.get_SkyQuality() en c:\ASCOM Build\Export\ASCOM.DriverAccess\ObservingConditions.cs:línea 218 en jr.br() [03-24-18 20:23:06.651][DEBUG] [Main Thread] ASCOM EnvironmentDevice: Failed to get SkyTemperature! : Property read SkyTemperature is not implemented in this driver. (ASCOM.PropertyNotImplementedException: Property read SkyTemperature is not implemented in this driver. en ASCOM.OpenWeatherMap.ObservingConditions.get_SkyTemperature() en c:\ASCOM Build\Export\Drivers and Simulators\OpenWeatherMap\OpenWeatherMap\Driver.cs:línea 427) en ASCOM.DriverAccess.MemberFactory.GetTargetInvocationExceptionHandler(String memberName, Exception e) en c:\ASCOM Build\Export\ASCOM.DriverAccess\MemberFactory.cs:línea 661 en ASCOM.DriverAccess.MemberFactory.CallMember(Int32 memberCode, String memberName, Type[] parameterTypes, Object[] parms) en c:\ASCOM Build\Export\ASCOM.DriverAccess\MemberFactory.cs:línea 231 en ASCOM.DriverAccess.ObservingConditions.get_SkyTemperature() en c:\ASCOM Build\Export\ASCOM.DriverAccess\ObservingConditions.cs:línea 249 en jr.br() [03-24-18 20:23:06.656][DEBUG] [Main Thread] ASCOM EnvironmentDevice: Failed to get WindGust! : Property read WindGust is not implemented in this driver. (ASCOM.PropertyNotImplementedException: Property read WindGust is not implemented in this driver. en ASCOM.OpenWeatherMap.ObservingConditions.get_WindGust() en c:\ASCOM Build\Export\Drivers and Simulators\OpenWeatherMap\OpenWeatherMap\Driver.cs:línea 491) en ASCOM.DriverAccess.MemberFactory.GetTargetInvocationExceptionHandler(String memberName, Exception e) en c:\ASCOM Build\Export\ASCOM.DriverAccess\MemberFactory.cs:línea 661 en ASCOM.DriverAccess.MemberFactory.CallMember(Int32 memberCode, String memberName, Type[] parameterTypes, Object[] parms) en c:\ASCOM Build\Export\ASCOM.DriverAccess\MemberFactory.cs:línea 231 en ASCOM.DriverAccess.ObservingConditions.get_WindGust() en c:\ASCOM Build\Export\ASCOM.DriverAccess\ObservingConditions.cs:línea 298 en jr.br() [03-24-18 20:24:23.835][DEBUG] [Main Thread] PopulateDataModel: Transferring view to the data model... [03-24-18 20:24:23.849][DEBUG] [MF Update Thread] Performing serialize... [03-24-18 20:49:16.723][DEBUG] [Main Thread] Connecting camera in main thread... [03-24-18 20:49:16.735][DEBUG] [Main Thread] Connecting ASCOM camera: ASCOM.QHY10.Camera... [03-24-18 20:49:18.359][DEBUG] [Main Thread] Failed to get ExposureMin from camera. : Property read ASCOM.QHY10.Camera ExposureMin is not implemented in this driver. en ASCOM.DriverAccess.MemberFactory.CallMember(Int32 memberCode, String memberName, Type[] parameterTypes, Object[] parms) en c:\ASCOM Build\Export\ASCOM.DriverAccess\MemberFactory.cs:línea 300 en ASCOM.DriverAccess.Camera.get_ExposureMin() en c:\ASCOM Build\Export\ASCOM.DriverAccess\Camera.cs:línea 730 en q7.f7() [03-24-18 20:49:18.360][DEBUG] [Main Thread] Camera does not implement ASCOM gain. [03-24-18 20:49:18.836][DEBUG] [Main Thread] Camera cooler detected... [03-24-18 20:49:18.837][DEBUG] [Main Thread] Camera cooler is OFF... [03-24-18 20:49:18.838][DEBUG] [Main Thread] Camera cooldown on connect is selected... [03-24-18 20:49:18.838][DEBUG] [Main Thread] Camera cooldown is starting... [03-24-18 20:49:18.848][DEBUG] [TEC Thread] SGM_CHANGE_COOLER_TEMP message received... [03-24-18 20:49:18.853][DEBUG] [TEC Thread] TEC Change: Starting... [03-24-18 20:49:18.871][DEBUG] [TEC Thread] TEC Change: After camera connect change, waiting ~5 seconds... [03-24-18 20:49:23.883][DEBUG] [TEC Thread] TEC Change: Attempting to turn the camera cooler on... [03-24-18 20:49:23.885][DEBUG] [Main Thread] Turning camera cooler on... [03-24-18 20:49:23.923][DEBUG] [Main Thread] Turning camera cooler on... [03-24-18 20:49:28.892][DEBUG] [TEC Thread] TEC Change: Changing temp from 11,26 to -20,00 in 600 seconds... [03-24-18 20:51:52.694][DEBUG] [Camera Thread] SGM_FOCUSER_AUTO_FOCUS message received... [03-24-18 20:51:52.721][DEBUG] [Camera Thread] Checking for auto focus... [03-24-18 20:51:52.721][DEBUG] [Camera Thread] Auto focus required (frame count trigger)... [03-24-18 20:51:52.721][DEBUG] [Camera Thread] Auto focus required (temperature change trigger). Last temp: -500; Current temp: 19,9133333333333 [03-24-18 20:51:52.721][DEBUG] [Camera Thread] Auto focus required (focus before first frame)... [03-24-18 20:51:52.742][DEBUG] [Camera Thread] Auto focus: setting filter None [03-24-18 20:51:52.814][DEBUG] [Camera Thread] Auto focus running... [03-24-18 20:51:52.824][DEBUG] [Camera Thread] Turning temp comp off.. [03-24-18 20:51:52.927][DEBUG] [Camera Thread] ASCOM Focuser: Temp comp is avaialble, setting to False [03-24-18 20:51:53.004][DEBUG] [Camera Thread] Performing Sequence Generator auto focus using Half Flux Radius. AFID: 0 [03-24-18 20:51:53.035][DEBUG] [AfChartThread] Showing AF chart dialog... [03-24-18 20:51:53.041][DEBUG] [Camera Thread] Auto focus data [03-24-18 20:51:53.041][DEBUG] [Camera Thread] - Data Points: 7 [03-24-18 20:51:53.041][DEBUG] [Camera Thread] - Step Size: 35 [03-24-18 20:51:53.041][DEBUG] [Camera Thread] - Current Position: 1700 [03-24-18 20:51:53.041][DEBUG] [Camera Thread] - Initial Move Position: 1805 [03-24-18 20:51:54.442][DEBUG] [Camera Thread] No AF darks found... [03-24-18 20:51:54.445][DEBUG] [Camera Thread] Moving focuser to next position (1805)... [03-24-18 20:51:54.462][DEBUG] [Focuser Move Thread] Focuser moving to 1805 [03-24-18 20:51:54.465][DEBUG] [Focuser Move Thread] Focuser backlash active, modified move to 1905 [03-24-18 20:51:54.501][DEBUG] [Focuser Move Thread] Focuser move call complete [03-24-18 20:51:54.538][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has started... [03-24-18 20:51:57.576][DEBUG] [Focuser Backlash Thread] Backlash thread has detected that movement to modified position (1905) is complete... [03-24-18 20:51:57.576][DEBUG] [Focuser Backlash Thread] Moving focuser to original requested position (1805)... [03-24-18 20:51:57.576][DEBUG] [Focuser Backlash Thread] Focuser backlash compensation needed. Steps: 100 Direction:IN [03-24-18 20:51:57.576][DEBUG] [Focuser Backlash Thread] Focuser moving to 1805 [03-24-18 20:51:57.615][DEBUG] [Focuser Backlash Thread] Focuser move call complete [03-24-18 20:51:59.566][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1805 matches requested position 1805 [03-24-18 20:51:59.566][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [03-24-18 20:51:59.619][DEBUG] [Focuser Backlash Thread] Focuser backlash completed. Focuser is at original request position (1805)... [03-24-18 20:51:59.619][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has ended... [03-24-18 20:52:00.567][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [03-24-18 20:52:00.567][DEBUG] [Camera Thread] Focuser move complete... [03-24-18 20:52:00.612][DEBUG] [Camera Thread] Focuser position matches requested position (1805), continuing... [03-24-18 20:52:00.613][DEBUG] [Camera Thread] Calculating step metric... [03-24-18 20:52:00.618][DEBUG] [Camera Thread] Taking auto focus frame(s)... [03-24-18 20:52:00.623][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [03-24-18 20:52:00.623][DEBUG] [Camera Thread] ASCOM: AF Binning: 2x2... [03-24-18 20:52:00.623][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 8 seconds... [03-24-18 20:52:00.623][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: a [03-24-18 20:52:00.636][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 20:52:00.637][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 20:52:11.115][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 20:52:11.115][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 20:52:11.115][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 20:52:11.118][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 20:52:11.118][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 20:52:11.156][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 20:52:11.157][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 20:52:12.176][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [03-24-18 20:52:12.176][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [03-24-18 20:52:12.177][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [03-24-18 20:52:12.387][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [03-24-18 20:52:12.558][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [03-24-18 20:52:12.558][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [03-24-18 20:52:12.558][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [03-24-18 20:52:12.574][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 20:52:12.574][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 20:52:13.124][DEBUG] [Main Thread] --> Find stars (normal) [03-24-18 20:52:17.704][DEBUG] [Main Thread] Star detection using min star size of 3px... [03-24-18 20:52:17.704][DEBUG] [Main Thread] Star detection using max star size of 60px... [03-24-18 20:52:18.027][DEBUG] [Main Thread] Find stars took: 4903 ms... [03-24-18 20:52:18.029][DEBUG] [Main Thread] Star list contains 13 stars... [03-24-18 20:52:18.029][DEBUG] [Main Thread] Calculating HFR with sample size: 3 [03-24-18 20:52:18.713][DEBUG] [Camera Thread] Auto focus HFR calculated at: 1,19 [03-24-18 20:52:19.949][DEBUG] [Camera Thread] Moving focuser to next position (1770)... [03-24-18 20:52:19.955][DEBUG] [Focuser Move Thread] Focuser moving to 1770 [03-24-18 20:52:20.098][DEBUG] [Focuser Move Thread] Focuser move call complete [03-24-18 20:52:21.099][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1770 matches requested position 1770 [03-24-18 20:52:21.099][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [03-24-18 20:52:22.100][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [03-24-18 20:52:22.100][DEBUG] [Camera Thread] Focuser move complete... [03-24-18 20:52:22.129][DEBUG] [Camera Thread] Focuser position matches requested position (1770), continuing... [03-24-18 20:52:22.131][DEBUG] [Camera Thread] Calculating step metric... [03-24-18 20:52:22.131][DEBUG] [Camera Thread] Taking auto focus frame(s)... [03-24-18 20:52:22.134][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [03-24-18 20:52:22.134][DEBUG] [Camera Thread] ASCOM: AF Binning: 2x2... [03-24-18 20:52:22.134][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 8 seconds... [03-24-18 20:52:22.134][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: a [03-24-18 20:52:22.136][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 20:52:22.136][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 20:52:31.775][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 20:52:31.775][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 20:52:31.775][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 20:52:31.780][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 20:52:31.780][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 20:52:31.815][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 20:52:31.815][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 20:52:31.815][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [03-24-18 20:52:31.815][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [03-24-18 20:52:31.816][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [03-24-18 20:52:31.816][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [03-24-18 20:52:31.927][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [03-24-18 20:52:31.927][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [03-24-18 20:52:31.928][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [03-24-18 20:52:31.939][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 20:52:31.939][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 20:52:32.207][DEBUG] [Main Thread] --> Find stars (normal) [03-24-18 20:52:35.853][DEBUG] [Main Thread] Star detection using min star size of 3px... [03-24-18 20:52:35.853][DEBUG] [Main Thread] Star detection using max star size of 60px... [03-24-18 20:52:35.950][DEBUG] [Main Thread] Find stars took: 3742 ms... [03-24-18 20:52:35.950][DEBUG] [Main Thread] Star list contains 28 stars... [03-24-18 20:52:35.950][DEBUG] [Main Thread] Calculating HFR with sample size: 3 [03-24-18 20:52:36.455][DEBUG] [Camera Thread] Auto focus HFR calculated at: 1,09 [03-24-18 20:52:37.473][DEBUG] [Camera Thread] Moving focuser to next position (1735)... [03-24-18 20:52:37.474][DEBUG] [Focuser Move Thread] Focuser moving to 1735 [03-24-18 20:52:37.512][DEBUG] [Focuser Move Thread] Focuser move call complete [03-24-18 20:52:38.514][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1735 matches requested position 1735 [03-24-18 20:52:38.514][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [03-24-18 20:52:39.515][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [03-24-18 20:52:39.515][DEBUG] [Camera Thread] Focuser move complete... [03-24-18 20:52:39.516][DEBUG] [Camera Thread] Focuser position matches requested position (1735), continuing... [03-24-18 20:52:39.516][DEBUG] [Camera Thread] Calculating step metric... [03-24-18 20:52:39.516][DEBUG] [Camera Thread] Taking auto focus frame(s)... [03-24-18 20:52:39.519][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [03-24-18 20:52:39.519][DEBUG] [Camera Thread] ASCOM: AF Binning: 2x2... [03-24-18 20:52:39.519][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 8 seconds... [03-24-18 20:52:39.520][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: a [03-24-18 20:52:39.521][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 20:52:39.521][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 20:52:49.174][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 20:52:49.174][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 20:52:49.174][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 20:52:49.177][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 20:52:49.177][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 20:52:49.212][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 20:52:49.212][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 20:52:49.212][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [03-24-18 20:52:49.212][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [03-24-18 20:52:49.241][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [03-24-18 20:52:49.241][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [03-24-18 20:52:49.351][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [03-24-18 20:52:49.351][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [03-24-18 20:52:49.351][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [03-24-18 20:52:49.361][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 20:52:49.361][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 20:52:49.689][DEBUG] [Main Thread] --> Find stars (normal) [03-24-18 20:52:52.856][DEBUG] [Main Thread] Star detection using min star size of 3px... [03-24-18 20:52:52.856][DEBUG] [Main Thread] Star detection using max star size of 60px... [03-24-18 20:52:52.971][DEBUG] [Main Thread] Find stars took: 3281 ms... [03-24-18 20:52:52.971][DEBUG] [Main Thread] Star list contains 11 stars... [03-24-18 20:52:52.971][DEBUG] [Main Thread] Calculating HFR with sample size: 3 [03-24-18 20:52:53.445][DEBUG] [Camera Thread] Auto focus HFR calculated at: 1,16 [03-24-18 20:52:54.459][DEBUG] [Camera Thread] Moving focuser to next position (1700)... [03-24-18 20:52:54.460][DEBUG] [Focuser Move Thread] Focuser moving to 1700 [03-24-18 20:52:54.496][DEBUG] [Focuser Move Thread] Focuser move call complete [03-24-18 20:52:55.496][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1700 matches requested position 1700 [03-24-18 20:52:55.496][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [03-24-18 20:52:56.497][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [03-24-18 20:52:56.497][DEBUG] [Camera Thread] Focuser move complete... [03-24-18 20:52:56.498][DEBUG] [Camera Thread] Focuser position matches requested position (1700), continuing... [03-24-18 20:52:56.499][DEBUG] [Camera Thread] Calculating step metric... [03-24-18 20:52:56.499][DEBUG] [Camera Thread] Taking auto focus frame(s)... [03-24-18 20:52:56.502][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [03-24-18 20:52:56.502][DEBUG] [Camera Thread] ASCOM: AF Binning: 2x2... [03-24-18 20:52:56.502][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 8 seconds... [03-24-18 20:52:56.503][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: a [03-24-18 20:52:56.504][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 20:52:56.504][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 20:53:06.145][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 20:53:06.145][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 20:53:06.145][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 20:53:06.148][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 20:53:06.149][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 20:53:06.183][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 20:53:06.183][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 20:53:06.183][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [03-24-18 20:53:06.183][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [03-24-18 20:53:06.184][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [03-24-18 20:53:06.184][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [03-24-18 20:53:06.365][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [03-24-18 20:53:06.365][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [03-24-18 20:53:06.365][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [03-24-18 20:53:06.374][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 20:53:06.374][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 20:53:06.642][DEBUG] [Main Thread] --> Find stars (normal) [03-24-18 20:53:09.724][DEBUG] [Main Thread] Star detection using min star size of 3px... [03-24-18 20:53:09.724][DEBUG] [Main Thread] Star detection using max star size of 60px... [03-24-18 20:53:09.914][DEBUG] [Main Thread] Find stars took: 3271 ms... [03-24-18 20:53:09.914][DEBUG] [Main Thread] Star list contains 7 stars... [03-24-18 20:53:09.914][DEBUG] [Main Thread] Calculating HFR with sample size: 3 [03-24-18 20:53:10.315][DEBUG] [Camera Thread] Auto focus HFR calculated at: 0,95 [03-24-18 20:53:11.342][DEBUG] [Camera Thread] Moving focuser to next position (1665)... [03-24-18 20:53:11.343][DEBUG] [Focuser Move Thread] Focuser moving to 1665 [03-24-18 20:53:11.381][DEBUG] [Focuser Move Thread] Focuser move call complete [03-24-18 20:53:12.384][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1665 matches requested position 1665 [03-24-18 20:53:12.385][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [03-24-18 20:53:13.385][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [03-24-18 20:53:13.385][DEBUG] [Camera Thread] Focuser move complete... [03-24-18 20:53:13.386][DEBUG] [Camera Thread] Focuser position matches requested position (1665), continuing... [03-24-18 20:53:13.386][DEBUG] [Camera Thread] Calculating step metric... [03-24-18 20:53:13.386][DEBUG] [Camera Thread] Taking auto focus frame(s)... [03-24-18 20:53:13.389][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [03-24-18 20:53:13.390][DEBUG] [Camera Thread] ASCOM: AF Binning: 2x2... [03-24-18 20:53:13.390][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 8 seconds... [03-24-18 20:53:13.390][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: a [03-24-18 20:53:13.391][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 20:53:13.391][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 20:53:23.116][DEBUG] [Camera Thread] HFR value from auto focuser is 0!, trying again... [03-24-18 20:53:23.119][DEBUG] [Camera Thread] AF abort requested [03-24-18 20:53:23.125][DEBUG] [Camera Thread] Auto focus (finally): closing dialog... [03-24-18 20:53:23.133][DEBUG] [Focuser Thread] SGM_FOCUSER_MOVE_ABS message received... [03-24-18 20:53:23.133][DEBUG] [Focuser Thread] Focuser moving to 1700 [03-24-18 20:53:23.191][DEBUG] [AfChartThread] AF chart dialog is closed... [03-24-18 20:53:23.219][DEBUG] [Camera Thread] Auto focus complete... [03-24-18 20:53:23.269][DEBUG] [Camera Thread] SGM_FOCUSER_AUTO_FOCUS complete... [03-24-18 20:53:23.279][DEBUG] [Focuser Thread] Focuser backlash active, modified move to 1800 [03-24-18 20:53:23.315][DEBUG] [Focuser Thread] Focuser move call complete [03-24-18 20:53:23.316][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has started... [03-24-18 20:53:25.322][DEBUG] [Focuser Backlash Thread] Backlash thread has detected that movement to modified position (1800) is complete... [03-24-18 20:53:25.322][DEBUG] [Focuser Backlash Thread] Moving focuser to original requested position (1700)... [03-24-18 20:53:25.322][DEBUG] [Focuser Backlash Thread] Focuser backlash compensation needed. Steps: 100 Direction:IN [03-24-18 20:53:25.323][DEBUG] [Focuser Backlash Thread] Focuser moving to 1700 [03-24-18 20:53:25.359][DEBUG] [Focuser Backlash Thread] Focuser move call complete [03-24-18 20:53:27.362][DEBUG] [Focuser Backlash Thread] Focuser backlash completed. Focuser is at original request position (1700)... [03-24-18 20:53:27.362][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has ended... [03-24-18 20:53:27.471][DEBUG] [Focuser Thread] SGM_FOCUSER_MOVE_ABS complete... [03-24-18 20:53:38.592][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS message received... [03-24-18 20:53:38.610][DEBUG] [Camera Thread] ASCOM camera: frame and focus... [03-24-18 20:53:38.612][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 20:53:38.612][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 20:53:45.294][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 20:53:45.294][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 20:53:45.294][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 20:53:45.295][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 20:53:45.295][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 20:53:45.391][DEBUG] [Focuser Thread] SGM_FOCUSER_MOVE_REL message received... [03-24-18 20:53:45.393][DEBUG] [Focuser Thread] Focuser moving to 1600 [03-24-18 20:53:45.430][DEBUG] [Focuser Thread] Focuser move call complete [03-24-18 20:53:45.456][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 20:53:45.456][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 20:53:45.632][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 20:53:45.632][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 20:53:48.384][DEBUG] [Camera Thread] ASCOM camera: frame and focus... [03-24-18 20:53:48.388][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 20:53:48.388][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 20:53:48.427][DEBUG] [Focuser Thread] SGM_FOCUSER_MOVE_REL complete... [03-24-18 20:53:54.993][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 20:53:54.993][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 20:53:54.993][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 20:53:54.995][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 20:53:54.995][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 20:53:55.141][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 20:53:55.141][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 20:53:55.324][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 20:53:55.324][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 20:53:57.827][DEBUG] [Camera Thread] ASCOM camera: frame and focus... [03-24-18 20:53:57.828][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 20:53:57.828][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 20:53:57.841][DEBUG] [Focuser Thread] SGM_FOCUSER_MOVE_REL message received... [03-24-18 20:53:57.868][DEBUG] [Focuser Thread] Focuser moving to 1500 [03-24-18 20:53:57.927][DEBUG] [Focuser Thread] Focuser move call complete [03-24-18 20:53:59.961][DEBUG] [Focuser Thread] SGM_FOCUSER_MOVE_REL complete... [03-24-18 20:54:04.432][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 20:54:04.432][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 20:54:04.433][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 20:54:04.434][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 20:54:04.434][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 20:54:04.575][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 20:54:04.575][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 20:54:04.684][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 20:54:04.684][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 20:54:07.266][DEBUG] [Camera Thread] ASCOM camera: frame and focus... [03-24-18 20:54:07.267][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 20:54:07.267][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 20:54:10.431][DEBUG] [Focuser Thread] SGM_FOCUSER_MOVE_REL message received... [03-24-18 20:54:10.450][DEBUG] [Focuser Thread] Focuser moving to 1400 [03-24-18 20:54:10.493][DEBUG] [Focuser Thread] Focuser move call complete [03-24-18 20:54:12.626][DEBUG] [Focuser Thread] SGM_FOCUSER_MOVE_REL complete... [03-24-18 20:54:13.872][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 20:54:13.872][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 20:54:13.872][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 20:54:13.872][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 20:54:13.873][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 20:54:14.015][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 20:54:14.016][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 20:54:14.201][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 20:54:14.201][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 20:54:15.847][DEBUG] [Camera Thread] ASCOM camera: frame and focus... [03-24-18 20:54:15.848][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 20:54:15.849][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 20:54:19.299][DEBUG] [Focuser Thread] SGM_FOCUSER_MOVE_REL message received... [03-24-18 20:54:19.349][DEBUG] [Focuser Thread] Focuser moving to 1300 [03-24-18 20:54:19.385][DEBUG] [Focuser Thread] Focuser move call complete [03-24-18 20:54:21.512][DEBUG] [Focuser Thread] SGM_FOCUSER_MOVE_REL complete... [03-24-18 20:54:22.455][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 20:54:22.455][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 20:54:22.455][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 20:54:22.456][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 20:54:22.456][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 20:54:22.607][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 20:54:22.607][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 20:54:22.789][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 20:54:22.789][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 20:54:24.880][DEBUG] [Camera Thread] ASCOM camera: frame and focus... [03-24-18 20:54:24.881][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 20:54:24.882][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 20:54:24.899][DEBUG] [Focuser Thread] SGM_FOCUSER_MOVE_REL message received... [03-24-18 20:54:24.941][DEBUG] [Focuser Thread] Focuser moving to 1200 [03-24-18 20:54:24.978][DEBUG] [Focuser Thread] Focuser move call complete [03-24-18 20:54:27.108][DEBUG] [Focuser Thread] SGM_FOCUSER_MOVE_REL complete... [03-24-18 20:54:27.108][DEBUG] [Focuser Thread] SGM_FOCUSER_MOVE_REL message received... [03-24-18 20:54:27.109][DEBUG] [Focuser Thread] Focuser moving to 1100 [03-24-18 20:54:27.147][DEBUG] [Focuser Thread] Focuser move call complete [03-24-18 20:54:29.018][DEBUG] [Focuser Thread] SGM_FOCUSER_MOVE_REL complete... [03-24-18 20:54:31.497][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 20:54:31.497][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 20:54:31.497][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 20:54:31.498][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 20:54:31.498][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 20:54:31.640][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 20:54:31.640][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 20:54:31.847][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 20:54:31.847][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 20:54:34.768][DEBUG] [Camera Thread] ASCOM camera: frame and focus... [03-24-18 20:54:34.773][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 20:54:34.773][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 20:54:34.780][DEBUG] [Focuser Thread] SGM_FOCUSER_MOVE_REL message received... [03-24-18 20:54:34.782][DEBUG] [Focuser Thread] Focuser moving to 1000 [03-24-18 20:54:34.819][DEBUG] [Focuser Thread] Focuser move call complete [03-24-18 20:54:36.931][DEBUG] [Focuser Thread] SGM_FOCUSER_MOVE_REL complete... [03-24-18 20:54:41.383][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 20:54:41.383][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 20:54:41.383][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 20:54:41.384][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 20:54:41.384][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 20:54:41.523][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 20:54:41.523][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 20:54:41.706][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 20:54:41.706][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 20:54:44.538][DEBUG] [Focuser Thread] SGM_FOCUSER_MOVE_REL message received... [03-24-18 20:54:44.542][DEBUG] [Camera Thread] ASCOM camera: frame and focus... [03-24-18 20:54:44.543][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 20:54:44.543][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 20:54:44.548][DEBUG] [Focuser Thread] Focuser moving to 900 [03-24-18 20:54:44.585][DEBUG] [Focuser Thread] Focuser move call complete [03-24-18 20:54:46.648][DEBUG] [Focuser Thread] SGM_FOCUSER_MOVE_REL complete... [03-24-18 20:54:46.648][DEBUG] [Focuser Thread] SGM_FOCUSER_MOVE_REL message received... [03-24-18 20:54:46.649][DEBUG] [Focuser Thread] Focuser moving to 800 [03-24-18 20:54:46.687][DEBUG] [Focuser Thread] Focuser move call complete [03-24-18 20:54:48.662][DEBUG] [Focuser Thread] SGM_FOCUSER_MOVE_REL complete... [03-24-18 20:54:51.148][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 20:54:51.149][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 20:54:51.149][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 20:54:51.149][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 20:54:51.150][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 20:54:51.288][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 20:54:51.288][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 20:54:51.401][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 20:54:51.404][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 20:54:53.651][DEBUG] [Camera Thread] ASCOM camera: frame and focus... [03-24-18 20:54:53.653][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 20:54:53.653][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 20:54:53.680][DEBUG] [Focuser Thread] SGM_FOCUSER_MOVE_REL message received... [03-24-18 20:54:53.685][DEBUG] [Focuser Thread] Focuser moving to 700 [03-24-18 20:54:53.726][DEBUG] [Focuser Thread] Focuser move call complete [03-24-18 20:54:55.797][DEBUG] [Focuser Thread] SGM_FOCUSER_MOVE_REL complete... [03-24-18 20:55:00.262][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 20:55:00.262][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 20:55:00.262][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 20:55:00.263][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 20:55:00.264][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 20:55:00.405][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 20:55:00.405][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 20:55:00.527][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 20:55:00.527][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 20:55:00.902][DEBUG] [Camera Thread] ASCOM camera: frame and focus... [03-24-18 20:55:00.904][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 20:55:00.904][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 20:55:03.455][DEBUG] [Focuser Thread] SGM_FOCUSER_MOVE_REL message received... [03-24-18 20:55:03.458][DEBUG] [Focuser Thread] Focuser moving to 800 [03-24-18 20:55:03.459][DEBUG] [Focuser Thread] Focuser backlash active, modified move to 900 [03-24-18 20:55:03.495][DEBUG] [Focuser Thread] Focuser move call complete [03-24-18 20:55:03.496][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has started... [03-24-18 20:55:06.501][DEBUG] [Focuser Backlash Thread] Backlash thread has detected that movement to modified position (900) is complete... [03-24-18 20:55:06.501][DEBUG] [Focuser Backlash Thread] Moving focuser to original requested position (800)... [03-24-18 20:55:06.501][DEBUG] [Focuser Backlash Thread] Focuser backlash compensation needed. Steps: 100 Direction:IN [03-24-18 20:55:06.502][DEBUG] [Focuser Backlash Thread] Focuser moving to 800 [03-24-18 20:55:06.538][DEBUG] [Focuser Backlash Thread] Focuser move call complete [03-24-18 20:55:07.513][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 20:55:07.513][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 20:55:07.514][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 20:55:07.514][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 20:55:07.515][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 20:55:07.664][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 20:55:07.664][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 20:55:07.809][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 20:55:07.809][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 20:55:08.689][DEBUG] [Focuser Backlash Thread] Focuser backlash completed. Focuser is at original request position (800)... [03-24-18 20:55:08.689][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has ended... [03-24-18 20:55:09.170][DEBUG] [Camera Thread] ASCOM camera: frame and focus... [03-24-18 20:55:09.171][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 20:55:09.171][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 20:55:09.225][DEBUG] [Focuser Thread] SGM_FOCUSER_MOVE_REL complete... [03-24-18 20:55:14.722][DEBUG] [Focuser Thread] SGM_FOCUSER_MOVE_REL message received... [03-24-18 20:55:14.755][DEBUG] [Focuser Thread] Focuser moving to 820 [03-24-18 20:55:14.756][DEBUG] [Focuser Thread] Focuser backlash active, modified move to 920 [03-24-18 20:55:14.793][DEBUG] [Focuser Thread] Focuser move call complete [03-24-18 20:55:14.794][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has started... [03-24-18 20:55:15.775][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 20:55:15.775][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 20:55:15.775][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 20:55:15.777][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 20:55:15.777][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 20:55:15.923][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 20:55:15.923][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 20:55:16.030][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 20:55:16.030][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 20:55:17.797][DEBUG] [Focuser Backlash Thread] Backlash thread has detected that movement to modified position (920) is complete... [03-24-18 20:55:17.797][DEBUG] [Focuser Backlash Thread] Moving focuser to original requested position (820)... [03-24-18 20:55:17.797][DEBUG] [Focuser Backlash Thread] Focuser backlash compensation needed. Steps: 100 Direction:IN [03-24-18 20:55:17.797][DEBUG] [Focuser Backlash Thread] Focuser moving to 820 [03-24-18 20:55:17.965][DEBUG] [Focuser Backlash Thread] Focuser move call complete [03-24-18 20:55:18.010][DEBUG] [Camera Thread] ASCOM camera: frame and focus... [03-24-18 20:55:18.012][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 20:55:18.012][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 20:55:19.969][DEBUG] [Focuser Backlash Thread] Focuser backlash completed. Focuser is at original request position (820)... [03-24-18 20:55:19.969][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has ended... [03-24-18 20:55:20.078][DEBUG] [Focuser Thread] SGM_FOCUSER_MOVE_REL complete... [03-24-18 20:55:24.618][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 20:55:24.618][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 20:55:24.618][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 20:55:24.619][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 20:55:24.619][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 20:55:24.765][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 20:55:24.765][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 20:55:24.944][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 20:55:24.944][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 20:55:27.566][DEBUG] [Focuser Thread] SGM_FOCUSER_MOVE_REL message received... [03-24-18 20:55:27.571][DEBUG] [Camera Thread] ASCOM camera: frame and focus... [03-24-18 20:55:27.572][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 20:55:27.572][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 20:55:27.641][DEBUG] [Focuser Thread] Focuser moving to 840 [03-24-18 20:55:27.661][DEBUG] [Focuser Thread] Focuser backlash active, modified move to 940 [03-24-18 20:55:27.701][DEBUG] [Focuser Thread] Focuser move call complete [03-24-18 20:55:27.712][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has started... [03-24-18 20:55:29.714][DEBUG] [Focuser Backlash Thread] Backlash thread has detected that movement to modified position (940) is complete... [03-24-18 20:55:29.714][DEBUG] [Focuser Backlash Thread] Moving focuser to original requested position (840)... [03-24-18 20:55:29.714][DEBUG] [Focuser Backlash Thread] Focuser backlash compensation needed. Steps: 100 Direction:IN [03-24-18 20:55:29.714][DEBUG] [Focuser Backlash Thread] Focuser moving to 840 [03-24-18 20:55:29.754][DEBUG] [Focuser Backlash Thread] Focuser move call complete [03-24-18 20:55:31.755][DEBUG] [Focuser Backlash Thread] Focuser backlash completed. Focuser is at original request position (840)... [03-24-18 20:55:31.755][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has ended... [03-24-18 20:55:31.859][DEBUG] [Focuser Thread] SGM_FOCUSER_MOVE_REL complete... [03-24-18 20:55:34.182][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 20:55:34.182][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 20:55:34.182][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 20:55:34.183][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 20:55:34.183][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 20:55:34.329][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 20:55:34.329][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 20:55:34.555][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 20:55:34.555][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 20:55:37.456][DEBUG] [Camera Thread] ASCOM camera: frame and focus... [03-24-18 20:55:37.462][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 20:55:37.462][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 20:55:44.071][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 20:55:44.071][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 20:55:44.071][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 20:55:44.072][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 20:55:44.072][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 20:55:44.232][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 20:55:44.232][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 20:55:44.352][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 20:55:44.353][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 20:55:46.669][DEBUG] [Camera Thread] ASCOM camera: frame and focus... [03-24-18 20:55:46.670][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 20:55:46.670][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 20:55:51.432][DEBUG] [Camera Thread] ASCOM Camera: abort message received... [03-24-18 20:55:51.432][DEBUG] [Camera Thread] ASCOM Camera: Attempting to abort exposure... [03-24-18 20:55:51.432][DEBUG] [Camera Thread] ASCOM Camera: Asking camera to abort... [03-24-18 20:55:51.433][DEBUG] [Camera Thread] ASCOM Camera: Camera has acknowledged abort request... [03-24-18 20:55:51.433][DEBUG] [Camera Thread] ASCOM Camera: Blocking until image is aborted... [03-24-18 20:55:51.433][DEBUG] [Camera Thread] ASCOM Camera: Image is aborted... [03-24-18 20:55:52.002][DEBUG] [Camera Thread] SGM_FRAME_AND_FOCUS complete... [03-24-18 20:55:55.887][DEBUG] [Camera Thread] SGM_FOCUSER_AUTO_FOCUS message received... [03-24-18 20:55:55.887][DEBUG] [Camera Thread] Checking for auto focus... [03-24-18 20:55:55.887][DEBUG] [Camera Thread] Auto focus required (temperature change trigger). Last temp: 19,9133333333333; Current temp: 19,2666666666667 [03-24-18 20:55:55.887][DEBUG] [Camera Thread] Auto focus required (focus before first frame)... [03-24-18 20:55:55.907][DEBUG] [Camera Thread] Auto focus: setting filter None [03-24-18 20:55:55.912][DEBUG] [Camera Thread] Auto focus running... [03-24-18 20:55:55.922][DEBUG] [Camera Thread] Turning temp comp off.. [03-24-18 20:55:55.954][DEBUG] [Camera Thread] ASCOM Focuser: Temp comp is avaialble, setting to False [03-24-18 20:55:55.955][DEBUG] [Camera Thread] Performing Sequence Generator auto focus using Half Flux Radius. AFID: 1 [03-24-18 20:55:55.969][DEBUG] [AfChartThread] Showing AF chart dialog... [03-24-18 20:55:55.999][DEBUG] [Camera Thread] Auto focus data [03-24-18 20:55:56.000][DEBUG] [Camera Thread] - Data Points: 7 [03-24-18 20:55:56.000][DEBUG] [Camera Thread] - Step Size: 35 [03-24-18 20:55:56.000][DEBUG] [Camera Thread] - Current Position: 840 [03-24-18 20:55:56.000][DEBUG] [Camera Thread] - Initial Move Position: 945 [03-24-18 20:55:56.193][DEBUG] [Camera Thread] No AF darks found... [03-24-18 20:55:56.204][DEBUG] [Camera Thread] Moving focuser to next position (945)... [03-24-18 20:55:56.232][DEBUG] [Focuser Move Thread] Focuser moving to 945 [03-24-18 20:55:56.343][DEBUG] [Focuser Move Thread] Focuser backlash active, modified move to 1045 [03-24-18 20:55:56.383][DEBUG] [Focuser Move Thread] Focuser move call complete [03-24-18 20:55:56.431][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has started... [03-24-18 20:55:59.435][DEBUG] [Focuser Backlash Thread] Backlash thread has detected that movement to modified position (1045) is complete... [03-24-18 20:55:59.435][DEBUG] [Focuser Backlash Thread] Moving focuser to original requested position (945)... [03-24-18 20:55:59.435][DEBUG] [Focuser Backlash Thread] Focuser backlash compensation needed. Steps: 100 Direction:IN [03-24-18 20:55:59.435][DEBUG] [Focuser Backlash Thread] Focuser moving to 945 [03-24-18 20:55:59.493][DEBUG] [Focuser Backlash Thread] Focuser move call complete [03-24-18 20:56:01.647][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 945 matches requested position 945 [03-24-18 20:56:01.648][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [03-24-18 20:56:02.648][DEBUG] [Focuser Backlash Thread] Focuser backlash completed. Focuser is at original request position (945)... [03-24-18 20:56:02.648][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has ended... [03-24-18 20:56:02.658][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [03-24-18 20:56:02.661][DEBUG] [Camera Thread] Focuser move complete... [03-24-18 20:56:02.664][DEBUG] [Camera Thread] Focuser position matches requested position (945), continuing... [03-24-18 20:56:02.667][DEBUG] [Camera Thread] Calculating step metric... [03-24-18 20:56:02.667][DEBUG] [Camera Thread] Taking auto focus frame(s)... [03-24-18 20:56:02.698][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [03-24-18 20:56:02.698][DEBUG] [Camera Thread] ASCOM: AF Binning: 2x2... [03-24-18 20:56:02.699][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 8 seconds... [03-24-18 20:56:02.699][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: a [03-24-18 20:56:02.700][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 20:56:02.700][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 20:56:12.391][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 20:56:12.391][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 20:56:12.391][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 20:56:12.394][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 20:56:12.394][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 20:56:12.433][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 20:56:12.433][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 20:56:12.485][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [03-24-18 20:56:12.485][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [03-24-18 20:56:12.486][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [03-24-18 20:56:12.486][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [03-24-18 20:56:12.605][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [03-24-18 20:56:12.605][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [03-24-18 20:56:12.605][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [03-24-18 20:56:12.632][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 20:56:12.632][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 20:56:12.975][DEBUG] [Main Thread] --> Find stars (normal) [03-24-18 20:56:16.442][DEBUG] [Main Thread] Star detection using min star size of 3px... [03-24-18 20:56:16.442][DEBUG] [Main Thread] Star detection using max star size of 60px... [03-24-18 20:56:16.540][DEBUG] [Main Thread] Find stars took: 3563 ms... [03-24-18 20:56:16.540][DEBUG] [Main Thread] Star list contains 6 stars... [03-24-18 20:56:16.540][DEBUG] [Main Thread] Calculating HFR with sample size: 3 [03-24-18 20:56:17.019][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3,91 [03-24-18 20:56:18.035][DEBUG] [Camera Thread] Moving focuser to next position (910)... [03-24-18 20:56:18.036][DEBUG] [Focuser Move Thread] Focuser moving to 910 [03-24-18 20:56:18.150][DEBUG] [Focuser Move Thread] Focuser move call complete [03-24-18 20:56:19.152][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 910 matches requested position 910 [03-24-18 20:56:19.152][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [03-24-18 20:56:20.152][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [03-24-18 20:56:20.152][DEBUG] [Camera Thread] Focuser move complete... [03-24-18 20:56:20.173][DEBUG] [Camera Thread] Focuser position matches requested position (910), continuing... [03-24-18 20:56:20.174][DEBUG] [Camera Thread] Calculating step metric... [03-24-18 20:56:20.174][DEBUG] [Camera Thread] Taking auto focus frame(s)... [03-24-18 20:56:20.177][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [03-24-18 20:56:20.178][DEBUG] [Camera Thread] ASCOM: AF Binning: 2x2... [03-24-18 20:56:20.178][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 8 seconds... [03-24-18 20:56:20.178][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: a [03-24-18 20:56:20.180][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 20:56:20.180][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 20:56:29.844][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 20:56:29.844][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 20:56:29.844][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 20:56:29.848][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 20:56:29.848][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 20:56:29.887][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 20:56:29.887][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 20:56:29.887][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [03-24-18 20:56:29.887][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [03-24-18 20:56:29.888][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [03-24-18 20:56:29.888][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [03-24-18 20:56:30.016][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [03-24-18 20:56:30.016][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [03-24-18 20:56:30.016][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [03-24-18 20:56:30.092][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 20:56:30.092][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 20:56:30.472][DEBUG] [Main Thread] --> Find stars (normal) [03-24-18 20:56:33.742][DEBUG] [Main Thread] Star detection using min star size of 3px... [03-24-18 20:56:33.743][DEBUG] [Main Thread] Star detection using max star size of 60px... [03-24-18 20:56:33.837][DEBUG] [Main Thread] Find stars took: 3364 ms... [03-24-18 20:56:33.837][DEBUG] [Main Thread] Star list contains 19 stars... [03-24-18 20:56:33.837][DEBUG] [Main Thread] Calculating HFR with sample size: 3 [03-24-18 20:56:34.401][DEBUG] [Camera Thread] Auto focus HFR calculated at: 2,43 [03-24-18 20:56:35.417][DEBUG] [Camera Thread] Moving focuser to next position (875)... [03-24-18 20:56:35.417][DEBUG] [Focuser Move Thread] Focuser moving to 875 [03-24-18 20:56:35.491][DEBUG] [Focuser Move Thread] Focuser move call complete [03-24-18 20:56:36.537][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 875 matches requested position 875 [03-24-18 20:56:36.537][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [03-24-18 20:56:37.540][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [03-24-18 20:56:37.540][DEBUG] [Camera Thread] Focuser move complete... [03-24-18 20:56:37.581][DEBUG] [Camera Thread] Focuser position matches requested position (875), continuing... [03-24-18 20:56:37.582][DEBUG] [Camera Thread] Calculating step metric... [03-24-18 20:56:37.582][DEBUG] [Camera Thread] Taking auto focus frame(s)... [03-24-18 20:56:37.588][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [03-24-18 20:56:37.588][DEBUG] [Camera Thread] ASCOM: AF Binning: 2x2... [03-24-18 20:56:37.589][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 8 seconds... [03-24-18 20:56:37.589][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: a [03-24-18 20:56:37.595][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 20:56:37.595][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 20:56:47.242][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 20:56:47.242][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 20:56:47.242][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 20:56:47.245][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 20:56:47.246][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 20:56:47.282][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 20:56:47.282][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 20:56:47.282][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [03-24-18 20:56:47.282][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [03-24-18 20:56:47.317][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [03-24-18 20:56:47.317][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [03-24-18 20:56:47.418][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [03-24-18 20:56:47.418][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [03-24-18 20:56:47.418][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [03-24-18 20:56:47.430][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 20:56:47.431][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 20:56:47.796][DEBUG] [Main Thread] --> Find stars (normal) [03-24-18 20:56:51.322][DEBUG] [Main Thread] Star detection using min star size of 3px... [03-24-18 20:56:51.322][DEBUG] [Main Thread] Star detection using max star size of 60px... [03-24-18 20:56:51.421][DEBUG] [Main Thread] Find stars took: 3624 ms... [03-24-18 20:56:51.421][DEBUG] [Main Thread] Star list contains 62 stars... [03-24-18 20:56:51.421][DEBUG] [Main Thread] Calculating HFR with sample size: 3 [03-24-18 20:56:52.399][DEBUG] [Camera Thread] Auto focus HFR calculated at: 1,13 [03-24-18 20:56:53.414][DEBUG] [Camera Thread] Moving focuser to next position (840)... [03-24-18 20:56:53.416][DEBUG] [Focuser Move Thread] Focuser moving to 840 [03-24-18 20:56:53.474][DEBUG] [Focuser Move Thread] Focuser move call complete [03-24-18 20:56:54.474][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 840 matches requested position 840 [03-24-18 20:56:54.474][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [03-24-18 20:56:55.477][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [03-24-18 20:56:55.477][DEBUG] [Camera Thread] Focuser move complete... [03-24-18 20:56:55.501][DEBUG] [Camera Thread] Focuser position matches requested position (840), continuing... [03-24-18 20:56:55.502][DEBUG] [Camera Thread] Calculating step metric... [03-24-18 20:56:55.502][DEBUG] [Camera Thread] Taking auto focus frame(s)... [03-24-18 20:56:55.505][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [03-24-18 20:56:55.505][DEBUG] [Camera Thread] ASCOM: AF Binning: 2x2... [03-24-18 20:56:55.505][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 8 seconds... [03-24-18 20:56:55.505][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: a [03-24-18 20:56:55.506][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 20:56:55.507][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 20:57:05.171][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 20:57:05.171][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 20:57:05.171][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 20:57:05.174][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 20:57:05.174][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 20:57:05.213][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 20:57:05.213][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 20:57:05.213][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [03-24-18 20:57:05.214][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [03-24-18 20:57:05.215][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [03-24-18 20:57:05.219][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [03-24-18 20:57:05.317][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [03-24-18 20:57:05.317][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [03-24-18 20:57:05.317][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [03-24-18 20:57:05.329][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 20:57:05.329][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 20:57:05.703][DEBUG] [Main Thread] --> Find stars (normal) [03-24-18 20:57:09.482][DEBUG] [Main Thread] Star detection using min star size of 3px... [03-24-18 20:57:09.482][DEBUG] [Main Thread] Star detection using max star size of 60px... [03-24-18 20:57:09.743][DEBUG] [Main Thread] Find stars took: 4039 ms... [03-24-18 20:57:09.743][DEBUG] [Main Thread] Star list contains 65 stars... [03-24-18 20:57:09.743][DEBUG] [Main Thread] Calculating HFR with sample size: 3 [03-24-18 20:57:10.343][DEBUG] [Camera Thread] Auto focus HFR calculated at: 0,97 [03-24-18 20:57:11.384][DEBUG] [Camera Thread] Moving focuser to next position (805)... [03-24-18 20:57:11.387][DEBUG] [Focuser Move Thread] Focuser moving to 805 [03-24-18 20:57:11.426][DEBUG] [Focuser Move Thread] Focuser move call complete [03-24-18 20:57:12.444][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 805 matches requested position 805 [03-24-18 20:57:12.444][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [03-24-18 20:57:13.444][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [03-24-18 20:57:13.444][DEBUG] [Camera Thread] Focuser move complete... [03-24-18 20:57:13.445][DEBUG] [Camera Thread] Focuser position matches requested position (805), continuing... [03-24-18 20:57:13.445][DEBUG] [Camera Thread] Calculating step metric... [03-24-18 20:57:13.445][DEBUG] [Camera Thread] Taking auto focus frame(s)... [03-24-18 20:57:13.448][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [03-24-18 20:57:13.448][DEBUG] [Camera Thread] ASCOM: AF Binning: 2x2... [03-24-18 20:57:13.448][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 8 seconds... [03-24-18 20:57:13.453][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: a [03-24-18 20:57:13.454][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 20:57:13.454][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 20:57:23.133][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 20:57:23.133][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 20:57:23.133][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 20:57:23.137][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 20:57:23.137][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 20:57:23.178][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 20:57:23.178][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 20:57:23.178][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [03-24-18 20:57:23.178][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [03-24-18 20:57:23.180][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [03-24-18 20:57:23.180][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [03-24-18 20:57:23.295][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [03-24-18 20:57:23.296][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [03-24-18 20:57:23.296][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [03-24-18 20:57:23.308][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 20:57:23.308][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 20:57:23.704][DEBUG] [Main Thread] --> Find stars (normal) [03-24-18 20:57:27.588][DEBUG] [Main Thread] Star detection using min star size of 3px... [03-24-18 20:57:27.588][DEBUG] [Main Thread] Star detection using max star size of 60px... [03-24-18 20:57:27.757][DEBUG] [Main Thread] Find stars took: 4051 ms... [03-24-18 20:57:27.757][DEBUG] [Main Thread] Star list contains 26 stars... [03-24-18 20:57:27.757][DEBUG] [Main Thread] Calculating HFR with sample size: 3 [03-24-18 20:57:28.269][DEBUG] [Camera Thread] Auto focus HFR calculated at: 2,14 [03-24-18 20:57:29.298][DEBUG] [Camera Thread] Moving focuser to next position (770)... [03-24-18 20:57:29.302][DEBUG] [Focuser Move Thread] Focuser moving to 770 [03-24-18 20:57:29.340][DEBUG] [Focuser Move Thread] Focuser move call complete [03-24-18 20:57:30.379][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 770 matches requested position 770 [03-24-18 20:57:30.379][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [03-24-18 20:57:31.379][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [03-24-18 20:57:31.379][DEBUG] [Camera Thread] Focuser move complete... [03-24-18 20:57:31.380][DEBUG] [Camera Thread] Focuser position matches requested position (770), continuing... [03-24-18 20:57:31.381][DEBUG] [Camera Thread] Calculating step metric... [03-24-18 20:57:31.381][DEBUG] [Camera Thread] Taking auto focus frame(s)... [03-24-18 20:57:31.386][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [03-24-18 20:57:31.386][DEBUG] [Camera Thread] ASCOM: AF Binning: 2x2... [03-24-18 20:57:31.387][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 8 seconds... [03-24-18 20:57:31.387][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: a [03-24-18 20:57:31.388][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 20:57:31.388][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 20:57:41.043][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 20:57:41.043][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 20:57:41.043][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 20:57:41.046][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 20:57:41.046][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 20:57:41.099][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 20:57:41.099][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 20:57:41.099][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [03-24-18 20:57:41.099][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [03-24-18 20:57:41.102][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [03-24-18 20:57:41.103][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [03-24-18 20:57:41.203][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [03-24-18 20:57:41.203][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [03-24-18 20:57:41.203][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [03-24-18 20:57:41.215][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 20:57:41.215][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 20:57:41.620][DEBUG] [Main Thread] --> Find stars (normal) [03-24-18 20:57:44.757][DEBUG] [Main Thread] Star detection using min star size of 3px... [03-24-18 20:57:44.757][DEBUG] [Main Thread] Star detection using max star size of 60px... [03-24-18 20:57:44.865][DEBUG] [Main Thread] Find stars took: 3244 ms... [03-24-18 20:57:44.865][DEBUG] [Main Thread] Star list contains 17 stars... [03-24-18 20:57:44.865][DEBUG] [Main Thread] Calculating HFR with sample size: 3 [03-24-18 20:57:45.347][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3,28 [03-24-18 20:57:46.362][DEBUG] [Camera Thread] Moving focuser to next position (735)... [03-24-18 20:57:46.363][DEBUG] [Focuser Move Thread] Focuser moving to 735 [03-24-18 20:57:46.534][DEBUG] [Focuser Move Thread] Focuser move call complete [03-24-18 20:57:47.536][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 735 matches requested position 735 [03-24-18 20:57:47.536][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [03-24-18 20:57:48.538][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [03-24-18 20:57:48.538][DEBUG] [Camera Thread] Focuser move complete... [03-24-18 20:57:48.582][DEBUG] [Camera Thread] Focuser position matches requested position (735), continuing... [03-24-18 20:57:48.583][DEBUG] [Camera Thread] Calculating step metric... [03-24-18 20:57:48.583][DEBUG] [Camera Thread] Taking auto focus frame(s)... [03-24-18 20:57:48.588][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [03-24-18 20:57:48.588][DEBUG] [Camera Thread] ASCOM: AF Binning: 2x2... [03-24-18 20:57:48.588][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 8 seconds... [03-24-18 20:57:48.589][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: a [03-24-18 20:57:48.590][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 20:57:48.590][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 20:57:58.238][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 20:57:58.238][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 20:57:58.238][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 20:57:58.241][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 20:57:58.241][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 20:57:58.277][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 20:57:58.277][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 20:57:58.277][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [03-24-18 20:57:58.277][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [03-24-18 20:57:58.278][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [03-24-18 20:57:58.278][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [03-24-18 20:57:58.391][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [03-24-18 20:57:58.391][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [03-24-18 20:57:58.391][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [03-24-18 20:57:58.432][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 20:57:58.432][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 20:57:58.747][DEBUG] [Main Thread] --> Find stars (normal) [03-24-18 20:58:01.960][DEBUG] [Main Thread] Star detection using min star size of 3px... [03-24-18 20:58:01.960][DEBUG] [Main Thread] Star detection using max star size of 60px... [03-24-18 20:58:02.067][DEBUG] [Main Thread] Find stars took: 3319 ms... [03-24-18 20:58:02.067][DEBUG] [Main Thread] Star list contains 7 stars... [03-24-18 20:58:02.067][DEBUG] [Main Thread] Calculating HFR with sample size: 3 [03-24-18 20:58:02.551][DEBUG] [Camera Thread] Auto focus HFR calculated at: 4,76 [03-24-18 20:58:03.571][DEBUG] [Camera Thread] Moving focuser to next position (700)... [03-24-18 20:58:03.572][DEBUG] [Focuser Move Thread] Focuser moving to 700 [03-24-18 20:58:03.693][DEBUG] [Focuser Move Thread] Focuser move call complete [03-24-18 20:58:04.694][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 700 matches requested position 700 [03-24-18 20:58:04.694][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [03-24-18 20:58:05.695][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [03-24-18 20:58:05.695][DEBUG] [Camera Thread] Focuser move complete... [03-24-18 20:58:05.722][DEBUG] [Camera Thread] Focuser position matches requested position (700), continuing... [03-24-18 20:58:05.723][DEBUG] [Camera Thread] Calculating step metric... [03-24-18 20:58:05.723][DEBUG] [Camera Thread] Taking auto focus frame(s)... [03-24-18 20:58:05.727][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [03-24-18 20:58:05.728][DEBUG] [Camera Thread] ASCOM: AF Binning: 2x2... [03-24-18 20:58:05.728][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 8 seconds... [03-24-18 20:58:05.728][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: a [03-24-18 20:58:05.729][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 20:58:05.729][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 20:58:15.372][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 20:58:15.372][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 20:58:15.372][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 20:58:15.375][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 20:58:15.375][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 20:58:15.410][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 20:58:15.410][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 20:58:15.410][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [03-24-18 20:58:15.410][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [03-24-18 20:58:15.411][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [03-24-18 20:58:15.411][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [03-24-18 20:58:15.511][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [03-24-18 20:58:15.511][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [03-24-18 20:58:15.511][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [03-24-18 20:58:15.522][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 20:58:15.522][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 20:58:15.913][DEBUG] [Main Thread] --> Find stars (normal) [03-24-18 20:58:19.071][DEBUG] [Main Thread] Star detection using min star size of 3px... [03-24-18 20:58:19.071][DEBUG] [Main Thread] Star detection using max star size of 60px... [03-24-18 20:58:19.177][DEBUG] [Main Thread] Find stars took: 3264 ms... [03-24-18 20:58:19.177][DEBUG] [Main Thread] Star list contains 8 stars... [03-24-18 20:58:19.177][DEBUG] [Main Thread] Calculating HFR with sample size: 3 [03-24-18 20:58:19.918][DEBUG] [Camera Thread] Auto focus HFR calculated at: 4,17 [03-24-18 20:58:20.979][DEBUG] [Camera Thread] Moving focuser to next position (665)... [03-24-18 20:58:20.980][DEBUG] [Focuser Move Thread] Focuser moving to 665 [03-24-18 20:58:21.030][DEBUG] [Focuser Move Thread] Focuser move call complete [03-24-18 20:58:22.031][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 665 matches requested position 665 [03-24-18 20:58:22.031][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [03-24-18 20:58:23.032][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [03-24-18 20:58:23.032][DEBUG] [Camera Thread] Focuser move complete... [03-24-18 20:58:23.033][DEBUG] [Camera Thread] Focuser position matches requested position (665), continuing... [03-24-18 20:58:23.034][DEBUG] [Camera Thread] Calculating step metric... [03-24-18 20:58:23.034][DEBUG] [Camera Thread] Taking auto focus frame(s)... [03-24-18 20:58:23.039][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [03-24-18 20:58:23.040][DEBUG] [Camera Thread] ASCOM: AF Binning: 2x2... [03-24-18 20:58:23.040][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 8 seconds... [03-24-18 20:58:23.040][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: a [03-24-18 20:58:23.041][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 20:58:23.041][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 20:58:32.690][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 20:58:32.690][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 20:58:32.690][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 20:58:32.693][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 20:58:32.693][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 20:58:32.728][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 20:58:32.728][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 20:58:32.729][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [03-24-18 20:58:32.729][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [03-24-18 20:58:32.729][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [03-24-18 20:58:32.730][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [03-24-18 20:58:32.838][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [03-24-18 20:58:32.838][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [03-24-18 20:58:32.838][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [03-24-18 20:58:32.849][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 20:58:32.850][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 20:58:33.205][DEBUG] [Main Thread] --> Find stars (normal) [03-24-18 20:58:36.328][DEBUG] [Main Thread] Star detection using min star size of 3px... [03-24-18 20:58:36.328][DEBUG] [Main Thread] Star detection using max star size of 60px... [03-24-18 20:58:36.386][DEBUG] [Main Thread] Find stars took: 3180 ms... [03-24-18 20:58:36.386][DEBUG] [Main Thread] Star list contains 3 stars... [03-24-18 20:58:36.386][DEBUG] [Main Thread] Calculating HFR with sample size: 3 [03-24-18 20:58:36.864][DEBUG] [Camera Thread] Auto focus HFR calculated at: 7,37 [03-24-18 20:58:37.880][DEBUG] [Camera Thread] Moving focuser to next position (630)... [03-24-18 20:58:37.880][DEBUG] [Focuser Move Thread] Focuser moving to 630 [03-24-18 20:58:37.917][DEBUG] [Focuser Move Thread] Focuser move call complete [03-24-18 20:58:38.927][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 630 matches requested position 630 [03-24-18 20:58:38.927][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [03-24-18 20:58:39.928][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [03-24-18 20:58:39.928][DEBUG] [Camera Thread] Focuser move complete... [03-24-18 20:58:39.947][DEBUG] [Camera Thread] Focuser position matches requested position (630), continuing... [03-24-18 20:58:39.948][DEBUG] [Camera Thread] Calculating step metric... [03-24-18 20:58:39.948][DEBUG] [Camera Thread] Taking auto focus frame(s)... [03-24-18 20:58:39.953][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [03-24-18 20:58:39.953][DEBUG] [Camera Thread] ASCOM: AF Binning: 2x2... [03-24-18 20:58:39.953][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 8 seconds... [03-24-18 20:58:39.953][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: a [03-24-18 20:58:39.954][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 20:58:39.954][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 20:58:49.601][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 20:58:49.601][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 20:58:49.601][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 20:58:49.604][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 20:58:49.604][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 20:58:49.642][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 20:58:49.642][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 20:58:49.642][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [03-24-18 20:58:49.643][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [03-24-18 20:58:49.677][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [03-24-18 20:58:49.677][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [03-24-18 20:58:49.790][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [03-24-18 20:58:49.790][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [03-24-18 20:58:49.790][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [03-24-18 20:58:49.799][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 20:58:49.799][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 20:58:50.165][DEBUG] [Main Thread] --> Find stars (normal) [03-24-18 20:58:53.329][DEBUG] [Main Thread] Star detection using min star size of 3px... [03-24-18 20:58:53.329][DEBUG] [Main Thread] Star detection using max star size of 60px... [03-24-18 20:58:53.389][DEBUG] [Main Thread] Find stars took: 3223 ms... [03-24-18 20:58:53.389][DEBUG] [Main Thread] Star list contains 2 stars... [03-24-18 20:58:53.389][DEBUG] [Main Thread] Calculating HFR with sample size: 3 [03-24-18 20:58:53.913][DEBUG] [Camera Thread] Auto focus HFR calculated at: 8,94 [03-24-18 20:58:54.933][DEBUG] [Camera Thread] AF Have >= 6 left slope and >= 3 right slope but no fit. Something isn't right here! [03-24-18 20:58:54.938][DEBUG] [Camera Thread] New auto focus method calculated focus at: [03-24-18 20:58:54.938][DEBUG] [Camera Thread] Old auto focus method calculated focus at: 843 [03-24-18 20:58:54.938][DEBUG] [Camera Thread] Unable to calculate focus with best fit lines, defaulting to weighted average of low HFRs... [03-24-18 20:58:54.938][DEBUG] [Camera Thread] Using old auto focus method for focuser position [03-24-18 20:58:54.938][DEBUG] [Camera Thread] New focus position is at 843 (@19,01C). Moving focuser... [03-24-18 20:58:54.941][DEBUG] [Focuser Move Thread] Focuser moving to 843 [03-24-18 20:58:54.942][DEBUG] [Focuser Move Thread] Focuser backlash active, modified move to 943 [03-24-18 20:58:54.979][DEBUG] [Focuser Move Thread] Focuser move call complete [03-24-18 20:58:54.982][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has started... [03-24-18 20:58:58.988][DEBUG] [Focuser Backlash Thread] Backlash thread has detected that movement to modified position (943) is complete... [03-24-18 20:58:58.988][DEBUG] [Focuser Backlash Thread] Moving focuser to original requested position (843)... [03-24-18 20:58:58.988][DEBUG] [Focuser Backlash Thread] Focuser backlash compensation needed. Steps: 100 Direction:IN [03-24-18 20:58:58.988][DEBUG] [Focuser Backlash Thread] Focuser moving to 843 [03-24-18 20:58:59.025][DEBUG] [Focuser Backlash Thread] Focuser move call complete [03-24-18 20:59:01.026][DEBUG] [Focuser Backlash Thread] Focuser backlash completed. Focuser is at original request position (843)... [03-24-18 20:59:01.026][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has ended... [03-24-18 20:59:01.130][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 843 matches requested position 843 [03-24-18 20:59:01.130][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [03-24-18 20:59:01.131][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [03-24-18 20:59:01.131][DEBUG] [Camera Thread] Focuser move complete... [03-24-18 20:59:01.131][DEBUG] [Camera Thread] Focuser successfully set to focus position (843) [03-24-18 20:59:01.162][DEBUG] [Camera Thread] Taking validation frame at focus position... [03-24-18 20:59:01.164][DEBUG] [Camera Thread] Taking auto focus frame(s)... [03-24-18 20:59:01.168][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [03-24-18 20:59:01.168][DEBUG] [Camera Thread] ASCOM: AF Binning: 2x2... [03-24-18 20:59:01.168][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 8 seconds... [03-24-18 20:59:01.168][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: a [03-24-18 20:59:01.169][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 20:59:01.169][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 20:59:10.815][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 20:59:10.815][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 20:59:10.815][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 20:59:10.818][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 20:59:10.818][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 20:59:10.853][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 20:59:10.853][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 20:59:10.853][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [03-24-18 20:59:10.853][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [03-24-18 20:59:10.854][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [03-24-18 20:59:10.854][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [03-24-18 20:59:10.958][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [03-24-18 20:59:10.958][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [03-24-18 20:59:10.958][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [03-24-18 20:59:10.967][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 20:59:10.967][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 20:59:11.324][DEBUG] [Main Thread] --> Find stars (normal) [03-24-18 20:59:14.970][DEBUG] [Main Thread] Star detection using min star size of 3px... [03-24-18 20:59:14.970][DEBUG] [Main Thread] Star detection using max star size of 60px... [03-24-18 20:59:15.093][DEBUG] [Main Thread] Find stars took: 3768 ms... [03-24-18 20:59:15.093][DEBUG] [Main Thread] Star list contains 76 stars... [03-24-18 20:59:15.093][DEBUG] [Main Thread] Calculating HFR with sample size: 3 [03-24-18 20:59:15.602][DEBUG] [Camera Thread] Auto focus HFR calculated at: 1,07 [03-24-18 20:59:16.610][DEBUG] [Camera Thread] Auto focus is complete (weighted avg method: 843; HFR 1,06)... [03-24-18 20:59:16.621][DEBUG] [Camera Thread] Entering chart disposal loop... [03-24-18 20:59:28.899][DEBUG] [TEC Thread] TEC Change: Complete... [03-24-18 20:59:28.901][DEBUG] [TEC Thread] SGM_CHANGE_COOLER_TEMP complete... [03-24-18 20:59:40.937][DEBUG] [Camera Thread] Rerun auto focus... [03-24-18 20:59:40.937][DEBUG] [Camera Thread] Chart disposal loop is complete... [03-24-18 20:59:40.978][DEBUG] [Camera Thread] Auto focus data [03-24-18 20:59:40.978][DEBUG] [Camera Thread] - Data Points: 7 [03-24-18 20:59:40.978][DEBUG] [Camera Thread] - Step Size: 35 [03-24-18 20:59:40.978][DEBUG] [Camera Thread] - Current Position: 843 [03-24-18 20:59:40.978][DEBUG] [Camera Thread] - Initial Move Position: 948 [03-24-18 20:59:41.090][DEBUG] [Camera Thread] No AF darks found... [03-24-18 20:59:41.093][DEBUG] [Camera Thread] Moving focuser to next position (948)... [03-24-18 20:59:41.101][DEBUG] [Focuser Move Thread] Focuser moving to 948 [03-24-18 20:59:41.107][DEBUG] [Focuser Move Thread] Focuser backlash active, modified move to 1048 [03-24-18 20:59:41.144][DEBUG] [Focuser Move Thread] Focuser move call complete [03-24-18 20:59:41.145][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has started... [03-24-18 20:59:45.147][DEBUG] [Focuser Backlash Thread] Backlash thread has detected that movement to modified position (1048) is complete... [03-24-18 20:59:45.147][DEBUG] [Focuser Backlash Thread] Moving focuser to original requested position (948)... [03-24-18 20:59:45.147][DEBUG] [Focuser Backlash Thread] Focuser backlash compensation needed. Steps: 100 Direction:IN [03-24-18 20:59:45.147][DEBUG] [Focuser Backlash Thread] Focuser moving to 948 [03-24-18 20:59:45.184][DEBUG] [Focuser Backlash Thread] Focuser move call complete [03-24-18 20:59:46.332][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 948 matches requested position 948 [03-24-18 20:59:46.332][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [03-24-18 20:59:47.376][DEBUG] [Focuser Backlash Thread] Focuser backlash completed. Focuser is at original request position (948)... [03-24-18 20:59:47.376][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has ended... [03-24-18 20:59:48.412][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [03-24-18 20:59:48.412][DEBUG] [Camera Thread] Focuser move complete... [03-24-18 20:59:48.412][DEBUG] [Camera Thread] Focuser position matches requested position (948), continuing... [03-24-18 20:59:48.413][DEBUG] [Camera Thread] Calculating step metric... [03-24-18 20:59:48.414][DEBUG] [Camera Thread] Taking auto focus frame(s)... [03-24-18 20:59:48.417][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [03-24-18 20:59:48.417][DEBUG] [Camera Thread] ASCOM: AF Binning: 2x2... [03-24-18 20:59:48.417][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 8 seconds... [03-24-18 20:59:48.417][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: a [03-24-18 20:59:48.418][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 20:59:48.418][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 20:59:58.074][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 20:59:58.074][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 20:59:58.074][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 20:59:58.078][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 20:59:58.078][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 20:59:58.121][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 20:59:58.121][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 20:59:58.140][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [03-24-18 20:59:58.140][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [03-24-18 20:59:58.141][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [03-24-18 20:59:58.143][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [03-24-18 20:59:58.257][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [03-24-18 20:59:58.257][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [03-24-18 20:59:58.257][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [03-24-18 20:59:58.266][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 20:59:58.266][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 20:59:58.663][DEBUG] [Main Thread] --> Find stars (normal) [03-24-18 21:00:01.786][DEBUG] [Main Thread] Star detection using min star size of 3px... [03-24-18 21:00:01.786][DEBUG] [Main Thread] Star detection using max star size of 60px... [03-24-18 21:00:02.269][DEBUG] [Main Thread] Find stars took: 3605 ms... [03-24-18 21:00:02.269][DEBUG] [Main Thread] Star list contains 8 stars... [03-24-18 21:00:02.269][DEBUG] [Main Thread] Calculating HFR with sample size: 3 [03-24-18 21:00:02.741][DEBUG] [Camera Thread] Auto focus HFR calculated at: 4,15 [03-24-18 21:00:03.756][DEBUG] [Camera Thread] Moving focuser to next position (913)... [03-24-18 21:00:03.756][DEBUG] [Focuser Move Thread] Focuser moving to 913 [03-24-18 21:00:03.851][DEBUG] [Focuser Move Thread] Focuser move call complete [03-24-18 21:00:04.852][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 913 matches requested position 913 [03-24-18 21:00:04.852][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [03-24-18 21:00:05.853][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [03-24-18 21:00:05.853][DEBUG] [Camera Thread] Focuser move complete... [03-24-18 21:00:05.880][DEBUG] [Camera Thread] Focuser position matches requested position (913), continuing... [03-24-18 21:00:05.881][DEBUG] [Camera Thread] Calculating step metric... [03-24-18 21:00:05.881][DEBUG] [Camera Thread] Taking auto focus frame(s)... [03-24-18 21:00:05.886][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [03-24-18 21:00:05.886][DEBUG] [Camera Thread] ASCOM: AF Binning: 2x2... [03-24-18 21:00:05.886][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 8 seconds... [03-24-18 21:00:05.886][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: a [03-24-18 21:00:05.887][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 21:00:05.887][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 21:00:15.534][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 21:00:15.534][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 21:00:15.534][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 21:00:15.537][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 21:00:15.537][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 21:00:15.572][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 21:00:15.572][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 21:00:15.572][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [03-24-18 21:00:15.572][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [03-24-18 21:00:15.573][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [03-24-18 21:00:15.573][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [03-24-18 21:00:15.678][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [03-24-18 21:00:15.678][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [03-24-18 21:00:15.678][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [03-24-18 21:00:15.689][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 21:00:15.689][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 21:00:16.054][DEBUG] [Main Thread] --> Find stars (normal) [03-24-18 21:00:19.421][DEBUG] [Main Thread] Star detection using min star size of 3px... [03-24-18 21:00:19.421][DEBUG] [Main Thread] Star detection using max star size of 60px... [03-24-18 21:00:19.550][DEBUG] [Main Thread] Find stars took: 3496 ms... [03-24-18 21:00:19.550][DEBUG] [Main Thread] Star list contains 29 stars... [03-24-18 21:00:19.551][DEBUG] [Main Thread] Calculating HFR with sample size: 3 [03-24-18 21:00:20.026][DEBUG] [Camera Thread] Auto focus HFR calculated at: 2,44 [03-24-18 21:00:21.040][DEBUG] [Camera Thread] Moving focuser to next position (878)... [03-24-18 21:00:21.040][DEBUG] [Focuser Move Thread] Focuser moving to 878 [03-24-18 21:00:21.165][DEBUG] [Focuser Move Thread] Focuser move call complete [03-24-18 21:00:22.168][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 878 matches requested position 878 [03-24-18 21:00:22.168][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [03-24-18 21:00:23.169][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [03-24-18 21:00:23.169][DEBUG] [Camera Thread] Focuser move complete... [03-24-18 21:00:23.193][DEBUG] [Camera Thread] Focuser position matches requested position (878), continuing... [03-24-18 21:00:23.193][DEBUG] [Camera Thread] Calculating step metric... [03-24-18 21:00:23.194][DEBUG] [Camera Thread] Taking auto focus frame(s)... [03-24-18 21:00:23.198][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [03-24-18 21:00:23.198][DEBUG] [Camera Thread] ASCOM: AF Binning: 2x2... [03-24-18 21:00:23.198][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 8 seconds... [03-24-18 21:00:23.199][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: a [03-24-18 21:00:23.200][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 21:00:23.200][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 21:00:32.847][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 21:00:32.847][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 21:00:32.847][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 21:00:32.850][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 21:00:32.850][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 21:00:32.885][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 21:00:32.885][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 21:00:32.885][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [03-24-18 21:00:32.885][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [03-24-18 21:00:32.888][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [03-24-18 21:00:32.888][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [03-24-18 21:00:33.000][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [03-24-18 21:00:33.001][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [03-24-18 21:00:33.001][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [03-24-18 21:00:33.011][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 21:00:33.011][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 21:00:33.343][DEBUG] [Main Thread] --> Find stars (normal) [03-24-18 21:00:36.544][DEBUG] [Main Thread] Star detection using min star size of 3px... [03-24-18 21:00:36.544][DEBUG] [Main Thread] Star detection using max star size of 60px... [03-24-18 21:00:36.654][DEBUG] [Main Thread] Find stars took: 3310 ms... [03-24-18 21:00:36.654][DEBUG] [Main Thread] Star list contains 65 stars... [03-24-18 21:00:36.654][DEBUG] [Main Thread] Calculating HFR with sample size: 3 [03-24-18 21:00:37.180][DEBUG] [Camera Thread] Auto focus HFR calculated at: 1,17 [03-24-18 21:00:38.194][DEBUG] [Camera Thread] Moving focuser to next position (843)... [03-24-18 21:00:38.195][DEBUG] [Focuser Move Thread] Focuser moving to 843 [03-24-18 21:00:38.235][DEBUG] [Focuser Move Thread] Focuser move call complete [03-24-18 21:00:39.236][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 843 matches requested position 843 [03-24-18 21:00:39.236][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [03-24-18 21:00:40.238][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [03-24-18 21:00:40.238][DEBUG] [Camera Thread] Focuser move complete... [03-24-18 21:00:40.247][DEBUG] [Camera Thread] Focuser position matches requested position (843), continuing... [03-24-18 21:00:40.250][DEBUG] [Camera Thread] Calculating step metric... [03-24-18 21:00:40.250][DEBUG] [Camera Thread] Taking auto focus frame(s)... [03-24-18 21:00:40.261][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [03-24-18 21:00:40.261][DEBUG] [Camera Thread] ASCOM: AF Binning: 2x2... [03-24-18 21:00:40.261][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 8 seconds... [03-24-18 21:00:40.261][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: a [03-24-18 21:00:40.262][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 21:00:40.262][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 21:00:49.918][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 21:00:49.918][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 21:00:49.918][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 21:00:49.921][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 21:00:49.921][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 21:00:49.961][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 21:00:49.961][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 21:00:49.961][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [03-24-18 21:00:49.961][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [03-24-18 21:00:49.962][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [03-24-18 21:00:49.962][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [03-24-18 21:00:50.061][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [03-24-18 21:00:50.062][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [03-24-18 21:00:50.062][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [03-24-18 21:00:50.071][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 21:00:50.071][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 21:00:50.480][DEBUG] [Main Thread] --> Find stars (normal) [03-24-18 21:00:54.062][DEBUG] [Main Thread] Star detection using min star size of 3px... [03-24-18 21:00:54.062][DEBUG] [Main Thread] Star detection using max star size of 60px... [03-24-18 21:00:54.128][DEBUG] [Main Thread] Find stars took: 3647 ms... [03-24-18 21:00:54.128][DEBUG] [Main Thread] Star list contains 69 stars... [03-24-18 21:00:54.128][DEBUG] [Main Thread] Calculating HFR with sample size: 3 [03-24-18 21:00:54.699][DEBUG] [Camera Thread] Auto focus HFR calculated at: 1,12 [03-24-18 21:00:55.716][DEBUG] [Camera Thread] Moving focuser to next position (808)... [03-24-18 21:00:55.719][DEBUG] [Focuser Move Thread] Focuser moving to 808 [03-24-18 21:00:55.760][DEBUG] [Focuser Move Thread] Focuser move call complete [03-24-18 21:00:56.761][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 808 matches requested position 808 [03-24-18 21:00:56.761][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [03-24-18 21:00:57.762][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [03-24-18 21:00:57.762][DEBUG] [Camera Thread] Focuser move complete... [03-24-18 21:00:57.763][DEBUG] [Camera Thread] Focuser position matches requested position (808), continuing... [03-24-18 21:00:57.764][DEBUG] [Camera Thread] Calculating step metric... [03-24-18 21:00:57.764][DEBUG] [Camera Thread] Taking auto focus frame(s)... [03-24-18 21:00:57.768][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [03-24-18 21:00:57.768][DEBUG] [Camera Thread] ASCOM: AF Binning: 2x2... [03-24-18 21:00:57.768][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 8 seconds... [03-24-18 21:00:57.768][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: a [03-24-18 21:00:57.770][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 21:00:57.770][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 21:01:07.409][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 21:01:07.409][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 21:01:07.409][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 21:01:07.411][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 21:01:07.411][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 21:01:07.446][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 21:01:07.446][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 21:01:07.446][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [03-24-18 21:01:07.446][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [03-24-18 21:01:07.448][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [03-24-18 21:01:07.448][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [03-24-18 21:01:07.558][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [03-24-18 21:01:07.558][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [03-24-18 21:01:07.558][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [03-24-18 21:01:07.567][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 21:01:07.567][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 21:01:07.941][DEBUG] [Main Thread] --> Find stars (normal) [03-24-18 21:01:11.117][DEBUG] [Main Thread] Star detection using min star size of 3px... [03-24-18 21:01:11.117][DEBUG] [Main Thread] Star detection using max star size of 60px... [03-24-18 21:01:11.220][DEBUG] [Main Thread] Find stars took: 3278 ms... [03-24-18 21:01:11.220][DEBUG] [Main Thread] Star list contains 30 stars... [03-24-18 21:01:11.220][DEBUG] [Main Thread] Calculating HFR with sample size: 3 [03-24-18 21:01:11.780][DEBUG] [Camera Thread] Auto focus HFR calculated at: 2,26 [03-24-18 21:01:12.796][DEBUG] [Camera Thread] Moving focuser to next position (773)... [03-24-18 21:01:12.797][DEBUG] [Focuser Move Thread] Focuser moving to 773 [03-24-18 21:01:12.834][DEBUG] [Focuser Move Thread] Focuser move call complete [03-24-18 21:01:13.835][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 773 matches requested position 773 [03-24-18 21:01:13.835][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [03-24-18 21:01:14.836][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [03-24-18 21:01:14.836][DEBUG] [Camera Thread] Focuser move complete... [03-24-18 21:01:14.845][DEBUG] [Camera Thread] Focuser position matches requested position (773), continuing... [03-24-18 21:01:14.847][DEBUG] [Camera Thread] Calculating step metric... [03-24-18 21:01:14.847][DEBUG] [Camera Thread] Taking auto focus frame(s)... [03-24-18 21:01:14.855][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [03-24-18 21:01:14.855][DEBUG] [Camera Thread] ASCOM: AF Binning: 2x2... [03-24-18 21:01:14.855][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 8 seconds... [03-24-18 21:01:14.855][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: a [03-24-18 21:01:14.856][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 21:01:14.856][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 21:01:24.493][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 21:01:24.493][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 21:01:24.493][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 21:01:24.496][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 21:01:24.496][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 21:01:24.533][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 21:01:24.533][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 21:01:24.533][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [03-24-18 21:01:24.533][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [03-24-18 21:01:24.535][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [03-24-18 21:01:24.535][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [03-24-18 21:01:24.647][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [03-24-18 21:01:24.647][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [03-24-18 21:01:24.647][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [03-24-18 21:01:24.658][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 21:01:24.658][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 21:01:24.965][DEBUG] [Main Thread] --> Find stars (normal) [03-24-18 21:01:28.381][DEBUG] [Main Thread] Star detection using min star size of 3px... [03-24-18 21:01:28.381][DEBUG] [Main Thread] Star detection using max star size of 60px... [03-24-18 21:01:28.478][DEBUG] [Main Thread] Find stars took: 3513 ms... [03-24-18 21:01:28.478][DEBUG] [Main Thread] Star list contains 16 stars... [03-24-18 21:01:28.478][DEBUG] [Main Thread] Calculating HFR with sample size: 3 [03-24-18 21:01:28.979][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3,53 [03-24-18 21:01:29.994][DEBUG] [Camera Thread] Moving focuser to next position (738)... [03-24-18 21:01:29.995][DEBUG] [Focuser Move Thread] Focuser moving to 738 [03-24-18 21:01:30.089][DEBUG] [Focuser Move Thread] Focuser move call complete [03-24-18 21:01:31.089][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 738 matches requested position 738 [03-24-18 21:01:31.089][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [03-24-18 21:01:32.091][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [03-24-18 21:01:32.091][DEBUG] [Camera Thread] Focuser move complete... [03-24-18 21:01:32.146][DEBUG] [Camera Thread] Focuser position matches requested position (738), continuing... [03-24-18 21:01:32.147][DEBUG] [Camera Thread] Calculating step metric... [03-24-18 21:01:32.147][DEBUG] [Camera Thread] Taking auto focus frame(s)... [03-24-18 21:01:32.152][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [03-24-18 21:01:32.152][DEBUG] [Camera Thread] ASCOM: AF Binning: 2x2... [03-24-18 21:01:32.152][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 8 seconds... [03-24-18 21:01:32.152][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: a [03-24-18 21:01:32.154][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 21:01:32.154][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 21:01:41.791][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 21:01:41.791][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 21:01:41.791][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 21:01:41.793][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 21:01:41.794][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 21:01:41.828][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 21:01:41.828][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 21:01:41.828][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [03-24-18 21:01:41.829][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [03-24-18 21:01:41.829][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [03-24-18 21:01:41.830][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [03-24-18 21:01:41.937][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [03-24-18 21:01:41.937][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [03-24-18 21:01:41.937][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [03-24-18 21:01:41.949][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 21:01:41.949][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 21:01:42.251][DEBUG] [Main Thread] --> Find stars (normal) [03-24-18 21:01:45.391][DEBUG] [Main Thread] Star detection using min star size of 3px... [03-24-18 21:01:45.391][DEBUG] [Main Thread] Star detection using max star size of 60px... [03-24-18 21:01:45.494][DEBUG] [Main Thread] Find stars took: 3244 ms... [03-24-18 21:01:45.494][DEBUG] [Main Thread] Star list contains 8 stars... [03-24-18 21:01:45.494][DEBUG] [Main Thread] Calculating HFR with sample size: 3 [03-24-18 21:01:46.431][DEBUG] [Camera Thread] Auto focus HFR calculated at: 4,97 [03-24-18 21:01:47.442][DEBUG] [Camera Thread] Detected focus getting worse! Shifting auto focus range [03-24-18 21:01:47.443][DEBUG] [Focuser Move Thread] Focuser moving to 1000 [03-24-18 21:01:47.503][DEBUG] [Focuser Move Thread] Focuser backlash active, modified move to 1100 [03-24-18 21:01:47.541][DEBUG] [Focuser Move Thread] Focuser move call complete [03-24-18 21:01:47.541][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has started... [03-24-18 21:01:52.546][DEBUG] [Focuser Backlash Thread] Backlash thread has detected that movement to modified position (1100) is complete... [03-24-18 21:01:52.546][DEBUG] [Focuser Backlash Thread] Moving focuser to original requested position (1000)... [03-24-18 21:01:52.546][DEBUG] [Focuser Backlash Thread] Focuser backlash compensation needed. Steps: 100 Direction:IN [03-24-18 21:01:52.546][DEBUG] [Focuser Backlash Thread] Focuser moving to 1000 [03-24-18 21:01:52.641][DEBUG] [Focuser Backlash Thread] Focuser move call complete [03-24-18 21:01:53.808][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1000 matches requested position 1000 [03-24-18 21:01:53.808][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [03-24-18 21:01:54.642][DEBUG] [Focuser Backlash Thread] Focuser backlash completed. Focuser is at original request position (1000)... [03-24-18 21:01:54.642][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has ended... [03-24-18 21:01:54.810][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [03-24-18 21:01:54.810][DEBUG] [Camera Thread] Focuser move complete... [03-24-18 21:01:54.864][DEBUG] [Camera Thread] Auto focus data [03-24-18 21:01:54.864][DEBUG] [Camera Thread] - Data Points: 7 [03-24-18 21:01:54.864][DEBUG] [Camera Thread] - Step Size: 35 [03-24-18 21:01:54.864][DEBUG] [Camera Thread] - Current Position: 1000 [03-24-18 21:01:54.864][DEBUG] [Camera Thread] - Initial Move Position: 1105 [03-24-18 21:01:54.986][DEBUG] [Camera Thread] No AF darks found... [03-24-18 21:01:54.988][DEBUG] [Camera Thread] Moving focuser to next position (1105)... [03-24-18 21:01:54.990][DEBUG] [Focuser Move Thread] Focuser moving to 1105 [03-24-18 21:01:55.033][DEBUG] [Focuser Move Thread] Focuser backlash active, modified move to 1205 [03-24-18 21:01:55.101][DEBUG] [Focuser Move Thread] Focuser move call complete [03-24-18 21:01:55.102][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has started... [03-24-18 21:01:58.104][DEBUG] [Focuser Backlash Thread] Backlash thread has detected that movement to modified position (1205) is complete... [03-24-18 21:01:58.104][DEBUG] [Focuser Backlash Thread] Moving focuser to original requested position (1105)... [03-24-18 21:01:58.104][DEBUG] [Focuser Backlash Thread] Focuser backlash compensation needed. Steps: 100 Direction:IN [03-24-18 21:01:58.104][DEBUG] [Focuser Backlash Thread] Focuser moving to 1105 [03-24-18 21:01:58.185][DEBUG] [Focuser Backlash Thread] Focuser move call complete [03-24-18 21:02:00.159][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1105 matches requested position 1105 [03-24-18 21:02:00.159][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [03-24-18 21:02:00.186][DEBUG] [Focuser Backlash Thread] Focuser backlash completed. Focuser is at original request position (1105)... [03-24-18 21:02:00.186][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has ended... [03-24-18 21:02:01.191][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [03-24-18 21:02:01.191][DEBUG] [Camera Thread] Focuser move complete... [03-24-18 21:02:01.192][DEBUG] [Camera Thread] Focuser position matches requested position (1105), continuing... [03-24-18 21:02:01.193][DEBUG] [Camera Thread] Calculating step metric... [03-24-18 21:02:01.193][DEBUG] [Camera Thread] Taking auto focus frame(s)... [03-24-18 21:02:01.196][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [03-24-18 21:02:01.197][DEBUG] [Camera Thread] ASCOM: AF Binning: 2x2... [03-24-18 21:02:01.197][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 8 seconds... [03-24-18 21:02:01.197][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: a [03-24-18 21:02:01.198][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 21:02:01.198][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 21:02:10.838][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 21:02:10.838][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 21:02:10.838][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 21:02:10.841][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 21:02:10.841][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 21:02:10.875][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 21:02:10.875][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 21:02:10.895][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [03-24-18 21:02:10.895][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [03-24-18 21:02:10.896][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [03-24-18 21:02:10.900][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [03-24-18 21:02:11.007][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [03-24-18 21:02:11.007][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [03-24-18 21:02:11.007][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [03-24-18 21:02:11.015][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 21:02:11.015][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 21:02:11.330][DEBUG] [Main Thread] --> Find stars (normal) [03-24-18 21:02:14.463][DEBUG] [Main Thread] Star detection using min star size of 3px... [03-24-18 21:02:14.464][DEBUG] [Main Thread] Star detection using max star size of 60px... [03-24-18 21:02:14.572][DEBUG] [Main Thread] Find stars took: 3242 ms... [03-24-18 21:02:14.573][DEBUG] [Main Thread] Star list contains 2 stars... [03-24-18 21:02:14.573][DEBUG] [Main Thread] Calculating HFR with sample size: 3 [03-24-18 21:02:15.042][DEBUG] [Camera Thread] Auto focus HFR calculated at: 6,64 [03-24-18 21:02:16.057][DEBUG] [Camera Thread] Moving focuser to next position (1070)... [03-24-18 21:02:16.058][DEBUG] [Focuser Move Thread] Focuser moving to 1070 [03-24-18 21:02:16.187][DEBUG] [Focuser Move Thread] Focuser move call complete [03-24-18 21:02:17.207][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1070 matches requested position 1070 [03-24-18 21:02:17.207][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [03-24-18 21:02:18.209][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [03-24-18 21:02:18.209][DEBUG] [Camera Thread] Focuser move complete... [03-24-18 21:02:18.210][DEBUG] [Camera Thread] Focuser position matches requested position (1070), continuing... [03-24-18 21:02:18.211][DEBUG] [Camera Thread] Calculating step metric... [03-24-18 21:02:18.211][DEBUG] [Camera Thread] Taking auto focus frame(s)... [03-24-18 21:02:18.216][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [03-24-18 21:02:18.216][DEBUG] [Camera Thread] ASCOM: AF Binning: 2x2... [03-24-18 21:02:18.216][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 8 seconds... [03-24-18 21:02:18.216][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: a [03-24-18 21:02:18.217][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 21:02:18.217][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 21:02:27.860][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 21:02:27.860][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 21:02:27.860][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 21:02:27.862][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 21:02:27.863][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 21:02:27.898][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 21:02:27.898][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 21:02:27.898][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [03-24-18 21:02:27.898][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [03-24-18 21:02:27.899][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [03-24-18 21:02:27.900][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [03-24-18 21:02:28.001][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [03-24-18 21:02:28.001][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [03-24-18 21:02:28.001][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [03-24-18 21:02:28.012][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 21:02:28.013][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 21:02:28.418][DEBUG] [Main Thread] --> Find stars (normal) [03-24-18 21:02:31.573][DEBUG] [Main Thread] Star detection using min star size of 3px... [03-24-18 21:02:31.574][DEBUG] [Main Thread] Star detection using max star size of 60px... [03-24-18 21:02:31.665][DEBUG] [Main Thread] Find stars took: 3246 ms... [03-24-18 21:02:31.665][DEBUG] [Main Thread] Star list contains 2 stars... [03-24-18 21:02:31.665][DEBUG] [Main Thread] Calculating HFR with sample size: 3 [03-24-18 21:02:32.066][DEBUG] [Camera Thread] Auto focus HFR calculated at: 8,66 [03-24-18 21:02:33.080][DEBUG] [Camera Thread] Moving focuser to next position (1035)... [03-24-18 21:02:33.081][DEBUG] [Focuser Move Thread] Focuser moving to 1035 [03-24-18 21:02:33.161][DEBUG] [Focuser Move Thread] Focuser move call complete [03-24-18 21:02:34.162][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1035 matches requested position 1035 [03-24-18 21:02:34.162][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [03-24-18 21:02:35.163][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [03-24-18 21:02:35.163][DEBUG] [Camera Thread] Focuser move complete... [03-24-18 21:02:35.204][DEBUG] [Camera Thread] Focuser position matches requested position (1035), continuing... [03-24-18 21:02:35.205][DEBUG] [Camera Thread] Calculating step metric... [03-24-18 21:02:35.205][DEBUG] [Camera Thread] Taking auto focus frame(s)... [03-24-18 21:02:35.211][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [03-24-18 21:02:35.211][DEBUG] [Camera Thread] ASCOM: AF Binning: 2x2... [03-24-18 21:02:35.211][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 8 seconds... [03-24-18 21:02:35.211][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: a [03-24-18 21:02:35.212][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 21:02:35.212][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 21:02:44.889][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 21:02:44.889][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 21:02:44.889][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 21:02:44.892][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 21:02:44.892][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 21:02:44.928][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 21:02:44.928][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 21:02:44.928][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [03-24-18 21:02:44.928][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [03-24-18 21:02:44.931][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [03-24-18 21:02:44.931][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [03-24-18 21:02:45.037][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [03-24-18 21:02:45.038][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [03-24-18 21:02:45.038][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [03-24-18 21:02:45.054][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 21:02:45.054][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 21:02:45.331][DEBUG] [Main Thread] --> Find stars (normal) [03-24-18 21:02:47.635][DEBUG] [Main Thread] Star detection using min star size of 3px... [03-24-18 21:02:47.636][DEBUG] [Main Thread] Star detection using max star size of 60px... [03-24-18 21:02:47.727][DEBUG] [Main Thread] Find stars took: 2396 ms... [03-24-18 21:02:47.727][DEBUG] [Main Thread] Star list contains 3 stars... [03-24-18 21:02:47.727][DEBUG] [Main Thread] Calculating HFR with sample size: 3 [03-24-18 21:02:48.151][DEBUG] [Camera Thread] Auto focus HFR calculated at: 7,40 [03-24-18 21:02:49.164][DEBUG] [Camera Thread] Moving focuser to next position (1000)... [03-24-18 21:02:49.166][DEBUG] [Focuser Move Thread] Focuser moving to 1000 [03-24-18 21:02:49.207][DEBUG] [Focuser Move Thread] Focuser move call complete [03-24-18 21:02:50.208][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 1000 matches requested position 1000 [03-24-18 21:02:50.208][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [03-24-18 21:02:51.210][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [03-24-18 21:02:51.210][DEBUG] [Camera Thread] Focuser move complete... [03-24-18 21:02:51.215][DEBUG] [Camera Thread] Focuser position matches requested position (1000), continuing... [03-24-18 21:02:51.216][DEBUG] [Camera Thread] Calculating step metric... [03-24-18 21:02:51.216][DEBUG] [Camera Thread] Taking auto focus frame(s)... [03-24-18 21:02:51.221][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [03-24-18 21:02:51.221][DEBUG] [Camera Thread] ASCOM: AF Binning: 2x2... [03-24-18 21:02:51.221][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 8 seconds... [03-24-18 21:02:51.221][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: a [03-24-18 21:02:51.222][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 21:02:51.222][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 21:03:00.866][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 21:03:00.866][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 21:03:00.866][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 21:03:00.869][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 21:03:00.869][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 21:03:00.908][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 21:03:00.908][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 21:03:00.908][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [03-24-18 21:03:00.908][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [03-24-18 21:03:00.909][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [03-24-18 21:03:00.909][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [03-24-18 21:03:01.017][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [03-24-18 21:03:01.017][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [03-24-18 21:03:01.017][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [03-24-18 21:03:01.026][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 21:03:01.026][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 21:03:01.356][DEBUG] [Main Thread] --> Find stars (normal) [03-24-18 21:03:04.531][DEBUG] [Main Thread] Star detection using min star size of 3px... [03-24-18 21:03:04.531][DEBUG] [Main Thread] Star detection using max star size of 60px... [03-24-18 21:03:04.652][DEBUG] [Main Thread] Find stars took: 3295 ms... [03-24-18 21:03:04.652][DEBUG] [Main Thread] Star list contains 5 stars... [03-24-18 21:03:04.652][DEBUG] [Main Thread] Calculating HFR with sample size: 3 [03-24-18 21:03:05.207][DEBUG] [Camera Thread] Auto focus HFR calculated at: 5,18 [03-24-18 21:03:06.221][DEBUG] [Camera Thread] Moving focuser to next position (965)... [03-24-18 21:03:06.222][DEBUG] [Focuser Move Thread] Focuser moving to 965 [03-24-18 21:03:06.259][DEBUG] [Focuser Move Thread] Focuser move call complete [03-24-18 21:03:07.260][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 965 matches requested position 965 [03-24-18 21:03:07.260][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [03-24-18 21:03:08.262][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [03-24-18 21:03:08.262][DEBUG] [Camera Thread] Focuser move complete... [03-24-18 21:03:08.268][DEBUG] [Camera Thread] Focuser position matches requested position (965), continuing... [03-24-18 21:03:08.269][DEBUG] [Camera Thread] Calculating step metric... [03-24-18 21:03:08.269][DEBUG] [Camera Thread] Taking auto focus frame(s)... [03-24-18 21:03:08.285][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [03-24-18 21:03:08.285][DEBUG] [Camera Thread] ASCOM: AF Binning: 2x2... [03-24-18 21:03:08.285][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 8 seconds... [03-24-18 21:03:08.285][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: a [03-24-18 21:03:08.286][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 21:03:08.286][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 21:03:17.930][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 21:03:17.930][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 21:03:17.930][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 21:03:17.933][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 21:03:17.933][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 21:03:17.969][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 21:03:17.969][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 21:03:17.969][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [03-24-18 21:03:17.969][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [03-24-18 21:03:18.002][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [03-24-18 21:03:18.002][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [03-24-18 21:03:18.108][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [03-24-18 21:03:18.108][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [03-24-18 21:03:18.108][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [03-24-18 21:03:18.117][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 21:03:18.117][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 21:03:18.584][DEBUG] [Main Thread] --> Find stars (normal) [03-24-18 21:03:22.306][DEBUG] [Main Thread] Star detection using min star size of 3px... [03-24-18 21:03:22.306][DEBUG] [Main Thread] Star detection using max star size of 60px... [03-24-18 21:03:22.408][DEBUG] [Main Thread] Find stars took: 3824 ms... [03-24-18 21:03:22.408][DEBUG] [Main Thread] Star list contains 6 stars... [03-24-18 21:03:22.408][DEBUG] [Main Thread] Calculating HFR with sample size: 3 [03-24-18 21:03:22.975][DEBUG] [Camera Thread] Auto focus HFR calculated at: 4,50 [03-24-18 21:03:23.989][DEBUG] [Camera Thread] Moving focuser to next position (930)... [03-24-18 21:03:23.990][DEBUG] [Focuser Move Thread] Focuser moving to 930 [03-24-18 21:03:24.026][DEBUG] [Focuser Move Thread] Focuser move call complete [03-24-18 21:03:25.026][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 930 matches requested position 930 [03-24-18 21:03:25.026][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [03-24-18 21:03:26.032][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [03-24-18 21:03:26.032][DEBUG] [Camera Thread] Focuser move complete... [03-24-18 21:03:26.034][DEBUG] [Camera Thread] Focuser position matches requested position (930), continuing... [03-24-18 21:03:26.035][DEBUG] [Camera Thread] Calculating step metric... [03-24-18 21:03:26.035][DEBUG] [Camera Thread] Taking auto focus frame(s)... [03-24-18 21:03:26.039][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [03-24-18 21:03:26.040][DEBUG] [Camera Thread] ASCOM: AF Binning: 2x2... [03-24-18 21:03:26.040][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 8 seconds... [03-24-18 21:03:26.040][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: a [03-24-18 21:03:26.041][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 21:03:26.041][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 21:03:35.705][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 21:03:35.705][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 21:03:35.705][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 21:03:35.708][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 21:03:35.708][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 21:03:35.743][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 21:03:35.743][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 21:03:35.743][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [03-24-18 21:03:35.743][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [03-24-18 21:03:35.745][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [03-24-18 21:03:35.745][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [03-24-18 21:03:35.852][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [03-24-18 21:03:35.852][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [03-24-18 21:03:35.852][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [03-24-18 21:03:35.863][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 21:03:35.863][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 21:03:36.244][DEBUG] [Main Thread] --> Find stars (normal) [03-24-18 21:03:39.752][DEBUG] [Main Thread] Star detection using min star size of 3px... [03-24-18 21:03:39.752][DEBUG] [Main Thread] Star detection using max star size of 60px... [03-24-18 21:03:39.875][DEBUG] [Main Thread] Find stars took: 3631 ms... [03-24-18 21:03:39.875][DEBUG] [Main Thread] Star list contains 16 stars... [03-24-18 21:03:39.875][DEBUG] [Main Thread] Calculating HFR with sample size: 3 [03-24-18 21:03:40.494][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3,23 [03-24-18 21:03:41.508][DEBUG] [Camera Thread] Moving focuser to next position (895)... [03-24-18 21:03:41.509][DEBUG] [Focuser Move Thread] Focuser moving to 895 [03-24-18 21:03:41.574][DEBUG] [Focuser Move Thread] Focuser move call complete [03-24-18 21:03:42.579][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 895 matches requested position 895 [03-24-18 21:03:42.579][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [03-24-18 21:03:43.583][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [03-24-18 21:03:43.584][DEBUG] [Camera Thread] Focuser move complete... [03-24-18 21:03:43.618][DEBUG] [Camera Thread] Focuser position matches requested position (895), continuing... [03-24-18 21:03:43.619][DEBUG] [Camera Thread] Calculating step metric... [03-24-18 21:03:43.619][DEBUG] [Camera Thread] Taking auto focus frame(s)... [03-24-18 21:03:43.622][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [03-24-18 21:03:43.623][DEBUG] [Camera Thread] ASCOM: AF Binning: 2x2... [03-24-18 21:03:43.623][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 8 seconds... [03-24-18 21:03:43.623][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: a [03-24-18 21:03:43.624][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 21:03:43.624][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 21:03:53.266][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 21:03:53.267][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 21:03:53.267][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 21:03:53.269][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 21:03:53.270][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 21:03:53.310][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 21:03:53.310][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 21:03:53.310][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [03-24-18 21:03:53.310][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [03-24-18 21:03:53.324][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [03-24-18 21:03:53.324][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [03-24-18 21:03:53.435][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [03-24-18 21:03:53.435][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [03-24-18 21:03:53.436][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [03-24-18 21:03:53.446][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 21:03:53.446][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 21:03:53.738][DEBUG] [Main Thread] --> Find stars (normal) [03-24-18 21:03:56.141][DEBUG] [Main Thread] Star detection using min star size of 3px... [03-24-18 21:03:56.141][DEBUG] [Main Thread] Star detection using max star size of 60px... [03-24-18 21:03:56.207][DEBUG] [Main Thread] Find stars took: 2467 ms... [03-24-18 21:03:56.207][DEBUG] [Main Thread] Star list contains 45 stars... [03-24-18 21:03:56.207][DEBUG] [Main Thread] Calculating HFR with sample size: 3 [03-24-18 21:03:56.566][DEBUG] [Camera Thread] Auto focus HFR calculated at: 1,88 [03-24-18 21:03:57.581][DEBUG] [Camera Thread] Moving focuser to next position (860)... [03-24-18 21:03:57.583][DEBUG] [Focuser Move Thread] Focuser moving to 860 [03-24-18 21:03:57.620][DEBUG] [Focuser Move Thread] Focuser move call complete [03-24-18 21:03:58.635][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 860 matches requested position 860 [03-24-18 21:03:58.635][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [03-24-18 21:03:59.635][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [03-24-18 21:03:59.635][DEBUG] [Camera Thread] Focuser move complete... [03-24-18 21:03:59.635][DEBUG] [Camera Thread] Focuser position matches requested position (860), continuing... [03-24-18 21:03:59.636][DEBUG] [Camera Thread] Calculating step metric... [03-24-18 21:03:59.636][DEBUG] [Camera Thread] Taking auto focus frame(s)... [03-24-18 21:03:59.639][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [03-24-18 21:03:59.639][DEBUG] [Camera Thread] ASCOM: AF Binning: 2x2... [03-24-18 21:03:59.640][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 8 seconds... [03-24-18 21:03:59.640][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: a [03-24-18 21:03:59.641][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 21:03:59.641][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 21:04:09.311][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 21:04:09.311][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 21:04:09.311][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 21:04:09.314][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 21:04:09.314][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 21:04:09.350][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 21:04:09.350][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 21:04:09.350][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [03-24-18 21:04:09.350][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [03-24-18 21:04:09.354][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [03-24-18 21:04:09.354][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [03-24-18 21:04:09.461][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [03-24-18 21:04:09.461][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [03-24-18 21:04:09.461][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [03-24-18 21:04:09.472][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 21:04:09.472][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 21:04:09.838][DEBUG] [Main Thread] --> Find stars (normal) [03-24-18 21:04:13.575][DEBUG] [Main Thread] Star detection using min star size of 3px... [03-24-18 21:04:13.575][DEBUG] [Main Thread] Star detection using max star size of 60px... [03-24-18 21:04:13.780][DEBUG] [Main Thread] Find stars took: 3942 ms... [03-24-18 21:04:13.780][DEBUG] [Main Thread] Star list contains 61 stars... [03-24-18 21:04:13.780][DEBUG] [Main Thread] Calculating HFR with sample size: 3 [03-24-18 21:04:14.348][DEBUG] [Camera Thread] Auto focus HFR calculated at: 0,93 [03-24-18 21:04:15.364][DEBUG] [Camera Thread] Moving focuser to next position (825)... [03-24-18 21:04:15.365][DEBUG] [Focuser Move Thread] Focuser moving to 825 [03-24-18 21:04:15.446][DEBUG] [Focuser Move Thread] Focuser move call complete [03-24-18 21:04:16.477][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 825 matches requested position 825 [03-24-18 21:04:16.477][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [03-24-18 21:04:17.478][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [03-24-18 21:04:17.478][DEBUG] [Camera Thread] Focuser move complete... [03-24-18 21:04:17.497][DEBUG] [Camera Thread] Focuser position matches requested position (825), continuing... [03-24-18 21:04:17.498][DEBUG] [Camera Thread] Calculating step metric... [03-24-18 21:04:17.498][DEBUG] [Camera Thread] Taking auto focus frame(s)... [03-24-18 21:04:17.502][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [03-24-18 21:04:17.502][DEBUG] [Camera Thread] ASCOM: AF Binning: 2x2... [03-24-18 21:04:17.502][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 8 seconds... [03-24-18 21:04:17.502][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: a [03-24-18 21:04:17.503][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 21:04:17.503][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 21:04:27.151][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 21:04:27.151][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 21:04:27.151][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 21:04:27.154][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 21:04:27.155][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 21:04:27.189][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 21:04:27.189][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 21:04:27.189][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [03-24-18 21:04:27.189][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [03-24-18 21:04:27.190][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [03-24-18 21:04:27.190][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [03-24-18 21:04:27.293][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [03-24-18 21:04:27.293][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [03-24-18 21:04:27.293][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [03-24-18 21:04:27.302][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 21:04:27.302][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 21:04:27.734][DEBUG] [Main Thread] --> Find stars (normal) [03-24-18 21:04:31.408][DEBUG] [Main Thread] Star detection using min star size of 3px... [03-24-18 21:04:31.408][DEBUG] [Main Thread] Star detection using max star size of 60px... [03-24-18 21:04:31.520][DEBUG] [Main Thread] Find stars took: 3785 ms... [03-24-18 21:04:31.520][DEBUG] [Main Thread] Star list contains 51 stars... [03-24-18 21:04:31.520][DEBUG] [Main Thread] Calculating HFR with sample size: 3 [03-24-18 21:04:32.077][DEBUG] [Camera Thread] Auto focus HFR calculated at: 1,58 [03-24-18 21:04:33.091][DEBUG] [Camera Thread] Moving focuser to next position (790)... [03-24-18 21:04:33.092][DEBUG] [Focuser Move Thread] Focuser moving to 790 [03-24-18 21:04:33.131][DEBUG] [Focuser Move Thread] Focuser move call complete [03-24-18 21:04:34.207][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 790 matches requested position 790 [03-24-18 21:04:34.207][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [03-24-18 21:04:35.208][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [03-24-18 21:04:35.208][DEBUG] [Camera Thread] Focuser move complete... [03-24-18 21:04:35.209][DEBUG] [Camera Thread] Focuser position matches requested position (790), continuing... [03-24-18 21:04:35.210][DEBUG] [Camera Thread] Calculating step metric... [03-24-18 21:04:35.210][DEBUG] [Camera Thread] Taking auto focus frame(s)... [03-24-18 21:04:35.213][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [03-24-18 21:04:35.213][DEBUG] [Camera Thread] ASCOM: AF Binning: 2x2... [03-24-18 21:04:35.213][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 8 seconds... [03-24-18 21:04:35.213][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: a [03-24-18 21:04:35.215][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 21:04:35.215][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 21:04:44.863][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 21:04:44.863][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 21:04:44.863][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 21:04:44.868][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 21:04:44.868][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 21:04:44.910][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 21:04:44.910][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 21:04:44.910][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [03-24-18 21:04:44.910][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [03-24-18 21:04:44.912][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [03-24-18 21:04:44.912][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [03-24-18 21:04:45.072][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [03-24-18 21:04:45.072][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [03-24-18 21:04:45.072][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [03-24-18 21:04:45.084][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 21:04:45.084][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 21:04:45.678][DEBUG] [Main Thread] --> Find stars (normal) [03-24-18 21:04:49.294][DEBUG] [Main Thread] Star detection using min star size of 3px... [03-24-18 21:04:49.294][DEBUG] [Main Thread] Star detection using max star size of 60px... [03-24-18 21:04:49.397][DEBUG] [Main Thread] Find stars took: 3719 ms... [03-24-18 21:04:49.398][DEBUG] [Main Thread] Star list contains 22 stars... [03-24-18 21:04:49.398][DEBUG] [Main Thread] Calculating HFR with sample size: 3 [03-24-18 21:04:49.933][DEBUG] [Camera Thread] Auto focus HFR calculated at: 2,98 [03-24-18 21:04:50.949][DEBUG] [Camera Thread] Moving focuser to next position (755)... [03-24-18 21:04:50.950][DEBUG] [Focuser Move Thread] Focuser moving to 755 [03-24-18 21:04:50.989][DEBUG] [Focuser Move Thread] Focuser move call complete [03-24-18 21:04:52.014][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 755 matches requested position 755 [03-24-18 21:04:52.014][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [03-24-18 21:04:53.015][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [03-24-18 21:04:53.015][DEBUG] [Camera Thread] Focuser move complete... [03-24-18 21:04:53.016][DEBUG] [Camera Thread] Focuser position matches requested position (755), continuing... [03-24-18 21:04:53.016][DEBUG] [Camera Thread] Calculating step metric... [03-24-18 21:04:53.016][DEBUG] [Camera Thread] Taking auto focus frame(s)... [03-24-18 21:04:53.020][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [03-24-18 21:04:53.020][DEBUG] [Camera Thread] ASCOM: AF Binning: 2x2... [03-24-18 21:04:53.020][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 8 seconds... [03-24-18 21:04:53.021][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: a [03-24-18 21:04:53.022][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 21:04:53.022][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 21:05:02.684][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 21:05:02.684][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 21:05:02.684][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 21:05:02.691][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 21:05:02.691][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 21:05:02.730][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 21:05:02.731][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 21:05:02.731][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [03-24-18 21:05:02.731][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [03-24-18 21:05:02.733][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [03-24-18 21:05:02.733][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [03-24-18 21:05:02.832][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [03-24-18 21:05:02.832][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [03-24-18 21:05:02.832][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [03-24-18 21:05:02.843][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 21:05:02.843][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 21:05:03.326][DEBUG] [Main Thread] --> Find stars (normal) [03-24-18 21:05:06.982][DEBUG] [Main Thread] Star detection using min star size of 3px... [03-24-18 21:05:06.982][DEBUG] [Main Thread] Star detection using max star size of 60px... [03-24-18 21:05:07.100][DEBUG] [Main Thread] Find stars took: 3774 ms... [03-24-18 21:05:07.100][DEBUG] [Main Thread] Star list contains 9 stars... [03-24-18 21:05:07.100][DEBUG] [Main Thread] Calculating HFR with sample size: 3 [03-24-18 21:05:07.596][DEBUG] [Camera Thread] Auto focus HFR calculated at: 4,18 [03-24-18 21:05:08.612][DEBUG] [Camera Thread] New auto focus method calculated focus at: 844 [03-24-18 21:05:08.612][DEBUG] [Camera Thread] Old auto focus method calculated focus at: 860 [03-24-18 21:05:08.613][DEBUG] [Camera Thread] Using new auto focus method for focuser position [03-24-18 21:05:08.613][DEBUG] [Camera Thread] New focus position is at 844 (@18,47C). Moving focuser... [03-24-18 21:05:08.614][DEBUG] [Focuser Move Thread] Focuser moving to 844 [03-24-18 21:05:08.615][DEBUG] [Focuser Move Thread] Focuser backlash active, modified move to 944 [03-24-18 21:05:08.651][DEBUG] [Focuser Move Thread] Focuser move call complete [03-24-18 21:05:08.651][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has started... [03-24-18 21:05:11.676][DEBUG] [Focuser Backlash Thread] Backlash thread has detected that movement to modified position (944) is complete... [03-24-18 21:05:11.676][DEBUG] [Focuser Backlash Thread] Moving focuser to original requested position (844)... [03-24-18 21:05:11.676][DEBUG] [Focuser Backlash Thread] Focuser backlash compensation needed. Steps: 100 Direction:IN [03-24-18 21:05:11.676][DEBUG] [Focuser Backlash Thread] Focuser moving to 844 [03-24-18 21:05:11.713][DEBUG] [Focuser Backlash Thread] Focuser move call complete [03-24-18 21:05:13.680][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 844 matches requested position 844 [03-24-18 21:05:13.680][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [03-24-18 21:05:13.714][DEBUG] [Focuser Backlash Thread] Focuser backlash completed. Focuser is at original request position (844)... [03-24-18 21:05:13.714][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has ended... [03-24-18 21:05:14.696][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [03-24-18 21:05:14.696][DEBUG] [Camera Thread] Focuser move complete... [03-24-18 21:05:14.697][DEBUG] [Camera Thread] Focuser successfully set to focus position (844) [03-24-18 21:05:14.698][DEBUG] [Camera Thread] Taking validation frame at focus position... [03-24-18 21:05:14.703][DEBUG] [Camera Thread] Taking auto focus frame(s)... [03-24-18 21:05:14.709][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [03-24-18 21:05:14.709][DEBUG] [Camera Thread] ASCOM: AF Binning: 2x2... [03-24-18 21:05:14.709][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 8 seconds... [03-24-18 21:05:14.709][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: a [03-24-18 21:05:14.710][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 21:05:14.710][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 21:05:24.377][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 21:05:24.377][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 21:05:24.377][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 21:05:24.381][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 21:05:24.381][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 21:05:24.415][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 21:05:24.415][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 21:05:24.415][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [03-24-18 21:05:24.415][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [03-24-18 21:05:24.417][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [03-24-18 21:05:24.417][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [03-24-18 21:05:24.531][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [03-24-18 21:05:24.531][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [03-24-18 21:05:24.531][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [03-24-18 21:05:24.545][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 21:05:24.545][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 21:05:24.951][DEBUG] [Main Thread] --> Find stars (normal) [03-24-18 21:05:28.090][DEBUG] [Main Thread] Star detection using min star size of 3px... [03-24-18 21:05:28.091][DEBUG] [Main Thread] Star detection using max star size of 60px... [03-24-18 21:05:28.232][DEBUG] [Main Thread] Find stars took: 3280 ms... [03-24-18 21:05:28.232][DEBUG] [Main Thread] Star list contains 69 stars... [03-24-18 21:05:28.232][DEBUG] [Main Thread] Calculating HFR with sample size: 3 [03-24-18 21:05:28.805][DEBUG] [Camera Thread] Auto focus HFR calculated at: 1,13 [03-24-18 21:05:29.807][DEBUG] [Camera Thread] Warning! Auto focus validation frame HFR (1,13) might be out of tolerance with respect to expected HFR (1,07 or lower). [03-24-18 21:05:33.852][DEBUG] [Camera Thread] Auto focus is complete (best fit method: 844; HFR 1,13)... [03-24-18 21:05:33.868][DEBUG] [Camera Thread] Entering chart disposal loop... [03-24-18 21:06:27.864][DEBUG] [Camera Thread] Rerun auto focus... [03-24-18 21:06:27.864][DEBUG] [Camera Thread] Chart disposal loop is complete... [03-24-18 21:06:27.959][DEBUG] [Camera Thread] Auto focus data [03-24-18 21:06:27.959][DEBUG] [Camera Thread] - Data Points: 7 [03-24-18 21:06:27.959][DEBUG] [Camera Thread] - Step Size: 35 [03-24-18 21:06:27.959][DEBUG] [Camera Thread] - Current Position: 844 [03-24-18 21:06:27.959][DEBUG] [Camera Thread] - Initial Move Position: 949 [03-24-18 21:06:28.068][DEBUG] [Camera Thread] No AF darks found... [03-24-18 21:06:28.070][DEBUG] [Camera Thread] Moving focuser to next position (949)... [03-24-18 21:06:28.078][DEBUG] [Focuser Move Thread] Focuser moving to 949 [03-24-18 21:06:28.126][DEBUG] [Focuser Move Thread] Focuser backlash active, modified move to 1049 [03-24-18 21:06:28.190][DEBUG] [Focuser Move Thread] Focuser move call complete [03-24-18 21:06:28.191][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has started... [03-24-18 21:06:31.192][DEBUG] [Focuser Backlash Thread] Backlash thread has detected that movement to modified position (1049) is complete... [03-24-18 21:06:31.192][DEBUG] [Focuser Backlash Thread] Moving focuser to original requested position (949)... [03-24-18 21:06:31.192][DEBUG] [Focuser Backlash Thread] Focuser backlash compensation needed. Steps: 100 Direction:IN [03-24-18 21:06:31.192][DEBUG] [Focuser Backlash Thread] Focuser moving to 949 [03-24-18 21:06:31.292][DEBUG] [Focuser Backlash Thread] Focuser move call complete [03-24-18 21:06:33.214][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 949 matches requested position 949 [03-24-18 21:06:33.214][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [03-24-18 21:06:33.295][DEBUG] [Focuser Backlash Thread] Focuser backlash completed. Focuser is at original request position (949)... [03-24-18 21:06:33.295][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has ended... [03-24-18 21:06:34.299][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [03-24-18 21:06:34.299][DEBUG] [Camera Thread] Focuser move complete... [03-24-18 21:06:34.299][DEBUG] [Camera Thread] Focuser position matches requested position (949), continuing... [03-24-18 21:06:34.303][DEBUG] [Camera Thread] Calculating step metric... [03-24-18 21:06:34.303][DEBUG] [Camera Thread] Taking auto focus frame(s)... [03-24-18 21:06:34.307][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [03-24-18 21:06:34.307][DEBUG] [Camera Thread] ASCOM: AF Binning: 2x2... [03-24-18 21:06:34.307][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 8 seconds... [03-24-18 21:06:34.307][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: a [03-24-18 21:06:34.309][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 21:06:34.309][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 21:06:43.948][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 21:06:43.948][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 21:06:43.948][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 21:06:43.953][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 21:06:43.953][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 21:06:43.991][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 21:06:43.991][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 21:06:44.017][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [03-24-18 21:06:44.017][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [03-24-18 21:06:44.018][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [03-24-18 21:06:44.018][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [03-24-18 21:06:44.125][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [03-24-18 21:06:44.125][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [03-24-18 21:06:44.125][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [03-24-18 21:06:44.135][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 21:06:44.135][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 21:06:44.529][DEBUG] [Main Thread] --> Find stars (normal) [03-24-18 21:06:47.754][DEBUG] [Main Thread] Star detection using min star size of 3px... [03-24-18 21:06:47.754][DEBUG] [Main Thread] Star detection using max star size of 60px... [03-24-18 21:06:47.819][DEBUG] [Main Thread] Find stars took: 3291 ms... [03-24-18 21:06:47.819][DEBUG] [Main Thread] Star list contains 11 stars... [03-24-18 21:06:47.819][DEBUG] [Main Thread] Calculating HFR with sample size: 3 [03-24-18 21:06:48.359][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3,86 [03-24-18 21:06:49.374][DEBUG] [Camera Thread] Moving focuser to next position (914)... [03-24-18 21:06:49.376][DEBUG] [Focuser Move Thread] Focuser moving to 914 [03-24-18 21:06:49.416][DEBUG] [Focuser Move Thread] Focuser move call complete [03-24-18 21:06:50.417][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 914 matches requested position 914 [03-24-18 21:06:50.417][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [03-24-18 21:06:51.419][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [03-24-18 21:06:51.419][DEBUG] [Camera Thread] Focuser move complete... [03-24-18 21:06:51.420][DEBUG] [Camera Thread] Focuser position matches requested position (914), continuing... [03-24-18 21:06:51.420][DEBUG] [Camera Thread] Calculating step metric... [03-24-18 21:06:51.420][DEBUG] [Camera Thread] Taking auto focus frame(s)... [03-24-18 21:06:51.424][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [03-24-18 21:06:51.424][DEBUG] [Camera Thread] ASCOM: AF Binning: 2x2... [03-24-18 21:06:51.424][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 8 seconds... [03-24-18 21:06:51.424][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: a [03-24-18 21:06:51.426][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 21:06:51.426][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 21:07:01.097][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 21:07:01.097][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 21:07:01.097][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 21:07:01.100][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 21:07:01.100][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 21:07:01.137][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 21:07:01.137][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 21:07:01.137][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [03-24-18 21:07:01.137][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [03-24-18 21:07:01.138][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [03-24-18 21:07:01.138][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [03-24-18 21:07:01.247][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [03-24-18 21:07:01.247][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [03-24-18 21:07:01.247][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [03-24-18 21:07:01.256][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 21:07:01.256][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 21:07:01.613][DEBUG] [Main Thread] --> Find stars (normal) [03-24-18 21:07:04.769][DEBUG] [Main Thread] Star detection using min star size of 3px... [03-24-18 21:07:04.769][DEBUG] [Main Thread] Star detection using max star size of 60px... [03-24-18 21:07:04.880][DEBUG] [Main Thread] Find stars took: 3266 ms... [03-24-18 21:07:04.880][DEBUG] [Main Thread] Star list contains 20 stars... [03-24-18 21:07:04.880][DEBUG] [Main Thread] Calculating HFR with sample size: 3 [03-24-18 21:07:05.916][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3,45 [03-24-18 21:07:06.931][DEBUG] [Camera Thread] Moving focuser to next position (879)... [03-24-18 21:07:06.932][DEBUG] [Focuser Move Thread] Focuser moving to 879 [03-24-18 21:07:07.060][DEBUG] [Focuser Move Thread] Focuser move call complete [03-24-18 21:07:08.061][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 879 matches requested position 879 [03-24-18 21:07:08.061][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [03-24-18 21:07:09.063][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [03-24-18 21:07:09.063][DEBUG] [Camera Thread] Focuser move complete... [03-24-18 21:07:09.081][DEBUG] [Camera Thread] Focuser position matches requested position (879), continuing... [03-24-18 21:07:09.082][DEBUG] [Camera Thread] Calculating step metric... [03-24-18 21:07:09.082][DEBUG] [Camera Thread] Taking auto focus frame(s)... [03-24-18 21:07:09.085][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [03-24-18 21:07:09.085][DEBUG] [Camera Thread] ASCOM: AF Binning: 2x2... [03-24-18 21:07:09.085][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 8 seconds... [03-24-18 21:07:09.085][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: a [03-24-18 21:07:09.087][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 21:07:09.087][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 21:07:18.750][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 21:07:18.750][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 21:07:18.750][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 21:07:18.754][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 21:07:18.754][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 21:07:18.789][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 21:07:18.790][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 21:07:18.790][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [03-24-18 21:07:18.790][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [03-24-18 21:07:18.791][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [03-24-18 21:07:18.791][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [03-24-18 21:07:18.940][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [03-24-18 21:07:18.941][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [03-24-18 21:07:18.941][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [03-24-18 21:07:18.956][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 21:07:18.956][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 21:07:19.430][DEBUG] [Main Thread] --> Find stars (normal) [03-24-18 21:07:22.569][DEBUG] [Main Thread] Star detection using min star size of 3px... [03-24-18 21:07:22.569][DEBUG] [Main Thread] Star detection using max star size of 60px... [03-24-18 21:07:22.636][DEBUG] [Main Thread] Find stars took: 3205 ms... [03-24-18 21:07:22.636][DEBUG] [Main Thread] Star list contains 37 stars... [03-24-18 21:07:22.636][DEBUG] [Main Thread] Calculating HFR with sample size: 3 [03-24-18 21:07:23.185][DEBUG] [Camera Thread] Auto focus HFR calculated at: 2,96 [03-24-18 21:07:24.202][DEBUG] [Camera Thread] Moving focuser to next position (844)... [03-24-18 21:07:24.225][DEBUG] [Focuser Move Thread] Focuser moving to 844 [03-24-18 21:07:24.276][DEBUG] [Focuser Move Thread] Focuser move call complete [03-24-18 21:07:25.277][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 844 matches requested position 844 [03-24-18 21:07:25.277][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [03-24-18 21:07:26.278][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [03-24-18 21:07:26.278][DEBUG] [Camera Thread] Focuser move complete... [03-24-18 21:07:26.279][DEBUG] [Camera Thread] Focuser position matches requested position (844), continuing... [03-24-18 21:07:26.280][DEBUG] [Camera Thread] Calculating step metric... [03-24-18 21:07:26.280][DEBUG] [Camera Thread] Taking auto focus frame(s)... [03-24-18 21:07:26.287][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [03-24-18 21:07:26.287][DEBUG] [Camera Thread] ASCOM: AF Binning: 2x2... [03-24-18 21:07:26.287][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 8 seconds... [03-24-18 21:07:26.287][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: a [03-24-18 21:07:26.288][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 21:07:26.288][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 21:07:35.941][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 21:07:35.941][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 21:07:35.941][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 21:07:35.950][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 21:07:35.950][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 21:07:35.985][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 21:07:35.985][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 21:07:35.986][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [03-24-18 21:07:35.986][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [03-24-18 21:07:35.986][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [03-24-18 21:07:35.987][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [03-24-18 21:07:36.083][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [03-24-18 21:07:36.083][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [03-24-18 21:07:36.083][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [03-24-18 21:07:36.092][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 21:07:36.092][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 21:07:36.555][DEBUG] [Main Thread] --> Find stars (normal) [03-24-18 21:07:39.725][DEBUG] [Main Thread] Star detection using min star size of 3px... [03-24-18 21:07:39.725][DEBUG] [Main Thread] Star detection using max star size of 60px... [03-24-18 21:07:39.868][DEBUG] [Main Thread] Find stars took: 3313 ms... [03-24-18 21:07:39.869][DEBUG] [Main Thread] Star list contains 57 stars... [03-24-18 21:07:39.869][DEBUG] [Main Thread] Calculating HFR with sample size: 3 [03-24-18 21:07:40.311][DEBUG] [Camera Thread] Auto focus HFR calculated at: 2,66 [03-24-18 21:07:41.330][DEBUG] [Camera Thread] Moving focuser to next position (809)... [03-24-18 21:07:41.332][DEBUG] [Focuser Move Thread] Focuser moving to 809 [03-24-18 21:07:41.455][DEBUG] [Focuser Move Thread] Focuser move call complete [03-24-18 21:07:42.457][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 809 matches requested position 809 [03-24-18 21:07:42.457][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [03-24-18 21:07:43.458][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [03-24-18 21:07:43.458][DEBUG] [Camera Thread] Focuser move complete... [03-24-18 21:07:43.487][DEBUG] [Camera Thread] Focuser position matches requested position (809), continuing... [03-24-18 21:07:43.488][DEBUG] [Camera Thread] Calculating step metric... [03-24-18 21:07:43.488][DEBUG] [Camera Thread] Taking auto focus frame(s)... [03-24-18 21:07:43.491][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [03-24-18 21:07:43.491][DEBUG] [Camera Thread] ASCOM: AF Binning: 2x2... [03-24-18 21:07:43.491][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 8 seconds... [03-24-18 21:07:43.491][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: a [03-24-18 21:07:43.492][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 21:07:43.493][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 21:07:53.139][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 21:07:53.139][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 21:07:53.139][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 21:07:53.143][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 21:07:53.143][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 21:07:53.178][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 21:07:53.178][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 21:07:53.178][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [03-24-18 21:07:53.178][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [03-24-18 21:07:53.180][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [03-24-18 21:07:53.180][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [03-24-18 21:07:53.292][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [03-24-18 21:07:53.292][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [03-24-18 21:07:53.292][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [03-24-18 21:07:53.310][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 21:07:53.310][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 21:07:53.642][DEBUG] [Main Thread] --> Find stars (normal) [03-24-18 21:07:55.848][DEBUG] [Main Thread] Star detection using min star size of 3px... [03-24-18 21:07:55.848][DEBUG] [Main Thread] Star detection using max star size of 60px... [03-24-18 21:07:55.917][DEBUG] [Main Thread] Find stars took: 2275 ms... [03-24-18 21:07:55.917][DEBUG] [Main Thread] Star list contains 30 stars... [03-24-18 21:07:55.917][DEBUG] [Main Thread] Calculating HFR with sample size: 3 [03-24-18 21:07:56.276][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3,20 [03-24-18 21:07:57.293][DEBUG] [Camera Thread] Moving focuser to next position (774)... [03-24-18 21:07:57.294][DEBUG] [Focuser Move Thread] Focuser moving to 774 [03-24-18 21:07:57.331][DEBUG] [Focuser Move Thread] Focuser move call complete [03-24-18 21:07:58.331][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 774 matches requested position 774 [03-24-18 21:07:58.332][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [03-24-18 21:07:59.333][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [03-24-18 21:07:59.333][DEBUG] [Camera Thread] Focuser move complete... [03-24-18 21:07:59.355][DEBUG] [Camera Thread] Focuser position matches requested position (774), continuing... [03-24-18 21:07:59.356][DEBUG] [Camera Thread] Calculating step metric... [03-24-18 21:07:59.356][DEBUG] [Camera Thread] Taking auto focus frame(s)... [03-24-18 21:07:59.359][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [03-24-18 21:07:59.359][DEBUG] [Camera Thread] ASCOM: AF Binning: 2x2... [03-24-18 21:07:59.360][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 8 seconds... [03-24-18 21:07:59.360][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: a [03-24-18 21:07:59.361][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 21:07:59.361][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 21:08:09.012][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 21:08:09.012][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 21:08:09.012][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 21:08:09.015][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 21:08:09.015][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 21:08:09.051][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 21:08:09.052][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 21:08:09.052][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [03-24-18 21:08:09.052][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [03-24-18 21:08:09.054][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [03-24-18 21:08:09.054][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [03-24-18 21:08:09.156][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [03-24-18 21:08:09.156][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [03-24-18 21:08:09.156][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [03-24-18 21:08:09.169][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 21:08:09.169][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 21:08:09.580][DEBUG] [Main Thread] --> Find stars (normal) [03-24-18 21:08:13.297][DEBUG] [Main Thread] Star detection using min star size of 3px... [03-24-18 21:08:13.297][DEBUG] [Main Thread] Star detection using max star size of 60px... [03-24-18 21:08:13.415][DEBUG] [Main Thread] Find stars took: 3835 ms... [03-24-18 21:08:13.415][DEBUG] [Main Thread] Star list contains 17 stars... [03-24-18 21:08:13.415][DEBUG] [Main Thread] Calculating HFR with sample size: 3 [03-24-18 21:08:14.010][DEBUG] [Camera Thread] Auto focus HFR calculated at: 3,79 [03-24-18 21:08:15.027][DEBUG] [Camera Thread] Moving focuser to next position (739)... [03-24-18 21:08:15.031][DEBUG] [Focuser Move Thread] Focuser moving to 739 [03-24-18 21:08:15.126][DEBUG] [Focuser Move Thread] Focuser move call complete [03-24-18 21:08:16.127][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 739 matches requested position 739 [03-24-18 21:08:16.127][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [03-24-18 21:08:17.129][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [03-24-18 21:08:17.129][DEBUG] [Camera Thread] Focuser move complete... [03-24-18 21:08:17.130][DEBUG] [Camera Thread] Focuser position matches requested position (739), continuing... [03-24-18 21:08:17.130][DEBUG] [Camera Thread] Calculating step metric... [03-24-18 21:08:17.131][DEBUG] [Camera Thread] Taking auto focus frame(s)... [03-24-18 21:08:17.136][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [03-24-18 21:08:17.136][DEBUG] [Camera Thread] ASCOM: AF Binning: 2x2... [03-24-18 21:08:17.136][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 8 seconds... [03-24-18 21:08:17.136][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: a [03-24-18 21:08:17.138][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 21:08:17.138][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 21:08:26.869][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 21:08:26.870][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 21:08:26.870][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 21:08:26.875][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 21:08:26.875][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 21:08:26.926][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 21:08:26.926][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 21:08:26.926][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [03-24-18 21:08:26.926][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [03-24-18 21:08:26.927][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [03-24-18 21:08:26.928][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [03-24-18 21:08:27.069][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [03-24-18 21:08:27.069][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [03-24-18 21:08:27.069][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [03-24-18 21:08:27.078][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 21:08:27.078][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 21:08:27.439][DEBUG] [Main Thread] --> Find stars (normal) [03-24-18 21:08:30.943][DEBUG] [Main Thread] Star detection using min star size of 3px... [03-24-18 21:08:30.943][DEBUG] [Main Thread] Star detection using max star size of 60px... [03-24-18 21:08:31.006][DEBUG] [Main Thread] Find stars took: 3567 ms... [03-24-18 21:08:31.006][DEBUG] [Main Thread] Star list contains 16 stars... [03-24-18 21:08:31.006][DEBUG] [Main Thread] Calculating HFR with sample size: 3 [03-24-18 21:08:31.542][DEBUG] [Camera Thread] Auto focus HFR calculated at: 4,58 [03-24-18 21:08:32.556][DEBUG] [Camera Thread] New auto focus method calculated focus at: 842 [03-24-18 21:08:32.556][DEBUG] [Camera Thread] Old auto focus method calculated focus at: 860 [03-24-18 21:08:32.556][DEBUG] [Camera Thread] Using new auto focus method for focuser position [03-24-18 21:08:32.556][DEBUG] [Camera Thread] New focus position is at 842 (@18,30C). Moving focuser... [03-24-18 21:08:32.556][DEBUG] [Focuser Move Thread] Focuser moving to 842 [03-24-18 21:08:32.557][DEBUG] [Focuser Move Thread] Focuser backlash active, modified move to 942 [03-24-18 21:08:32.594][DEBUG] [Focuser Move Thread] Focuser move call complete [03-24-18 21:08:32.594][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has started... [03-24-18 21:08:36.617][DEBUG] [Focuser Backlash Thread] Backlash thread has detected that movement to modified position (942) is complete... [03-24-18 21:08:36.617][DEBUG] [Focuser Backlash Thread] Moving focuser to original requested position (842)... [03-24-18 21:08:36.617][DEBUG] [Focuser Backlash Thread] Focuser backlash compensation needed. Steps: 100 Direction:IN [03-24-18 21:08:36.617][DEBUG] [Focuser Backlash Thread] Focuser moving to 842 [03-24-18 21:08:36.662][DEBUG] [Focuser Backlash Thread] Focuser move call complete [03-24-18 21:08:38.706][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser position 842 matches requested position 842 [03-24-18 21:08:38.706][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Waiting for focuser to report done... [03-24-18 21:08:38.706][DEBUG] [Focuser Backlash Thread] Focuser backlash completed. Focuser is at original request position (842)... [03-24-18 21:08:38.706][DEBUG] [Focuser Backlash Thread] Focuser backlash thread has ended... [03-24-18 21:08:39.743][DEBUG] [Focuser Move Thread] MoveFocuserAbsBlocking: Focuser reports it is done moving, continuing... [03-24-18 21:08:39.743][DEBUG] [Camera Thread] Focuser move complete... [03-24-18 21:08:39.743][DEBUG] [Camera Thread] Focuser successfully set to focus position (842) [03-24-18 21:08:39.744][DEBUG] [Camera Thread] Taking validation frame at focus position... [03-24-18 21:08:39.749][DEBUG] [Camera Thread] Taking auto focus frame(s)... [03-24-18 21:08:39.756][DEBUG] [Camera Thread] ASCOM camera: Capturing auto focus frame... [03-24-18 21:08:39.756][DEBUG] [Camera Thread] ASCOM: AF Binning: 2x2... [03-24-18 21:08:39.756][DEBUG] [Camera Thread] ASCOM: AF Exposure length: 8 seconds... [03-24-18 21:08:39.756][DEBUG] [Camera Thread] ASCOM: AF Exposure DL speed: a [03-24-18 21:08:39.758][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 21:08:39.758][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 21:08:49.414][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 21:08:49.414][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 21:08:49.414][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 21:08:49.417][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 21:08:49.417][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 21:08:49.455][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 21:08:49.455][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 21:08:49.455][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [03-24-18 21:08:49.455][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [03-24-18 21:08:49.456][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [03-24-18 21:08:49.457][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [03-24-18 21:08:49.562][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [03-24-18 21:08:49.562][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [03-24-18 21:08:49.562][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [03-24-18 21:08:49.576][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 21:08:49.576][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 21:08:49.891][DEBUG] [Main Thread] --> Find stars (normal) [03-24-18 21:08:52.113][DEBUG] [Main Thread] Star detection using min star size of 3px... [03-24-18 21:08:52.113][DEBUG] [Main Thread] Star detection using max star size of 60px... [03-24-18 21:08:52.183][DEBUG] [Main Thread] Find stars took: 2292 ms... [03-24-18 21:08:52.183][DEBUG] [Main Thread] Star list contains 84 stars... [03-24-18 21:08:52.183][DEBUG] [Main Thread] Calculating HFR with sample size: 3 [03-24-18 21:08:52.567][DEBUG] [Camera Thread] Auto focus HFR calculated at: 1,46 [03-24-18 21:08:53.569][DEBUG] [Camera Thread] Warning! Auto focus validation frame HFR (1,46) might be out of tolerance with respect to expected HFR (1,07 or lower). [03-24-18 21:08:55.952][DEBUG] [Camera Thread] Auto focus is complete (best fit method: 842; HFR 1,46)... [03-24-18 21:08:55.967][DEBUG] [Camera Thread] Entering chart disposal loop... [03-24-18 21:09:07.896][DEBUG] [Camera Thread] Chart disposal loop is complete... [03-24-18 21:09:07.896][DEBUG] [Camera Thread] Auto focus is done... [03-24-18 21:09:07.913][DEBUG] [AfChartThread] AF chart dialog is closed... [03-24-18 21:09:07.914][DEBUG] [Camera Thread] Auto focus complete... [03-24-18 21:09:07.945][DEBUG] [Camera Thread] SGM_FOCUSER_AUTO_FOCUS complete... [03-24-18 21:09:27.569][DEBUG] [Main Thread] ASCOM Telescope: Set park message received. [03-24-18 21:09:37.146][DEBUG] [Telescope Thread] SGM_TELESCOPE_SOLVE message received... [03-24-18 21:09:37.146][DEBUG] [Telescope Thread] Telescope solve with plate solver PlateSolve2... [03-24-18 21:09:37.149][DEBUG] [Telescope Thread] Plate solving scope frame... [03-24-18 21:09:37.151][DEBUG] [Telescope Thread] Created full file name (file does not exist): C:\Users\Leandro\AppData\Local\SequenceGenerator\Temp\plate_solve_image.fit [03-24-18 21:09:37.190][DEBUG] [Camera Thread] SGM_CAMERA_PLATE_SOLVER_CAPTURE message received... [03-24-18 21:09:37.194][DEBUG] [Camera Thread] Collecting FITs headers for plate solve frame... [03-24-18 21:09:37.197][DEBUG] [Camera Thread] Collecting FITs headers for plate solve frame... [03-24-18 21:09:37.313][DEBUG] [Camera Thread] SetAscomHighSpeed... [03-24-18 21:09:37.313][DEBUG] [Camera Thread] Cannot set readout speed, not supported by camera... [03-24-18 21:09:48.970][DEBUG] [Camera Thread] Created full file name (file does not exist): C:\Users\Leandro\AppData\Local\SequenceGenerator\Temp\plate_solve_image.fit [03-24-18 21:09:48.970][DEBUG] [Camera Thread] SaveFileAscom: Start... [03-24-18 21:09:48.970][DEBUG] [Camera Thread] SaveFileAscom: Checking image data... [03-24-18 21:09:48.970][DEBUG] [Camera Thread] SaveFileAscom: Create normal preview bitmap... [03-24-18 21:09:48.973][DEBUG] [Camera Thread] SaveFileAscom: Locking preview bits... [03-24-18 21:09:48.973][DEBUG] [Camera Thread] SaveFileAscom: Validating file name... [03-24-18 21:09:48.973][DEBUG] [Camera Thread] SaveFileAscom: Starting byte traversal... [03-24-18 21:09:49.007][DEBUG] [Camera Thread] SaveFileAscom: Unlocking preview bits... [03-24-18 21:09:49.007][DEBUG] [Camera Thread] SaveFileAscom: Saving preview bitmap to disk... [03-24-18 21:09:49.007][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [03-24-18 21:09:49.007][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [03-24-18 21:09:49.008][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [03-24-18 21:09:49.010][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [03-24-18 21:09:49.110][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [03-24-18 21:09:49.111][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [03-24-18 21:09:49.111][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [03-24-18 21:09:49.292][DEBUG] [Camera Thread] Internal Interface: Set Preview... [03-24-18 21:09:49.292][DEBUG] [Camera Thread] Display image preview using asynch task... [03-24-18 21:09:49.749][DEBUG] [Camera Thread] SaveFileAscom: Done [03-24-18 21:09:49.749][DEBUG] [Camera Thread] =========== Save file took 781 ms [03-24-18 21:09:49.749][DEBUG] [Camera Thread] SGM_CAMERA_PLATE_SOLVER_CAPTURE complete... [03-24-18 21:09:49.853][DEBUG] [Telescope Thread] ************* SOLVE HINTS **************** [03-24-18 21:09:49.853][DEBUG] [Telescope Thread] SOLVER: PlateSolve2 [03-24-18 21:09:49.853][DEBUG] [Telescope Thread] BLIND: False [03-24-18 21:09:49.853][DEBUG] [Telescope Thread] METHOD: Max Regions [03-24-18 21:09:49.853][DEBUG] [Telescope Thread] RA: 7,46395341594729 [03-24-18 21:09:49.853][DEBUG] [Telescope Thread] DEC: -32,8569308295587 [03-24-18 21:09:49.854][DEBUG] [Telescope Thread] SCALE: 3,82512 [03-24-18 21:09:49.854][DEBUG] [Telescope Thread] ****************************************** [03-24-18 21:09:50.079][DEBUG] [Telescope Thread] FitsFileHeaderData: Angle - 0 [03-24-18 21:09:50.079][DEBUG] [Telescope Thread] FitsFileHeaderData: Scale - 0 [03-24-18 21:09:50.079][DEBUG] [Telescope Thread] FitsFileHeaderData: RA - 7,46395341594727 [03-24-18 21:09:50.079][DEBUG] [Telescope Thread] FitsFileHeaderData: DEC - -32,8569308295587 [03-24-18 21:09:50.105][DEBUG] [Telescope Thread] PlateSove2 Param: RA (RAD) - 1,95405843485637 [03-24-18 21:09:50.105][DEBUG] [Telescope Thread] PlateSove2 Param: DEC (RAD) - -0,573461625075832 [03-24-18 21:09:50.105][DEBUG] [Telescope Thread] PlateSove2 Param: Width - 1306 [03-24-18 21:09:50.105][DEBUG] [Telescope Thread] PlateSove2 Param: Height - 1948 [03-24-18 21:09:50.105][DEBUG] [Telescope Thread] PlateSolve2 Command Line: [03-24-18 21:09:50.105][DEBUG] [Telescope Thread] C:\Users\Leandro\AppData\Local\SequenceGenerator\\PlateSolve2.exe 1.95405843485637,-0.57346162507583,0.02421938483299,0.03612508549361,3000,C:\Users\Leandro\AppData\Local\SequenceGenerator\\Temp\psXSolve_0.fit [03-24-18 21:10:20.038][DEBUG] [Telescope Thread] *********** SUCCESSFUL SOLVE ************* [03-24-18 21:10:20.038][DEBUG] [Telescope Thread] SOLVER: False [03-24-18 21:10:20.038][DEBUG] [Telescope Thread] SUCCESS: False [03-24-18 21:10:20.038][DEBUG] [Telescope Thread] CONF: 0 [03-24-18 21:10:20.038][DEBUG] [Telescope Thread] BLIND: False [03-24-18 21:10:20.038][DEBUG] [Telescope Thread] RA: 0 [03-24-18 21:10:20.038][DEBUG] [Telescope Thread] DEC: 0 [03-24-18 21:10:20.038][DEBUG] [Telescope Thread] SCALE: 0 [03-24-18 21:10:20.038][DEBUG] [Telescope Thread] FLIPPED: False [03-24-18 21:10:20.038][DEBUG] [Telescope Thread] ANGLE (EON): -500 [03-24-18 21:10:20.038][DEBUG] [Telescope Thread] MSG: Solve failed! User aborted. [03-24-18 21:10:20.038][DEBUG] [Telescope Thread] ****************************************** [03-24-18 21:10:20.038][DEBUG] [Telescope Thread] Plate solving scope frame failed! Invoking blind solver... [03-24-18 21:10:20.090][DEBUG] [Telescope Thread] Astrometry.NET: Sequence endpoint data: Astrometry.NET (Remote) [03-24-18 21:10:20.091][DEBUG] [Telescope Thread] Astrometry.NET: Sequence endpoint data: Astrometry.NET (Remote) [03-24-18 21:10:20.132][DEBUG] [Telescope Thread] ************* SOLVE HINTS **************** [03-24-18 21:10:20.132][DEBUG] [Telescope Thread] SOLVER: Astrometry.NET [03-24-18 21:10:20.132][DEBUG] [Telescope Thread] BLIND: True [03-24-18 21:10:20.132][DEBUG] [Telescope Thread] METHOD: Max Regions [03-24-18 21:10:20.132][DEBUG] [Telescope Thread] RA: 7,46395341594729 [03-24-18 21:10:20.132][DEBUG] [Telescope Thread] DEC: -32,8569308295587 [03-24-18 21:10:20.132][DEBUG] [Telescope Thread] SCALE: 3,82512 [03-24-18 21:10:20.132][DEBUG] [Telescope Thread] ****************************************** [03-24-18 21:10:20.132][DEBUG] [Telescope Thread] Astrometry.NET: Sequence endpoint data: ANSVR (Local Astrometry.NET) [03-24-18 21:10:20.317][DEBUG] [Telescope Thread] Astrometry.NET convertedAstrometry.fits path: C:\Users\Leandro\AppData\Local\SequenceGenerator\Temp\convertedAstometry.fits [03-24-18 21:10:20.317][DEBUG] [Telescope Thread] Astrometry.NET - File is too large, resizing [03-24-18 21:10:20.477][DEBUG] [Telescope Thread] Astrometry.NET - Saving file [03-24-18 21:10:20.477][DEBUG] [Telescope Thread] SaveFileBmp16: Locking bitmap for disk write... [03-24-18 21:10:20.477][DEBUG] [Telescope Thread] SaveFileBmp16: Opening file stream... [03-24-18 21:10:20.478][DEBUG] [Telescope Thread] SaveFileBmp16: Writing FITS headers... [03-24-18 21:10:20.478][DEBUG] [Telescope Thread] SaveFileBmp16: Traversing and persisting bitmap data... [03-24-18 21:10:20.561][DEBUG] [Telescope Thread] SaveFileBmp16: FITS block fill... [03-24-18 21:10:20.561][DEBUG] [Telescope Thread] SaveFileBmp16: Unlocking bitmap... [03-24-18 21:10:20.561][DEBUG] [Telescope Thread] SaveFileBmp16: Closing file stream [03-24-18 21:10:20.573][DEBUG] [Telescope Thread] Astrometry.NET using endpoint: http://127.0.0.1:8080/api/ [03-24-18 21:10:20.573][DEBUG] [Telescope Thread] Astrometry.NET - Calling Async Solve [03-24-18 21:10:22.106][DEBUG] [Unknown] Astrometry.NET uploading file: C:\Users\Leandro\AppData\Local\SequenceGenerator\Temp\convertedAstometry.fits [03-24-18 21:10:26.852][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:10:26.852][DEBUG] [Unknown] Astrometry.NET returned: {"jobs":[],"processing_finished":"0","processing_started":"1","user":"0","user_images":[]} [03-24-18 21:10:27.931][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:10:27.931][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"user":"0","processing_started":"1","jobs":[],"processing_finished":"0"} [03-24-18 21:10:28.952][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:10:28.952][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"user":"0","processing_started":"1","jobs":[],"processing_finished":"0"} [03-24-18 21:10:29.955][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:10:29.955][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"jobs":[],"processing_finished":"0","processing_started":"1","user":"0"} [03-24-18 21:10:30.970][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:10:30.970][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"jobs":[],"processing_finished":"0","processing_started":"1","user":"0"} [03-24-18 21:10:31.973][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:10:31.973][DEBUG] [Unknown] Astrometry.NET returned: {"jobs":[],"processing_finished":"0","processing_started":"1","user":"0","user_images":[]} [03-24-18 21:10:32.976][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:10:32.976][DEBUG] [Unknown] Astrometry.NET returned: {"user":"0","processing_started":"1","jobs":[],"processing_finished":"0","user_images":[]} [03-24-18 21:10:33.979][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:10:33.979][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"jobs":[],"processing_finished":"0","processing_started":"1","user":"0"} [03-24-18 21:10:34.982][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:10:34.982][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"processing_started":"1","jobs":[],"processing_finished":"0","user":"0"} [03-24-18 21:10:35.985][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:10:35.985][DEBUG] [Unknown] Astrometry.NET returned: {"user":"0","jobs":[],"processing_started":"1","processing_finished":"0","user_images":[]} [03-24-18 21:10:36.988][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:10:36.988][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"jobs":[],"processing_finished":"0","processing_started":"1","user":"0"} [03-24-18 21:10:37.991][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:10:37.991][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"user":"0","processing_started":"1","jobs":[],"processing_finished":"0"} [03-24-18 21:10:38.994][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:10:38.994][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"user":"0","processing_started":"1","jobs":[],"processing_finished":"0"} [03-24-18 21:10:39.997][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:10:39.997][DEBUG] [Unknown] Astrometry.NET returned: {"jobs":[],"processing_started":"1","processing_finished":"0","user":"0","user_images":[]} [03-24-18 21:10:40.999][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:10:40.999][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"jobs":[],"processing_finished":"0","processing_started":"1","user":"0"} [03-24-18 21:10:42.002][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:10:42.002][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"user":"0","processing_finished":"0","jobs":[],"processing_started":"1"} [03-24-18 21:10:43.005][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:10:43.005][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"jobs":[],"processing_finished":"0","processing_started":"1","user":"0"} [03-24-18 21:10:44.007][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:10:44.007][DEBUG] [Unknown] Astrometry.NET returned: {"jobs":[],"processing_started":"1","processing_finished":"0","user":"0","user_images":[]} [03-24-18 21:10:45.010][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:10:45.010][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"jobs":[],"processing_finished":"0","processing_started":"1","user":"0"} [03-24-18 21:10:46.016][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:10:46.016][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"jobs":[],"processing_started":"1","processing_finished":"0","user":"0"} [03-24-18 21:10:47.019][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:10:47.019][DEBUG] [Unknown] Astrometry.NET returned: {"user":"0","processing_finished":"0","jobs":[],"processing_started":"1","user_images":[]} [03-24-18 21:10:48.022][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:10:48.023][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"user":"0","processing_started":"1","jobs":[],"processing_finished":"0"} [03-24-18 21:10:49.025][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:10:49.025][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"user":"0","processing_started":"1","jobs":[],"processing_finished":"0"} [03-24-18 21:10:50.028][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:10:50.028][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"jobs":[],"processing_finished":"0","processing_started":"1","user":"0"} [03-24-18 21:10:51.031][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:10:51.031][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"user":"0","processing_started":"1","jobs":[],"processing_finished":"0"} [03-24-18 21:10:52.034][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:10:52.034][DEBUG] [Unknown] Astrometry.NET returned: {"jobs":[],"processing_started":"1","processing_finished":"0","user":"0","user_images":[]} [03-24-18 21:10:53.037][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:10:53.037][DEBUG] [Unknown] Astrometry.NET returned: {"user":"0","processing_finished":"0","jobs":[],"processing_started":"1","user_images":[]} [03-24-18 21:10:54.040][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:10:54.040][DEBUG] [Unknown] Astrometry.NET returned: {"user":"0","processing_started":"1","jobs":[],"processing_finished":"0","user_images":[]} [03-24-18 21:10:55.051][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:10:55.051][DEBUG] [Unknown] Astrometry.NET returned: {"user":"0","processing_started":"1","jobs":[],"processing_finished":"0","user_images":[]} [03-24-18 21:10:56.054][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:10:56.054][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"jobs":[],"processing_finished":"0","processing_started":"1","user":"0"} [03-24-18 21:10:57.066][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:10:57.066][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"user":"0","jobs":[],"processing_started":"1","processing_finished":"0"} [03-24-18 21:10:58.069][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:10:58.069][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"user":"0","jobs":[],"processing_started":"1","processing_finished":"0"} [03-24-18 21:10:59.071][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:10:59.071][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"user":"0","processing_finished":"0","jobs":[],"processing_started":"1"} [03-24-18 21:11:00.075][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:00.075][DEBUG] [Unknown] Astrometry.NET returned: {"user":"0","processing_started":"1","jobs":[],"processing_finished":"0","user_images":[]} [03-24-18 21:11:01.086][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:01.087][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"user":"0","processing_finished":"0","jobs":[],"processing_started":"1"} [03-24-18 21:11:02.089][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:02.089][DEBUG] [Unknown] Astrometry.NET returned: {"processing_finished":"0","jobs":[],"processing_started":"1","user":"0","user_images":[]} [03-24-18 21:11:03.092][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:03.092][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"user":"0","processing_started":"1","jobs":[],"processing_finished":"0"} [03-24-18 21:11:04.096][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:04.096][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"user":"0","jobs":[],"processing_started":"1","processing_finished":"0"} [03-24-18 21:11:05.099][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:05.099][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"jobs":[],"processing_finished":"0","processing_started":"1","user":"0"} [03-24-18 21:11:06.102][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:06.102][DEBUG] [Unknown] Astrometry.NET returned: {"jobs":[],"processing_started":"1","processing_finished":"0","user":"0","user_images":[]} [03-24-18 21:11:07.121][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:07.121][DEBUG] [Unknown] Astrometry.NET returned: {"user":"0","processing_finished":"0","jobs":[],"processing_started":"1","user_images":[]} [03-24-18 21:11:08.124][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:08.124][DEBUG] [Unknown] Astrometry.NET returned: {"user":"0","processing_finished":"0","jobs":[],"processing_started":"1","user_images":[]} [03-24-18 21:11:09.136][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:09.136][DEBUG] [Unknown] Astrometry.NET returned: {"user":"0","processing_started":"1","jobs":[],"processing_finished":"0","user_images":[]} [03-24-18 21:11:10.138][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:10.138][DEBUG] [Unknown] Astrometry.NET returned: {"jobs":[],"processing_started":"1","processing_finished":"0","user":"0","user_images":[]} [03-24-18 21:11:11.141][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:11.141][DEBUG] [Unknown] Astrometry.NET returned: {"user":"0","jobs":[],"processing_finished":"0","processing_started":"1","user_images":[]} [03-24-18 21:11:12.143][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:12.143][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"user":"0","jobs":[],"processing_started":"1","processing_finished":"0"} [03-24-18 21:11:13.146][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:13.146][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"jobs":[],"processing_started":"1","processing_finished":"0","user":"0"} [03-24-18 21:11:14.165][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:14.165][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"jobs":[],"processing_finished":"0","processing_started":"1","user":"0"} [03-24-18 21:11:15.167][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:15.167][DEBUG] [Unknown] Astrometry.NET returned: {"processing_finished":"0","jobs":[],"processing_started":"1","user":"0","user_images":[]} [03-24-18 21:11:16.171][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:16.171][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"jobs":[],"processing_finished":"0","processing_started":"1","user":"0"} [03-24-18 21:11:17.173][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:17.173][DEBUG] [Unknown] Astrometry.NET returned: {"user":"0","processing_finished":"0","jobs":[],"processing_started":"1","user_images":[]} [03-24-18 21:11:18.176][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:18.176][DEBUG] [Unknown] Astrometry.NET returned: {"processing_finished":"0","jobs":[],"processing_started":"1","user":"0","user_images":[]} [03-24-18 21:11:19.179][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:19.179][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"jobs":[],"processing_finished":"0","processing_started":"1","user":"0"} [03-24-18 21:11:20.182][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:20.182][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"jobs":[],"processing_finished":"0","processing_started":"1","user":"0"} [03-24-18 21:11:21.185][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:21.185][DEBUG] [Unknown] Astrometry.NET returned: {"user":"0","jobs":[],"processing_started":"1","processing_finished":"0","user_images":[]} [03-24-18 21:11:22.188][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:22.188][DEBUG] [Unknown] Astrometry.NET returned: {"user":"0","processing_finished":"0","jobs":[],"processing_started":"1","user_images":[]} [03-24-18 21:11:23.191][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:23.191][DEBUG] [Unknown] Astrometry.NET returned: {"processing_finished":"0","jobs":[],"processing_started":"1","user":"0","user_images":[]} [03-24-18 21:11:24.195][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:24.195][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"processing_started":"1","jobs":[],"processing_finished":"0","user":"0"} [03-24-18 21:11:25.198][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:25.198][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"user":"0","processing_finished":"0","jobs":[],"processing_started":"1"} [03-24-18 21:11:26.201][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:26.201][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"jobs":[],"processing_finished":"0","processing_started":"1","user":"0"} [03-24-18 21:11:27.204][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:27.204][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"jobs":[],"processing_finished":"0","processing_started":"1","user":"0"} [03-24-18 21:11:28.207][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:28.207][DEBUG] [Unknown] Astrometry.NET returned: {"user":"0","processing_finished":"0","jobs":[],"processing_started":"1","user_images":[]} [03-24-18 21:11:29.210][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:29.210][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"user":"0","processing_started":"1","jobs":[],"processing_finished":"0"} [03-24-18 21:11:30.213][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:30.213][DEBUG] [Unknown] Astrometry.NET returned: {"user":"0","processing_finished":"0","jobs":[],"processing_started":"1","user_images":[]} [03-24-18 21:11:31.216][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:31.216][DEBUG] [Unknown] Astrometry.NET returned: {"user":"0","jobs":[],"processing_finished":"0","processing_started":"1","user_images":[]} [03-24-18 21:11:32.220][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:32.220][DEBUG] [Unknown] Astrometry.NET returned: {"jobs":[],"processing_started":"1","processing_finished":"0","user":"0","user_images":[]} [03-24-18 21:11:33.223][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:33.223][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"user":"0","processing_finished":"0","jobs":[],"processing_started":"1"} [03-24-18 21:11:34.226][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:34.226][DEBUG] [Unknown] Astrometry.NET returned: {"user":"0","processing_finished":"0","jobs":[],"processing_started":"1","user_images":[]} [03-24-18 21:11:35.230][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:35.230][DEBUG] [Unknown] Astrometry.NET returned: {"user":"0","processing_finished":"0","jobs":[],"processing_started":"1","user_images":[]} [03-24-18 21:11:36.232][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:36.232][DEBUG] [Unknown] Astrometry.NET returned: {"processing_finished":"0","jobs":[],"processing_started":"1","user":"0","user_images":[]} [03-24-18 21:11:37.235][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:37.235][DEBUG] [Unknown] Astrometry.NET returned: {"user":"0","processing_started":"1","jobs":[],"processing_finished":"0","user_images":[]} [03-24-18 21:11:38.238][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:38.238][DEBUG] [Unknown] Astrometry.NET returned: {"jobs":[],"processing_started":"1","processing_finished":"0","user":"0","user_images":[]} [03-24-18 21:11:39.241][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:39.241][DEBUG] [Unknown] Astrometry.NET returned: {"user":"0","processing_finished":"0","jobs":[],"processing_started":"1","user_images":[]} [03-24-18 21:11:39.731][DEBUG] [CP Update Thread] ASCOM Telescope: Error in IsParkedOrNotTracking. : Timed out waiting for received data (System.Reflection.TargetInvocationException: Se produjo una excepción en el destino de la invocación. ---> System.Runtime.InteropServices.COMException: Timed out waiting for received data --- Fin del seguimiento de la pila de la excepción interna --- en System.RuntimeType.InvokeDispMethod(String name, BindingFlags invokeAttr, Object target, Object[] args, Boolean[] byrefModifiers, Int32 culture, String[] namedParameters) en System.RuntimeType.InvokeMember(String name, BindingFlags bindingFlags, Binder binder, Object target, Object[] providedArgs, ParameterModifier[] modifiers, CultureInfo culture, String[] namedParams) en System.Type.InvokeMember(String name, BindingFlags invokeAttr, Binder binder, Object target, Object[] args, CultureInfo culture) en ASCOM.DriverAccess.MemberFactory.CallMember(Int32 memberCode, String memberName, Type[] parameterTypes, Object[] parms) en c:\ASCOM Build\Export\ASCOM.DriverAccess\MemberFactory.cs:línea 243) en ASCOM.DriverAccess.MemberFactory.CallMember(Int32 memberCode, String memberName, Type[] parameterTypes, Object[] parms) en c:\ASCOM Build\Export\ASCOM.DriverAccess\MemberFactory.cs:línea 284 en ASCOM.DriverAccess.Telescope.get_Tracking() en c:\ASCOM Build\Export\ASCOM.DriverAccess\Telescope.cs:línea 1189 en qp.jo() [03-24-18 21:11:40.244][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:40.244][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"user":"0","processing_started":"1","jobs":[],"processing_finished":"0"} [03-24-18 21:11:41.247][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:41.247][DEBUG] [Unknown] Astrometry.NET returned: {"user":"0","processing_finished":"0","jobs":[],"processing_started":"1","user_images":[]} [03-24-18 21:11:42.252][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:42.253][DEBUG] [Unknown] Astrometry.NET returned: {"jobs":[],"processing_started":"1","processing_finished":"0","user":"0","user_images":[]} [03-24-18 21:11:43.256][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:43.256][DEBUG] [Unknown] Astrometry.NET returned: {"user":"0","processing_finished":"0","jobs":[],"processing_started":"1","user_images":[]} [03-24-18 21:11:44.259][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:44.259][DEBUG] [Unknown] Astrometry.NET returned: {"user":"0","processing_finished":"0","jobs":[],"processing_started":"1","user_images":[]} [03-24-18 21:11:45.262][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:45.262][DEBUG] [Unknown] Astrometry.NET returned: {"user":"0","processing_finished":"0","jobs":[],"processing_started":"1","user_images":[]} [03-24-18 21:11:46.265][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:46.265][DEBUG] [Unknown] Astrometry.NET returned: {"jobs":[],"processing_started":"1","processing_finished":"0","user":"0","user_images":[]} [03-24-18 21:11:47.268][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:47.268][DEBUG] [Unknown] Astrometry.NET returned: {"jobs":[],"processing_finished":"0","processing_started":"1","user":"0","user_images":[]} [03-24-18 21:11:48.274][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:48.274][DEBUG] [Unknown] Astrometry.NET returned: {"user":"0","jobs":[],"processing_finished":"0","processing_started":"1","user_images":[]} [03-24-18 21:11:49.278][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:49.278][DEBUG] [Unknown] Astrometry.NET returned: {"processing_started":"1","jobs":[],"processing_finished":"0","user":"0","user_images":[]} [03-24-18 21:11:50.280][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:50.285][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"user":"0","processing_started":"1","jobs":[],"processing_finished":"0"} [03-24-18 21:11:51.288][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:51.288][DEBUG] [Unknown] Astrometry.NET returned: {"processing_finished":"0","jobs":[],"processing_started":"1","user":"0","user_images":[]} [03-24-18 21:11:52.291][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:52.291][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"jobs":[],"processing_finished":"0","processing_started":"1","user":"0"} [03-24-18 21:11:53.294][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:53.294][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"jobs":[],"processing_finished":"0","processing_started":"1","user":"0"} [03-24-18 21:11:54.297][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:54.297][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"jobs":[],"processing_finished":"0","processing_started":"1","user":"0"} [03-24-18 21:11:55.300][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:55.300][DEBUG] [Unknown] Astrometry.NET returned: {"user":"0","processing_finished":"0","jobs":[],"processing_started":"1","user_images":[]} [03-24-18 21:11:56.303][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:56.303][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"user":"0","jobs":[],"processing_started":"1","processing_finished":"0"} [03-24-18 21:11:57.305][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:57.305][DEBUG] [Unknown] Astrometry.NET returned: {"user":"0","processing_finished":"0","jobs":[],"processing_started":"1","user_images":[]} [03-24-18 21:11:58.308][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:58.308][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"user":"0","processing_started":"1","jobs":[],"processing_finished":"0"} [03-24-18 21:11:59.311][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:11:59.311][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"processing_finished":"0","jobs":[],"processing_started":"1","user":"0"} [03-24-18 21:12:00.314][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:12:00.314][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"jobs":[],"processing_finished":"0","processing_started":"1","user":"0"} [03-24-18 21:12:01.317][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:12:01.317][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"jobs":[],"processing_finished":"0","processing_started":"1","user":"0"} [03-24-18 21:12:02.320][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:12:02.320][DEBUG] [Unknown] Astrometry.NET returned: {"jobs":[],"processing_started":"1","processing_finished":"0","user":"0","user_images":[]} [03-24-18 21:12:03.322][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:12:03.322][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"user":"0","processing_started":"1","jobs":[],"processing_finished":"0"} [03-24-18 21:12:04.325][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:12:04.325][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"jobs":[],"processing_started":"1","processing_finished":"0","user":"0"} [03-24-18 21:12:05.328][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:12:05.328][DEBUG] [Unknown] Astrometry.NET returned: {"user":"0","jobs":[],"processing_started":"1","processing_finished":"0","user_images":[]} [03-24-18 21:12:06.330][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:12:06.330][DEBUG] [Unknown] Astrometry.NET returned: {"jobs":[],"processing_started":"1","processing_finished":"0","user":"0","user_images":[]} [03-24-18 21:12:07.333][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:12:07.333][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"processing_started":"1","jobs":[],"processing_finished":"0","user":"0"} [03-24-18 21:12:08.336][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:12:08.336][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"jobs":[],"processing_started":"1","processing_finished":"0","user":"0"} [03-24-18 21:12:09.348][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:12:09.348][DEBUG] [Unknown] Astrometry.NET returned: {"jobs":[],"processing_started":"1","processing_finished":"0","user":"0","user_images":[]} [03-24-18 21:12:10.368][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:12:10.368][DEBUG] [Unknown] Astrometry.NET returned: {"user":"0","processing_finished":"0","jobs":[],"processing_started":"1","user_images":[]} [03-24-18 21:12:11.382][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:12:11.382][DEBUG] [Unknown] Astrometry.NET returned: {"user":"0","jobs":[],"processing_finished":"0","processing_started":"1","user_images":[]} [03-24-18 21:12:12.398][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:12:12.398][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"user":"0","jobs":[],"processing_finished":"0","processing_started":"1"} [03-24-18 21:12:13.413][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:12:13.413][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"processing_started":"1","jobs":[],"processing_finished":"0","user":"0"} [03-24-18 21:12:14.446][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:12:14.447][DEBUG] [Unknown] Astrometry.NET returned: {"jobs":[],"processing_started":"1","processing_finished":"0","user":"0","user_images":[]} [03-24-18 21:12:15.450][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:12:15.450][DEBUG] [Unknown] Astrometry.NET returned: {"user":"0","processing_finished":"0","jobs":[],"processing_started":"1","user_images":[]} [03-24-18 21:12:16.453][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:12:16.453][DEBUG] [Unknown] Astrometry.NET returned: {"jobs":[],"processing_finished":"0","processing_started":"1","user":"0","user_images":[]} [03-24-18 21:12:17.466][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:12:17.466][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"user":"0","jobs":[],"processing_finished":"0","processing_started":"1"} [03-24-18 21:12:18.469][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:12:18.469][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"processing_started":"1","jobs":[],"processing_finished":"0","user":"0"} [03-24-18 21:12:19.486][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:12:19.487][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"processing_started":"1","jobs":[],"processing_finished":"0","user":"0"} [03-24-18 21:12:20.503][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:12:20.504][DEBUG] [Unknown] Astrometry.NET returned: {"jobs":[],"processing_started":"1","processing_finished":"0","user":"0","user_images":[]} [03-24-18 21:12:21.519][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:12:21.519][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"user":"0","processing_started":"1","jobs":[],"processing_finished":"0"} [03-24-18 21:12:22.535][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:12:22.536][DEBUG] [Unknown] Astrometry.NET returned: {"user":"0","processing_finished":"0","jobs":[],"processing_started":"1","user_images":[]} [03-24-18 21:12:23.552][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:12:23.552][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"user":"0","processing_finished":"0","jobs":[],"processing_started":"1"} [03-24-18 21:12:24.571][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:12:24.571][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"user":"0","jobs":[],"processing_finished":"0","processing_started":"1"} [03-24-18 21:12:25.588][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:12:25.588][DEBUG] [Unknown] Astrometry.NET returned: {"user":"0","jobs":[],"processing_finished":"0","processing_started":"1","user_images":[]} [03-24-18 21:12:26.189][DEBUG] [Main Thread] Disconnecting ASCOM Telescope: ASCOM.Celestron.Telescope [03-24-18 21:12:26.455][DEBUG] [CP Update Thread] Caught exception in CanPierFlipNow : Referencia a objeto no establecida como instancia de un objeto. en ASCOM.Utilities.TraceLogger.LogMessageCrLf(String Identifier, String Message) en C:\ASCOM Build\Export\ASCOM.Utilities\ASCOM.Utilities\TraceLogger.vb:línea 211 en ASCOM.DriverAccess.MemberFactory.CallMember(Int32 memberCode, String memberName, Type[] parameterTypes, Object[] parms) en c:\ASCOM Build\Export\ASCOM.DriverAccess\MemberFactory.cs:línea 293 en ASCOM.DriverAccess.Telescope.get_SideOfPier() en c:\ASCOM Build\Export\ASCOM.DriverAccess\Telescope.cs:línea 895 en qp.kw() [03-24-18 21:12:26.605][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:12:26.605][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"user":"0","processing_started":"1","jobs":[],"processing_finished":"0"} [03-24-18 21:12:27.608][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:12:27.608][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"user":"0","processing_finished":"0","jobs":[],"processing_started":"1"} [03-24-18 21:12:28.612][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:12:28.612][DEBUG] [Unknown] Astrometry.NET returned: {"user":"0","processing_finished":"0","jobs":[],"processing_started":"1","user_images":[]} [03-24-18 21:12:29.615][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:12:29.615][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"user":"0","processing_started":"1","jobs":[],"processing_finished":"0"} [03-24-18 21:12:30.618][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:12:30.618][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"jobs":[],"processing_finished":"0","processing_started":"1","user":"0"} [03-24-18 21:12:31.621][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:12:31.621][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"jobs":[],"processing_finished":"0","processing_started":"1","user":"0"} [03-24-18 21:12:32.623][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:12:32.623][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"jobs":[],"processing_finished":"0","processing_started":"1","user":"0"} [03-24-18 21:12:33.626][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:12:33.626][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"user":"0","processing_started":"1","jobs":[],"processing_finished":"0"} [03-24-18 21:12:34.629][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:12:34.629][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"user":"0","processing_started":"1","jobs":[],"processing_finished":"0"} [03-24-18 21:12:35.632][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:12:35.632][DEBUG] [Unknown] Astrometry.NET returned: {"user":"0","jobs":[],"processing_finished":"0","processing_started":"1","user_images":[]} [03-24-18 21:12:36.647][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:12:36.648][DEBUG] [Unknown] Astrometry.NET returned: {"processing_started":"1","jobs":[],"processing_finished":"0","user":"0","user_images":[]} [03-24-18 21:12:37.663][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:12:37.663][DEBUG] [Unknown] Astrometry.NET returned: {"processing_finished":"0","jobs":[],"processing_started":"1","user":"0","user_images":[]} [03-24-18 21:12:38.667][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:12:38.667][DEBUG] [Unknown] Astrometry.NET returned: {"user":"0","processing_finished":"0","jobs":[],"processing_started":"1","user_images":[]} [03-24-18 21:12:39.670][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:12:39.671][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"jobs":[],"processing_finished":"0","processing_started":"1","user":"0"} [03-24-18 21:12:40.686][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:12:40.686][DEBUG] [Unknown] Astrometry.NET returned: {"user":"0","processing_started":"1","jobs":[],"processing_finished":"0","user_images":[]} [03-24-18 21:12:41.701][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:12:41.701][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"user":"0","processing_started":"1","jobs":[],"processing_finished":"0"} [03-24-18 21:12:42.718][DEBUG] [Unknown] Astrometry.NET request: /api/submissions/20 [03-24-18 21:12:42.718][DEBUG] [Unknown] Astrometry.NET returned: {"user_images":[],"user":"0","processing_finished":"1","jobs":[20],"processing_started":"1"} [03-24-18 21:12:42.752][DEBUG] [Unknown] Astrometry.NET - Upload complete [03-24-18 21:12:42.752][DEBUG] [Unknown] Astrometry.NET - Waiting for solve to complete [03-24-18 21:12:43.255][DEBUG] [Unknown] Astrometry.NET request: /api/jobs/20 [03-24-18 21:12:43.255][DEBUG] [Unknown] Astrometry.NET returned: {"status":"success"} [03-24-18 21:12:43.310][DEBUG] [Unknown] Astrometry.NET request: /api/jobs/20/calibration [03-24-18 21:12:43.311][DEBUG] [Unknown] Astrometry.NET returned: {"epoch":"J2000","dec":-34.0286048314,"orientation":83.7135592915189,"pixscale":4.14900525179729,"radius":0,"parity":-1,"ra":114.414789723} [03-24-18 21:12:43.439][DEBUG] [Unknown] ------------ Begin CalibrationResponse ------------- [03-24-18 21:12:43.439][DEBUG] [Unknown] dec -34,0286048314 [03-24-18 21:12:43.439][DEBUG] [Unknown] ra 114,414789723 [03-24-18 21:12:43.440][DEBUG] [Unknown] radius 0 [03-24-18 21:12:43.440][DEBUG] [Unknown] orientation 83,7135592915189 [03-24-18 21:12:43.440][DEBUG] [Unknown] pixscale 4,14900525179729 [03-24-18 21:12:43.440][DEBUG] [Unknown] epoch J2000 [03-24-18 21:12:43.440][DEBUG] [Unknown] time NULL [03-24-18 21:12:43.440][DEBUG] [Unknown] parity 4,14900525179729 [03-24-18 21:12:43.440][DEBUG] [Unknown] ------------ End CalibrationResponse --------------- [03-24-18 21:12:43.440][DEBUG] [Unknown] Astrometry.NET - Job successfully solved [03-24-18 21:12:43.440][DEBUG] [Unknown] Astrometry.NET solve done in 137 seconds. [03-24-18 21:12:43.712][DEBUG] [Telescope Thread] Astrometry.NET - Solve Completed [03-24-18 21:12:43.728][DEBUG] [Telescope Thread] *********** SUCCESSFUL SOLVE ************* [03-24-18 21:12:43.728][DEBUG] [Telescope Thread] SOLVER: True [03-24-18 21:12:43.728][DEBUG] [Telescope Thread] SUCCESS: True [03-24-18 21:12:43.728][DEBUG] [Telescope Thread] CONF: 100 [03-24-18 21:12:43.728][DEBUG] [Telescope Thread] BLIND: True [03-24-18 21:12:43.728][DEBUG] [Telescope Thread] RA: 7,6276526482 [03-24-18 21:12:43.728][DEBUG] [Telescope Thread] DEC: -34,0286048314 [03-24-18 21:12:43.728][DEBUG] [Telescope Thread] SCALE: 3,81225597408633 [03-24-18 21:12:43.728][DEBUG] [Telescope Thread] FLIPPED: True [03-24-18 21:12:43.728][DEBUG] [Telescope Thread] ANGLE (EON): 263,713559291519 [03-24-18 21:12:43.728][DEBUG] [Telescope Thread] MSG: Solve took 137,618 seconds [03-24-18 21:12:43.728][DEBUG] [Telescope Thread] ****************************************** [03-24-18 21:12:43.728][DEBUG] [Telescope Thread] Plate solving scope frame successful, scope is synced, writing FITs header info... [03-24-18 21:12:43.731][DEBUG] [Telescope Thread] Telescope: Syncing to J2000 RA: 7,6276526482 Dec: -34,0286048314 [03-24-18 21:12:43.732][DEBUG] [Telescope Thread] Telescope: Sync behavior set to "Sync"... [03-24-18 21:12:43.732][DEBUG] [Telescope Thread] ASCOM Telescope: Error in Sync : Referencia a objeto no establecida como instancia de un objeto. en qp.kk(Double A_0, Double A_1, Boolean A_2) [03-24-18 21:12:43.732][DEBUG] [Telescope Thread] Error: Could not sync the telescope!