Attachment 600764 Details for Bug 819886 – Screen log

Screen log

log (text/plain), 14.57 KB, created by mkovacik on 2012-07-27 12:31:58 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: mkovacik

Created: 2012-07-27 12:31:58 UTC

Size: 14.57 KB

patch

obsolete

>### RHUA: sync requests
>[root@ip-10-190-237-226 ~]# pulp-admin -u admin -p admin repo sync --id  rhel-6-rhui-server-optional-beta-source-rpms-6Server-i386
>Sync for repository rhel-6-rhui-server-optional-beta-source-rpms-6Server-i386 started
>Use "repo status" to check on the progress
>[root@ip-10-190-237-226 ~]# pulp-admin -u admin -p admin repo status --id  rhel-6-rhui-server-optional-beta-source-rpms-6Server-i386
>+------------------------------------------+
> Status for rhel-6-rhui-server-optional-beta-source-rpms-6Server-i386
>+------------------------------------------+
>Repository: rhel-6-rhui-server-optional-beta-source-rpms-6Server-i386
>Number of Packages: 129
>Last Sync: 2012-07-27 07:24:04-04:00
>Currently syncing: Downloading Items or Verifying (129 of 129 items downloaded. 0.0 bytes remaining)
>
>[root@ip-10-190-237-226 ~]# pulp-admin -u admin -p admin repo status --id  rhel-6-rhui-server-optional-beta-source-rpms-6Server-i386
>+------------------------------------------+
> Status for rhel-6-rhui-server-optional-beta-source-rpms-6Server-i386
>+------------------------------------------+
>Repository: rhel-6-rhui-server-optional-beta-source-rpms-6Server-i386
>Number of Packages: 129
>Last Sync: 2012-07-27 07:27:39-04:00
>
>[root@ip-10-190-237-226 ~]# tail -30 /var/log/pulp/grinder.log
>2012-07-27 07:27:38,481 17779:139986720511744: grinder.ParallelFetch:INFO: ParallelFetch:137 4 threads are active. 4 items left to be fetched
>2012-07-27 07:27:38,482 17779:139986741491456: grinder.ParallelFetch:INFO: ParallelFetch:308 WorkerThread deleting ActiveObject
>2012-07-27 07:27:38,486 17779:139986731001600: grinder.ParallelFetch:INFO: ParallelFetch:308 WorkerThread deleting ActiveObject
>2012-07-27 07:27:38,486 17779:139986720511744: grinder.ParallelFetch:INFO: ParallelFetch:308 WorkerThread deleting ActiveObject
>2012-07-27 07:27:38,490 17779:139986731001600: grinder.ParallelFetch:INFO: ParallelFetch:323 Thread ending
>2012-07-27 07:27:38,490 17779:139986720511744: grinder.ParallelFetch:INFO: ParallelFetch:323 Thread ending
>2012-07-27 07:27:38,491 17779:139986741491456: grinder.ParallelFetch:INFO: ParallelFetch:323 Thread ending
>2012-07-27 07:27:38,492 17779:139986961647360: grinder.ParallelFetch:INFO: ParallelFetch:137 3 threads are active. 3 items left to be fetched
>2012-07-27 07:27:38,493 17779:139986961647360: grinder.ParallelFetch:INFO: ParallelFetch:308 WorkerThread deleting ActiveObject
>2012-07-27 07:27:38,559 17779:139986961647360: grinder.ParallelFetch:INFO: ParallelFetch:323 Thread ending
>2012-07-27 07:27:38,766 17779:139986972137216: grinder.ParallelFetch:INFO: ParallelFetch:218 All threads have finished.
>2012-07-27 07:27:38,788 17779:139986972137216: grinder.ParallelFetch:INFO: ParallelFetch:237 ParallelFetch: 129 items successfully processed, 0 downloaded, 0 items had errors
>2012-07-27 07:27:38,788 17779:139986972137216: grinder.ParallelFetch:INFO: ParallelFetch:242 Transferred [1376618952] bytes of [rpm]
>2012-07-27 07:27:38,789 17779:139986972137216: grinder.ParallelFetch:INFO: ParallelFetch:243 Transferred [1376618952] total bytes in 6.25324678421 seconds
>2012-07-27 07:27:38,789 17779:139986972137216: grinder.RepoFetch:INFO: RepoFetch:202 Finalizing metadata, moving /var/lib/pulp//repos/content/beta/rhel/rhui/server/6/6Server/i386/optional/source/SRPMS//repodata.new to /var
>r/6/6Server/i386/optional/source/SRPMS//repodata
>2012-07-27 07:27:38,796 17779:139986972137216: grinder.RepoFetch:INFO: RepoFetch:156 Cleaning any orphaned packages..
>2012-07-27 07:27:38,812 17779:139986972137216: grinder.RepoFetch:INFO: RepoFetch:161 Processed <>,<https://cdn.redhat.com//content/beta/rhel/rhui/server/6/6Server/i386/optional/source/SRPMS> with <129> items in [6] seconds
>s
>2012-07-27 07:27:38,812 17779:139986972137216: grinder.ParallelFetch:INFO: ParallelFetch:187 Grinder stopping
>2012-07-27 07:27:38,812 17779:139986972137216: grinder.ParallelFetch:INFO: ParallelFetch:271 stop() invoked
>2012-07-27 07:27:38,813 17779:139986972137216: grinder.ParallelFetch:INFO: ParallelFetch:281 stop() completed
>2012-07-27 07:27:38,813 17779:139986972137216: grinder.ParallelFetch:INFO: ParallelFetch:191 Told thread <<WorkerThread(Thread-18, stopped daemon 139986720511744)>> to stop
>2012-07-27 07:27:38,813 17779:139986972137216: grinder.ParallelFetch:INFO: ParallelFetch:271 stop() invoked
>2012-07-27 07:27:38,813 17779:139986972137216: grinder.ParallelFetch:INFO: ParallelFetch:281 stop() completed
>2012-07-27 07:27:38,813 17779:139986972137216: grinder.ParallelFetch:INFO: ParallelFetch:191 Told thread <<WorkerThread(Thread-19, stopped daemon 139986741491456)>> to stop
>2012-07-27 07:27:38,814 17779:139986972137216: grinder.ParallelFetch:INFO: ParallelFetch:271 stop() invoked
>2012-07-27 07:27:38,814 17779:139986972137216: grinder.ParallelFetch:INFO: ParallelFetch:281 stop() completed
>2012-07-27 07:27:38,814 17779:139986972137216: grinder.ParallelFetch:INFO: ParallelFetch:191 Told thread <<WorkerThread(Thread-20, stopped daemon 139986961647360)>> to stop
>2012-07-27 07:27:38,814 17779:139986972137216: grinder.ParallelFetch:INFO: ParallelFetch:271 stop() invoked
>2012-07-27 07:27:38,814 17779:139986972137216: grinder.ParallelFetch:INFO: ParallelFetch:281 stop() completed
>2012-07-27 07:27:38,814 17779:139986972137216: grinder.ParallelFetch:INFO: ParallelFetch:191 Told thread <<WorkerThread(Thread-21, stopped daemon 139986731001600)>> to stop
>[root@ip-10-190-237-226 ~]# pulp-admin -u admin -p admin repo sync --id  rhel-6-rhui-server-optional-beta-source-rpms-6Server-i386
>Sync for repository rhel-6-rhui-server-optional-beta-source-rpms-6Server-i386 started
>Use "repo status" to check on the progress
>[root@ip-10-190-237-226 ~]# pulp-admin -u admin -p admin repo status --id  rhel-6-rhui-server-optional-beta-source-rpms-6Server-i386
>+------------------------------------------+
> Status for rhel-6-rhui-server-optional-beta-source-rpms-6Server-i386
>+------------------------------------------+
>Repository: rhel-6-rhui-server-optional-beta-source-rpms-6Server-i386
>Number of Packages: 129
>Last Sync: 2012-07-27 07:27:39-04:00
>Last Error: 2012-07-27 07:39:08-04:00
>Exception: Traceback (most recent call last):
>
>  File "/usr/lib/python2.6/site-packages/grinder/activeobject.py", line 429, in process
>    retval = method(*args, **kwargs)
>
>  File "/usr/lib/python2.6/site-packages/grinder/RepoFetch.py", line 51, in fetchItem
>    verify_options=self.verify_options)
>
>  File "/usr/lib/python2.6/site-packages/grinder/BaseFetch.py", line 328, in fetch
>    checksum, headers, retryTimes, packages_location)
>
>  File "/usr/lib/python2.6/site-packages/grinder/BaseFetch.py", line 328, in fetch
>    checksum, headers, retryTimes, packages_location)
>
>  File "/usr/lib/python2.6/site-packages/grinder/BaseFetch.py", line 270, in fetch
>    curl.perform()
>
>error: (56, 'Received HTTP code 403 from proxy after CONNECT')
>
>
>
>[root@ip-10-190-237-226 ~]# tail -30 /var/log/pulp/grinder.log
>2012-07-27 07:27:38,814 17779:139986972137216: grinder.ParallelFetch:INFO: ParallelFetch:271 stop() invoked
>2012-07-27 07:27:38,814 17779:139986972137216: grinder.ParallelFetch:INFO: ParallelFetch:281 stop() completed
>2012-07-27 07:27:38,814 17779:139986972137216: grinder.ParallelFetch:INFO: ParallelFetch:191 Told thread <<WorkerThread(Thread-20, stopped daemon 139986961647360)>> to stop
>2012-07-27 07:27:38,814 17779:139986972137216: grinder.ParallelFetch:INFO: ParallelFetch:271 stop() invoked
>2012-07-27 07:27:38,814 17779:139986972137216: grinder.ParallelFetch:INFO: ParallelFetch:281 stop() completed
>2012-07-27 07:27:38,814 17779:139986972137216: grinder.ParallelFetch:INFO: ParallelFetch:191 Told thread <<WorkerThread(Thread-21, stopped daemon 139986731001600)>> to stop
>2012-07-27 07:39:08,264 17779:139986972137216: grinder.RepoFetch:INFO: RepoFetch:125 fetchYumRepo() repo_label = , repo_url = https://cdn.redhat.com//content/beta/rhel/rhui/server/6/6Server/i386/optional/source/SRPMS, base
>/rhui/server/6/6Server/i386/optional/source/SRPMS, verify_options = {'checksum': False, 'size': False}
>2012-07-27 07:39:08,265 17779:139986972137216: grinder.RepoFetch:INFO: RepoFetch:129 , https://cdn.redhat.com//content/beta/rhel/rhui/server/6/6Server/i386/optional/source/SRPMS, Calling RepoFetch with: cacert=</etc/pki/pu
>ource-rpms-6Server-i386/feed-rhel-6-rhui-server-optional-beta-source-rpms-6Server-i386.ca>, clicert=</etc/pki/pulp/content/rhel-6-rhui-server-optional-beta-source-rpms-6Server-i386/feed-rhel-6-rhui-server-optional-beta-sou
>roxy_url=<http://10.36.29.87>, proxy_port=<3128>, proxy_user=<None>, proxy_pass=<NOT_LOGGED>, sslverify=<1>, max_speed=<0>, verify_options=<{'checksum': False, 'size': False}>
>2012-07-27 07:39:08,419 17779:139986972137216: grinder.DistroInfo:INFO: DistroInfo:55 Preparing to fetch any available trees..
>2012-07-27 07:39:08,666 17779:139986972137216: grinder.BaseFetch:INFO: activeobject:160 Fetching Unknown bytes: .treeinfo from https://cdn.redhat.com//content/beta/rhel/rhui/server/6/6Server/i386/optional/source/SRPMS/.tre
>2012-07-27 07:39:08,673 17779:139986972137216: grinder.BaseFetch:ERROR: activeobject:160 Caught exception<(56, 'Received HTTP code 403 from proxy after CONNECT')> in fetch(.treeinfo, https://cdn.redhat.com//content/beta/rh
>/SRPMS/.treeinfo)
>2012-07-27 07:39:08,673 17779:139986972137216: grinder.BaseFetch:ERROR: activeobject:160 Retrying fetch of: .treeinfo with 1 retry attempts left.
>2012-07-27 07:39:08,675 17779:139986972137216: grinder.BaseFetch:INFO: activeobject:160 Fetching Unknown bytes: .treeinfo from https://cdn.redhat.com//content/beta/rhel/rhui/server/6/6Server/i386/optional/source/SRPMS/.tre
>2012-07-27 07:39:08,680 17779:139986972137216: grinder.BaseFetch:ERROR: activeobject:160 Caught exception<(56, 'Received HTTP code 403 from proxy after CONNECT')> in fetch(.treeinfo, https://cdn.redhat.com//content/beta/rh
>/SRPMS/.treeinfo)
>2012-07-27 07:39:08,681 17779:139986972137216: grinder.BaseFetch:ERROR: activeobject:160 Retrying fetch of: .treeinfo with 0 retry attempts left.
>2012-07-27 07:39:08,683 17779:139986972137216: grinder.BaseFetch:INFO: activeobject:160 Fetching Unknown bytes: .treeinfo from https://cdn.redhat.com//content/beta/rhel/rhui/server/6/6Server/i386/optional/source/SRPMS/.tre
>2012-07-27 07:39:08,689 17779:139986972137216: grinder.BaseFetch:ERROR: activeobject:160 Caught exception<(56, 'Received HTTP code 403 from proxy after CONNECT')> in fetch(.treeinfo, https://cdn.redhat.com//content/beta/rh
>/SRPMS/.treeinfo)
>2012-07-27 07:39:08,727 17779:139986972137216: grinder.ParallelFetch:INFO: ParallelFetch:187 Grinder stopping
>2012-07-27 07:39:08,728 17779:139986972137216: grinder.ParallelFetch:INFO: ParallelFetch:271 stop() invoked
>2012-07-27 07:39:08,729 17779:139986972137216: grinder.ParallelFetch:INFO: ParallelFetch:281 stop() completed
>2012-07-27 07:39:08,730 17779:139986972137216: grinder.ParallelFetch:INFO: ParallelFetch:191 Told thread <<WorkerThread(Thread-23, initial daemon)>> to stop
>2012-07-27 07:39:08,730 17779:139986972137216: grinder.ParallelFetch:INFO: ParallelFetch:271 stop() invoked
>2012-07-27 07:39:08,731 17779:139986972137216: grinder.ParallelFetch:INFO: ParallelFetch:281 stop() completed
>2012-07-27 07:39:08,731 17779:139986972137216: grinder.ParallelFetch:INFO: ParallelFetch:191 Told thread <<WorkerThread(Thread-24, initial daemon)>> to stop
>2012-07-27 07:39:08,731 17779:139986972137216: grinder.ParallelFetch:INFO: ParallelFetch:271 stop() invoked
>2012-07-27 07:39:08,733 17779:139986972137216: grinder.ParallelFetch:INFO: ParallelFetch:281 stop() completed
>2012-07-27 07:39:08,733 17779:139986972137216: grinder.ParallelFetch:INFO: ParallelFetch:191 Told thread <<WorkerThread(Thread-25, initial daemon)>> to stop
>2012-07-27 07:39:08,733 17779:139986972137216: grinder.ParallelFetch:INFO: ParallelFetch:271 stop() invoked
>2012-07-27 07:39:08,734 17779:139986972137216: grinder.ParallelFetch:INFO: ParallelFetch:281 stop() completed
>2012-07-27 07:39:08,735 17779:139986972137216: grinder.ParallelFetch:INFO: ParallelFetch:191 Told thread <<WorkerThread(Thread-26, initial daemon)>> to stop
>[root@ip-10-190-237-226 ~]# exit
>logout
>Connection to ec2-184-73-14-155.compute-1.amazonaws.com closed.
>(reverse-i-search)`grep': find ./ -name "*.py" -exec ^Cep "scp" {} \; -print
>[mkovacik@malina rh_4]$ ssh -i ~/.pem/aws-us-east-1.pem root@ec2-184-73-14-155.compute-1.amazonaws.com
>reverse mapping checking getaddrinfo for ec2 [184.73.14.155] failed - POSSIBLE BREAK-IN ATTEMPT!
>Last login: Fri Jul 27 06:07:59 2012 from 209.132.186.34
>[root@ip-10-190-237-226 ~]# grep -C1 proxy /etc/rhui/rhui-tools.conf
>[yum]
># Uncomment and set the following attributes to use a proxy to connect to Red Hat.
>proxy_url: http://10.36.29.87
>proxy_port: 3128
># proxy_user:
># proxy_pass:
>
>[root@ip-10-190-237-226 ~]# grep -C1 proxy /etc/pulp/pulp.conf
># limit_in_KB: 500
># Uncomment the below section with appropriate values for proxy configuration
>proxy_url: http://10.36.29.87
>proxy_port: 3128
># proxy_user:
># proxy_pass:
># Verify existing package options
>[root@ip-10-190-237-226 ~]#
>
>### SQUID  CONFIG&LOG
>[root@ip-10-36-29-87 ~]# vim /etc/squid/squid.conf
>[root@ip-10-36-29-87 ~]# service squid reload
>[root@ip-10-36-29-87 ~]# grep -C 1 metadata /etc/squid/squid.conf
>### Testing: https://bugzilla.redhat.com/show_bug.cgi?id=819886
>acl metadata urlpath_regex .xml
>http_access deny metadata
>
>[root@ip-10-36-29-87 ~]# fg
>-bash: fg: current: no such job
>[root@ip-10-36-29-87 ~]# tail -f /var/log/squid/access.log
>1343388458.287    239 10.190.237.226 TCP_MISS/200 69056 CONNECT cdn.redhat.com:443 - DIRECT/184.85.254.251 -
>1343388458.526    222 10.190.237.226 TCP_MISS/200 48385 CONNECT cdn.redhat.com:443 - DIRECT/184.85.254.251 -
>1343388458.764    232 10.190.237.226 TCP_MISS/200 46337 CONNECT cdn.redhat.com:443 - DIRECT/184.85.254.251 -
>1343388458.924    156 10.190.237.226 TCP_MISS/200 69040 CONNECT cdn.redhat.com:443 - DIRECT/184.85.254.251 -
>1343388459.171    243 10.190.237.226 TCP_MISS/200 99706 CONNECT cdn.redhat.com:443 - DIRECT/184.85.254.251 -
>1343388459.412    237 10.190.237.226 TCP_MISS/200 95178 CONNECT cdn.redhat.com:443 - DIRECT/184.85.254.251 -
>1343388459.660    243 10.190.237.226 TCP_MISS/200 49233 CONNECT cdn.redhat.com:443 - DIRECT/184.85.254.251 -
>1343389151.464      0 10.190.237.226 TCP_DENIED/403 3522 CONNECT cdn.redhat.com:443 - NONE/- text/html
>1343389151.472      0 10.190.237.226 TCP_DENIED/403 3522 CONNECT cdn.redhat.com:443 - NONE/- text/html
>1343389151.479      0 10.190.237.226 TCP_DENIED/403 3522 CONNECT cdn.redhat.com:443 - NONE/- text/html
>^C
>[root@ip-10-36-29-87 ~]# exit
>logout
>Connection to ec2-23-23-4-146.compute-1.amazonaws.com closed.
>[mkovacik@malina rh_4]$ ssh -i ~/.pem/aws-us-east-1.pem root@ec2-23-23-4-146.compute-1.amazonaws.com
>reverse mapping checking getaddrinfo for ec2 [23.23.4.146] failed - POSSIBLE BREAK-IN ATTEMPT!
>Last login: Fri Jul 27 06:53:14 2012 from 209.132.186.34
>[root@ip-10-36-29-87 ~]#

Actions: View

Attachments on bug 819886: 600764 | 601220