Snapins fail to deploy with 1.0.1

[Bill Arlofski]

Hi Tom.

I am seeing the same issues with FOG 1.0.1 and a Win7 client that used to work with snapins. For example, fog.log on client shows that is it downloading my notepad.exe test snapin and “Starting FOG Snapin Installation.” but it never pops up notepad, and I never see a "Snapin installation complete"in the log.

A couple quick suggestions to aide in debugging snap-ins, and a couple possible bugs reports:

1. the fog.log lists the ID, and some other info about the snap-in, however, it does not list the snap-in file downloaded. It just says "starting FOG Snapin download then Download complete. Would help to know that it was requesting the correct file in the client-side log

2. The fog.log shows FOG::SnapinClient ID:x (where x is the task’s ID in the DB)

however, no where in the FOG web interface does it list a snapin’s ID nor the taks’s ID. I can query the db directly, but another column (task ID) in the task lists would be helpful I think to normal end-users. Also, maybe list the snapin’s ID in snapins lists.

BUG 1: If I queue a snapin to be deployed to a client, I see the snapin in “active snapin tasks” and in “active tasks” OK, so far so good.

If I delete the task from “Active tasks” it is still listed in “Active snapin tasks” list

BUG 2: And finally I am noticing something odd in the FOG Client

On startup (Win 7) it goes through all the module startups and delays, etc… Then, after it finds and downloads the first snapin, logs “downloading complete” and “starting FOG Snapin INstallation.” the only module that logs to the file is the FOG::TaskReboot which logs over and over again every 5 mins:

FOG::TaskReboot Attempting to connect to fog server…
FOG::TaskReboot No task found for client.

No other modules seem to be doing anything after that. It’s like the Snapin module did not complete its task, and is holding every other module back from running - Except for the TaskReboot module.

[Tom Elliott]

So, Bill and I were just worked out what the issue was. While my terms were wrong, my facts were correct.

FIRST: Running basic “tests” are not going to work because if you tell the snapin to “install” notepad.exe, ISD (this is correct) is what’s actually running the “install”. So, if your snapin simply opens a file that requires some sort of “interaction” with it Windows 7 and up operates it, inside this Interactive Service Detection window. You’ll see an ICON on the start bar that looks like gears. Notepad.exe, never finishes because it’s still, technically, running and never returns to the SnapinClient. If you close the notepad.exe that’s opened in ISD, it will finish and the other modules will complete as well.

SECOND: I Tried telling people, but apparently they didn’t want to trust me. Hopefully this helps you understand. I’ve tested many many things. When I say a simple test to see if things are working is to create a batch file that generates a text file, it’s not because I think something is broken. We have used this to test in the past. For example, create a batch file with the contents like:
[code]@echo off
echo “This is a test, it is only a test, if this was a real snapin, it would’ve actually done something unless you needed to interact with it. If it requires interaction, none of the other snapin’s will be able to be performed AFTER this one until it’s properly exited from.” > c:\SNAPINTEST.txt
[/code]

Hopefully this makes sense to you all.

[Jarett LeBlang]

[quote=“Tom Elliott, post: 27507, member: 7271”]Try updating to the latest on SVN. I’ve found a few bugs and have corrected them. The core of 1.0.1 is the same, but there were new tweaks and changes.

All you should have to do, as root on your system, is:
[code]svn co https://svn.code.sf.net/p/freeghost/code/trunk
cd trunk/bin
./installfog.sh[/code][/quote]

Ok i did the update to 1.0.1 and then attempted to do this to update to the newest SVN, Fog now wants to update the database again and is failing.

[BPSTravis]

Did you set a MySQL password? If so you need to update that password in /var/www/fog/commons/config.php

[Jarett LeBlang]

Yes… yes… i’m dumb. Thanks. I didn’t realize that the config file got overwritten again.

[kwisher]

Still no luck with my VBS snapin. There is no ISD present at the time of the deployment.
Ubuntu Server 14.04 fully updated
FOG 1.01 Trunk 1746

