[10/15/18 20:44:28.528][DEBUG] [Main Thread] Licensing: Machine ID found... [10/15/18 20:44:28.528][DEBUG] [Main Thread] Checking for valid internet connection... [10/15/18 20:44:28.731][DEBUG] [Main Thread] Valid internet connection detected... [10/15/18 20:44:29.542][DEBUG] [Main Thread] Licensing: Request for licenses successful, writing cookie... [10/15/18 20:44:29.558][DEBUG] [Main Thread] Licensing: Applying licenses.. [10/15/18 20:44:29.558][DEBUG] [Main Thread] Licensing: Licenses applied.. [10/15/18 20:44:29.558][DEBUG] [Main Thread] SGPro trial license... [10/15/18 20:44:30.556][DEBUG] [Main Thread] API successfully started on port 59590... [10/15/18 20:44:31.348][DEBUG] [Main Thread] Error loading notification settings! Could not find file 'C:\Users\rcowley\AppData\Local\SequenceGenerator\Sequence Generator Pro\notifications.sgd'. [10/15/18 20:44:31.355][DEBUG] [Main Thread] Error loading email endpoint addresses! Could not find file 'C:\Users\rcowley\AppData\Local\SequenceGenerator\Sequence Generator Pro\1-emailEndpointAddresses.sgd'. [10/15/18 20:44:31.356][DEBUG] [Main Thread] Error loading email endpoint addresses! Could not find file 'C:\Users\rcowley\AppData\Local\SequenceGenerator\Sequence Generator Pro\2-emailEndpointAddresses.sgd'. [10/15/18 20:44:31.356][DEBUG] [Main Thread] Error loading email endpoint addresses! Could not find file 'C:\Users\rcowley\AppData\Local\SequenceGenerator\Sequence Generator Pro\3-emailEndpointAddresses.sgd'. [10/15/18 20:44:31.357][DEBUG] [Main Thread] Error loading text file endpoint data! Could not find file 'C:\Users\rcowley\AppData\Local\SequenceGenerator\Sequence Generator Pro\1-textFileEndpointSettings.sgd'. [10/15/18 20:44:31.357][DEBUG] [Main Thread] Error loading text file endpoint data! Could not find file 'C:\Users\rcowley\AppData\Local\SequenceGenerator\Sequence Generator Pro\2-textFileEndpointSettings.sgd'. [10/15/18 20:44:31.357][DEBUG] [Main Thread] Error loading text file endpoint data! Could not find file 'C:\Users\rcowley\AppData\Local\SequenceGenerator\Sequence Generator Pro\3-textFileEndpointSettings.sgd'. [10/15/18 20:44:31.362][DEBUG] [Main Thread] ===== S E Q U E N C E G E N E R A T O R (v3.0.2.94) ===== [10/15/18 20:44:31.504][DEBUG] [Main Thread] OS: Microsoft Windows 7 Professional [10/15/18 20:44:31.940][DEBUG] [Focuser Thread] Entering Fouser message dispatch loop... [10/15/18 20:44:31.942][DEBUG] [Camera Thread] Entering camera message dispatch loop... [10/15/18 20:44:31.948][DEBUG] [TEC Thread] Entering TEC message dispatch loop... [10/15/18 20:44:31.949][DEBUG] [Filter Wheel Thread] Entering Filter Wheel message dispatch loop... [10/15/18 20:44:31.950][DEBUG] [Rotator Thread] Entering Rotator message dispatch loop... [10/15/18 20:44:31.951][DEBUG] [Auto Guider Thread] Entering Auto Guider message dispatch loop... [10/15/18 20:44:31.962][DEBUG] [Auto Guider Error Thread] Entering Auto Guider message dispatch loop... [10/15/18 20:44:31.963][DEBUG] [Dome Thread] Entering Dome message dispatch loop... [10/15/18 20:44:31.971][DEBUG] [Telescope Thread] Entering Telescope message dispatch loop... [10/15/18 20:44:31.972][DEBUG] [Safety Thread] Starting safety monitor... [10/15/18 20:44:31.973][DEBUG] [Environment Device Thread] Starting environment device monitor... [10/15/18 20:44:32.004][DEBUG] [Main Thread] Settings changed (or loaded)... [10/15/18 20:44:32.005][DEBUG] [Main Thread] * SETTING: settingMdiAutoStretch = False [10/15/18 20:44:32.005][DEBUG] [Main Thread] * SETTING: settingIgLastFileFolder = [10/15/18 20:44:32.005][DEBUG] [Main Thread] * SETTING: settingFormScaleWarning = False [10/15/18 20:44:32.005][DEBUG] [Main Thread] * SETTING: settingRecoveryDuration = 90 [10/15/18 20:44:32.005][DEBUG] [Main Thread] * SETTING: settingDelayBetween = 0 [10/15/18 20:44:32.005][DEBUG] [Main Thread] * SETTING: settingFliFlushLow = 0 [10/15/18 20:44:32.005][DEBUG] [Main Thread] * SETTING: settingSBIGLpt2Address = 278 [10/15/18 20:44:32.005][DEBUG] [Main Thread] * SETTING: settingShowBetas = False [10/15/18 20:44:32.005][DEBUG] [Main Thread] * SETTING: settingMdiMouseAction = Stretch [10/15/18 20:44:32.005][DEBUG] [Main Thread] * SETTING: settingLastAscomCamera = [10/15/18 20:44:32.005][DEBUG] [Main Thread] * SETTING: settingAutoRecoveryActive = False [10/15/18 20:44:32.005][DEBUG] [Main Thread] * SETTING: settingTemperhumInterval = 300 [10/15/18 20:44:32.005][DEBUG] [Main Thread] * SETTING: settingASCOMWarningShown = False [10/15/18 20:44:32.005][DEBUG] [Main Thread] * SETTING: settingPlateCamScale = 0 [10/15/18 20:44:32.005][DEBUG] [Main Thread] * SETTING: settingCanonMirrorSettle = 0 [10/15/18 20:44:32.005][DEBUG] [Main Thread] * SETTING: settingInterface = [10/15/18 20:44:32.005][DEBUG] [Main Thread] * SETTING: settingLastAscomFilterWheel = [10/15/18 20:44:32.005][DEBUG] [Main Thread] * SETTING: settingAstroArtDisplayInstructions = True [10/15/18 20:44:32.005][DEBUG] [Main Thread] * SETTING: settingSBIGLpt1Address = 378 [10/15/18 20:44:32.005][DEBUG] [Main Thread] * SETTING: settingTrialQuestionsAsked = False [10/15/18 20:44:32.005][DEBUG] [Main Thread] * SETTING: settingCheckForUpdatesOnStart = True [10/15/18 20:44:32.005][DEBUG] [Main Thread] * SETTING: settingMdiLastImageDir = [10/15/18 20:44:32.005][DEBUG] [Main Thread] * SETTING: settingTemperHumUserTempComp = 0 [10/15/18 20:44:32.005][DEBUG] [Main Thread] * SETTING: settingCaptureCalFramesAlways = False [10/15/18 20:44:32.005][DEBUG] [Main Thread] * SETTING: settingDelayFirst = 0 [10/15/18 20:44:32.005][DEBUG] [Main Thread] * SETTING: settingFliNormalSpeedMode = 0 [10/15/18 20:44:32.005][DEBUG] [Main Thread] * SETTING: settingFliHighSpeedMode = 0 [10/15/18 20:44:32.005][DEBUG] [Main Thread] * SETTING: settingMfPosition = {X=0,Y=0} [10/15/18 20:44:32.005][DEBUG] [Main Thread] * SETTING: settingSoundAttention = [10/15/18 20:44:32.005][DEBUG] [Main Thread] * SETTING: settingPinpointCatalog = [10/15/18 20:44:32.005][DEBUG] [Main Thread] * SETTING: settingCameraOverhead = [10/15/18 20:44:32.005][DEBUG] [Main Thread] * SETTING: settingPlateSolveOn = False [10/15/18 20:44:32.018][DEBUG] [Main Thread] * SETTING: settingAstrometryNetEndpointList = System.Collections.Specialized.StringCollection [10/15/18 20:44:32.018][DEBUG] [Main Thread] * SETTING: settingSBIGGuider = None [10/15/18 20:44:32.018][DEBUG] [Main Thread] * SETTING: settingFocusLockDirectory = [10/15/18 20:44:32.018][DEBUG] [Main Thread] * SETTING: settingQSIFanSpeed = 2 [10/15/18 20:44:32.018][DEBUG] [Main Thread] * SETTING: settingNaggerText = [10/15/18 20:44:32.018][DEBUG] [Main Thread] * SETTING: settingMdiPhdDisplay = RA/Dec [10/15/18 20:44:32.018][DEBUG] [Main Thread] * SETTING: settingMdiShowToolStrip = True [10/15/18 20:44:32.018][DEBUG] [Main Thread] * SETTING: settingMdiShowCrosshairs = True [10/15/18 20:44:32.018][DEBUG] [Main Thread] * SETTING: WindowGeometry = [10/15/18 20:44:32.018][DEBUG] [Main Thread] * SETTING: settingIgRenameStringLocation = 2 [10/15/18 20:44:32.018][DEBUG] [Main Thread] * SETTING: settingIgSubFolders = False [10/15/18 20:44:32.018][DEBUG] [Main Thread] * SETTING: WindowGeometrySearch = [10/15/18 20:44:32.018][DEBUG] [Main Thread] * SETTING: settingOffset = -1 [10/15/18 20:44:32.018][DEBUG] [Main Thread] * SETTING: settingAutoStretchLevel = Low [10/15/18 20:44:32.018][DEBUG] [Main Thread] * SETTING: settingTemperHumUnits = C [10/15/18 20:44:32.018][DEBUG] [Main Thread] * SETTING: settingProfileDirectory = [10/15/18 20:44:32.018][DEBUG] [Main Thread] * SETTING: settingBasename = [10/15/18 20:44:32.018][DEBUG] [Main Thread] * SETTING: settingHfrColor = Color [RoyalBlue] [10/15/18 20:44:32.018][DEBUG] [Main Thread] * SETTING: settingNikonAfBinning = 1 [10/15/18 20:44:32.018][DEBUG] [Main Thread] * SETTING: settingSBIGFanOffWithTec = False [10/15/18 20:44:32.018][DEBUG] [Main Thread] * SETTING: settingLastAscomSafetyMonitor = [10/15/18 20:44:32.018][DEBUG] [Main Thread] * SETTING: settingMdiSize = {Width=800, Height=600} [10/15/18 20:44:32.018][DEBUG] [Main Thread] * SETTING: settingIgCopyAndRenameFiles = False [10/15/18 20:44:32.018][DEBUG] [Main Thread] * SETTING: settingSimDownloadTimesAf = False [10/15/18 20:44:32.019][DEBUG] [Main Thread] * SETTING: settingNagger = False [10/15/18 20:44:32.019][DEBUG] [Main Thread] * SETTING: settingImportFromCfw = True [10/15/18 20:44:32.019][DEBUG] [Main Thread] * SETTING: settingMdiShowStatusTrip = True [10/15/18 20:44:32.019][DEBUG] [Main Thread] * SETTING: settingShowTooltipHelp = True [10/15/18 20:44:32.019][DEBUG] [Main Thread] * SETTING: settingLastUpdateCheck = 1/1/2012 12:00:00 AM [10/15/18 20:44:32.019][DEBUG] [Main Thread] * SETTING: settingEventSuffixes = null [10/15/18 20:44:32.019][DEBUG] [Main Thread] * SETTING: settingCpPosition = {X=0,Y=0} [10/15/18 20:44:32.019][DEBUG] [Main Thread] * SETTING: settingSBIGCfwPort = [10/15/18 20:44:32.019][DEBUG] [Main Thread] * SETTING: settingMdiLocation = {X=0,Y=0} [10/15/18 20:44:32.019][DEBUG] [Main Thread] * SETTING: settingIgLastCsvFolder = [10/15/18 20:44:32.019][DEBUG] [Main Thread] * SETTING: settingSBIGFilterWheel = Auto Detect [10/15/18 20:44:32.019][DEBUG] [Main Thread] * SETTING: settingSBIGConnection = USB [10/15/18 20:44:32.019][DEBUG] [Main Thread] * SETTING: settingDSLRSoftwareBinForAf = False [10/15/18 20:44:32.019][DEBUG] [Main Thread] * SETTING: settingAutoSave = True [10/15/18 20:44:32.019][DEBUG] [Main Thread] * SETTING: settingFirstTime = True [10/15/18 20:44:32.019][DEBUG] [Main Thread] * SETTING: settingFliFlushHigh = 0 [10/15/18 20:44:32.019][DEBUG] [Main Thread] * SETTING: settingMdiState = maximized [10/15/18 20:44:32.019][DEBUG] [Main Thread] * SETTING: settingUseSequenceOptions = False [10/15/18 20:44:32.019][DEBUG] [Main Thread] * SETTING: settingSoundCompleted = [10/15/18 20:44:32.019][DEBUG] [Main Thread] * SETTING: settingTrialExpiredShown = False [10/15/18 20:44:32.019][DEBUG] [Main Thread] * SETTING: settingTemperHumColor = Grey [10/15/18 20:44:32.019][DEBUG] [Main Thread] * SETTING: settingDataDirectory = [10/15/18 20:44:32.019][DEBUG] [Main Thread] * SETTING: settingCanonFileFormat = Single Image (CFA FITS) [10/15/18 20:44:32.019][DEBUG] [Main Thread] * SETTING: settingAlnitakComPort = [10/15/18 20:44:32.019][DEBUG] [Main Thread] * SETTING: settingIgRenameFiles = False [10/15/18 20:44:32.019][DEBUG] [Main Thread] * SETTING: settingSubFolder = True [10/15/18 20:44:32.019][DEBUG] [Main Thread] * SETTING: settingLastSGSaveFolder = [10/15/18 20:44:32.019][DEBUG] [Main Thread] * SETTING: settingFindStars = Normal (recommended) [10/15/18 20:44:32.019][DEBUG] [Main Thread] * SETTING: settingNikonDataFormat = FITS [10/15/18 20:44:32.019][DEBUG] [Main Thread] * SETTING: settingPlaySounds = False [10/15/18 20:44:32.019][DEBUG] [Main Thread] * SETTING: settingPlateSolve3Catalogs = [10/15/18 20:44:32.019][DEBUG] [Main Thread] * SETTING: settingLastNikonType = Auto Connect [10/15/18 20:44:32.019][DEBUG] [Main Thread] * SETTING: settingLastSequenceStart = 1/1/0001 12:00:00 AM [10/15/18 20:44:32.019][DEBUG] [Main Thread] * SETTING: settingLastAscomTelescope = [10/15/18 20:44:32.019][DEBUG] [Main Thread] * SETTING: settingMdiPhdScale = 100 [10/15/18 20:44:32.019][DEBUG] [Main Thread] * SETTING: settingIgRenameType = 0 [10/15/18 20:44:32.019][DEBUG] [Main Thread] * SETTING: settingHelpLanguage = English [10/15/18 20:44:32.019][DEBUG] [Main Thread] * SETTING: settingTemperHumUserHumidityComp = 0 [10/15/18 20:44:32.019][DEBUG] [Main Thread] * SETTING: settingMdiPhdRaColor = Color [Blue] [10/15/18 20:44:32.019][DEBUG] [Main Thread] * SETTING: settingLastImageSaveFolder = [10/15/18 20:44:32.019][DEBUG] [Main Thread] * SETTING: settingEnableImageHistory = False [10/15/18 20:44:32.019][DEBUG] [Main Thread] * SETTING: settingFinishFullEvent = True [10/15/18 20:44:32.020][DEBUG] [Main Thread] * SETTING: settingMosaicOverlap = 20 [10/15/18 20:44:32.020][DEBUG] [Main Thread] * SETTING: settingQSIFanOffWithTec = False [10/15/18 20:44:32.020][DEBUG] [Main Thread] * SETTING: settingRecentSequences = [10/15/18 20:44:32.020][DEBUG] [Main Thread] * SETTING: settingDefaultDirectory = [10/15/18 20:44:32.020][DEBUG] [Main Thread] * SETTING: settingMdiPhdDecColor = Color [Red] [10/15/18 20:44:32.020][DEBUG] [Main Thread] * SETTING: settingAstrometryNetEndpoint = Astrometry.NET (Remote) [10/15/18 20:44:32.020][DEBUG] [Main Thread] * SETTING: settingSBIGCfwUseRS232 = False [10/15/18 20:44:32.020][DEBUG] [Main Thread] * SETTING: settingStarsColor = Color [OrangeRed] [10/15/18 20:44:32.020][DEBUG] [Main Thread] * SETTING: settingPhd2InstallPath = [10/15/18 20:44:32.020][DEBUG] [Main Thread] * SETTING: settingIgOverwriteExisting = False [10/15/18 20:44:32.020][DEBUG] [Main Thread] * SETTING: settingLastAscomFocuser = [10/15/18 20:44:32.020][DEBUG] [Main Thread] * SETTING: settingIgLastFolder = [10/15/18 20:44:32.020][DEBUG] [Main Thread] * SETTING: WindowGeometryOpen = [10/15/18 20:44:32.020][DEBUG] [Main Thread] * SETTING: settingNumEvents = 5 [10/15/18 20:44:32.020][DEBUG] [Main Thread] * SETTING: settingCanonAfBinning = 1 [10/15/18 20:44:32.020][DEBUG] [Main Thread] * SETTING: settingPlateCamBlank = [10/15/18 20:44:32.020][DEBUG] [Main Thread] * SETTING: settingLogApiCalls = False [10/15/18 20:44:32.020][DEBUG] [Main Thread] * SETTING: settingNikonUseBulbModeAlways = False [10/15/18 20:44:32.020][DEBUG] [Main Thread] * SETTING: settingTempFiles = C:\Temp [10/15/18 20:44:32.020][DEBUG] [Main Thread] * SETTING: settingFliHighSpeedDesc = [10/15/18 20:44:32.020][DEBUG] [Main Thread] * SETTING: settingRecoveryInterval = 10 [10/15/18 20:44:32.020][DEBUG] [Main Thread] * SETTING: settingDefaultMfwFov = 4.0 [10/15/18 20:44:32.020][DEBUG] [Main Thread] * SETTING: settingSBIGEthernetIP = 0.0.0.0 [10/15/18 20:44:32.020][DEBUG] [Main Thread] * SETTING: settingSBIGLpt3Address = 3BC [10/15/18 20:44:32.020][DEBUG] [Main Thread] * SETTING: settingFliNormalSpeedDesc = [10/15/18 20:44:32.020][DEBUG] [Main Thread] * SETTING: settingIgCopyLocation = [10/15/18 20:44:32.020][DEBUG] [Main Thread] * SETTING: settingSBIGAutoDetectIp = True [10/15/18 20:44:32.020][DEBUG] [Main Thread] * SETTING: settingAstroArtStartApp = True [10/15/18 20:44:32.020][DEBUG] [Main Thread] * SETTING: settingSimDownloadTimesPlate = False [10/15/18 20:44:32.020][DEBUG] [Main Thread] * SETTING: settingMicroTouchLocation = [10/15/18 20:44:32.020][DEBUG] [Main Thread] * SETTING: settingMdiPhdZoom = 40 [10/15/18 20:44:32.020][DEBUG] [Main Thread] * SETTING: settingMdiNewWindow = False [10/15/18 20:44:32.020][DEBUG] [Main Thread] * SETTING: settingEventAlwaysVisible = False [10/15/18 20:44:32.020][DEBUG] [Main Thread] * SETTING: settingFliRbiMitigation = False [10/15/18 20:44:32.020][DEBUG] [Main Thread] * SETTING: settingGain = -1 [10/15/18 20:44:32.020][DEBUG] [Main Thread] * SETTING: settingLastAscomDome = [10/15/18 20:44:32.020][DEBUG] [Main Thread] * SETTING: settingLookForTemperDevices = False [10/15/18 20:44:32.020][DEBUG] [Main Thread] * SETTING: settingLastAscomRotator = [10/15/18 20:44:32.020][DEBUG] [Main Thread] * SETTING: settingAfTestPackPath = [10/15/18 20:44:32.167][DEBUG] [Main Thread] ASCOM Observing Conditions Hub (OCH) [10/15/18 20:44:32.167][DEBUG] [Main Thread] ASCOM Observing Conditions Simulator [10/15/18 20:44:32.167][DEBUG] [Main Thread] OpenWeatherMap ObservingConditions [10/15/18 20:44:32.167][DEBUG] [Main Thread] TEMPerHUM [10/15/18 20:44:32.170][DEBUG] [Main Thread] Performing initialization... [10/15/18 20:44:32.171][DEBUG] [Main Thread] Creating new sequence with default profile: RRAAP Canon 6D [10/15/18 20:44:32.193][DEBUG] [Main Thread] Performing full form reset (with profile RRAAP Canon 6D)... [10/15/18 20:44:32.202][DEBUG] [Main Thread] Retreiving new equipment objects... [10/15/18 20:44:32.202][DEBUG] [Main Thread] New camera object (Canon EOS) dispatched... [10/15/18 20:44:32.207][DEBUG] [Main Thread] New filter wheel object (No Filter Wheel) dispatched... [10/15/18 20:44:32.208][DEBUG] [Main Thread] New environment device object (No Environment Device) dispatched... [10/15/18 20:44:32.208][DEBUG] [Main Thread] New focuser object (No Focuser) dispatched... [10/15/18 20:44:32.209][DEBUG] [Main Thread] New telescope object (iOptron ASCOM Driver for Mount) dispatched... [10/15/18 20:44:32.213][DEBUG] [Main Thread] New rotator object (No Rotator) dispatched... [10/15/18 20:44:32.214][DEBUG] [Main Thread] New dome object (No Observatory) dispatched... [10/15/18 20:44:32.215][DEBUG] [Main Thread] New flat box object (No Flat Box) dispatched... [10/15/18 20:44:32.216][DEBUG] [Main Thread] New safety monitor object (No Safety Monitor) dispatched... [10/15/18 20:44:32.216][DEBUG] [Main Thread] New auto guider object (PHD2) dispatched... [10/15/18 20:44:32.217][DEBUG] [Main Thread] New plate solver object (PlateSolve2) dispatched... [10/15/18 20:44:32.245][DEBUG] [Telescope Listener] Starting external telescope listener... [10/15/18 20:44:32.267][DEBUG] [Main Thread] Populating the form controls... [10/15/18 20:44:32.337][DEBUG] [Main Thread] Added row 0... [10/15/18 20:44:32.380][DEBUG] [Main Thread] Added row 1... [10/15/18 20:44:32.434][DEBUG] [Main Thread] Added row 2... [10/15/18 20:44:32.498][DEBUG] [Main Thread] Added row 3... [10/15/18 20:44:32.576][DEBUG] [Main Thread] Added row 4... [10/15/18 20:44:32.597][DEBUG] [Main Thread] Loading custom filter names... [10/15/18 20:44:32.608][DEBUG] [Main Thread] PopulateDataModel: Transferring view to the data model... [10/15/18 20:44:32.701][DEBUG] [Main Thread] Retreiving new equipment objects... [10/15/18 20:44:32.701][DEBUG] [Main Thread] No change in camera object... [10/15/18 20:44:32.701][DEBUG] [Main Thread] New filter wheel object (No Filter Wheel) dispatched... [10/15/18 20:44:32.703][DEBUG] [Main Thread] New environment device object (No Environment Device) dispatched... [10/15/18 20:44:32.703][DEBUG] [Sequence Thread] Entering sequence message dispatch loop... [10/15/18 20:44:32.704][DEBUG] [Main Thread] New focuser object (No Focuser) dispatched... [10/15/18 20:44:32.705][DEBUG] [Main Thread] No change in telescope object... [10/15/18 20:44:32.705][DEBUG] [Main Thread] New rotator object (No Rotator) dispatched... [10/15/18 20:44:32.706][DEBUG] [Main Thread] New dome object (No Observatory) dispatched... [10/15/18 20:44:32.706][DEBUG] [Main Thread] New flat box object (No Flat Box) dispatched... [10/15/18 20:44:32.707][DEBUG] [Main Thread] New safety monitor object (No Safety Monitor) dispatched... [10/15/18 20:44:32.708][DEBUG] [Main Thread] New auto guider object (PHD2) dispatched... [10/15/18 20:44:32.708][DEBUG] [Main Thread] No change in plate solver object... [10/15/18 20:44:32.712][DEBUG] [Main Thread] Checking if version check is required... [10/15/18 20:44:32.827][DEBUG] [Cleaner Thread] Running cleanup... [10/15/18 20:44:32.827][DEBUG] [Cleaner Thread] Cleaning 1 files in directory C:\Users\rcowley\AppData\Local\SequenceGenerator\Temp\... [10/15/18 20:44:32.827][DEBUG] [Main Thread] UI layout found, loading layout at C:\Users\rcowley\AppData\Local\SequenceGenerator\sg_ui_config.xml [10/15/18 20:44:32.832][DEBUG] [Cleaner Thread] Cleaning multi camera registration file... [10/15/18 20:44:32.832][DEBUG] [Cleaner Thread] Cleaning finished... [10/15/18 20:44:46.088][DEBUG] [Main Thread] Connecting camera in main thread... [10/15/18 20:44:46.595][DEBUG] [Main Thread] Canon: Connect to camera Canon EOS 6D [10/15/18 20:44:46.595][DEBUG] [Main Thread] Canon: Connected to port \\?\usb#vid_04a9&pid_3250#6&2b8f043&0&1#{6ac27878-a6fa-4155-ba85-f98f491d4f33} [10/15/18 20:44:46.596][DEBUG] [Main Thread] Canon: Populating Shutter Speeds [10/15/18 20:44:46.673][DEBUG] [Main Thread] Camera cooler NOT detected... [10/15/18 20:44:46.703][DEBUG] [Telescope Thread] Telescope Dispatch loop: Received SGM_TELESCOPE_CONNECT... [10/15/18 20:44:46.913][DEBUG] [Telescope Thread] Telescope can slewing supported... [10/15/18 20:44:46.919][DEBUG] [Telescope Thread] Telescope: Implements MoveAxis... [10/15/18 20:44:46.920][DEBUG] [Telescope Thread] RA Move Rate Range: Min->0.00416666666666667 Max->0.00416666666666667 [10/15/18 20:44:46.920][DEBUG] [Telescope Thread] RA Move Rate Range: Min->0.00833333333333333 Max->0.00833333333333333 [10/15/18 20:44:46.920][DEBUG] [Telescope Thread] RA Move Rate Range: Min->0.0333333333333333 Max->0.0333333333333333 [10/15/18 20:44:46.920][DEBUG] [Telescope Thread] RA Move Rate Range: Min->0.0666666666666667 Max->0.0666666666666667 [10/15/18 20:44:46.920][DEBUG] [Telescope Thread] RA Move Rate Range: Min->0.266666666666667 Max->0.266666666666667 [10/15/18 20:44:46.920][DEBUG] [Telescope Thread] RA Move Rate Range: Min->0.533333333333333 Max->0.533333333333333 [10/15/18 20:44:46.920][DEBUG] [Telescope Thread] RA Move Rate Range: Min->1.06666666666667 Max->1.06666666666667 [10/15/18 20:44:46.920][DEBUG] [Telescope Thread] RA Move Rate Range: Min->2.13333333333333 Max->2.13333333333333 [10/15/18 20:44:46.920][DEBUG] [Telescope Thread] RA Move Rate Range: Min->3.5 Max->3.5 [10/15/18 20:44:46.920][DEBUG] [Telescope Thread] DEC Move Rate Range: Min->0.00416666666666667 Max->0.00416666666666667 [10/15/18 20:44:46.920][DEBUG] [Telescope Thread] DEC Move Rate Range: Min->0.00833333333333333 Max->0.00833333333333333 [10/15/18 20:44:46.920][DEBUG] [Telescope Thread] DEC Move Rate Range: Min->0.0333333333333333 Max->0.0333333333333333 [10/15/18 20:44:46.920][DEBUG] [Telescope Thread] DEC Move Rate Range: Min->0.0666666666666667 Max->0.0666666666666667 [10/15/18 20:44:46.920][DEBUG] [Telescope Thread] DEC Move Rate Range: Min->0.266666666666667 Max->0.266666666666667 [10/15/18 20:44:46.920][DEBUG] [Telescope Thread] DEC Move Rate Range: Min->0.533333333333333 Max->0.533333333333333 [10/15/18 20:44:46.920][DEBUG] [Telescope Thread] DEC Move Rate Range: Min->1.06666666666667 Max->1.06666666666667 [10/15/18 20:44:46.920][DEBUG] [Telescope Thread] DEC Move Rate Range: Min->2.13333333333333 Max->2.13333333333333 [10/15/18 20:44:46.920][DEBUG] [Telescope Thread] DEC Move Rate Range: Min->3.5 Max->3.5 [10/15/18 20:44:46.921][DEBUG] [Telescope Thread] Telescope: CanSetSideOfPier returned False [10/15/18 20:44:46.921][DEBUG] [Telescope Thread] Telescope: CanPark returned True [10/15/18 20:44:46.922][DEBUG] [Telescope Thread] Telescope equatorial system is JNOW... [10/15/18 20:44:46.923][DEBUG] [Telescope Thread] SGM_TELESCOPE_CONNECT complete... [10/15/18 20:44:47.000][DEBUG] [Main Thread] Telescope: Setting sync behavior to: Sync [10/15/18 20:44:47.090][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 406, parameter 0, [10/15/18 20:44:47.090][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 405, parameter 0, [10/15/18 20:44:47.090][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 402, parameter 0, [10/15/18 20:44:48.016][DEBUG] [Auto Guider Equipment Connection Thread] Connecting to PHD2... [10/15/18 20:44:48.017][DEBUG] [Auto Guider Equipment Connection Thread] Waiting for socket connection to PHD2... [10/15/18 20:44:48.021][DEBUG] [PHD2 Listener Thread] Attempting to connect to PHD2... [10/15/18 20:44:49.017][DEBUG] [Auto Guider Equipment Connection Thread] Successfully connected to PHD2... [10/15/18 20:44:49.018][DEBUG] [Auto Guider Equipment Connection Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/15/18 20:44:49.018][DEBUG] [Auto Guider Equipment Connection Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/15/18 20:44:49.218][DEBUG] [Auto Guider Equipment Connection Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/15/18 20:44:49.220][DEBUG] [Auto Guider Equipment Connection Thread] PHD2: PHD2 is already open... [10/15/18 20:44:49.220][DEBUG] [Auto Guider Equipment Connection Thread] Sending to PHD2: {"method": "get_profiles", "id": 1009} [10/15/18 20:44:49.720][DEBUG] [Auto Guider Equipment Connection Thread] Sending to PHD2: {"method":"set_connected","params":[false],"id":1007} [10/15/18 20:44:49.720][DEBUG] [Auto Guider Equipment Connection Thread] Sending to PHD2: {"method":"set_profile","params":[2],"id":1008} [10/15/18 20:44:49.721][DEBUG] [Auto Guider Equipment Connection Thread] Sending to PHD2: {"method":"set_connected","params":[true],"id":1007} [10/15/18 20:45:48.263][DEBUG] [DSS Fetch Thread] Object name iris nebula resolved by SIMBAD - (simbad.cfa.harvard.edu)... [10/15/18 20:45:48.913][DEBUG] [DSS Fetch Thread] Internal Interface: Set Preview... [10/15/18 20:45:48.914][DEBUG] [DSS Fetch Thread] Display image preview using asynch task... [10/15/18 20:45:51.785][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [10/15/18 20:45:51.785][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [10/15/18 20:45:51.785][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/15/18 20:45:51.785][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/15/18 20:45:51.785][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/15/18 20:46:17.198][DEBUG] [Main Thread] Display image preview using asynch task... [10/15/18 20:46:17.810][DEBUG] [Main Thread] Tile 1 at pos 754,758 => 315.46,68.16 [10/15/18 20:46:17.818][DEBUG] [Main Thread] Populating the form controls... [10/15/18 20:46:17.852][DEBUG] [Main Thread] Added row 0... [10/15/18 20:46:17.891][DEBUG] [Main Thread] Added row 1... [10/15/18 20:46:17.941][DEBUG] [Main Thread] Added row 2... [10/15/18 20:46:18.001][DEBUG] [Main Thread] Added row 3... [10/15/18 20:46:18.073][DEBUG] [Main Thread] Added row 4... [10/15/18 20:46:18.098][DEBUG] [Main Thread] PopulateDataModel: Transferring view to the data model... [10/15/18 20:46:37.285][DEBUG] [Main Thread] Connecting camera in main thread... [10/15/18 20:46:37.774][DEBUG] [Main Thread] Canon: Connect to camera Canon EOS 6D [10/15/18 20:46:37.774][DEBUG] [Main Thread] Canon: Connected to port \\?\usb#vid_04a9&pid_3250#6&2b8f043&0&1#{6ac27878-a6fa-4155-ba85-f98f491d4f33} [10/15/18 20:46:37.774][DEBUG] [Main Thread] Canon: Populating Shutter Speeds [10/15/18 20:46:37.852][DEBUG] [Main Thread] Camera cooler NOT detected... [10/15/18 20:46:37.989][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 406, parameter 0, [10/15/18 20:46:37.989][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 405, parameter 0, [10/15/18 20:46:37.990][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 402, parameter 0, [10/15/18 20:46:51.666][DEBUG] [Main Thread] Slewing to target... [10/15/18 20:46:51.670][DEBUG] [Telescope Thread] Slew telescope message received... [10/15/18 20:46:51.675][DEBUG] [Telescope Thread] Telescope: Slewing to J2000 RA: 21.0304416666667 (21h01m49.59s) Dec: 68.1595194444445 (68°09'34.27") [10/15/18 20:46:51.675][DEBUG] [Telescope Thread] Telescope: Slew received J2000 coordinates, mount requires JNOW, converting... [10/15/18 20:46:51.676][DEBUG] [CP Update Thread] Error in control panel UI updater: Object reference not set to an instance of an object. [10/15/18 20:46:51.677][DEBUG] [Telescope Thread] Telescope: Slewing to JNOW RA: 21.0346804721266 Dec: 68.2391369258954 [10/15/18 20:46:51.922][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [10/15/18 20:46:51.922][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [10/15/18 20:46:51.922][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/15/18 20:46:51.922][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/15/18 20:46:51.922][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/15/18 20:47:41.994][DEBUG] [Telescope Thread] Scope reports it is done with synchronous slew, verifying... [10/15/18 20:47:41.994][DEBUG] [Telescope Thread] Telescope: Slewing has completed [10/15/18 20:47:41.994][DEBUG] [Telescope Thread] Telescope: Settling for 2 seconds [10/15/18 20:47:43.994][DEBUG] [Telescope Thread] Telescope: Settling has completed [10/15/18 20:47:43.994][DEBUG] [Telescope Thread] Slew complete... [10/15/18 20:47:43.997][DEBUG] [Slew Monitor] Waiting for slew to complete... [10/15/18 20:47:44.139][DEBUG] [CP Update Thread] Error in control panel UI updater: Object reference not set to an instance of an object. [10/15/18 20:47:52.043][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [10/15/18 20:47:52.043][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [10/15/18 20:47:52.043][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/15/18 20:47:52.043][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/15/18 20:47:52.043][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/15/18 20:48:09.880][DEBUG] [Main Thread] Telescope is required... [10/15/18 20:48:09.880][DEBUG] [Main Thread] Telescope is still connected. Ignoring connect step... [10/15/18 20:48:09.964][DEBUG] [Main Thread] PopulateDataModel: Transferring view to the data model... [10/15/18 20:48:09.970][DEBUG] [Main Thread] GNS Startup... [10/15/18 20:48:09.988][DEBUG] [Sequence Thread] Dispatch loop: Received SGM_RUN_SEQUENCE... [10/15/18 20:48:10.022][DEBUG] [Sequence Thread] GuiderCheck: Checking auto guider status... [10/15/18 20:48:10.022][DEBUG] [Sequence Thread] GuiderCheck: Auto guider reports connected... [10/15/18 20:48:10.022][DEBUG] [Sequence Thread] Checking auto guider version... [10/15/18 20:48:10.023][DEBUG] [Sequence Thread] PHD2: Performing version check... [10/15/18 20:48:10.023][DEBUG] [Sequence Thread] PHD2: Min version is 2.6.0 [10/15/18 20:48:10.023][DEBUG] [Sequence Thread] PHD2: User version is 2.6.5 [10/15/18 20:48:10.025][DEBUG] [Sequence Thread] Finding first active group... [10/15/18 20:48:10.025][DEBUG] [Sequence Thread] Looking at target iris nebula... [10/15/18 20:48:10.025][DEBUG] [Sequence Thread] Target is active: True [10/15/18 20:48:10.025][DEBUG] [Sequence Thread] Target is complete: False [10/15/18 20:48:10.025][DEBUG] [Sequence Thread] Target marked as past end time: False [10/15/18 20:48:10.025][DEBUG] [Sequence Thread] Target has end time: False [10/15/18 20:48:10.025][DEBUG] [Sequence Thread] Target end time: 10/15/2018 8:46:00 PM [10/15/18 20:48:10.025][DEBUG] [Sequence Thread] Active group found... [10/15/18 20:48:10.025][DEBUG] [Sequence Thread] Sequence should find starting target; Found target: 0 [10/15/18 20:48:10.030][DEBUG] [Sequence Thread] ************** SEQUENCE DUMP ************** [10/15/18 20:48:10.030][DEBUG] [Sequence Thread] sDirectory: C:\Users\rcowley\Pictures\BackyardEOS [10/15/18 20:48:10.030][DEBUG] [Sequence Thread] sBaseName: [10/15/18 20:48:10.030][DEBUG] [Sequence Thread] nDelayFirst: 0 [10/15/18 20:48:10.030][DEBUG] [Sequence Thread] nDelayBetween: 0 [10/15/18 20:48:10.030][DEBUG] [Sequence Thread] bFinishFullEventFirst: False [10/15/18 20:48:10.030][DEBUG] [Sequence Thread] arEventGroups: System.Collections.Generic.List`1[SequenceGenerator.EventGroup] [10/15/18 20:48:10.030][DEBUG] [Sequence Thread] nCurrentEventIndex: 0 [10/15/18 20:48:10.030][DEBUG] [Sequence Thread] dtElapsedTime: 1/1/0001 12:00:00 AM +00:00 [10/15/18 20:48:10.030][DEBUG] [Sequence Thread] bStarted: False [10/15/18 20:48:10.030][DEBUG] [Sequence Thread] bIsSequenceRunning: False [10/15/18 20:48:10.031][DEBUG] [Sequence Thread] sFileNamePattern: %tn\%dt\%tn_%ft_%el_%is_%fz [10/15/18 20:48:10.031][DEBUG] [Sequence Thread] sVersion: 3.0.2.94 [10/15/18 20:48:10.031][DEBUG] [Sequence Thread] tsElapsedTime: 00:00:00 [10/15/18 20:48:10.031][DEBUG] [Sequence Thread] bCaptureUntil: False [10/15/18 20:48:10.031][DEBUG] [Sequence Thread] dtCaptureUntil: 10/15/2018 8:44:00 PM [10/15/18 20:48:10.031][DEBUG] [Sequence Thread] userProfile: SequenceGenerator.UserProfile [10/15/18 20:48:10.031][DEBUG] [Sequence Thread] nAfNebulosityRejection: 0.85 [10/15/18 20:48:10.031][DEBUG] [Sequence Thread] sequenceFiles: System.Collections.Generic.Dictionary`2[System.String,System.String] [10/15/18 20:48:10.031][DEBUG] [Sequence Thread] sequenceFileList: System.Collections.Generic.List`1[SequenceGenerator.SequenceFile] [10/15/18 20:48:10.031][DEBUG] [Sequence Thread] imageHistory: SequenceGenerator.ImageHistoryManager [10/15/18 20:48:10.031][DEBUG] [Sequence Thread] nAfSampleSize: 100 [10/15/18 20:48:10.031][DEBUG] [Sequence Thread] bCaptureCalFramesIfSequenceFails: False [10/15/18 20:48:10.031][DEBUG] [Sequence Thread] notificationsList: System.Collections.Generic.List`1[SequenceGenerator.NotoficationInfo] [10/15/18 20:48:10.031][DEBUG] [Sequence Thread] bRunEndOfSequenceScript: False [10/15/18 20:48:10.031][DEBUG] [Sequence Thread] sEndOfSequenceScript: [10/15/18 20:48:10.031][DEBUG] [Sequence Thread] bDisconnectAllGearOnSequenceEnd: False [10/15/18 20:48:10.031][DEBUG] [Sequence Thread] ************** PROFILE DUMP ************** [10/15/18 20:48:10.031][DEBUG] [Sequence Thread] sForImagingInstance: Sequence Generator Pro [10/15/18 20:48:10.031][DEBUG] [Sequence Thread] sProfileName: RRAAP Canon 6D [10/15/18 20:48:10.031][DEBUG] [Sequence Thread] bDefault: True [10/15/18 20:48:10.031][DEBUG] [Sequence Thread] sCameraName: Canon EOS [10/15/18 20:48:10.031][DEBUG] [Sequence Thread] nGain1x1: 0 [10/15/18 20:48:10.031][DEBUG] [Sequence Thread] nOffset1x1: 100 [10/15/18 20:48:10.031][DEBUG] [Sequence Thread] nGain2x2: 0 [10/15/18 20:48:10.031][DEBUG] [Sequence Thread] nOffset2x2: 100 [10/15/18 20:48:10.031][DEBUG] [Sequence Thread] nGain3x3: 0 [10/15/18 20:48:10.031][DEBUG] [Sequence Thread] nOffset3x3: 100 [10/15/18 20:48:10.031][DEBUG] [Sequence Thread] nGain4x4: 0 [10/15/18 20:48:10.031][DEBUG] [Sequence Thread] nOffset4x4: 100 [10/15/18 20:48:10.031][DEBUG] [Sequence Thread] nCooldownTemp: -15 [10/15/18 20:48:10.031][DEBUG] [Sequence Thread] nCooldownTime: 5 [10/15/18 20:48:10.031][DEBUG] [Sequence Thread] nWarmupTemp: 20 [10/15/18 20:48:10.031][DEBUG] [Sequence Thread] nWarmupTime: 10 [10/15/18 20:48:10.031][DEBUG] [Sequence Thread] bAutoCoolDown: True [10/15/18 20:48:10.031][DEBUG] [Sequence Thread] bAutoWarmUp: True [10/15/18 20:48:10.031][DEBUG] [Sequence Thread] sFilterWheelName: No Filter Wheel [10/15/18 20:48:10.031][DEBUG] [Sequence Thread] nFilterDelay: 0 [10/15/18 20:48:10.032][DEBUG] [Sequence Thread] sFocuserName: No Focuser [10/15/18 20:48:10.032][DEBUG] [Sequence Thread] bReverseFocuser: False [10/15/18 20:48:10.032][DEBUG] [Sequence Thread] nFineFocusStepSize: 10 [10/15/18 20:48:10.032][DEBUG] [Sequence Thread] nCoarseFocusStepSize: 100 [10/15/18 20:48:10.032][DEBUG] [Sequence Thread] bAutoAdjustFocusPerFilter: False [10/15/18 20:48:10.032][DEBUG] [Sequence Thread] bAutoFocusForFilterChangeOnly: True [10/15/18 20:48:10.032][DEBUG] [Sequence Thread] bUseAutoFocus: False [10/15/18 20:48:10.032][DEBUG] [Sequence Thread] bAutoFocusPerFrame: False [10/15/18 20:48:10.032][DEBUG] [Sequence Thread] bAutoFocusOverTime: False [10/15/18 20:48:10.032][DEBUG] [Sequence Thread] nAutoFocusFramerInterval: 1 [10/15/18 20:48:10.032][DEBUG] [Sequence Thread] nAutoFocusTimerInterval: 10 [10/15/18 20:48:10.032][DEBUG] [Sequence Thread] bAutoFocusForceForFilter: False [10/15/18 20:48:10.032][DEBUG] [Sequence Thread] bAutoFocusBeforeFirstFrame: True [10/15/18 20:48:10.032][DEBUG] [Sequence Thread] bAutoFocusManualStar: False [10/15/18 20:48:10.032][DEBUG] [Sequence Thread] bAutoManualFocusForFilterChangeOnly: True [10/15/18 20:48:10.032][DEBUG] [Sequence Thread] bUseAutoManualFocus: False [10/15/18 20:48:10.032][DEBUG] [Sequence Thread] bAutoManualFocusPerFrame: False [10/15/18 20:48:10.032][DEBUG] [Sequence Thread] bAutoManualFocusOverTime: False [10/15/18 20:48:10.032][DEBUG] [Sequence Thread] nAutoManualFocusFramerInterval: 1 [10/15/18 20:48:10.032][DEBUG] [Sequence Thread] nAutoManualFocusTimerInterval: 10 [10/15/18 20:48:10.032][DEBUG] [Sequence Thread] bAutoManualFocusForceForFilter: False [10/15/18 20:48:10.032][DEBUG] [Sequence Thread] bAutoManualFocusBeforeFirstFrame: True [10/15/18 20:48:10.032][DEBUG] [Sequence Thread] bOverrideMaxSteps: False [10/15/18 20:48:10.032][DEBUG] [Sequence Thread] nOverrideMaxSteps: 1 [10/15/18 20:48:10.032][DEBUG] [Sequence Thread] bTempComp: False [10/15/18 20:48:10.032][DEBUG] [Sequence Thread] nStepsPerDegree: 0 [10/15/18 20:48:10.032][DEBUG] [Sequence Thread] bPhdDither: True [10/15/18 20:48:10.032][DEBUG] [Sequence Thread] nPhdDitherIndex: 3 [10/15/18 20:48:10.032][DEBUG] [Sequence Thread] nPhdSettleValue: 0.5 [10/15/18 20:48:10.032][DEBUG] [Sequence Thread] bRecalGuiderOnSlew: False [10/15/18 20:48:10.032][DEBUG] [Sequence Thread] nDecRecalValue: 0 [10/15/18 20:48:10.032][DEBUG] [Sequence Thread] sTelescopeName: iOptron ASCOM Driver for Mount [10/15/18 20:48:10.032][DEBUG] [Sequence Thread] bAutoSlew: False [10/15/18 20:48:10.032][DEBUG] [Sequence Thread] tpFocusFrame: [10/15/18 20:48:10.032][DEBUG] [Sequence Thread] tpTargetFrame: [10/15/18 20:48:10.032][DEBUG] [Sequence Thread] bPausePhdDuringDownload: False [10/15/18 20:48:10.032][DEBUG] [Sequence Thread] bShutdownPhdWhenDone: True [10/15/18 20:48:10.032][DEBUG] [Sequence Thread] bParkTelescopeWhenDone: True [10/15/18 20:48:10.032][DEBUG] [Sequence Thread] sReferenceImagePath: [10/15/18 20:48:10.032][DEBUG] [Sequence Thread] bAutoCenterFrame: False [10/15/18 20:48:10.033][DEBUG] [Sequence Thread] siReference: nEstRa: 0 nEstDec: 0 nRightAscention: 0 nDeclination: 0 nArcsecPerPixel: 0 nAngle: -500 bFlipped: False sSearchType: sBinning: nExposureLength: 0 sImagePath: bFailed: True sInfo: bDoneSolving: True nSides: 0 nMaxSolveTime: 60 [10/15/18 20:48:10.033][DEBUG] [Sequence Thread] siScope: nEstRa: 0 nEstDec: 0 nRightAscention: 0 nDeclination: 0 nArcsecPerPixel: 0 nAngle: 268 bFlipped: False sSearchType: Max Regions sBinning: 3200 nExposureLength: 0 sImagePath: bFailed: True sInfo: bDoneSolving: True nSides: 0 nMaxSolveTime: 60 [10/15/18 20:48:10.033][DEBUG] [Sequence Thread] nSolverExposure1x1: 5 [10/15/18 20:48:10.033][DEBUG] [Sequence Thread] nSolverExposure2x2: 1 [10/15/18 20:48:10.033][DEBUG] [Sequence Thread] nSolverExposure3x3: 1 [10/15/18 20:48:10.033][DEBUG] [Sequence Thread] nSolverExposure4x4: 1 [10/15/18 20:48:10.033][DEBUG] [Sequence Thread] nArcsec1x1: 0.67 [10/15/18 20:48:10.033][DEBUG] [Sequence Thread] nArcsec2x2: 0 [10/15/18 20:48:10.033][DEBUG] [Sequence Thread] nArcsec3x3: 0 [10/15/18 20:48:10.033][DEBUG] [Sequence Thread] nArcsec4x4: 0 [10/15/18 20:48:10.033][DEBUG] [Sequence Thread] sDithertext: Very High Dither [10/15/18 20:48:10.033][DEBUG] [Sequence Thread] sAutoGuider: PHD2 [10/15/18 20:48:10.033][DEBUG] [Sequence Thread] nFFBinning: 3 [10/15/18 20:48:10.033][DEBUG] [Sequence Thread] nFFExposure: 1 [10/15/18 20:48:10.033][DEBUG] [Sequence Thread] nAFExposure: 10 [10/15/18 20:48:10.033][DEBUG] [Sequence Thread] nAFBinning: 0 [10/15/18 20:48:10.033][DEBUG] [Sequence Thread] nAFStepSize: 20 [10/15/18 20:48:10.033][DEBUG] [Sequence Thread] nAFDataPoints: 9 [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] sPlateSolver: PlateSolve2 [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] sAutoFocuser: Half Flux Radius [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] nAutoFocusDialogCloseDelay: 10 [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] bAutoFocusDegree: True [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] bAutoManualFocusDegree: False [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] nAutoFocusDegreeInterval: 1 [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] nAutoManualFocusDegreeInterval: 1 [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] bAutoFocusAutoContinue: False [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] nNudgeSpeed: 0 [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] bReverseNs: False [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] bReverseEw: False [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] nHiSpeedRate: 1 [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] nLoSpeedRate: 0.5 [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] sCameraGain1x1: NA [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] sCameraGain2x2: NA [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] sCameraGain3x3: NA [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] sCameraGain4x4: NA [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] sCameraOffset1x1: [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] sCameraOffset2x2: [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] sCameraOffset3x3: [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] sCameraOffset4x4: [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] bPauseAutoGuiderDuringAutoFocus: False [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] bUseSameStarsForAf: False [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] bSaveAfData: False [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] sSaveAfDataPath: [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] bAllowExternalScopeControl: True [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] sFFBinning: 12800 [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] sAFBinning: 1600 [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] nMountSettling: 2 [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] ffExposure: 3 [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] noFilter: SequenceGenerator.Filter [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] bAutoFocusOnResume: False [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] sFlatBoxName: No Flat Box [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] flatBoxBrightness: 0 [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] bFocuserBacklash: False [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] nFocuserBacklashStepSize: 0 [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] bFocuserBacklashMovesIn: True [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] nGuiderSettlingTime: 3 [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] nAutoCenterAttempts: 4 [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] nAutoCenterError: 30 [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] bFrameRestart: False [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] nFrameRestartError: 0 [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] bDoAutoMeridianFlip: True [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] autoMeridianFlipVals: SequenceGenerator.AutoMeridianFlipValues [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] bUseAfDarks: False [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] sAfDarksPath: [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] bUseAutoCenterFilter: False [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] sAutoCenterFilter: [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] bUseAutoFocusFilter: False [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] sAutoFocusFilter: None [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] bSettleAgBeforeAf: False [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] bUseHighSpeedDl: True [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] bBlindSolveFailover: True [10/15/18 20:48:10.034][DEBUG] [Sequence Thread] nReadoutNoise: 3 [10/15/18 20:48:10.035][DEBUG] [Sequence Thread] ccdArraySize: {Width=5472, Height=3648} [10/15/18 20:48:10.035][DEBUG] [Sequence Thread] nCameraGain1x1: 0 [10/15/18 20:48:10.035][DEBUG] [Sequence Thread] nCameraGain2x2: 0 [10/15/18 20:48:10.035][DEBUG] [Sequence Thread] nCameraGain3x3: 0 [10/15/18 20:48:10.035][DEBUG] [Sequence Thread] nCameraGain4x4: 0 [10/15/18 20:48:10.035][DEBUG] [Sequence Thread] sRotatorName: No Rotator [10/15/18 20:48:10.035][DEBUG] [Sequence Thread] nRotationError: 3 [10/15/18 20:48:10.035][DEBUG] [Sequence Thread] bUseTemperDevice: False [10/15/18 20:48:10.035][DEBUG] [Sequence Thread] bAutoFocusAfterFlip: False [10/15/18 20:48:10.035][DEBUG] [Sequence Thread] bReverseManualRotatorCorrections: False [10/15/18 20:48:10.035][DEBUG] [Sequence Thread] sObservatoryName: No Observatory [10/15/18 20:48:10.035][DEBUG] [Sequence Thread] sObsType: Dome [10/15/18 20:48:10.035][DEBUG] [Sequence Thread] SlavingVals: Radius: 0 NorthOffset: 0 VerticalOffset: 0 GemOffset: 0 SlavingFrequency: 60 SlavingAngleAllowance: 0 SlaveOnSeqStart: False UnparkWithMount: False ParkMountFirst: False OpenShutterFirst: False UnparkOpensShutter: False ParkClosesShutter: False [10/15/18 20:48:10.035][DEBUG] [Sequence Thread] bSlaveObsToScope: False [10/15/18 20:48:10.035][DEBUG] [Sequence Thread] bPromptToPlaceLightBox: False [10/15/18 20:48:10.035][DEBUG] [Sequence Thread] nDownloadTime1x1: 20 [10/15/18 20:48:10.035][DEBUG] [Sequence Thread] nDownloadTime2x2: 10 [10/15/18 20:48:10.035][DEBUG] [Sequence Thread] nDownloadTime3x3: 5 [10/15/18 20:48:10.035][DEBUG] [Sequence Thread] nDownloadTime4x4: 2.5 [10/15/18 20:48:10.035][DEBUG] [Sequence Thread] bAfCropFrames: False [10/15/18 20:48:10.036][DEBUG] [Sequence Thread] nAfCropFramesPercent: 20 [10/15/18 20:48:10.036][DEBUG] [Sequence Thread] sFilePattern: %tn\%dt\%tn_%ft_%el_%is_%fz [10/15/18 20:48:10.036][DEBUG] [Sequence Thread] sWeatherStationName: No Safety Monitor [10/15/18 20:48:10.036][DEBUG] [Sequence Thread] nScopeFocalLength: 2000 [10/15/18 20:48:10.036][DEBUG] [Sequence Thread] bCameraCooldownOnSequenceStart: False [10/15/18 20:48:10.036][DEBUG] [Sequence Thread] sRtfNotes: [10/15/18 20:48:10.036][DEBUG] [Sequence Thread] bStopTracking: False [10/15/18 20:48:10.036][DEBUG] [Sequence Thread] bHasCentralObstruction: False [10/15/18 20:48:10.036][DEBUG] [Sequence Thread] sCustomVariables: [10/15/18 20:48:10.036][DEBUG] [Sequence Thread] sPhd2Profile: kozphd [10/15/18 20:48:10.036][DEBUG] [Sequence Thread] bAutoConnectPhd2Gear: True [10/15/18 20:48:10.036][DEBUG] [Sequence Thread] bAutoDisconnectPhd2Gear: True [10/15/18 20:48:10.036][DEBUG] [Sequence Thread] bPauseGuiderDuringBacklashComp: False [10/15/18 20:48:10.036][DEBUG] [Sequence Thread] bSetAbsPositiononFirstFilterChange: False [10/15/18 20:48:10.036][DEBUG] [Sequence Thread] sEnvironmentDeviceName: No Environment Device [10/15/18 20:48:10.036][DEBUG] [Sequence Thread] bSettleGuiderTime: True [10/15/18 20:48:10.036][DEBUG] [Sequence Thread] equipmentData: System.Collections.Generic.Dictionary`2[System.String,SequenceGenerator.EquipmentData] [10/15/18 20:48:10.036][DEBUG] [Sequence Thread] nMinStarSize: 6 [10/15/18 20:48:10.036][DEBUG] [Sequence Thread] telescopeSyncOption: Sync [10/15/18 20:48:10.036][DEBUG] [Sequence Thread] nAutoguiderDitherEveryXFrameCount: 2 [10/15/18 20:48:10.036][DEBUG] [Sequence Thread] bFocusLockRestartWhenOutOfFocus: False [10/15/18 20:48:10.036][DEBUG] [Sequence Thread] bFocusLockPreventFocuserMovement: False [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] ************** EVENT DUMP ************** [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] * Target iris nebula [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Active: True [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Start Time: False [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Start: 10/15/2018 8:46:00 PM [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -End Time: False [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -End: 10/15/2018 8:46:00 PM [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Auto Rotate: False [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Auto Center: True [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Location set: False [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -RA: 21.0304416666667 [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -DEC: 68.1595194444445 [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Finish Full Event: True [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Completed: False [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -After End Time: False [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Rotate Camera: False [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Rotation: 268 [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] ** Event 1 [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Active: True [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Frame Type: Light [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Filter: 0 [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Suffix: [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Exposure Time: 120 [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Binning: 1600 [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Repeat: 30 [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Gain: 0 [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Offset: 100 [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Num Complete: 0 [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] ** Event 2 [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Active: False [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Frame Type: Light [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Filter: 0 [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Suffix: [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Exposure Time: 0 [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Binning: 800 [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Repeat: 1 [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Gain: 0 [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Offset: 100 [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Num Complete: 0 [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] ** Event 3 [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Active: False [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Frame Type: Light [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Filter: 0 [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Suffix: [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Exposure Time: 0 [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Binning: 800 [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Repeat: 1 [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Gain: 0 [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Offset: 100 [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Num Complete: 0 [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] ** Event 4 [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Active: False [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Frame Type: Light [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Filter: 0 [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Suffix: [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Exposure Time: 0 [10/15/18 20:48:10.040][DEBUG] [Sequence Thread] -Binning: 800 [10/15/18 20:48:10.041][DEBUG] [Sequence Thread] -Repeat: 1 [10/15/18 20:48:10.041][DEBUG] [Sequence Thread] -Gain: 0 [10/15/18 20:48:10.041][DEBUG] [Sequence Thread] -Offset: 100 [10/15/18 20:48:10.041][DEBUG] [Sequence Thread] -Num Complete: 0 [10/15/18 20:48:10.041][DEBUG] [Sequence Thread] ** Event 5 [10/15/18 20:48:10.041][DEBUG] [Sequence Thread] -Active: False [10/15/18 20:48:10.041][DEBUG] [Sequence Thread] -Frame Type: Light [10/15/18 20:48:10.041][DEBUG] [Sequence Thread] -Filter: 0 [10/15/18 20:48:10.041][DEBUG] [Sequence Thread] -Suffix: [10/15/18 20:48:10.041][DEBUG] [Sequence Thread] -Exposure Time: 0 [10/15/18 20:48:10.041][DEBUG] [Sequence Thread] -Binning: 800 [10/15/18 20:48:10.041][DEBUG] [Sequence Thread] -Repeat: 1 [10/15/18 20:48:10.041][DEBUG] [Sequence Thread] -Gain: 0 [10/15/18 20:48:10.041][DEBUG] [Sequence Thread] -Offset: 100 [10/15/18 20:48:10.041][DEBUG] [Sequence Thread] -Num Complete: 0 [10/15/18 20:48:10.041][DEBUG] [Sequence Thread] ************** END SEQUENCE DUMP ************** [10/15/18 20:48:10.041][DEBUG] [Sequence Thread] ********** Run sequence started ********** [10/15/18 20:48:10.099][DEBUG] [Sequence Thread] Checking if observatory requires auto slaving... [10/15/18 20:48:10.100][DEBUG] [Sequence Thread] Observatory does not require auto slave... [10/15/18 20:48:10.106][DEBUG] [Sequence Thread] DoEventGroupChange: Changing to event group: iris nebula [10/15/18 20:48:10.108][DEBUG] [Sequence Thread] Checking for start delay... [10/15/18 20:48:10.108][DEBUG] [Sequence Thread] DoEventGroupChange: Stopping Autoguider [10/15/18 20:48:10.109][DEBUG] [Sequence Thread] Attempting to stop PHD2 guiding... [10/15/18 20:48:10.109][DEBUG] [Sequence Thread] Checking PHD2 state... [10/15/18 20:48:10.109][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/15/18 20:48:10.109][DEBUG] [Sequence Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/15/18 20:48:10.209][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/15/18 20:48:10.209][DEBUG] [Sequence Thread] PHD2 Guider is already stopped. [10/15/18 20:48:10.210][DEBUG] [Sequence Thread] DoEventGroupChange: Slewing to target [10/15/18 20:48:10.219][DEBUG] [Sequence Thread] Telescope: Slewing to J2000 RA: 21.0304416666667 (21h01m49.59s) Dec: 68.1595194444445 (68°09'34.27") [10/15/18 20:48:10.219][DEBUG] [Sequence Thread] Telescope: Slew received J2000 coordinates, mount requires JNOW, converting... [10/15/18 20:48:10.219][DEBUG] [Sequence Thread] Telescope: Slewing to JNOW RA: 21.0346804580715 Dec: 68.2391369654793 [10/15/18 20:48:12.678][DEBUG] [Sequence Thread] Scope reports it is done with synchronous slew, verifying... [10/15/18 20:48:12.678][DEBUG] [Sequence Thread] Telescope: Slewing has completed [10/15/18 20:48:12.678][DEBUG] [Sequence Thread] Telescope: Settling for 2 seconds [10/15/18 20:48:14.678][DEBUG] [Sequence Thread] Telescope: Settling has completed [10/15/18 20:48:14.678][DEBUG] [Sequence Thread] DoEventGroupChange: Slew complete [10/15/18 20:48:14.678][DEBUG] [Sequence Thread] DoEventGroupChange: Centering on target [10/15/18 20:48:14.750][DEBUG] [Telescope Thread] Center telescope message received... [10/15/18 20:48:14.750][DEBUG] [Telescope Thread] Solving with Plate Solver PlateSolve2... [10/15/18 20:48:14.762][DEBUG] [Telescope Thread] Performing auto center step 1... [10/15/18 20:48:14.762][DEBUG] [Telescope Thread] Skipping step 1... [10/15/18 20:48:14.762][DEBUG] [Telescope Thread] Auto center reference frame solved successfully... [10/15/18 20:48:14.762][DEBUG] [Telescope Thread] Performing auto center step 3 (scope)... [10/15/18 20:48:14.762][DEBUG] [Telescope Thread] Auto center slewing scope to match reference... [10/15/18 20:48:14.764][DEBUG] [Telescope Thread] Telescope: Slewing to J2000 RA: 21.0304416666667 (21h01m49.59s) Dec: 68.1595194444445 (68°09'34.27") [10/15/18 20:48:14.764][DEBUG] [Telescope Thread] Telescope: Slew received J2000 coordinates, mount requires JNOW, converting... [10/15/18 20:48:14.765][DEBUG] [Telescope Thread] Telescope: Slewing to JNOW RA: 21.0346804572582 Dec: 68.2391369677698 [10/15/18 20:48:17.252][DEBUG] [Telescope Thread] Scope reports it is done with synchronous slew, verifying... [10/15/18 20:48:17.252][DEBUG] [Telescope Thread] Telescope: Slewing has completed [10/15/18 20:48:17.252][DEBUG] [Telescope Thread] Telescope: Settling for 2 seconds [10/15/18 20:48:19.252][DEBUG] [Telescope Thread] Telescope: Settling has completed [10/15/18 20:48:19.252][DEBUG] [Telescope Thread] Auto center slew complete... [10/15/18 20:48:19.252][DEBUG] [Telescope Thread] Performing auto center step 4... [10/15/18 20:48:19.253][DEBUG] [Telescope Thread] Created full file name (file does not exist): C:\Users\rcowley\AppData\Local\SequenceGenerator\Temp\plate_solve_image.fit [10/15/18 20:48:19.267][DEBUG] [Camera Thread] SGM_CAMERA_PLATE_SOLVER_CAPTURE message received... [10/15/18 20:48:19.268][DEBUG] [Camera Thread] Collecting FITs headers for plate solve frame... [10/15/18 20:48:19.270][DEBUG] [Camera Thread] Collecting FITs headers for plate solve frame... [10/15/18 20:48:19.318][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 40A, parameter 0, [10/15/18 20:48:19.318][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property B, parameter 0, [10/15/18 20:48:19.318][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 40A, parameter 0, [10/15/18 20:48:19.354][DEBUG] [Camera Thread] Canon: Setting ISO to: 3200 [10/15/18 20:48:19.354][DEBUG] [Camera Thread] Canon: ISO look val: 112 [10/15/18 20:48:19.437][DEBUG] [Camera Thread] Canon: Exposing for 5 seconds... [10/15/18 20:48:19.534][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 40A, parameter 0, [10/15/18 20:48:19.534][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property B, parameter 0, [10/15/18 20:48:19.534][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 40A, parameter 0, [10/15/18 20:48:19.534][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property B, parameter 0, [10/15/18 20:48:19.534][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 40A, parameter 0, [10/15/18 20:48:19.534][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 40A, parameter 0, [10/15/18 20:48:19.535][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 402, parameter 0, [10/15/18 20:48:19.535][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 40A, parameter 0, [10/15/18 20:48:19.535][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 40A, parameter 0, [10/15/18 20:48:19.535][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 402, parameter 0, [10/15/18 20:48:19.535][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 402, parameter 0, [10/15/18 20:48:19.535][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 100, parameter 0, [10/15/18 20:48:19.535][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 40A, parameter 0, [10/15/18 20:48:19.535][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 40A, parameter 0, [10/15/18 20:48:19.746][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 40A, parameter 0, [10/15/18 20:48:24.786][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 406, parameter 0, [10/15/18 20:48:24.786][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 405, parameter 0, [10/15/18 20:48:24.786][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 402, parameter 0, [10/15/18 20:48:24.786][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 102, property 406, parameter 0, [10/15/18 20:48:25.228][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 406, parameter 0, [10/15/18 20:48:25.228][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 405, parameter 0, [10/15/18 20:48:25.228][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 402, parameter 0, [10/15/18 20:48:25.228][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 102, property 406, parameter 0, [10/15/18 20:48:25.455][DEBUG] [Canon Download Thread] Canon: Directory Item Requested Transfer [10/15/18 20:48:25.455][DEBUG] [Canon Download Thread] -- Event delay: 0 ms [10/15/18 20:48:27.530][DEBUG] [Canon Download Thread] --------------> getCapturedItem time: 2074 ms [10/15/18 20:48:27.629][DEBUG] [Camera Thread] Canon Camera: disposing old preview memory... [10/15/18 20:48:27.630][DEBUG] [Camera Thread] Canon Camera: Saving FITS file... [10/15/18 20:48:27.630][DEBUG] [Camera Thread] Created full file name (file does not exist): C:\Users\rcowley\AppData\Local\SequenceGenerator\Temp\plate_solve_image.fit [10/15/18 20:48:27.636][DEBUG] [Camera Thread] Extracting RAW data... [10/15/18 20:48:27.636][DEBUG] [Camera Thread] RAW arg data: -D -T -4 -W -v -t 0 "C:\Users\rcowley\AppData\Local\SequenceGenerator\Temp\cannonRaw-0.tmp" [10/15/18 20:48:27.638][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 131072 [10/15/18 20:48:28.139][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 10407936 [10/15/18 20:48:28.640][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 17911808 [10/15/18 20:48:29.141][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 25575424 [10/15/18 20:48:29.642][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 33255424 [10/15/18 20:48:30.143][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 39378944 [10/15/18 20:48:30.650][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 40640512 [10/15/18 20:48:31.151][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 41865216 [10/15/18 20:48:31.652][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 43077632 [10/15/18 20:48:32.153][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 44281856 [10/15/18 20:48:32.654][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 166301696 [10/15/18 20:48:33.155][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 165122048 [10/15/18 20:48:33.659][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 165122048 [10/15/18 20:48:34.159][DEBUG] [Camera Thread] Done extracting RAW data, checking for success... [10/15/18 20:48:34.159][DEBUG] [Camera Thread] RAW data extraction is good... [10/15/18 20:48:34.159][DEBUG] [Camera Thread] Extracting bitmap data from RAW (tiff)... [10/15/18 20:48:34.291][DEBUG] [Camera Thread] Creating bitmap (5496,3670)... [10/15/18 20:48:34.330][DEBUG] [Camera Thread] Locking bitmap for write [10/15/18 20:48:34.331][DEBUG] [Camera Thread] RAW bith depth: 16 [10/15/18 20:48:34.480][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [10/15/18 20:48:34.480][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [10/15/18 20:48:34.480][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [10/15/18 20:48:34.487][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [10/15/18 20:48:35.030][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [10/15/18 20:48:35.030][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [10/15/18 20:48:35.030][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [10/15/18 20:48:35.033][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/15/18 20:48:35.033][DEBUG] [Camera Thread] Display image preview using asynch task... [10/15/18 20:48:37.615][DEBUG] [Camera Thread] ----> Canon IO time: 9986 ms [10/15/18 20:48:37.615][DEBUG] [Camera Thread] SGM_CAMERA_PLATE_SOLVER_CAPTURE complete... [10/15/18 20:48:38.073][DEBUG] [Telescope Thread] Auto center verification frame complete. Plate solving image C:\Users\rcowley\AppData\Local\SequenceGenerator\Temp\plate_solve_image.fit [10/15/18 20:48:38.078][DEBUG] [Telescope Thread] ************* SOLVE HINTS **************** [10/15/18 20:48:38.078][DEBUG] [Telescope Thread] SOLVER: PlateSolve2 [10/15/18 20:48:38.078][DEBUG] [Telescope Thread] BLIND: False [10/15/18 20:48:38.078][DEBUG] [Telescope Thread] METHOD: Max Regions [10/15/18 20:48:38.078][DEBUG] [Telescope Thread] RA: 21.0304805259936 [10/15/18 20:48:38.078][DEBUG] [Telescope Thread] DEC: 68.159520645417 [10/15/18 20:48:38.078][DEBUG] [Telescope Thread] SCALE: 0.67 [10/15/18 20:48:38.078][DEBUG] [Telescope Thread] ****************************************** [10/15/18 20:48:38.183][DEBUG] [Telescope Thread] FitsFileHeaderData: Angle - 0 [10/15/18 20:48:38.183][DEBUG] [Telescope Thread] FitsFileHeaderData: Scale - 0 [10/15/18 20:48:38.183][DEBUG] [Telescope Thread] FitsFileHeaderData: RA - 21.0304832907651 [10/15/18 20:48:38.184][DEBUG] [Telescope Thread] FitsFileHeaderData: DEC - 68.1595206045311 [10/15/18 20:48:38.192][DEBUG] [Telescope Thread] PlateSove2 Param: RA (RAD) - 5.50576692682706 [10/15/18 20:48:38.192][DEBUG] [Telescope Thread] PlateSove2 Param: DEC (RAD) - 1.18960805184358 [10/15/18 20:48:38.192][DEBUG] [Telescope Thread] PlateSove2 Param: Width - 5496 [10/15/18 20:48:38.192][DEBUG] [Telescope Thread] PlateSove2 Param: Height - 3670 [10/15/18 20:48:38.192][DEBUG] [Telescope Thread] PlateSolve2 Command Line: [10/15/18 20:48:38.192][DEBUG] [Telescope Thread] C:\Users\rcowley\AppData\Local\SequenceGenerator\\PlateSolve2.exe 5.50576692682706,1.18960805184358,0.01785239114223,0.01192108360480,3000,C:\Users\rcowley\AppData\Local\SequenceGenerator\\Temp\psXSolve_0.fit [10/15/18 20:48:49.703][DEBUG] [Telescope Thread] *********** SUCCESSFUL SOLVE ************* [10/15/18 20:48:49.703][DEBUG] [Telescope Thread] SOLVER: False [10/15/18 20:48:49.704][DEBUG] [Telescope Thread] SUCCESS: True [10/15/18 20:48:49.704][DEBUG] [Telescope Thread] CONF: 222 [10/15/18 20:48:49.704][DEBUG] [Telescope Thread] BLIND: False [10/15/18 20:48:49.704][DEBUG] [Telescope Thread] RA: 21.0504078574393 [10/15/18 20:48:49.704][DEBUG] [Telescope Thread] DEC: 68.8265448268498 [10/15/18 20:48:49.704][DEBUG] [Telescope Thread] SCALE: 0.67146 [10/15/18 20:48:49.704][DEBUG] [Telescope Thread] FLIPPED: False [10/15/18 20:48:49.704][DEBUG] [Telescope Thread] ANGLE (EON): 269.1 [10/15/18 20:48:49.704][DEBUG] [Telescope Thread] MSG: Valid solve. [10/15/18 20:48:49.704][DEBUG] [Telescope Thread] ****************************************** [10/15/18 20:48:49.705][DEBUG] [Telescope Thread] Checking to see if solve might be bad... [10/15/18 20:48:49.706][DEBUG] [Telescope Thread] Solve appears to be good... [10/15/18 20:48:49.706][DEBUG] [Telescope Thread] Auto center validation frame solved successfully... [10/15/18 20:48:49.707][DEBUG] [Telescope Thread] Telescope: Syncing to J2000 RA: 21.0504078574393 Dec: 68.8265448268498 [10/15/18 20:48:49.707][DEBUG] [Telescope Thread] Telescope: Sync behavior set to "Sync"... [10/15/18 20:48:49.707][DEBUG] [Telescope Thread] Telescope: Sync Passed in J2000 but mount requires JNOW, converting... [10/15/18 20:48:49.708][DEBUG] [Telescope Thread] Telescope: Syncing to JNOW RA: 21.0543149305624 Dec: 68.9065203243416 [10/15/18 20:48:49.919][DEBUG] [Telescope Thread] Attempting to write fits header info for C:\Users\rcowley\AppData\Local\SequenceGenerator\Temp\plate_solve_image.fit [10/15/18 20:48:49.919][DEBUG] [Telescope Thread] Opening fits file... [10/15/18 20:48:49.920][DEBUG] [Telescope Thread] Successfully opened fits file... [10/15/18 20:48:49.920][DEBUG] [Telescope Thread] Writing fits headers... [10/15/18 20:48:49.922][DEBUG] [Telescope Thread] Closing fits file [10/15/18 20:48:49.922][DEBUG] [Telescope Thread] Could not sync the rotator to 269.1 degrees (not connected)! [10/15/18 20:48:49.923][DEBUG] [Telescope Thread] Auto center complete... [10/15/18 20:48:49.987][DEBUG] [Center Scope Thread] Retrying auto center. Total Error > Allowable error: 3920.2 > 30.0 [10/15/18 20:48:49.987][DEBUG] [Center Scope Thread] RA Error: -1605.7 [10/15/18 20:48:49.987][DEBUG] [Center Scope Thread] DEC Error: -3576.2 [10/15/18 20:48:50.004][DEBUG] [Telescope Thread] Center telescope message received... [10/15/18 20:48:50.004][DEBUG] [Telescope Thread] Solving with Plate Solver PlateSolve2... [10/15/18 20:48:50.245][DEBUG] [Center Scope Thread] Error in auto center update thread, Thread was being aborted. [10/15/18 20:48:50.254][DEBUG] [Telescope Thread] Performing auto center step 1... [10/15/18 20:48:50.254][DEBUG] [Telescope Thread] Skipping step 1... [10/15/18 20:48:50.254][DEBUG] [Telescope Thread] Auto center reference frame solved successfully... [10/15/18 20:48:50.254][DEBUG] [Telescope Thread] Performing auto center step 3 (scope)... [10/15/18 20:48:50.254][DEBUG] [Telescope Thread] Auto center slewing scope to match reference... [10/15/18 20:48:50.256][DEBUG] [Telescope Thread] Telescope: Slewing to J2000 RA: 21.0304416666667 (21h01m49.59s) Dec: 68.1595194444445 (68°09'34.27") [10/15/18 20:48:50.256][DEBUG] [Telescope Thread] Telescope: Slew received J2000 coordinates, mount requires JNOW, converting... [10/15/18 20:48:50.257][DEBUG] [Telescope Thread] Telescope: Slewing to JNOW RA: 21.0346804509068 Dec: 68.2391369856553 [10/15/18 20:48:52.686][DEBUG] [Telescope Thread] Scope reports it is done with synchronous slew, verifying... [10/15/18 20:48:52.686][DEBUG] [Telescope Thread] Telescope: Slewing has completed [10/15/18 20:48:52.686][DEBUG] [Telescope Thread] Telescope: Settling for 2 seconds [10/15/18 20:48:54.686][DEBUG] [Telescope Thread] Telescope: Settling has completed [10/15/18 20:48:54.686][DEBUG] [Telescope Thread] Auto center slew complete... [10/15/18 20:48:54.686][DEBUG] [Telescope Thread] Performing auto center step 4... [10/15/18 20:48:54.686][DEBUG] [Telescope Thread] Created full file name (file does not exist): C:\Users\rcowley\AppData\Local\SequenceGenerator\Temp\plate_solve_image.fit [10/15/18 20:48:54.694][DEBUG] [Camera Thread] SGM_CAMERA_PLATE_SOLVER_CAPTURE message received... [10/15/18 20:48:54.694][DEBUG] [Camera Thread] Collecting FITs headers for plate solve frame... [10/15/18 20:48:54.694][DEBUG] [Camera Thread] Collecting FITs headers for plate solve frame... [10/15/18 20:48:54.731][DEBUG] [Camera Thread] Canon: Setting ISO to: 3200 [10/15/18 20:48:54.731][DEBUG] [Camera Thread] Canon: ISO look val: 112 [10/15/18 20:48:54.782][DEBUG] [Camera Thread] Canon: Exposing for 5 seconds... [10/15/18 20:48:54.855][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 40A, parameter 0, [10/15/18 20:48:54.855][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property B, parameter 0, [10/15/18 20:48:54.855][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 40A, parameter 0, [10/15/18 20:48:54.855][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property B, parameter 0, [10/15/18 20:48:54.855][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 40A, parameter 0, [10/15/18 20:48:54.855][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 40A, parameter 0, [10/15/18 20:48:54.855][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 40A, parameter 0, [10/15/18 20:48:54.855][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 402, parameter 0, [10/15/18 20:48:54.855][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 402, parameter 0, [10/15/18 20:48:54.855][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 100, parameter 0, [10/15/18 20:48:55.072][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 40A, parameter 0, [10/15/18 20:49:00.093][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 406, parameter 0, [10/15/18 20:49:00.093][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 405, parameter 0, [10/15/18 20:49:00.093][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 402, parameter 0, [10/15/18 20:49:00.093][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 102, property 406, parameter 0, [10/15/18 20:49:00.529][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 406, parameter 0, [10/15/18 20:49:00.529][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 405, parameter 0, [10/15/18 20:49:00.530][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 402, parameter 0, [10/15/18 20:49:00.530][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 102, property 406, parameter 0, [10/15/18 20:49:00.756][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 40A, parameter 0, [10/15/18 20:49:00.757][DEBUG] [Canon Download Thread] Canon: Directory Item Requested Transfer [10/15/18 20:49:00.757][DEBUG] [Canon Download Thread] -- Event delay: 0 ms [10/15/18 20:49:02.847][DEBUG] [Canon Download Thread] --------------> getCapturedItem time: 2090 ms [10/15/18 20:49:02.874][DEBUG] [Camera Thread] Canon Camera: disposing old preview memory... [10/15/18 20:49:02.879][DEBUG] [Camera Thread] Canon Camera: Saving FITS file... [10/15/18 20:49:02.879][DEBUG] [Camera Thread] Created full file name (file does not exist): C:\Users\rcowley\AppData\Local\SequenceGenerator\Temp\plate_solve_image.fit [10/15/18 20:49:02.879][DEBUG] [Camera Thread] Extracting RAW data... [10/15/18 20:49:02.879][DEBUG] [Camera Thread] RAW arg data: -D -T -4 -W -v -t 0 "C:\Users\rcowley\AppData\Local\SequenceGenerator\Temp\cannonRaw-0.tmp" [10/15/18 20:49:02.882][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 122880 [10/15/18 20:49:03.383][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 10293248 [10/15/18 20:49:03.884][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 17993728 [10/15/18 20:49:04.385][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 25575424 [10/15/18 20:49:04.891][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 33234944 [10/15/18 20:49:05.392][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 39366656 [10/15/18 20:49:05.893][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 40587264 [10/15/18 20:49:06.394][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 41811968 [10/15/18 20:49:06.896][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 43028480 [10/15/18 20:49:07.397][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 44195840 [10/15/18 20:49:07.898][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 147501056 [10/15/18 20:49:08.399][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 165122048 [10/15/18 20:49:08.900][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 165122048 [10/15/18 20:49:09.400][DEBUG] [Camera Thread] Done extracting RAW data, checking for success... [10/15/18 20:49:09.400][DEBUG] [Camera Thread] RAW data extraction is good... [10/15/18 20:49:09.400][DEBUG] [Camera Thread] Extracting bitmap data from RAW (tiff)... [10/15/18 20:49:09.557][DEBUG] [Camera Thread] Creating bitmap (5496,3670)... [10/15/18 20:49:09.589][DEBUG] [Camera Thread] Locking bitmap for write [10/15/18 20:49:09.589][DEBUG] [Camera Thread] RAW bith depth: 16 [10/15/18 20:49:09.732][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [10/15/18 20:49:09.733][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [10/15/18 20:49:09.733][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [10/15/18 20:49:09.733][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [10/15/18 20:49:10.201][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [10/15/18 20:49:10.201][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [10/15/18 20:49:10.201][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/15/18 20:49:10.201][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/15/18 20:49:10.201][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/15/18 20:49:10.284][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [10/15/18 20:49:10.284][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [10/15/18 20:49:10.284][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [10/15/18 20:49:10.284][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/15/18 20:49:10.284][DEBUG] [Camera Thread] Display image preview using asynch task... [10/15/18 20:49:15.364][DEBUG] [Camera Thread] ----> Canon IO time: 12490 ms [10/15/18 20:49:15.364][DEBUG] [Camera Thread] SGM_CAMERA_PLATE_SOLVER_CAPTURE complete... [10/15/18 20:49:15.583][DEBUG] [Telescope Thread] Auto center verification frame complete. Plate solving image C:\Users\rcowley\AppData\Local\SequenceGenerator\Temp\plate_solve_image.fit [10/15/18 20:49:15.698][DEBUG] [Telescope Thread] ************* SOLVE HINTS **************** [10/15/18 20:49:15.698][DEBUG] [Telescope Thread] SOLVER: PlateSolve2 [10/15/18 20:49:15.698][DEBUG] [Telescope Thread] BLIND: False [10/15/18 20:49:15.698][DEBUG] [Telescope Thread] METHOD: Max Regions [10/15/18 20:49:15.698][DEBUG] [Telescope Thread] RA: 21.0304971414333 [10/15/18 20:49:15.698][DEBUG] [Telescope Thread] DEC: 68.1595203237206 [10/15/18 20:49:15.698][DEBUG] [Telescope Thread] SCALE: 0.67146 [10/15/18 20:49:15.698][DEBUG] [Telescope Thread] ****************************************** [10/15/18 20:49:15.760][DEBUG] [Telescope Thread] PlateSove2 Param: RA (RAD) - 5.50577127673901 [10/15/18 20:49:15.760][DEBUG] [Telescope Thread] PlateSove2 Param: DEC (RAD) - 1.18960804622892 [10/15/18 20:49:15.760][DEBUG] [Telescope Thread] PlateSove2 Param: Width - 5496 [10/15/18 20:49:15.760][DEBUG] [Telescope Thread] PlateSove2 Param: Height - 3670 [10/15/18 20:49:15.760][DEBUG] [Telescope Thread] PlateSolve2 Command Line: [10/15/18 20:49:15.760][DEBUG] [Telescope Thread] C:\Users\rcowley\AppData\Local\SequenceGenerator\\PlateSolve2.exe 5.50577127673901,1.18960804622892,0.01789129336771,0.01194706089146,3000,C:\Users\rcowley\AppData\Local\SequenceGenerator\\Temp\psXSolve_1.fit [10/15/18 20:49:25.266][DEBUG] [Telescope Thread] *********** SUCCESSFUL SOLVE ************* [10/15/18 20:49:25.266][DEBUG] [Telescope Thread] SOLVER: False [10/15/18 20:49:25.266][DEBUG] [Telescope Thread] SUCCESS: True [10/15/18 20:49:25.266][DEBUG] [Telescope Thread] CONF: 100 [10/15/18 20:49:25.266][DEBUG] [Telescope Thread] BLIND: False [10/15/18 20:49:25.266][DEBUG] [Telescope Thread] RA: 21.029915525333 [10/15/18 20:49:25.266][DEBUG] [Telescope Thread] DEC: 68.1572086550845 [10/15/18 20:49:25.266][DEBUG] [Telescope Thread] SCALE: 0.6714 [10/15/18 20:49:25.266][DEBUG] [Telescope Thread] FLIPPED: False [10/15/18 20:49:25.266][DEBUG] [Telescope Thread] ANGLE (EON): 268.68 [10/15/18 20:49:25.266][DEBUG] [Telescope Thread] MSG: Valid solve. [10/15/18 20:49:25.266][DEBUG] [Telescope Thread] ****************************************** [10/15/18 20:49:25.266][DEBUG] [Telescope Thread] Checking to see if solve might be bad... [10/15/18 20:49:25.267][DEBUG] [Telescope Thread] Solve appears to be good... [10/15/18 20:49:25.267][DEBUG] [Telescope Thread] Auto center validation frame solved successfully... [10/15/18 20:49:25.267][DEBUG] [Telescope Thread] Telescope: Syncing to J2000 RA: 21.029915525333 Dec: 68.1572086550845 [10/15/18 20:49:25.267][DEBUG] [Telescope Thread] Telescope: Sync behavior set to "Sync"... [10/15/18 20:49:25.267][DEBUG] [Telescope Thread] Telescope: Sync Passed in J2000 but mount requires JNOW, converting... [10/15/18 20:49:25.268][DEBUG] [Telescope Thread] Telescope: Syncing to JNOW RA: 21.0341538334792 Dec: 68.236816637045 [10/15/18 20:49:25.495][DEBUG] [Telescope Thread] Attempting to write fits header info for C:\Users\rcowley\AppData\Local\SequenceGenerator\Temp\plate_solve_image.fit [10/15/18 20:49:25.495][DEBUG] [Telescope Thread] Opening fits file... [10/15/18 20:49:25.496][DEBUG] [Telescope Thread] Successfully opened fits file... [10/15/18 20:49:25.496][DEBUG] [Telescope Thread] Writing fits headers... [10/15/18 20:49:25.496][DEBUG] [Telescope Thread] Closing fits file [10/15/18 20:49:25.496][DEBUG] [Telescope Thread] Could not sync the rotator to 268.68 degrees (not connected)! [10/15/18 20:49:25.496][DEBUG] [Telescope Thread] Auto center complete... [10/15/18 20:49:25.715][DEBUG] [Center Scope Thread] Retrying auto center. Total Error > Allowable error: 44.1 > 30.0 [10/15/18 20:49:25.715][DEBUG] [Center Scope Thread] RA Error: 42.3 [10/15/18 20:49:25.715][DEBUG] [Center Scope Thread] DEC Error: 12.4 [10/15/18 20:49:25.727][DEBUG] [Telescope Thread] Center telescope message received... [10/15/18 20:49:25.727][DEBUG] [Telescope Thread] Solving with Plate Solver PlateSolve2... [10/15/18 20:49:25.976][DEBUG] [Center Scope Thread] Error in auto center update thread, Thread was being aborted. [10/15/18 20:49:25.977][DEBUG] [Telescope Thread] Performing auto center step 1... [10/15/18 20:49:25.977][DEBUG] [Telescope Thread] Skipping step 1... [10/15/18 20:49:25.977][DEBUG] [Telescope Thread] Auto center reference frame solved successfully... [10/15/18 20:49:25.977][DEBUG] [Telescope Thread] Performing auto center step 3 (scope)... [10/15/18 20:49:25.977][DEBUG] [Telescope Thread] Auto center slewing scope to match reference... [10/15/18 20:49:25.979][DEBUG] [Telescope Thread] Telescope: Slewing to J2000 RA: 21.0304416666667 (21h01m49.59s) Dec: 68.1595194444445 (68°09'34.27") [10/15/18 20:49:25.979][DEBUG] [Telescope Thread] Telescope: Slew received J2000 coordinates, mount requires JNOW, converting... [10/15/18 20:49:25.979][DEBUG] [Telescope Thread] Telescope: Slewing to JNOW RA: 21.0346804445139 Dec: 68.2391370036564 [10/15/18 20:49:28.443][DEBUG] [Telescope Thread] Scope reports it is done with synchronous slew, verifying... [10/15/18 20:49:28.443][DEBUG] [Telescope Thread] Telescope: Slewing has completed [10/15/18 20:49:28.443][DEBUG] [Telescope Thread] Telescope: Settling for 2 seconds [10/15/18 20:49:30.443][DEBUG] [Telescope Thread] Telescope: Settling has completed [10/15/18 20:49:30.443][DEBUG] [Telescope Thread] Auto center slew complete... [10/15/18 20:49:30.443][DEBUG] [Telescope Thread] Performing auto center step 4... [10/15/18 20:49:30.443][DEBUG] [Telescope Thread] Created full file name (file does not exist): C:\Users\rcowley\AppData\Local\SequenceGenerator\Temp\plate_solve_image.fit [10/15/18 20:49:30.453][DEBUG] [Camera Thread] SGM_CAMERA_PLATE_SOLVER_CAPTURE message received... [10/15/18 20:49:30.453][DEBUG] [Camera Thread] Collecting FITs headers for plate solve frame... [10/15/18 20:49:30.453][DEBUG] [Camera Thread] Collecting FITs headers for plate solve frame... [10/15/18 20:49:30.489][DEBUG] [Camera Thread] Canon: Setting ISO to: 3200 [10/15/18 20:49:30.489][DEBUG] [Camera Thread] Canon: ISO look val: 112 [10/15/18 20:49:30.544][DEBUG] [Camera Thread] Canon: Exposing for 5 seconds... [10/15/18 20:49:30.695][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 40A, parameter 0, [10/15/18 20:49:30.695][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property B, parameter 0, [10/15/18 20:49:30.695][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 40A, parameter 0, [10/15/18 20:49:30.695][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property B, parameter 0, [10/15/18 20:49:30.695][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 40A, parameter 0, [10/15/18 20:49:30.695][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 40A, parameter 0, [10/15/18 20:49:30.695][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 40A, parameter 0, [10/15/18 20:49:30.695][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 402, parameter 0, [10/15/18 20:49:30.695][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 402, parameter 0, [10/15/18 20:49:30.695][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 100, parameter 0, [10/15/18 20:49:30.917][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 40A, parameter 0, [10/15/18 20:49:35.944][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 406, parameter 0, [10/15/18 20:49:35.944][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 405, parameter 0, [10/15/18 20:49:35.944][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 402, parameter 0, [10/15/18 20:49:35.944][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 102, property 406, parameter 0, [10/15/18 20:49:36.382][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 406, parameter 0, [10/15/18 20:49:36.382][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 405, parameter 0, [10/15/18 20:49:36.382][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 402, parameter 0, [10/15/18 20:49:36.382][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 102, property 406, parameter 0, [10/15/18 20:49:36.597][DEBUG] [Canon Download Thread] Canon: Directory Item Requested Transfer [10/15/18 20:49:36.597][DEBUG] [Canon Download Thread] -- Event delay: 0 ms [10/15/18 20:49:38.706][DEBUG] [Canon Download Thread] --------------> getCapturedItem time: 2108 ms [10/15/18 20:49:38.725][DEBUG] [Camera Thread] Canon Camera: disposing old preview memory... [10/15/18 20:49:38.730][DEBUG] [Camera Thread] Canon Camera: Saving FITS file... [10/15/18 20:49:38.730][DEBUG] [Camera Thread] Created full file name (file does not exist): C:\Users\rcowley\AppData\Local\SequenceGenerator\Temp\plate_solve_image.fit [10/15/18 20:49:38.730][DEBUG] [Camera Thread] Extracting RAW data... [10/15/18 20:49:38.730][DEBUG] [Camera Thread] RAW arg data: -D -T -4 -W -v -t 0 "C:\Users\rcowley\AppData\Local\SequenceGenerator\Temp\cannonRaw-0.tmp" [10/15/18 20:49:38.733][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 122880 [10/15/18 20:49:39.234][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 10444800 [10/15/18 20:49:39.735][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 17903616 [10/15/18 20:49:40.236][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 25169920 [10/15/18 20:49:40.737][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 32296960 [10/15/18 20:49:41.242][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 39219200 [10/15/18 20:49:41.746][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 40394752 [10/15/18 20:49:42.247][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 41582592 [10/15/18 20:49:42.748][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 42729472 [10/15/18 20:49:43.249][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 43945984 [10/15/18 20:49:43.752][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 108912640 [10/15/18 20:49:44.260][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 165130240 [10/15/18 20:49:44.761][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 165130240 [10/15/18 20:49:45.262][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 154497024 [10/15/18 20:49:45.762][DEBUG] [Camera Thread] Done extracting RAW data, checking for success... [10/15/18 20:49:45.762][DEBUG] [Camera Thread] RAW data extraction is good... [10/15/18 20:49:45.762][DEBUG] [Camera Thread] Extracting bitmap data from RAW (tiff)... [10/15/18 20:49:45.899][DEBUG] [Camera Thread] Creating bitmap (5496,3670)... [10/15/18 20:49:45.932][DEBUG] [Camera Thread] Locking bitmap for write [10/15/18 20:49:45.932][DEBUG] [Camera Thread] RAW bith depth: 16 [10/15/18 20:49:46.079][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [10/15/18 20:49:46.079][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [10/15/18 20:49:46.079][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [10/15/18 20:49:46.079][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [10/15/18 20:49:46.632][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [10/15/18 20:49:46.632][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [10/15/18 20:49:46.632][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [10/15/18 20:49:46.632][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/15/18 20:49:46.632][DEBUG] [Camera Thread] Display image preview using asynch task... [10/15/18 20:49:49.688][DEBUG] [Camera Thread] ----> Canon IO time: 10964 ms [10/15/18 20:49:49.688][DEBUG] [Camera Thread] SGM_CAMERA_PLATE_SOLVER_CAPTURE complete... [10/15/18 20:49:49.828][DEBUG] [Telescope Thread] Auto center verification frame complete. Plate solving image C:\Users\rcowley\AppData\Local\SequenceGenerator\Temp\plate_solve_image.fit [10/15/18 20:49:49.829][DEBUG] [Telescope Thread] ************* SOLVE HINTS **************** [10/15/18 20:49:49.829][DEBUG] [Telescope Thread] SOLVER: PlateSolve2 [10/15/18 20:49:49.829][DEBUG] [Telescope Thread] BLIND: False [10/15/18 20:49:49.829][DEBUG] [Telescope Thread] METHOD: Max Regions [10/15/18 20:49:49.829][DEBUG] [Telescope Thread] RA: 21.0304916113015 [10/15/18 20:49:49.829][DEBUG] [Telescope Thread] DEC: 68.1595204071724 [10/15/18 20:49:49.829][DEBUG] [Telescope Thread] SCALE: 0.6714 [10/15/18 20:49:49.829][DEBUG] [Telescope Thread] ****************************************** [10/15/18 20:49:49.892][DEBUG] [Telescope Thread] PlateSove2 Param: RA (RAD) - 5.50576982895388 [10/15/18 20:49:49.892][DEBUG] [Telescope Thread] PlateSove2 Param: DEC (RAD) - 1.18960804768542 [10/15/18 20:49:49.892][DEBUG] [Telescope Thread] PlateSove2 Param: Width - 5496 [10/15/18 20:49:49.892][DEBUG] [Telescope Thread] PlateSove2 Param: Height - 3670 [10/15/18 20:49:49.892][DEBUG] [Telescope Thread] PlateSolve2 Command Line: [10/15/18 20:49:49.892][DEBUG] [Telescope Thread] C:\Users\rcowley\AppData\Local\SequenceGenerator\\PlateSolve2.exe 5.50576982895388,1.18960804768542,0.01788969464611,0.01194599333174,3000,C:\Users\rcowley\AppData\Local\SequenceGenerator\\Temp\psXSolve_2.fit [10/15/18 20:49:59.401][DEBUG] [Telescope Thread] *********** SUCCESSFUL SOLVE ************* [10/15/18 20:49:59.401][DEBUG] [Telescope Thread] SOLVER: False [10/15/18 20:49:59.401][DEBUG] [Telescope Thread] SUCCESS: True [10/15/18 20:49:59.401][DEBUG] [Telescope Thread] CONF: 107 [10/15/18 20:49:59.401][DEBUG] [Telescope Thread] BLIND: False [10/15/18 20:49:59.401][DEBUG] [Telescope Thread] RA: 21.0303951674019 [10/15/18 20:49:59.401][DEBUG] [Telescope Thread] DEC: 68.1585642732277 [10/15/18 20:49:59.401][DEBUG] [Telescope Thread] SCALE: 0.67172 [10/15/18 20:49:59.401][DEBUG] [Telescope Thread] FLIPPED: False [10/15/18 20:49:59.401][DEBUG] [Telescope Thread] ANGLE (EON): 268.67 [10/15/18 20:49:59.401][DEBUG] [Telescope Thread] MSG: Valid solve. [10/15/18 20:49:59.401][DEBUG] [Telescope Thread] ****************************************** [10/15/18 20:49:59.401][DEBUG] [Telescope Thread] Checking to see if solve might be bad... [10/15/18 20:49:59.402][DEBUG] [Telescope Thread] Solve appears to be good... [10/15/18 20:49:59.402][DEBUG] [Telescope Thread] Auto center validation frame solved successfully... [10/15/18 20:49:59.402][DEBUG] [Telescope Thread] Telescope: Syncing to J2000 RA: 21.0303951674019 Dec: 68.1585642732277 [10/15/18 20:49:59.402][DEBUG] [Telescope Thread] Telescope: Sync behavior set to "Sync"... [10/15/18 20:49:59.402][DEBUG] [Telescope Thread] Telescope: Sync Passed in J2000 but mount requires JNOW, converting... [10/15/18 20:49:59.403][DEBUG] [Telescope Thread] Telescope: Syncing to JNOW RA: 21.0346343388634 Dec: 68.2381810079964 [10/15/18 20:49:59.617][DEBUG] [Telescope Thread] Attempting to write fits header info for C:\Users\rcowley\AppData\Local\SequenceGenerator\Temp\plate_solve_image.fit [10/15/18 20:49:59.617][DEBUG] [Telescope Thread] Opening fits file... [10/15/18 20:49:59.618][DEBUG] [Telescope Thread] Successfully opened fits file... [10/15/18 20:49:59.618][DEBUG] [Telescope Thread] Writing fits headers... [10/15/18 20:49:59.618][DEBUG] [Telescope Thread] Closing fits file [10/15/18 20:49:59.618][DEBUG] [Telescope Thread] Could not sync the rotator to 268.67 degrees (not connected)! [10/15/18 20:49:59.618][DEBUG] [Telescope Thread] Auto center complete... [10/15/18 20:49:59.691][DEBUG] [Center Scope Thread] Automatic Auto Center Success - Total Error <= Allowable error: 6.3 <= 30.0 [10/15/18 20:49:59.691][DEBUG] [Center Scope Thread] RA Error: 3.7 [10/15/18 20:49:59.691][DEBUG] [Center Scope Thread] DEC Error: 5.1 [10/15/18 20:50:00.369][DEBUG] [Sequence Thread] DoEventGroupChange: Slewed or Centered, attempting to start guiding. [10/15/18 20:50:00.378][DEBUG] [Sequence Thread] PHD2: Auto Resume [10/15/18 20:50:00.378][DEBUG] [Sequence Thread] Checking PHD2 state... [10/15/18 20:50:00.378][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/15/18 20:50:00.378][DEBUG] [Sequence Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/15/18 20:50:00.479][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/15/18 20:50:01.479][DEBUG] [Sequence Thread] Checking PHD2 state... [10/15/18 20:50:01.479][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/15/18 20:50:01.479][DEBUG] [Sequence Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/15/18 20:50:01.579][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/15/18 20:50:02.579][DEBUG] [Sequence Thread] Checking PHD2 state... [10/15/18 20:50:02.579][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/15/18 20:50:02.579][DEBUG] [Sequence Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/15/18 20:50:02.679][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/15/18 20:50:03.679][DEBUG] [Sequence Thread] Checking PHD2 state... [10/15/18 20:50:03.679][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/15/18 20:50:03.679][DEBUG] [Sequence Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/15/18 20:50:03.779][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/15/18 20:50:04.779][DEBUG] [Sequence Thread] Checking PHD2 state... [10/15/18 20:50:04.779][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/15/18 20:50:04.779][DEBUG] [Sequence Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/15/18 20:50:04.879][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/15/18 20:50:04.879][DEBUG] [Sequence Thread] {"method": "guide", "params": [{"pixels": 0.5, "time": 3, "timeout": 600}, false ], "id": 1003} [10/15/18 20:50:04.879][DEBUG] [Sequence Thread] Sending to PHD2: {"method": "guide", "params": [{"pixels": 0.5, "time": 3, "timeout": 600}, false ], "id": 1003} [10/15/18 20:50:04.880][DEBUG] [Sequence Thread] PHD2: Attempting to start guiding, waiting for PHD2 settle done message... [10/15/18 20:50:11.841][DEBUG] [PHD2 Listener Thread] Unknown object from PHD2: {"Event":"SettleBegin","Timestamp":1539658211.820,"Host":"IT-LOANER-LT","Inst":1} [10/15/18 20:50:17.602][DEBUG] [PHD2 Listener Thread] PHD2: Settle done received: GOOD [10/15/18 20:50:17.894][DEBUG] [Sequence Thread] Checking PHD2 state... [10/15/18 20:50:17.894][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/15/18 20:50:17.894][DEBUG] [Sequence Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/15/18 20:50:17.994][DEBUG] [Sequence Thread] PHD2 GetPhdStatus - Post-Wait: Guiding [10/15/18 20:50:17.994][DEBUG] [Sequence Thread] PHD2 settle successful... [10/15/18 20:50:18.049][DEBUG] [Sequence Thread] DoEventGroupChange: Complete [10/15/18 20:50:18.050][DEBUG] [Sequence Thread] Attempting to find next event... [10/15/18 20:50:18.050][DEBUG] [Sequence Thread] Current event[0] frame count: 0/30... [10/15/18 20:50:18.052][DEBUG] [Sequence Thread] Looking at event[0]... [10/15/18 20:50:18.052][DEBUG] [Sequence Thread] Found event event[0] with remaining frames. [10/15/18 20:50:18.052][DEBUG] [Sequence Thread] Event[0] frame count: 0/30... [10/15/18 20:50:18.052][DEBUG] [Sequence Thread] Getting first event (0)... [10/15/18 20:50:18.052][DEBUG] [Main Thread] PopulateDataModel: Transferring view to the data model... [10/15/18 20:50:18.063][DEBUG] [Sequence Thread] Running pre event... [10/15/18 20:50:18.079][DEBUG] [Sequence Thread] Running capture event... [10/15/18 20:50:18.085][DEBUG] [Sequence Thread] ------------- Starting capture frame for event[0] ------------- [10/15/18 20:50:18.088][DEBUG] [Sequence Thread] Sending commands... [10/15/18 20:50:18.090][DEBUG] [Sequence Thread] SetFlatBox: Frame Type is Light [10/15/18 20:50:18.092][DEBUG] [Sequence Thread] EventMarker 2 - t:iris nebula (0); e:0; f:0; o: [10/15/18 20:50:18.094][DEBUG] [Sequence Thread] Checking if Meridian Flip is needed [10/15/18 20:50:18.094][DEBUG] [Sequence Thread] Telescope is on the West side of the mount [10/15/18 20:50:18.094][DEBUG] [Sequence Thread] Presenting wait for meridian dialog... [10/15/18 20:51:44.932][DEBUG] [Sequence Thread] Meridian Flip needed, Hour Angle >= Degrees Past To Flip: 0.018840540144005 >= 0 [10/15/18 20:51:44.932][DEBUG] [Sequence Thread] Running blocking meridian flip... [10/15/18 20:51:45.034][DEBUG] [Pier Flip Thread] Meridian Flip: Starting Meridian Flip Procedure [10/15/18 20:51:45.036][DEBUG] [Pier Flip Thread] Meridian Flip: Calling SGM_TELESCOPE_SOLVE - No Sync [10/15/18 20:51:45.037][DEBUG] [Pier Flip Thread] Meridian flip: Waiting for scope solve to complete... [10/15/18 20:51:45.038][DEBUG] [Telescope Thread] SGM_TELESCOPE_SOLVE message received... [10/15/18 20:51:45.038][DEBUG] [Telescope Thread] Telescope solve with plate solver PlateSolve2... [10/15/18 20:51:45.038][DEBUG] [Telescope Thread] Plate solving scope frame... [10/15/18 20:51:45.038][DEBUG] [Telescope Thread] Created full file name (file does not exist): C:\Users\rcowley\AppData\Local\SequenceGenerator\Temp\plate_solve_image.fit [10/15/18 20:51:45.048][DEBUG] [Camera Thread] SGM_CAMERA_PLATE_SOLVER_CAPTURE message received... [10/15/18 20:51:45.048][DEBUG] [Camera Thread] Collecting FITs headers for plate solve frame... [10/15/18 20:51:45.048][DEBUG] [Camera Thread] Collecting FITs headers for plate solve frame... [10/15/18 20:51:45.086][DEBUG] [Camera Thread] Canon: Setting ISO to: 3200 [10/15/18 20:51:45.086][DEBUG] [Camera Thread] Canon: ISO look val: 112 [10/15/18 20:51:45.137][DEBUG] [Camera Thread] Canon: Exposing for 5 seconds... [10/15/18 20:51:45.227][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 40A, parameter 0, [10/15/18 20:51:45.227][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property B, parameter 0, [10/15/18 20:51:45.227][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 40A, parameter 0, [10/15/18 20:51:45.227][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property B, parameter 0, [10/15/18 20:51:45.227][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 40A, parameter 0, [10/15/18 20:51:45.227][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 40A, parameter 0, [10/15/18 20:51:45.227][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 40A, parameter 0, [10/15/18 20:51:45.227][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 402, parameter 0, [10/15/18 20:51:45.227][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 402, parameter 0, [10/15/18 20:51:45.227][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 100, parameter 0, [10/15/18 20:51:45.447][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 40A, parameter 0, [10/15/18 20:51:50.476][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 406, parameter 0, [10/15/18 20:51:50.476][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 405, parameter 0, [10/15/18 20:51:50.476][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 402, parameter 0, [10/15/18 20:51:50.476][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 102, property 406, parameter 0, [10/15/18 20:51:50.915][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 406, parameter 0, [10/15/18 20:51:50.915][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 405, parameter 0, [10/15/18 20:51:50.915][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 101, property 402, parameter 0, [10/15/18 20:51:50.915][DEBUG] [Main Thread] Canon: PropertyEventHandler: event 102, property 406, parameter 0, [10/15/18 20:51:51.129][DEBUG] [Canon Download Thread] Canon: Directory Item Requested Transfer [10/15/18 20:51:51.129][DEBUG] [Canon Download Thread] -- Event delay: 0 ms [10/15/18 20:51:53.374][DEBUG] [Canon Download Thread] --------------> getCapturedItem time: 2245 ms [10/15/18 20:51:53.427][DEBUG] [Camera Thread] Canon Camera: disposing old preview memory... [10/15/18 20:51:53.432][DEBUG] [Camera Thread] Canon Camera: Saving FITS file... [10/15/18 20:51:53.433][DEBUG] [Camera Thread] Created full file name (file does not exist): C:\Users\rcowley\AppData\Local\SequenceGenerator\Temp\plate_solve_image.fit [10/15/18 20:51:53.433][DEBUG] [Camera Thread] Extracting RAW data... [10/15/18 20:51:53.433][DEBUG] [Camera Thread] RAW arg data: -D -T -4 -W -v -t 0 "C:\Users\rcowley\AppData\Local\SequenceGenerator\Temp\cannonRaw-0.tmp" [10/15/18 20:51:53.436][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 122880 [10/15/18 20:51:53.937][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 10178560 [10/15/18 20:51:54.443][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 17801216 [10/15/18 20:51:54.950][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 25153536 [10/15/18 20:51:55.451][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 32731136 [10/15/18 20:51:55.952][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 39288832 [10/15/18 20:51:56.455][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 40468480 [10/15/18 20:51:56.956][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 41680896 [10/15/18 20:51:57.457][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 42885120 [10/15/18 20:51:57.958][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 44023808 [10/15/18 20:51:58.459][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 118353920 [10/15/18 20:51:58.960][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 165126144 [10/15/18 20:51:59.461][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 165126144 [10/15/18 20:51:59.962][DEBUG] [Camera Thread] DCRAW Physical Memory Usage: 71786496 [10/15/18 20:52:00.462][DEBUG] [Camera Thread] Done extracting RAW data, checking for success... [10/15/18 20:52:00.462][DEBUG] [Camera Thread] RAW data extraction is good... [10/15/18 20:52:00.463][DEBUG] [Camera Thread] Extracting bitmap data from RAW (tiff)... [10/15/18 20:52:00.599][DEBUG] [Camera Thread] Creating bitmap (5496,3670)... [10/15/18 20:52:00.633][DEBUG] [Camera Thread] Locking bitmap for write [10/15/18 20:52:00.633][DEBUG] [Camera Thread] RAW bith depth: 16 [10/15/18 20:52:00.775][DEBUG] [Camera Thread] SaveFileBmp16: Locking bitmap for disk write... [10/15/18 20:52:00.775][DEBUG] [Camera Thread] SaveFileBmp16: Opening file stream... [10/15/18 20:52:00.775][DEBUG] [Camera Thread] SaveFileBmp16: Writing FITS headers... [10/15/18 20:52:00.776][DEBUG] [Camera Thread] SaveFileBmp16: Traversing and persisting bitmap data... [10/15/18 20:52:01.324][DEBUG] [Camera Thread] SaveFileBmp16: FITS block fill... [10/15/18 20:52:01.324][DEBUG] [Camera Thread] SaveFileBmp16: Unlocking bitmap... [10/15/18 20:52:01.324][DEBUG] [Camera Thread] SaveFileBmp16: Closing file stream [10/15/18 20:52:01.325][DEBUG] [Camera Thread] Internal Interface: Set Preview... [10/15/18 20:52:01.325][DEBUG] [Camera Thread] Display image preview using asynch task... [10/15/18 20:52:06.791][DEBUG] [Camera Thread] ----> Canon IO time: 13363 ms [10/15/18 20:52:06.791][DEBUG] [Camera Thread] SGM_CAMERA_PLATE_SOLVER_CAPTURE complete... [10/15/18 20:52:06.857][DEBUG] [Telescope Thread] ************* SOLVE HINTS **************** [10/15/18 20:52:06.857][DEBUG] [Telescope Thread] SOLVER: PlateSolve2 [10/15/18 20:52:06.857][DEBUG] [Telescope Thread] BLIND: False [10/15/18 20:52:06.857][DEBUG] [Telescope Thread] METHOD: Max Regions [10/15/18 20:52:06.857][DEBUG] [Telescope Thread] RA: 21.0303086211547 [10/15/18 20:52:06.857][DEBUG] [Telescope Thread] DEC: 68.1589875704296 [10/15/18 20:52:06.857][DEBUG] [Telescope Thread] SCALE: 0.67172 [10/15/18 20:52:06.857][DEBUG] [Telescope Thread] ****************************************** [10/15/18 20:52:06.935][DEBUG] [Telescope Thread] FitsFileHeaderData: Angle - 0 [10/15/18 20:52:06.935][DEBUG] [Telescope Thread] FitsFileHeaderData: Scale - 0 [10/15/18 20:52:06.935][DEBUG] [Telescope Thread] FitsFileHeaderData: RA - 21.0303086211547 [10/15/18 20:52:06.935][DEBUG] [Telescope Thread] FitsFileHeaderData: DEC - 68.1589875704296 [10/15/18 20:52:06.936][DEBUG] [Telescope Thread] PlateSove2 Param: RA (RAD) - 5.50572192224547 [10/15/18 20:52:06.936][DEBUG] [Telescope Thread] PlateSove2 Param: DEC (RAD) - 1.18959874792989 [10/15/18 20:52:06.936][DEBUG] [Telescope Thread] PlateSove2 Param: Width - 5496 [10/15/18 20:52:06.936][DEBUG] [Telescope Thread] PlateSove2 Param: Height - 3670 [10/15/18 20:52:06.936][DEBUG] [Telescope Thread] PlateSolve2 Command Line: [10/15/18 20:52:06.936][DEBUG] [Telescope Thread] C:\Users\rcowley\AppData\Local\SequenceGenerator\\PlateSolve2.exe 5.50572192224547,1.18959874792989,0.01789822116128,0.01195168698361,3000,C:\Users\rcowley\AppData\Local\SequenceGenerator\\Temp\psXSolve_3.fit [10/15/18 20:52:16.939][DEBUG] [Telescope Thread] *********** SUCCESSFUL SOLVE ************* [10/15/18 20:52:16.939][DEBUG] [Telescope Thread] SOLVER: False [10/15/18 20:52:16.939][DEBUG] [Telescope Thread] SUCCESS: True [10/15/18 20:52:16.939][DEBUG] [Telescope Thread] CONF: 110 [10/15/18 20:52:16.939][DEBUG] [Telescope Thread] BLIND: False [10/15/18 20:52:16.939][DEBUG] [Telescope Thread] RA: 21.0300248838775 [10/15/18 20:52:16.939][DEBUG] [Telescope Thread] DEC: 68.1589166422717 [10/15/18 20:52:16.939][DEBUG] [Telescope Thread] SCALE: 0.67167 [10/15/18 20:52:16.939][DEBUG] [Telescope Thread] FLIPPED: False [10/15/18 20:52:16.939][DEBUG] [Telescope Thread] ANGLE (EON): 268.67 [10/15/18 20:52:16.939][DEBUG] [Telescope Thread] MSG: Valid solve. [10/15/18 20:52:16.939][DEBUG] [Telescope Thread] ****************************************** [10/15/18 20:52:16.964][DEBUG] [Telescope Thread] Scope solve complete... [10/15/18 20:52:16.964][DEBUG] [Telescope Thread] SGM_TELESCOPE_SOLVE message complete... [10/15/18 20:52:17.008][DEBUG] [Pier Flip Thread] Meridian flip: Scope solve complete... [10/15/18 20:52:17.008][DEBUG] [Pier Flip Thread] Meridian Flip: Solve was Successful [10/15/18 20:52:17.008][DEBUG] [Pier Flip Thread] Meridian Flip: Stopping the Auto Guider [10/15/18 20:52:17.008][DEBUG] [Pier Flip Thread] Attempting to stop PHD2 guiding... [10/15/18 20:52:17.008][DEBUG] [Pier Flip Thread] Checking PHD2 state... [10/15/18 20:52:17.008][DEBUG] [Pier Flip Thread] PHD2 GetPhdStatus - Pre-Wait : Guiding [10/15/18 20:52:17.008][DEBUG] [Pier Flip Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/15/18 20:52:17.108][DEBUG] [Pier Flip Thread] PHD2 GetPhdStatus - Post-Wait: Guiding [10/15/18 20:52:17.108][DEBUG] [Pier Flip Thread] Sending to PHD2: {"method": "stop_capture", "id": 1004} [10/15/18 20:52:17.108][DEBUG] [Pier Flip Thread] Checking PHD2 state... [10/15/18 20:52:17.108][DEBUG] [Pier Flip Thread] PHD2 GetPhdStatus - Pre-Wait : Guiding [10/15/18 20:52:17.108][DEBUG] [Pier Flip Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/15/18 20:52:17.208][DEBUG] [Pier Flip Thread] PHD2 GetPhdStatus - Post-Wait: Guiding [10/15/18 20:52:18.208][DEBUG] [Pier Flip Thread] Checking PHD2 state... [10/15/18 20:52:18.208][DEBUG] [Pier Flip Thread] PHD2 GetPhdStatus - Pre-Wait : Guiding [10/15/18 20:52:18.208][DEBUG] [Pier Flip Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/15/18 20:52:18.308][DEBUG] [Pier Flip Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/15/18 20:52:18.308][DEBUG] [Pier Flip Thread] PHD2: Successfully stopped PHD2... [10/15/18 20:52:18.308][DEBUG] [Pier Flip Thread] Meridian Flip: Sending Telescope command to execute meridian flip [10/15/18 20:52:18.317][DEBUG] [Telescope Thread] ASCOM Telescope: Pier side is West [10/15/18 20:52:18.318][DEBUG] [Telescope Thread] ASCOM Telescope: attempting pier flip using slew [10/15/18 20:52:18.320][DEBUG] [Telescope Thread] Telescope: Slewing to J2000 RA: 21.0303171689049 (21h01m49.14s) Dec: 68.1593929561072 (68°09'33.81") [10/15/18 20:52:18.320][DEBUG] [Telescope Thread] Telescope: Slew received J2000 coordinates, mount requires JNOW, converting... [10/15/18 20:52:18.320][DEBUG] [Telescope Thread] Telescope: Slewing to JNOW RA: 21.0345555555556 Dec: 68.2390083333333 [10/15/18 20:52:20.776][DEBUG] [Telescope Thread] Scope reports it is done with synchronous slew, verifying... [10/15/18 20:52:20.776][DEBUG] [Telescope Thread] Telescope: Slewing has completed [10/15/18 20:52:20.776][DEBUG] [Telescope Thread] Telescope: Settling for 2 seconds [10/15/18 20:52:22.776][DEBUG] [Telescope Thread] Telescope: Settling has completed [10/15/18 20:52:23.276][DEBUG] [Telescope Thread] ASCOM Telescope: Failed to flip because starting pier side and ending pier side are the same! [10/15/18 20:52:23.488][DEBUG] [Pier Flip Thread] Meridian Flip: Telescope command to meridian flip has completed [10/15/18 20:52:23.488][DEBUG] [Pier Flip Thread] Meridian Flip: Telescope failed to perform meridian flip [10/15/18 20:52:28.471][DEBUG] [Pier Flip Thread] Meridian Flip: Procedure complete [10/15/18 20:53:18.333][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [10/15/18 20:53:18.333][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [10/15/18 20:53:18.333][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/15/18 20:53:18.333][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/15/18 20:53:18.333][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/15/18 20:53:25.530][DEBUG] [Sequence Thread] Blocking Pier Flip: Failed to meridian flip, aborting sequence (False) [10/15/18 20:53:25.532][DEBUG] [Sequence Thread] Adding sequence level notification: Failed to meridian flip, aborting sequence. [10/15/18 20:53:25.535][DEBUG] [Sequence Thread] Sending Notification: Error - Failed to meridian flip, aborting sequence. [10/15/18 20:53:25.535][DEBUG] [Sequence Thread] EventMarker 3 - t:iris nebula (0); e:0; f:0; o:* aborted * [10/15/18 20:53:25.536][DEBUG] [Sequence Thread] EventMarker 10 - t:iris nebula (0); e:0; f:0; o:* aborted * [10/15/18 20:53:25.588][DEBUG] [Sequence Thread] Run event requested sequence abort... [10/15/18 20:53:25.590][DEBUG] [Sequence Thread] ********* Run post sequence ********* [10/15/18 20:53:25.595][DEBUG] [Sequence Thread] SGPro capture cal frame mode is OFF... [10/15/18 20:53:25.598][DEBUG] [Sequence Thread] Clearing timed monitoring events... [10/15/18 20:53:25.598][DEBUG] [Sequence Thread] Checking RunEndOfSequenceEquipmentOptions, force = False [10/15/18 20:53:25.598][DEBUG] [Sequence Thread] Sequence was aborted, skipping end of sequence options... [10/15/18 20:53:25.604][DEBUG] [Main Thread] Aborting sequence timer thread... [10/15/18 20:53:25.604][DEBUG] [Main Thread] Sending sequence end notification... [10/15/18 20:53:25.604][DEBUG] [Main Thread] Resetting UI elements... [10/15/18 20:53:25.604][DEBUG] [Main Thread] Checking if sequence has work left... [10/15/18 20:53:25.604][DEBUG] [Main Thread] Work left... [10/15/18 20:53:25.604][DEBUG] [Main Thread] Updating big status... [10/15/18 20:53:25.605][DEBUG] [Main Thread] Enabling menu items... [10/15/18 20:53:25.640][DEBUG] [Main Thread] Set target icons... [10/15/18 20:53:25.640][DEBUG] [Main Thread] Removing camera protection... [10/15/18 20:53:25.669][DEBUG] [Main Thread] Monitoring system shutdown... [10/15/18 20:53:25.669][DEBUG] [Main Thread] SequenceEnds complete... [10/15/18 20:53:25.669][DEBUG] [Sequence Thread] Restoring system standby state... [10/15/18 20:54:18.441][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [10/15/18 20:54:18.441][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [10/15/18 20:54:18.442][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/15/18 20:54:18.442][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/15/18 20:54:18.442][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped [10/15/18 20:55:18.542][DEBUG] [PHD2 Listener Thread] PHD2 - No messages received from PHD2 for 1 minute, checking socket with status... [10/15/18 20:55:18.542][DEBUG] [PHD2 Listener Thread] Checking PHD2 state... [10/15/18 20:55:18.542][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Pre-Wait : Stopped [10/15/18 20:55:18.542][DEBUG] [PHD2 Listener Thread] Sending to PHD2: {"method": "get_app_state", "id": 1001} [10/15/18 20:55:18.542][DEBUG] [PHD2 Listener Thread] PHD2 GetPhdStatus - Post-Wait: Stopped