[shib_auth] shib_login: file not found

Tue Jul 12 03:16:37 CEST 2011

My apologies, I forgot to include the all important output from shibd.log and native.log..both of which i configured to run in debug mode:

Last 35 lines of Shibd.log:

+++++++++++++++++++

2011-07-11 17:19:55 INFO OpenSAML.Metadata.Chaining : building MetadataProvider of type XML
2011-07-11 17:19:55 DEBUG OpenSAML.MetadataProvider.XML : using local resource (C:\shibboleth-sp\etc\shibboleth\idp-metadata.xml), will monitor for changes
2011-07-11 17:19:55 DEBUG OpenSAML.MetadataProvider.XML : loading configuration from external resource...
2011-07-11 17:19:55 INFO OpenSAML.MetadataProvider.XML : loaded XML resource (C:\shibboleth-sp\etc\shibboleth\idp-metadata.xml)
2011-07-11 17:19:56 INFO Shibboleth.Application : building TrustEngine of type Chaining...
2011-07-11 17:19:56 INFO OpenSAML.MetadataProvider.XML : reload thread started...running when signaled
2011-07-11 17:19:56 INFO XMLTooling.TrustEngine.Chaining : building TrustEngine of type ExplicitKey
2011-07-11 17:19:56 INFO XMLTooling.TrustEngine.Chaining : building TrustEngine of type PKIX
2011-07-11 17:19:56 INFO Shibboleth.Application : building AttributeExtractor of type XML...
2011-07-11 17:19:56 DEBUG Shibboleth.AttributeExtractor.XML : using local resource (C:/shibboleth-sp/etc/shibboleth/attribute-map.xml), will monitor for changes
2011-07-11 17:19:56 DEBUG Shibboleth.AttributeExtractor.XML : loading configuration from external resource...
2011-07-11 17:19:56 INFO Shibboleth.AttributeExtractor.XML : reload thread started...running when signaled
2011-07-11 17:19:56 INFO Shibboleth.AttributeExtractor.XML : loaded XML resource (C:/shibboleth-sp/etc/shibboleth/attribute-map.xml)
2011-07-11 17:19:56 INFO Shibboleth.AttributeExtractor.XML : creating mapping for Attribute urn:oid:1.3.6.1.4.1.5923.1.1.1.6
2011-07-11 17:19:56 INFO Shibboleth.AttributeExtractor.XML : creating mapping for Attribute urn:mace:dir:attribute-def:eduPersonScopedAffiliation
2011-07-11 17:19:56 INFO Shibboleth.AttributeExtractor.XML : creating mapping for Attribute urn:oid:1.3.6.1.4.1.5923.1.1.1.9
2011-07-11 17:19:56 INFO Shibboleth.AttributeExtractor.XML : creating mapping for Attribute urn:mace:dir:attribute-def:eduPersonAffiliation
2011-07-11 17:19:56 INFO Shibboleth.AttributeExtractor.XML : creating mapping for Attribute urn:oid:1.3.6.1.4.1.5923.1.1.1.1
2011-07-11 17:19:56 INFO Shibboleth.AttributeExtractor.XML : creating mapping for Attribute urn:mace:dir:attribute-def:eduPersonEntitlement
2011-07-11 17:19:56 INFO Shibboleth.AttributeExtractor.XML : creating mapping for Attribute urn:oid:1.3.6.1.4.1.5923.1.1.1.7
2011-07-11 17:19:56 INFO Shibboleth.AttributeExtractor.XML : creating mapping for Attribute urn:mace:dir:attribute-def:eduPersonTargetedID
2011-07-11 17:19:56 INFO Shibboleth.AttributeExtractor.XML : creating mapping for Attribute urn:oid:1.3.6.1.4.1.5923.1.1.1.10
2011-07-11 17:19:56 INFO Shibboleth.AttributeExtractor.XML : creating mapping for Attribute urn:oasis:names:tc:SAML:2.0:nameid-format:persistent
2011-07-11 17:19:56 INFO Shibboleth.AttributeExtractor.XML : creating mapping for Attribute urn:mace:dir:attribute-def:PrincipalName
2011-07-11 17:19:56 INFO Shibboleth.Application : building AttributeFilter of type XML...
2011-07-11 17:19:56 DEBUG Shibboleth.AttributeFilter : using local resource (C:/shibboleth-sp/etc/shibboleth/attribute-policy.xml), will monitor for changes
2011-07-11 17:19:56 DEBUG Shibboleth.AttributeFilter : loading configuration from external resource...
2011-07-11 17:19:56 INFO Shibboleth.AttributeFilter : reload thread started...running when signaled
2011-07-11 17:19:56 INFO Shibboleth.AttributeFilter : loaded XML resource (C:/shibboleth-sp/etc/shibboleth/attribute-policy.xml)
2011-07-11 17:19:56 INFO Shibboleth.Application : building AttributeResolver of type Query...
2011-07-11 17:19:56 INFO Shibboleth.Application : building CredentialResolver of type File...
2011-07-11 17:19:56 INFO XMLTooling.SecurityHelper : loading private key from file (C:/shibboleth-sp/etc/shibboleth/sp-key.pem)
2011-07-11 17:19:56 DEBUG XMLTooling.SecurityHelper : key encoding format for (C:/shibboleth-sp/etc/shibboleth/sp-key.pem) dynamically resolved as (PEM)
2011-07-11 17:19:56 INFO XMLTooling.SecurityHelper : loading certificate(s) from file (C:/shibboleth-sp/etc/shibboleth/sp-cert.pem)
2011-07-11 17:19:56 INFO Shibboleth.Listener : registered remoted message endpoint (default::getHeaders::Application)
2011-07-11 17:19:56 INFO Shibboleth.Listener : listener service starting