Client FOG log:
[CODE]
5/27/2014 9:01 AM FOG::ClientUpdater Client update will be applied during next service startup.
5/27/2014 9:01 AM FOG::ClientUpdater Client update process complete, exiting…
5/27/2014 9:04 AM FOG::TaskReboot Attempting to connect to fog server…
5/27/2014 9:04 AM FOG::TaskReboot Module is active…
5/27/2014 9:04 AM FOG::TaskReboot Attempting to connect to fog server…
5/27/2014 9:04 AM FOG::TaskReboot No task found for client.
5/27/2014 9:05 AM FOG::SnapinClient Attempting to connect to fog server…
5/27/2014 9:05 AM FOG::SnapinClient Module is active…
5/27/2014 9:05 AM FOG::SnapinClient No Tasks found for: D0:67:E5:57:D3:6B|38:59:F9:40:D8:E8
5/27/2014 9:09 AM FOG::TaskReboot Attempting to connect to fog server…
5/27/2014 9:09 AM FOG::TaskReboot Module is active…
5/27/2014 9:09 AM FOG::TaskReboot Attempting to connect to fog server…
5/27/2014 9:09 AM FOG::TaskReboot No task found for client.
5/27/2014 9:11 AM FOG::SnapinClient Attempting to connect to fog server…
5/27/2014 9:11 AM FOG::SnapinClient Module is active…
5/27/2014 9:11 AM FOG::SnapinClient No Tasks found for: D0:67:E5:57:D3:6B|38:59:F9:40:D8:E8
5/27/2014 9:16 AM FOG::SnapinClient Attempting to connect to fog server…
5/27/2014 9:16 AM FOG::SnapinClient Module is active…
5/27/2014 9:16 AM FOG::SnapinClient Snapin Found:
5/27/2014 9:16 AM FOG::SnapinClient ID: 9035
5/27/2014 9:16 AM FOG::SnapinClient RunWith: c:\windows\system32\cscript.exe
5/27/2014 9:16 AM FOG::SnapinClient RunWithArgs:
5/27/2014 9:16 AM FOG::SnapinClient Name: W7-adjoin
5/27/2014 9:16 AM FOG::SnapinClient Created: 2014-05-27 08:11:56
5/27/2014 9:16 AM FOG::SnapinClient Args: -silent -eulaAccepted
5/27/2014 9:16 AM FOG::SnapinClient Reboot: No
5/27/2014 9:16 AM FOG::SnapinClient Starting FOG Snapin Download
5/27/2014 9:16 AM FOG::SnapinClient Download complete.
5/27/2014 9:16 AM FOG::SnapinClient Starting FOG Snapin Installation.
5/27/2014 9:16 AM FOG::SnapinClient FOG Snapin Installtion complete.
5/27/2014 9:16 AM FOG::SnapinClient Installation returned with code: 1
5/27/2014 9:16 AM FOG::SnapinClient Attempting to connect to fog server…
5/27/2014 9:16 AM FOG::SnapinClient Module is active…
5/27/2014 9:16 AM FOG::SnapinClient No Tasks found for: D0:67:E5:57:D3:6B|38:59:F9:40:D8:E8
5/27/2014 9:16 AM FOG::GUIWatcher Message found, attempting to notify GUI!
5/27/2014 9:16 AM FOG::GUIWatcher Dispatch OK!
5/27/2014 9:21 AM FOG::SnapinClient Attempting to connect to fog server…
5/27/2014 9:21 AM FOG::SnapinClient Module is active…
5/27/2014 9:21 AM FOG::SnapinClient No Tasks found for: D0:67:E5:57:D3:6B|38:59:F9:40:D8:E8
5/27/2014 9:26 AM FOG::SnapinClient Attempting to connect to fog server…
5/27/2014 9:26 AM FOG::SnapinClient Unknown error, module will exit.
5/27/2014 9:31 AM FOG::SnapinClient Attempting to connect to fog server…
5/27/2014 9:31 AM FOG::SnapinClient Module is active…
5/27/2014 9:31 AM FOG::SnapinClient Snapin Found:
5/27/2014 9:31 AM FOG::SnapinClient ID: 9036
5/27/2014 9:31 AM FOG::SnapinClient RunWith: c:\windows\system32\cscript.exe
5/27/2014 9:31 AM FOG::SnapinClient RunWithArgs:
5/27/2014 9:31 AM FOG::SnapinClient Name: W7-adjoin
5/27/2014 9:31 AM FOG::SnapinClient Created: 2014-05-27 08:28:52
5/27/2014 9:31 AM FOG::SnapinClient Args: -silent -eulaAccepted
5/27/2014 9:31 AM FOG::SnapinClient Reboot: No
5/27/2014 9:31 AM FOG::SnapinClient Starting FOG Snapin Download
5/27/2014 9:31 AM FOG::SnapinClient Download complete.
5/27/2014 9:31 AM FOG::SnapinClient Starting FOG Snapin Installation.
5/27/2014 9:31 AM FOG::SnapinClient FOG Snapin Installtion complete.
5/27/2014 9:31 AM FOG::SnapinClient Installation returned with code: 1
5/27/2014 9:31 AM FOG::SnapinClient Attempting to connect to fog server…
5/27/2014 9:31 AM FOG::SnapinClient Module is active…
5/27/2014 9:31 AM FOG::SnapinClient No Tasks found for: D0:67:E5:57:D3:6B|38:59:F9:40:D8:E8
5/27/2014 9:31 AM FOG::GUIWatcher Message found, attempting to notify GUI!
5/27/2014 9:31 AM FOG::GUIWatcher Dispatch OK!
5/27/2014 9:36 AM FOG::SnapinClient Attempting to connect to fog server…
5/27/2014 9:36 AM FOG::SnapinClient Failed to connect to fog server!
5/27/2014 9:36 AM FOG::SnapinClient Unable to connect to the remote server
5/27/2014 9:36 AM FOG::SnapinClient at System.Net.WebClient.DownloadDataInternal(Uri address, WebRequest& request)
at System.Net.WebClient.DownloadString(Uri address)
at FOG.SnapinClient.startWatching()
5/27/2014 9:36 AM FOG::SnapinClient Sleeping for 1 minute.
5/27/2014 9:37 AM FOG::SnapinClient Attempting to connect to fog server…
5/27/2014 9:38 AM FOG::SnapinClient Failed to connect to fog server!
5/27/2014 9:38 AM FOG::SnapinClient Unable to connect to the remote server
5/27/2014 9:38 AM FOG::SnapinClient at System.Net.WebClient.DownloadDataInternal(Uri address, WebRequest& request)
at System.Net.WebClient.DownloadString(Uri address)
at FOG.SnapinClient.startWatching()
5/27/2014 9:38 AM FOG::SnapinClient Sleeping for 1 minute.[/CODE]

