I did it!!!

Keep up to date with EG related information here.
User avatar
Luca Brasi
Experienced User
Posts: 262
Joined: Sat Oct 11, 2008 12:39 pm

Re: I did it!!!

Post by Luca Brasi » Thu Aug 03, 2017 7:12 am

I have the latest wip installed on one of my machines (Win10 x64). Let me know if you want me to cross check anything. Maybe we can find the culprit that way.
If you sent me the tree I can have a look what happens on my machine with it after setting up the respective plugins. (I couldn't quickly ascertain if this is only with the existing tree or not)
Win10 x64 Prof. / Eventghost latest / MCE Plugin / MCE RC6 Receiver / Logitech Harmony Hub / MediaPortal / kodi

User avatar
kgschlosser
Site Admin
Posts: 5498
Joined: Fri Jun 05, 2015 5:43 am
Location: Rocky Mountains, Colorado USA

Re: I did it!!!

Post by kgschlosser » Thu Aug 03, 2017 3:14 pm

Luca Brasi wrote:I have the latest wip installed on one of my machines (Win10 x64). Let me know if you want me to cross check anything. Maybe we can find the culprit that way.
If you sent me the tree I can have a look what happens on my machine with it after setting up the respective plugins. (I couldn't quickly ascertain if this is only with the existing tree or not)


Well Since I am unable to complete finding this error and trying to locate if this is a tree error or a plugin error or not. If you wouldn't mind Luca Brasi use an empty tree and put your computer to sleep. then wake it up and see if your computer hangs when you wake it up. let me know the results. I need to sort out where the problem is coming from. if it doesn't hang then it would have to be something tree/plugin related in which I am not going to be able to figure out until someone else comes across the problem.

This would be a big help and greatly appreciated...

Thanks
K
If you like the work I have been doing then feel free to Image

User avatar
Luca Brasi
Experienced User
Posts: 262
Joined: Sat Oct 11, 2008 12:39 pm

Re: I did it!!!

Post by Luca Brasi » Thu Aug 03, 2017 3:37 pm

I'll test right away with my current sys and my current settings.

@DIZ
please give me a couple of infos so I can test properly. Forgive me if I could find some of those infos earlier in the thread but I think it would be good to start from a complete list in order to do some structured debugging:
- Will the crash occur with an empty tree/config? If not please send me your egtree or at least a stripped down version which includes all used plugins/autostart.
- Does your sys go to sleep on itself when timing out, by some other trigger or do you put it into sleep manually?
- How does it come up again when the crash occurs? Manually, timed task or WOL?
- Do you use standby or hibernate?
- Which acpi state is set in your bios/uefi for sleep? S3/S4?
- Do you have quick start (hybrid sleep) enabled in power saving?
- Do you run Eg as Admin?
Win10 x64 Prof. / Eventghost latest / MCE Plugin / MCE RC6 Receiver / Logitech Harmony Hub / MediaPortal / kodi

User avatar
Luca Brasi
Experienced User
Posts: 262
Joined: Sat Oct 11, 2008 12:39 pm

Re: I did it!!!

Post by Luca Brasi » Fri Aug 04, 2017 5:49 am

Hi,

I ran into some problems when trying to test. As soon as I start eg with an empty tree it will hang on start. This is on two machines.
Don't know if this is related to the problem above.
07:45:40: 0 MainThread: ----------------------------------------
07:45:40: 0 MainThread: EventGhost started
07:45:40: 0 MainThread: ----------------------------------------
07:45:40: 0 MainThread: EventGhost Version: WIP-2017.07.28-16.16.57
07:45:40: 0 MainThread: Machine type: AMD64
07:45:40: 0 MainThread: Processor: Intel64 Family 6 Model 60 Stepping 3, GenuineIntel
07:45:40: 0 MainThread: Architecture: ('32bit', 'WindowsPE')
07:45:40: 0 MainThread: Python: 2.7.12 CPython ('default', 'Oct 31 2016 18:42:08') [MSC v.1500 32 bit (Intel)]
07:45:40: 0 MainThread: ----------------------------------------
07:45:40: 0 MainThread: PluginManager.ScanAllPlugins :0.16903431205984865
07:45:40: 0 MainThread: SendKeysParser.__init__()
07:45:40: 0 EventGhost.Pipe.Thread: Named Pipe: Creating security descriptor
07:45:40: 0 EventGhost.Pipe.Thread: Named Pipe: Creating pipe
07:45:40: 0 MainMessageReceiver: MainMessageReceiver.__MainLoop()
07:45:40: 0 MainMessageReceiver: MainMessageReceiver.Setup()
07:45:40: 0 MainThread: Document.ShowFrame()
07:45:40: 0 MainThread: Log.GetData()
07:45:41: 0 MainThread: Document.ShowFrame => None
07:45:41: 0 ActionThread: ActionThread.__MainLoop()
07:45:41: 0 EventThread: EventThread.__MainLoop()
07:45:41: 0 MainThread: Assignment to new attribute scratch
07:45:41: 0 EventThread: EventThread.StartSession(filename=u'D:\\Programmdaten\\Eventghost\\20170803_threading_wip_crash.egtree')
07:45:41: 0 ActionThread: ActionThread.StartSession(filename=u'D:\\Programmdaten\\Eventghost\\20170803_threading_wip_crash.egtree')
07:45:41: 0 Thread-1: SessionChangeNotifier.Register()
07:45:41: 0 MouseThread: MouseThread.run()
07:45:41: 0 ActionThread: Document.Load(filePath=u'D:\\Programmdaten\\Eventghost\\20170803_threading_wip_crash.egtree')
07:45:41: 0 ActionThread: StartLoad(cls=<class 'eg.Classes.TreeLink.TreeLink'>)
07:45:41: 0 ActionThread: Document.SetExpandState(expanded=set([0, 1]))
07:45:41: 0 ActionThread: XML loaded in 0.003606 seconds.
07:45:41: 1 MainThread: OSDFrame.__init__(parent=None)
screen_mmstation_20170804_07h47m04s.jpg
This is with a fresh install and after deleting everything from the example tree.
I'll reinstall again because I can't get it to run again.
Win10 x64 Prof. / Eventghost latest / MCE Plugin / MCE RC6 Receiver / Logitech Harmony Hub / MediaPortal / kodi

User avatar
Luca Brasi
Experienced User
Posts: 262
Joined: Sat Oct 11, 2008 12:39 pm

Re: I did it!!!

Post by Luca Brasi » Fri Aug 04, 2017 6:05 am

Ok, did the fresh install.
Eg started properly. I did remove the x10 plugin from the example tree and restarted - fine.
Now I started eg in debug mode and got the crash again.
This is the log file:

Code: Select all

07:53:05: 0 MainThread: ----------------------------------------
07:53:05: 0 MainThread:         EventGhost started
07:53:05: 0 MainThread: ----------------------------------------
07:53:05: 0 MainThread: EventGhost Version: WIP-2017.07.28-16.16.57
07:53:05: 0 MainThread: Machine type: AMD64
07:53:05: 0 MainThread: Processor: Intel64 Family 6 Model 60 Stepping 3, GenuineIntel
07:53:05: 0 MainThread: Architecture: ('32bit', 'WindowsPE')
07:53:05: 0 MainThread: Python:  2.7.12 CPython ('default', 'Oct 31 2016 18:42:08') [MSC v.1500 32 bit (Intel)]
07:53:05: 0 MainThread: ----------------------------------------
07:53:05: 0 MainThread: PluginManager.ScanAllPlugins :0.6431289512053485
07:53:05: 0 MainThread: SendKeysParser.__init__()
07:53:05: 0 EventGhost.Pipe.Thread: Named Pipe: Creating security descriptor
07:53:05: 0 EventGhost.Pipe.Thread: Named Pipe: Creating pipe
07:53:05: 0 MainMessageReceiver: MainMessageReceiver.__MainLoop()
07:53:05: 0 MainMessageReceiver: MainMessageReceiver.Setup()
07:53:05: 0 MainThread: Document.ShowFrame()
07:53:05: 0 MainThread: Log.GetData()
07:53:05: 0 MainThread: Document.ShowFrame => None
07:53:05: 0 ActionThread: ActionThread.__MainLoop()
07:53:05: 0 EventThread: EventThread.__MainLoop()
07:53:06: 0 MainThread: Assignment to new attribute scratch
07:53:06: 0 EventThread: EventThread.StartSession(filename=u'D:\\Programmdaten\\Eventghost\\20170803_threading_wip_crash.egtree')
07:53:06: 0 ActionThread: ActionThread.StartSession(filename=u'D:\\Programmdaten\\Eventghost\\20170803_threading_wip_crash.egtree')
07:53:06: 0 MouseThread: MouseThread.run()
07:53:06: 0 ActionThread: Document.Load(filePath=u'D:\\Programmdaten\\Eventghost\\20170803_threading_wip_crash.egtree')
07:53:06: 0 ActionThread: StartLoad(cls=<class 'eg.Classes.TreeLink.TreeLink'>)
07:53:06: 0 Thread-1: SessionChangeNotifier.Register()
07:53:06: 1 MainThread: OSDFrame.__init__(parent=None)
This is the window in eg. There was a moment where eg was responding so I could maximize the window and tried to copy the event log but it froze in that very moment.
Maybe you can see something in the screenshot...
screen_mmstation_20170804_08h02m25s.jpg
[Edit]
I managed to start eg again and get the event log:

Code: Select all

08:03:36: 0 MainThread: ----------------------------------------
08:03:36: 0 MainThread:         EventGhost started
08:03:36: 0 MainThread: ----------------------------------------
08:03:36: 0 MainThread: EventGhost Version: WIP-2017.07.28-16.16.57
08:03:36: 0 MainThread: Machine type: AMD64
08:03:36: 0 MainThread: Processor: Intel64 Family 6 Model 60 Stepping 3, GenuineIntel
08:03:36: 0 MainThread: Architecture: ('32bit', 'WindowsPE')
08:03:36: 0 MainThread: Python:  2.7.12 CPython ('default', 'Oct 31 2016 18:42:08') [MSC v.1500 32 bit (Intel)]
08:03:36: 0 MainThread: ----------------------------------------
08:03:36: 0 MainThread: PluginManager.ScanAllPlugins :0.6439162091611711
08:03:36: 0 MainThread: SendKeysParser.__init__()
08:03:36: 0 EventGhost.Pipe.Thread: Named Pipe: Creating security descriptor
08:03:36: 0 EventGhost.Pipe.Thread: Named Pipe: Creating pipe
08:03:36: 0 MainMessageReceiver: MainMessageReceiver.__MainLoop()
08:03:36: 0 MainMessageReceiver: MainMessageReceiver.Setup()
08:03:36: 0 MainThread: Document.ShowFrame()
08:03:36: 0 MainThread: Log.GetData()
08:03:36: 0 MainThread: ----------------------------------------
08:03:36: 0 MainThread:         EventGhost started
08:03:36: 0 MainThread: ----------------------------------------
08:03:36: 0 MainThread: EventGhost Version: WIP-2017.07.28-16.16.57
08:03:36: 0 MainThread: Machine type: AMD64
08:03:36: 0 MainThread: Processor: Intel64 Family 6 Model 60 Stepping 3, GenuineIntel
08:03:36: 0 MainThread: Architecture: ('32bit', 'WindowsPE')
08:03:36: 0 MainThread: Python:  2.7.12 CPython ('default', 'Oct 31 2016 18:42:08') [MSC v.1500 32 bit (Intel)]
08:03:36: 0 MainThread: ----------------------------------------
08:03:36: 0 MainThread: PluginManager.ScanAllPlugins :0.6439162091611711
08:03:36: 0 MainThread: SendKeysParser.__init__()
08:03:36: 0 EventGhost.Pipe.Thread: Named Pipe: Creating security descriptor
08:03:36: 0 EventGhost.Pipe.Thread: Named Pipe: Creating pipe
08:03:36: 0 MainMessageReceiver: MainMessageReceiver.__MainLoop()
08:03:36: 0 MainMessageReceiver: MainMessageReceiver.Setup()
08:03:36: 0 MainThread: Document.ShowFrame()
08:03:36: 0 MainThread: Log.GetData()
08:03:37: 0 MainThread: Document.ShowFrame => None
08:03:37: 0 ActionThread: ActionThread.__MainLoop()
08:03:37: 0 EventThread: EventThread.__MainLoop()
---> Willkommen beim EventGhost <---
08:03:37: 0 MainThread: Assignment to new attribute scratch
08:03:37: 0 EventThread: EventThread.StartSession(filename=u'D:\\Programmdaten\\Eventghost\\20170803_threading_wip_crash.egtree')
08:03:37: 0 ActionThread: ActionThread.StartSession(filename=u'D:\\Programmdaten\\Eventghost\\20170803_threading_wip_crash.egtree')
08:03:37: 0 MouseThread: MouseThread.run()
08:03:37: 0 ActionThread: Document.Load(filePath=u'D:\\Programmdaten\\Eventghost\\20170803_threading_wip_crash.egtree')
08:03:37: 0 ActionThread: StartLoad(cls=<class 'eg.Classes.TreeLink.TreeLink'>)
08:03:37: 1 MainThread: OSDFrame.__init__(parent=None)
08:03:37: 0 Thread-2: SessionChangeNotifier.Register()
08:03:37: 0 MainMessageReceiver: PowerBroadcastNotifier.OnPowerBroadcast(hwnd=463054, uMsg=536L, wParam=32787L, lParam=8730456)
Win10 x64 Prof. / Eventghost latest / MCE Plugin / MCE RC6 Receiver / Logitech Harmony Hub / MediaPortal / kodi

Diz
Experienced User
Posts: 121
Joined: Tue Jan 10, 2017 4:49 pm

Re: I did it!!!

Post by Diz » Fri Aug 04, 2017 1:22 pm

@ Luca Brasi

your list of questions and my answers:

- Will the crash occur with an empty tree/config?
yes... eg still crashes but my system is left stable so no need to restart

- Does your sys go to sleep on itself when timing out, by some other trigger or do you put it into sleep manually?
manual

- How does it come up again when the crash occurs? Manually, timed task or WOL?
manual

- Do you use standby or hibernate?
standby

- Which acpi state is set in your bios/uefi for sleep? S3/S4?
s1 & s3

- Do you have quick start (hybrid sleep) enabled in power saving?
no

- Do you run Eg as Admin?
no

User avatar
Luca Brasi
Experienced User
Posts: 262
Joined: Sat Oct 11, 2008 12:39 pm

Re: I did it!!!

Post by Luca Brasi » Sat Aug 05, 2017 2:29 pm

Diz wrote:@ Luca Brasi

your list of questions and my answers:
Ok thank you. Lets see...

Diz wrote: - Will the crash occur with an empty tree/config?
yes... eg still crashes but my system is left stable so no need to restart
Ok, this is good news for a start I guess. This is alpha so we should start with the most basic setup and make it running before throwing in some more variables like plugins and stuff. As you can see, eg isn't running without it freezing on my sys as well. And it's good news that eg with its new features is not not freezing you system on itself.
@kgschlosser How should we proceed with this? I guess it would be good to leave the system freeze out of the equation for the moment and have a look why it is not running with debugging on? As far as I can see we don't get much from the logs right?
Diz wrote: - Does your sys go to sleep on itself when timing out, by some other trigger or do you put it into sleep manually?
manual

- How does it come up again when the crash occurs? Manually, timed task or WOL?
manual

- Do you use standby or hibernate?
standby

- Which acpi state is set in your bios/uefi for sleep? S3/S4?
s1 & s3

- Do you have quick start (hybrid sleep) enabled in power saving?
no

- Do you run Eg as Admin?
no
Ok thanks for this as well. imho this will be valuable info when we get to the point of debugging with better logs.

As I couldn't test with debugging on if my system will fail as well I did some other testing. With a nearly empty tree my system didn't freeze and eg is running fine after resume.
If I turn on Log Debug Info in the eg menu it is still fine but I get some errors in the log:
[collapse=Click on me to reveal more information!]

Code: Select all

17:50:48: 0 MainThread: ----------------------------------------
17:50:48: 0 MainThread:         EventGhost started
17:50:48: 0 MainThread: ----------------------------------------
17:50:48: 0 MainThread: EventGhost Version: WIP-2017.07.28-16.16.57
17:50:48: 0 MainThread: Machine type: AMD64
17:50:48: 0 MainThread: Processor: Intel64 Family 6 Model 58 Stepping 9, GenuineIntel
17:50:48: 0 MainThread: Architecture: ('32bit', 'WindowsPE')
17:50:48: 0 MainThread: Python:  2.7.12 CPython ('default', 'Oct 31 2016 18:42:08') [MSC v.1500 32 bit (Intel)]
17:50:48: 0 MainThread: ----------------------------------------
17:50:50: 0 MainThread: PluginManager.ScanAllPlugins :1.6865773763705718
17:50:50: 0 MainThread: SendKeysParser.__init__()
17:50:50: 0 EventGhost.Pipe.Thread: Named Pipe: Creating security descriptor
17:50:50: 0 EventGhost.Pipe.Thread: Named Pipe: Creating pipe
17:50:50: 0 MainMessageReceiver: MainMessageReceiver.__MainLoop()
17:50:50: 0 MainMessageReceiver: MainMessageReceiver.Setup()
17:50:50: 0 MainThread: Document.ShowFrame()
17:50:50: 0 MainThread: Log.GetData()
17:50:50: 0 MainThread: Document.ShowFrame => None
17:50:50: 0 ActionThread: ActionThread.__MainLoop()
17:50:50: 0 EventThread: EventThread.__MainLoop()
17:50:51: 0 MainThread: Assignment to new attribute scratch
17:50:51: 0 EventThread: EventThread.StartSession(filename=u'D:\\Programmdaten\\EventGhost\\Configs\\test_multithreading_2.egtree')
17:50:51: 0 ActionThread: ActionThread.StartSession(filename=u'D:\\Programmdaten\\EventGhost\\Configs\\test_multithreading_2.egtree')
17:50:51: 0 Thread-1: SessionChangeNotifier.Register()
17:50:51: 0 MouseThread: MouseThread.run()
17:50:51: 0 ActionThread: Document.Load(filePath=u'D:\\Programmdaten\\EventGhost\\Configs\\test_multithreading_2.egtree')
17:50:51: 1 MainThread: OSDFrame.__init__(parent=None)
17:50:51: 0 ActionThread: StartLoad(cls=<class 'eg.Classes.TreeLink.TreeLink'>)
17:50:51: 0 ActionThread: Document.SetExpandState(expanded=set([0]))
17:50:51: 0 ActionThread: XML loaded in 0.011747 seconds.
17:55:46: 0 MainThread: ----------------------------------------
17:55:46: 0 MainThread:         EventGhost started
17:55:46: 0 MainThread: ----------------------------------------
17:55:46: 0 MainThread: EventGhost Version: WIP-2017.07.28-16.16.57
17:55:46: 0 MainThread: Machine type: AMD64
17:55:46: 0 MainThread: Processor: Intel64 Family 6 Model 58 Stepping 9, GenuineIntel
17:55:46: 0 MainThread: Architecture: ('32bit', 'WindowsPE')
17:55:46: 0 MainThread: Python:  2.7.12 CPython ('default', 'Oct 31 2016 18:42:08') [MSC v.1500 32 bit (Intel)]
17:55:46: 0 MainThread: ----------------------------------------
17:55:48: 0 MainThread: PluginManager.ScanAllPlugins :1.6942149467166145
17:55:48: 0 MainThread: SendKeysParser.__init__()
17:55:48: 0 EventGhost.Pipe.Thread: Named Pipe: Creating security descriptor
17:55:48: 0 EventGhost.Pipe.Thread: Named Pipe: Creating pipe
17:55:48: 0 MainMessageReceiver: MainMessageReceiver.__MainLoop()
17:55:48: 0 MainMessageReceiver: MainMessageReceiver.Setup()
17:55:48: 0 MainThread: Document.ShowFrame()
17:55:48: 0 MainThread: Log.GetData()
17:55:49: 0 MainThread: Document.ShowFrame => None
17:55:49: 0 ActionThread: ActionThread.__MainLoop()
17:55:49: 0 EventThread: EventThread.__MainLoop()
17:55:50: 0 MainThread: Assignment to new attribute scratch
17:55:50: 0 EventThread: EventThread.StartSession(filename=u'D:\\Programmdaten\\EventGhost\\Configs\\test_multithreading_2.egtree')
17:55:50: 0 ActionThread: ActionThread.StartSession(filename=u'D:\\Programmdaten\\EventGhost\\Configs\\test_multithreading_2.egtree')
17:55:50: 0 MouseThread: MouseThread.run()
17:55:50: 0 ActionThread: Document.Load(filePath=u'D:\\Programmdaten\\EventGhost\\Configs\\test_multithreading_2.egtree')
17:55:50: 0 Thread-1: SessionChangeNotifier.Register()
17:55:50: 0 ActionThread: StartLoad(cls=<class 'eg.Classes.TreeLink.TreeLink'>)
17:55:50: 1 MainThread: OSDFrame.__init__(parent=None)
17:55:50: 0 ActionThread: Document.SetExpandState(expanded=set([0, 1]))
17:55:50: 0 ActionThread: XML loaded in 0.011704 seconds.
16:19:03: 0 MainMessageReceiver: <class 'eg.CorePluginModule.System.PowerBroadcastNotifier.POWERBROADCAST_SETTING'> Monitor.Off 7973552
16:19:03: 0 MainMessageReceiver: <class 'eg.CorePluginModule.System.PowerBroadcastNotifier.POWERBROADCAST_SETTING'> BatteryLevel.50% 7974256
16:20:07: 0 MainMessageReceiver: <class 'eg.CorePluginModule.System.PowerBroadcastNotifier.POWERBROADCAST_SETTING'> Monitor.On 7974096
16:20:36: 0 MainMessageReceiver: <class 'eg.CorePluginModule.System.PowerBroadcastNotifier.POWERBROADCAST_SETTING'> BatteryLevel.49% 7973968
[/collapse]

Please be aware that all those 17:xx:xx entries are from the time when I tried to run it with debugging on from the command line. The stuff from today is the last four lines.

[Edit:]
I just got some other errors in the log
[collapse=Click on me to reveal more information!]

Code: Select all

16:26:38: 0 MainMessageReceiver: <class 'eg.CorePluginModule.System.PowerBroadcastNotifier.POWERBROADCAST_SETTING'> BatteryLevel.45% 7974128
Traceback (most recent call last) (WIP-2017.07.28-16.16.57):
  File "C:\Program Files (x86)\EventGhost\eg\Classes\MainFrame\LogCtrl.py", line 337, in OnGetItemTextWithTime
    indent * self.indent +
PyAssertionError: C++ assertion "m_menuDepth > 0" failed at ..\..\src\msw\toplevel.cpp(1544) in wxTopLevelWindowMSW::DoSendMenuOpenCloseEvent(): No open menus?
[/collapse]

This is with this very basic tree...
[collapse=Click on me to reveal more information!]

Code: Select all

<?xml version="1.0" encoding="UTF-8" ?>
<EventGhost Version="WIP-2017.07.28-16.16.57" Guid="{7A8C94CB-59CB-4905-9EC2-2B55DF1C6557}" Time="1501942298.84">
    <Autostart Name="Autostart" XML_Guid="{4C001647-9169-4A3C-93BB-9E29DADDA3BB}" Expanded="True">
        <Plugin XML_Guid="{6CF14EC4-EFC7-43B4-B2F1-600261445F3A}" Identifier="Process" Guid="{82BADF9F-D809-4EBC-A540-CCBF7563F8DF}" File="ProcessWatcher">
            gAIpLg==
        </Plugin>
    </Autostart>
    <Folder Name="Tests" XML_Guid="{44B0CFFF-E145-47FC-8685-465D5EB460EC}" Expanded="True">
        <Macro Name="Zeige OSD: System Resumed" XML_Guid="{B46C5C01-7DF5-49E6-AB6F-61B4688D855C}" Expanded="True">
            <Event Name="System.Resume" XML_Guid="{8BFB75E6-B210-499F-928D-9659BF60B916}" />
            <Action XML_Guid="{DC8C69A5-FDE8-4B93-967C-CCB2D2ADB3B9}">
                EventGhost.ShowOSD(u'System Resumed', u'0;-24;0;0;0;700;0;0;0;1;0;0;2;32;Arial', (255, 255, 255), (0, 0, 0), 0, (0, 0), 0, 10.0, u'Default')
            </Action>
        </Macro>
    </Folder>
</EventGhost>
[/collapse]
Win10 x64 Prof. / Eventghost latest / MCE Plugin / MCE RC6 Receiver / Logitech Harmony Hub / MediaPortal / kodi

User avatar
kgschlosser
Site Admin
Posts: 5498
Joined: Fri Jun 05, 2015 5:43 am
Location: Rocky Mountains, Colorado USA

Re: I did it!!!

Post by kgschlosser » Sat Aug 05, 2017 11:32 pm

the first log you posted has no errors in it at all..

I am not sure where the second one comes from.. this is a lot easier if you just attach the whole log as a file this way i can read through the whole thing and nothing is being left out.


OK i think you may have a misunderstanding of what the debugging log is.. It doesn't only log errors it also provides a trail to follow of what information is being passed to what function/class/method and what information is being returned.

because we have 2 different logs we are dealing with the debugging log file and the event log in EG. what i like to tell people to do is have the time stamps turned on for the event log. copy the entire event log and save it to a text file. then attach the debugging log file as well as the text file that has the event log in it.. each time you close EG or it hangs you have to go and delete the debugging log file as it will just get appended to next time you start EG. this helps to keep the time stamps proper so it will only contain the relevant information that pertains to that EG session.

I think I am going to have to change the logging in this version as to provide a whole lot more information because of the added complexities
If you like the work I have been doing then feel free to Image

User avatar
Luca Brasi
Experienced User
Posts: 262
Joined: Sat Oct 11, 2008 12:39 pm

Re: I did it!!!

Post by Luca Brasi » Sun Aug 06, 2017 9:18 am

Yes! That's what I tried to describe. Unfortunately I will not get any more logs.
I'll repeat the test and send you fresh logs for each step.

This is the tree

Code: Select all

<?xml version="1.0" encoding="UTF-8" ?>
<EventGhost Version="WIP-2017.07.28-16.16.57" Guid="{7A8C94CB-59CB-4905-9EC2-2B55DF1C6557}" Time="1501942298.84">
    <Autostart Name="Autostart" XML_Guid="{4C001647-9169-4A3C-93BB-9E29DADDA3BB}" Expanded="True">
        <Plugin XML_Guid="{6CF14EC4-EFC7-43B4-B2F1-600261445F3A}" Identifier="Process" Guid="{82BADF9F-D809-4EBC-A540-CCBF7563F8DF}" File="ProcessWatcher">
            gAIpLg==
        </Plugin>
    </Autostart>
    <Folder Name="Tests" XML_Guid="{44B0CFFF-E145-47FC-8685-465D5EB460EC}" Expanded="True">
        <Macro Name="Zeige OSD: System Resumed" XML_Guid="{B46C5C01-7DF5-49E6-AB6F-61B4688D855C}" Expanded="True">
            <Event Name="System.Resume" XML_Guid="{8BFB75E6-B210-499F-928D-9659BF60B916}" />
            <Action XML_Guid="{DC8C69A5-FDE8-4B93-967C-CCB2D2ADB3B9}">
                EventGhost.ShowOSD(u'System Resumed', u'0;-24;0;0;0;700;0;0;0;1;0;0;2;32;Arial', (255, 255, 255), (0, 0, 0), 0, (0, 0), 0, 10.0, u'Default')
            </Action>
        </Macro>
    </Folder>
</EventGhost>
NO DEBUG
1. Having eg run without any debugging
2. Sleep and wake the system
3. EventLogs:

Code: Select all

10:22:44   ---> Willkommen beim EventGhost <---
10:22:44   System.Monitor.On
10:22:44   System.PowerSource.Battery
10:22:44   System.BatteryLevel.53%
10:22:44   System.PowerSaving.Off
10:22:44   System.PowerProfile.Balanced
10:22:44   Autostart
10:22:44      Plugin: Process Watcher
10:22:47   Main.OnInit
10:23:13   System.Monitor.Off
10:23:13   Process.Created.SIHClient
10:23:13   Process.Created.provtool
10:23:13   Process.Created.LogonUI
10:23:13   System.BatteryLevel.51%
10:23:13   Process.Created.conhost
10:23:13   System.SessionLock [u'myuser']
10:23:13   Process.Created.WmiPrvSE
10:23:14   Process.Destroyed.SearchFilterHost
10:23:14   Process.Destroyed.provtool
10:23:15   System.Suspend
10:23:15   Process.Destroyed.SIHClient
10:23:15   Process.Destroyed.conhost
10:23:15   Process.Created.FMAPP
10:56:07   Process.Created.SearchProtocolHost
10:56:07   Process.Created.SDUpdate
10:56:07   Process.Created.conhost
10:56:07   Process.Created.schtasks
10:56:07   Process.Created.SearchFilterHost
10:56:07   Process.Destroyed.SearchProtocolHost
10:56:08   Process.Created.FMAPP
10:56:08   Process.Destroyed.FMAPP
10:56:08   Process.Destroyed.FMAPP
10:56:08   System.Resume
10:56:08   Process.Created.svchost
10:56:09   Process.Destroyed.svchost
10:56:09   System.ResumeAutomatic
10:56:09   System.Monitor.On
10:56:09   Process.Created.taskhostw
10:56:09   Process.Created.taskhostw
10:56:09   Process.Created.schtasks
10:56:09   Process.Destroyed.schtasks
10:56:09   Process.Destroyed.conhost
10:56:09   Process.Destroyed.taskhostw
10:56:09   Process.Destroyed.schtasks
10:56:09   Process.Created.conhost
10:56:09   Process.Created.NETSTAT
10:56:09   Process.Created.cmd
10:56:09   Process.Created.ROUTE
10:56:10   Process.Destroyed.cmd
10:56:10   Process.Destroyed.ROUTE
10:56:10   Process.Destroyed.conhost
10:56:10   Process.Destroyed.NETSTAT
10:56:11   Process.Created.msosync
10:56:11   Process.Created.taskhostw
10:56:11   Process.Created.svchost
10:56:12   Process.Created.SettingSyncHost
10:56:12   Process.Destroyed.msosync
10:56:14   Process.Created.sppsvc
10:56:16   Process.Destroyed.SDUpdate
10:56:16   System.SessionUnlock [u'myuser']
10:56:16   Process.Created.taskhostw
10:56:16   Process.Destroyed.taskhostw
10:56:16   Process.Destroyed.LogonUI
10:56:17   Process.Destroyed.taskhostw
10:56:17   Process.Destroyed.taskhostw
10:56:19   Process.Created.backgroundTaskHost
10:56:19   Process.Created.svchost
10:56:38   System.BatteryLevel.50%
10:56:49   Process.Destroyed.sppsvc
10:57:07   Process.Destroyed.splwow64
10:57:14   Process.Created.conhost
10:57:14   Process.Created.NETSTAT
10:57:14   Process.Created.cmd
10:57:14   Process.Destroyed.conhost
10:57:14   Process.Destroyed.cmd
10:57:14   Process.Destroyed.NETSTAT
10:57:15   Process.Destroyed.WmiPrvSE
[Note: There should be a entry in the log for this info osd I configured. The OSD is shown after wake but not logged.]

4. Debug Log: N/A

Internal Debug
1. Starting EG > View > Log Debug Info
2. Sending to sleep > wait 10s > Wake
3. Eventlogs:

Code: Select all

11:02:28   ---> Willkommen beim EventGhost <---
11:02:28   System.Monitor.On
11:02:28   System.PowerSource.Battery
11:02:28   System.BatteryLevel.48%
11:02:28   System.PowerSaving.Off
11:02:28   System.PowerProfile.Balanced
11:02:28   Autostart
11:02:28      Plugin: Process Watcher
11:02:31   Main.OnInit
11:02:40   System.BatteryLevel.46%
11:02:59   Process.Destroyed.WmiPrvSE
11:03:04   11:03:04: 0 MainMessageReceiver: <class 'eg.CorePluginModule.System.PowerBroadcastNotifier.POWERBROADCAST_SETTING'> Monitor.Off 5308888
11:03:04   System.Monitor.Off
11:03:04   Process.Created.LogonUI
11:03:05   11:03:05: 0 MainMessageReceiver: <class 'eg.CorePluginModule.System.PowerBroadcastNotifier.POWERBROADCAST_SETTING'> BatteryLevel.45% 5309144
11:03:05   System.BatteryLevel.45%
11:03:05   System.SessionLock [u'myuser']
11:03:05   System.Suspend
11:03:06   Process.Created.WmiPrvSE
11:03:21   System.Resume
11:03:21   System.ResumeAutomatic
11:03:21   11:03:21: 0 MainMessageReceiver: <class 'eg.CorePluginModule.System.PowerBroadcastNotifier.POWERBROADCAST_SETTING'> Monitor.On 5437984
11:03:21   Process.Destroyed.svchost
11:03:21   System.Monitor.On
11:03:22   Process.Created.msosync
11:03:22   Process.Created.mavinject32
11:03:22   Process.Destroyed.mavinject32
11:03:22   Process.Created.FMAPP
11:03:22   Process.Created.taskhostw
11:03:23   Process.Created.svchost
11:03:23   Process.Destroyed.taskhostw
11:03:23   Process.Destroyed.msosync
11:03:23   Process.Created.taskhostw
11:03:24   Process.Created.FMAPP
11:03:24   Process.Destroyed.taskhostw
11:03:24   Process.Destroyed.FMAPP
11:03:24   Process.Destroyed.FMAPP
11:03:27   System.SessionUnlock [u'myuser']
11:03:27   Process.Created.taskhostw
11:03:27   Process.Created.backgroundTaskHost
11:03:27   Process.Destroyed.LogonUI
11:03:28   Process.Destroyed.taskhostw
11:03:31   Process.Created.backgroundTaskHost
11:03:31   Process.Created.svchost
Some entries are shown as errors:
screen_mmnote_2017-08-06 11 04 54.png
4. Debug Logs: N/A

Command line DEBUGGING
1. Starting with EG with "C:\Program Files (x86)\EventGhost\EventGhost.exe" -debug
2. EG is starting but freezes instantly
3. Event logs: I cannot access to log to copy it. I managed to do so for one of my posts earlier. But this time I can only deliver the screenshot:
screen_mmnote_2017-08-06 11 13 47.png
4. Debug log:

Code: Select all

11:13:29: 0 MainThread: ----------------------------------------
11:13:29: 0 MainThread:         EventGhost started
11:13:29: 0 MainThread: ----------------------------------------
11:13:29: 0 MainThread: EventGhost Version: WIP-2017.07.28-16.16.57
11:13:29: 0 MainThread: Machine type: AMD64
11:13:29: 0 MainThread: Processor: Intel64 Family 6 Model 58 Stepping 9, GenuineIntel
11:13:29: 0 MainThread: Architecture: ('32bit', 'WindowsPE')
11:13:29: 0 MainThread: Python:  2.7.12 CPython ('default', 'Oct 31 2016 18:42:08') [MSC v.1500 32 bit (Intel)]
11:13:29: 0 MainThread: ----------------------------------------
11:13:29: 0 MainThread: PluginManager.ScanAllPlugins :0.4420479750032785
11:13:29: 0 MainThread: SendKeysParser.__init__()
11:13:29: 0 EventGhost.Pipe.Thread: Named Pipe: Creating security descriptor
11:13:29: 0 EventGhost.Pipe.Thread: Named Pipe: Creating pipe
11:13:29: 0 MainMessageReceiver: MainMessageReceiver.__MainLoop()
11:13:29: 0 MainMessageReceiver: MainMessageReceiver.Setup()
11:13:29: 0 MainThread: Document.ShowFrame()
11:13:29: 0 MainThread: Log.GetData()
11:13:30: 0 MainThread: Document.ShowFrame => None
11:13:30: 0 ActionThread: ActionThread.__MainLoop()
11:13:30: 0 EventThread: EventThread.__MainLoop()
11:13:30: 0 MainThread: Assignment to new attribute scratch
11:13:30: 0 EventThread: EventThread.StartSession(filename=u'D:\\Programmdaten\\EventGhost\\Configs\\test_multithreading_2.egtree')
11:13:30: 0 ActionThread: ActionThread.StartSession(filename=u'D:\\Programmdaten\\EventGhost\\Configs\\test_multithreading_2.egtree')
11:13:30: 0 MouseThread: MouseThread.run()
11:13:30: 0 ActionThread: Document.Load(filePath=u'D:\\Programmdaten\\EventGhost\\Configs\\test_multithreading_2.egtree')
11:13:30: 0 ActionThread: StartLoad(cls=<class 'eg.Classes.TreeLink.TreeLink'>)
11:13:30: 1 MainThread: OSDFrame.__init__(parent=None)
11:13:30: 0 ActionThread: EventManager.RegisterEvent(macro=<eg.Classes.Document.EventItem object at 0x0532D570>, eventString=u'System.Resume')
11:13:30: 0 ActionThread: EventManager['System.Resume'].RegisterMacro('Zeige OSD: System Resumed')
11:13:30: 0 ActionThread: Document.SetExpandState(expanded=set([0, 1, 2, 3]))
11:13:30: 0 ActionThread: XML loaded in 0.010362 seconds.
I did a sleep and resume after starting EG with debugging on but the log doesn't show anything about that.
Win10 x64 Prof. / Eventghost latest / MCE Plugin / MCE RC6 Receiver / Logitech Harmony Hub / MediaPortal / kodi

User avatar
topix
Experienced User
Posts: 441
Joined: Sat May 05, 2007 3:43 pm
Location: Germany
Contact:

Re: I did it!!!

Post by topix » Sun Aug 06, 2017 7:54 pm

As a side note: when running with -debug, EG scans all plugins. So for a very clean system to test you could also remove all (additional) plugins. (Core plugins keep: EventGhost, System, Window, Mouse).

User avatar
kgschlosser
Site Admin
Posts: 5498
Joined: Fri Jun 05, 2015 5:43 am
Location: Rocky Mountains, Colorado USA

Re: I did it!!!

Post by kgschlosser » Mon Aug 07, 2017 1:27 am

ok when you enable debugging from inside of EG it is almost the same thing as specifying -debug at the cli. the only difference is when you use the one from the cli is that debugging is enabled right out of the gate, so it willl also include debugging on everything when enabled from the gui debugging is preset to off so everything up to the point of the config being loaded does not get debugged. I am not sure as to why you have having EG lock up when you start it with debugging. but it has to have something to do with a plugin. and it is going to be one of the plugins that does not ship with EG. this narrows it down to only plugins that you have installed. so this helps. simply move one at a time to a backup folder and restart EG each time and when the problem goes away tell me what the plugin is then i can go and look and see what the plugin is doing and will be able to fix the issue. this is the link we need to solve this issue.

Thanks Luca Brasi for all of your help. This is exactly what i needed. a way to reproduce the problem on demand. and we know that one of the things that running debug when EG is booted is all of the plugins actually get loaded (tho they do not start) so I am going to look at any module level things that plugins are doing. I do know that some of the authors of some of the plugins did like to do things like load dll files at the module level. and this could be the culprit. Now remember it doesn't have to be a plugin you are actively using it can be any plugin that is not included in EG or even a plugin that you may have upgraded..



@topix

Here is a good idea. since the debugging does load the plugins and it simply uses the empty RegisterPlugin in the core.py file. we could use that to capture the plugin names and versions. bounce it off of a list of included plugins and if it has been added or upgraded we can print off the name. and if it has been removed as well we can print that too
If you like the work I have been doing then feel free to Image

User avatar
kgschlosser
Site Admin
Posts: 5498
Joined: Fri Jun 05, 2015 5:43 am
Location: Rocky Mountains, Colorado USA

Re: I did it!!!

Post by kgschlosser » Mon Aug 14, 2017 7:29 pm

I am pretty sure I am going to start from scratch again. The code is getting too much like voodoo code. I have to discover the least complex way of going about doing this. I am trying to change a bunch of things at one time. Since EG is event based the event should be the main object. And everything from there down goes into it. So the plugin that triggers the event would go into it. And the macros the event runs goes into it. Right now I am 1/2 way kinda doing this I have to make everything this way. Where I kinda lose the simplicity is when I make it so that the wildcards work. I also don't think I like the way the log works. I have to come up with a better idea. I know I can make it really nice but it's going to add alot off complexity if I do. So I want to keep it really simple.
If you like the work I have been doing then feel free to Image

Post Reply