+++++++++++++++++++

Last 50 lines of native.log:

2011-07-11 17:47:06 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/sites/all/themes/zeropoint/css/style-zero.css?y to default
2011-07-11 17:47:06 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/sites/all/themes/zeropoint/css/grey.css?y to default
2011-07-11 17:47:06 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/sites/all/themes/zeropoint/_custom/custom-style.css?y to default
2011-07-11 17:47:06 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/sites/all/themes/zeropoint/css/round.css?y to default
2011-07-11 17:47:06 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/modules/node/node.css?y to default
2011-07-11 17:47:06 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/sites/all/themes/zeropoint/css/print.css?y to default
2011-07-11 17:47:06 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/misc/jquery.1.5.2.js?y to default
2011-07-11 17:47:06 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/misc/jquery-ui-1.8.11/js/jquery-ui.js?y to default
2011-07-11 17:47:06 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/modules/system/admin.css?y to default
2011-07-11 17:47:06 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/misc/drupal.js?y to default
2011-07-11 17:47:06 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/misc/jwplayer-5.3-licensed/jwplayer.js?y to default
2011-07-11 17:47:06 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/sites/all/files/js/swfobject/swfobject.js?y to default
2011-07-11 17:47:06 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/sites/all/files/js/arches.js?y to default
2011-07-11 17:47:06 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/modules/admin_menu/admin_menu.js?y to default
2011-07-11 17:47:06 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/sites/all/themes/zeropoint/js/jquery.hoverIntent.minified.js?y to default
2011-07-11 17:47:06 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/sites/all/themes/zeropoint/js/dropdown.js?y to default
2011-07-11 17:47:06 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/modules/system/defaults.css?y to default
2011-07-11 17:50:17 DEBUG Shibboleth.SessionCache : cleanup thread running
2011-07-11 17:50:17 DEBUG Shibboleth.SessionCache : cleanup thread completed
2011-07-11 17:58:35 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/modules/admin_menu/admin_menu.css?y to default
2011-07-11 17:58:35 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/modules/system/system-menus.css?y to default
2011-07-11 17:58:35 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/modules/user/user.css?y to default
2011-07-11 17:58:35 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/sites/all/modules/cck/theme/content-module.css?y to default
2011-07-11 17:58:35 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/sites/all/modules/ckeditor/ckeditor.css?y to default
2011-07-11 17:58:35 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/misc/jquery-ui-1.8.11/css/cupertino/jquery-ui.css?y to default
2011-07-11 17:58:35 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/sites/all/modules/ctools/css/ctools.css?y to default
2011-07-11 17:58:35 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/modules/node/node.css?y to default
2011-07-11 17:58:35 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/sites/all/modules/logintoboggan/logintoboggan.css?y to default
2011-07-11 17:58:35 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/sites/all/modules/shib_auth/shib_auth.css?y to default
2011-07-11 17:58:35 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/sites/all/modules/cck/modules/fieldgroup/fieldgroup.css?y to default
2011-07-11 17:58:35 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/sites/all/themes/zeropoint/css/grey.css?y to default
2011-07-11 17:58:35 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/sites/all/themes/zeropoint/_custom/custom-style.css?y to default
2011-07-11 17:58:35 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/sites/all/themes/zeropoint/css/style-zero.css?y to default
2011-07-11 17:58:35 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/modules/system/admin.css?y to default
2011-07-11 17:58:35 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/sites/all/themes/zeropoint/css/round.css?y to default
2011-07-11 17:58:35 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/sites/all/themes/zeropoint/css/print.css?y to default
2011-07-11 17:58:35 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/misc/jquery.1.5.2.js?y to default
2011-07-11 17:58:35 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/modules/system/defaults.css?y to default
2011-07-11 17:58:35 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/misc/jquery-ui-1.8.11/js/jquery-ui.js?y to default
2011-07-11 17:58:35 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/misc/drupal.js?y to default
2011-07-11 17:58:35 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/misc/jwplayer-5.3-licensed/jwplayer.js?y to default
2011-07-11 17:58:35 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/sites/all/files/js/swfobject/swfobject.js?y to default
2011-07-11 17:58:35 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/sites/all/files/js/arches.js?y to default
2011-07-11 17:58:35 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/modules/admin_menu/admin_menu.js?y to default
2011-07-11 17:58:35 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/sites/all/themes/zeropoint/js/jquery.hoverIntent.minified.js?y to default
2011-07-11 17:58:35 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/sites/all/themes/zeropoint/js/dropdown.js?y to default
2011-07-11 17:58:35 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/misc/tableheader.js?y to default
2011-07-11 17:58:35 DEBUG Shibboleth.Apache [8432] shib_handler: mapped https://cmsdev/modules/system/system.css?y to default
2011-07-11 18:05:17 DEBUG Shibboleth.SessionCache : cleanup thread running
2011-07-11 18:05:17 DEBUG Shibboleth.SessionCache : cleanup thread completed