[Junkhacker]

have you verified that the last line in the vbs script is a carriage return?

[kwisher]

Yes, I placed the cursor at the end of the last line of text, held the delete key to clear anything that might be there, and then pressed the return key and saved the file.

[Junkhacker]

and this snapin fails for you on both 32 and 64 bit computers?

[kwisher]

We’re 95% 64-bit here so I haven’t tested on a Win-7 32-bit machine yet with 1.0.1. This snapin previously worked on both architectures.

[kwisher]

Permissions for the server snapin folder. Are these correct?
[CODE]root@fog:/opt/fog/snapins# ls -la
total 16
drwxr-xr-x 2 www-data root 4096 May 23 09:16 .
drwxr-xr-x 6 root root 4096 May 16 13:40 …
-rw-r–r-- 1 www-data www-data 934 May 23 10:01 domjoin.vbs
-rw-r–r-- 1 www-data www-data 848 May 27 09:18 W7-domjoin.vbs
[/CODE]

[Junkhacker]

yes, those permissions look correct.
do you have the %windir%\syswow64\cscript.exe set for the “Snapin Run With”?

[kwisher]

N
[quote=“Junkhacker, post: 28293, member: 21583”]yes, those permissions look correct.
do you have the %windir%\syswow64\cscript.exe set for the “Snapin Run With”?[/quote]
No, c:\windows\system32\cscript.exe as it was in .32. You can see this in my post with the client log. I’ll give your suggestion a try.
And as I previously stated, the script will run manually on the client.

[kwisher]

Your suggested run with command failed:
[CODE]5/27/2014 1:54 PM FOG::SnapinClient Snapin Found:
5/27/2014 1:54 PM FOG::SnapinClient ID: 9041
5/27/2014 1:54 PM FOG::SnapinClient RunWith: %windir%\syswow64\cscript.exe
5/27/2014 1:54 PM FOG::SnapinClient RunWithArgs:
5/27/2014 1:54 PM FOG::SnapinClient Name: W7-adjoin
5/27/2014 1:54 PM FOG::SnapinClient Created: 2014-05-27 12:51:41
5/27/2014 1:54 PM FOG::SnapinClient Args: -silent -eulaAccepted
5/27/2014 1:54 PM FOG::SnapinClient Reboot: No
5/27/2014 1:54 PM FOG::SnapinClient Starting FOG Snapin Download
5/27/2014 1:54 PM FOG::SnapinClient Download complete.
5/27/2014 1:54 PM FOG::SnapinClient Starting FOG Snapin Installation.
5/27/2014 1:54 PM FOG::SnapinClient The system cannot find the file specified
5/27/2014 1:54 PM FOG::SnapinClient at System.Diagnostics.Process.StartWithCreateProcess(ProcessStartInfo startInfo)
at FOG.SnapinClient.startWatching()
5/27/2014 1:54 PM FOG::SnapinClient Attempting to connect to fog server…
5/27/2014 1:54 PM FOG::SnapinClient Module is active…
5/27/2014 1:54 PM FOG::SnapinClient No Tasks found for: D0:67:E5:57:D3:6B|38:59:F9:40:D8:E8
5/27/2014 1:54 PM FOG::GUIWatcher Message found, attempting to notify GUI!
5/27/2014 1:55 PM FOG::GUIWatcher Dispatch OK![/CODE]