From: Richard Esmonde
Sent: Monday, July 11, 2011 6:07 PM
To: 'shib_auth at listserv.niif.hu'
Subject: shib_login: file not found

Hi,

I need some advice.  I am setting up Drupal SSO project on one windows 7 64-Bit system for testing purposes.

*         I have Drupal working (v6.20) with Zeropoint theme +MySQL 5.x backend

*         I have my IdP up and running with a Postgres database behind it - the DB is up and accessible  IdP URL is:  https://ssoidp:9443/idp/login.jsp

*         I have installed my SP

*         I have installed the shib_auth module and set perms (6.x-4.0-rc6<https://drupal.org/node/1120904>)

When I click on Shibboleth Login link I just get a 404.  This is all that's in my logs.  Page not Found.  What might I check next?  ...any fool proof techniques for testing my idp-metadata and sp-metadata.xml files?

Some further details

1.    I have ssl on port 443 for Drupal so that it is accessible on https://cmsdev

2.    My handler URL for Shib inside the module is https://cmsdev/ Shibboleth.sso/Login

3.    ..so I get the 404 on this URI:  https://cmsdev/Shibboleth.sso/Login?target=https%3A%2F%2Fcmsdev%2Fshib_login%2F%3Fq%3Dnode%252F53

Are there any docs on how to set this up on windows 7?  I have been trolling the web for same.  Mostly Shib 1.3 docs.

Would really appreciate any pointers and I can certainly give you more info on my setup.  Apologies for the apparent helplessness of this plea but I am totally stuck for the last few days on this.  I must be doing something obviously stupid so hoping somebody sees the error of my ways.

Thanks in advance for any tips,

Richard.
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://listserv.niif.hu/pipermail/shib_auth/attachments/20110711/218941ae/attachment-0001.htm>