strange powerpill error - aria2 timeouts all the time [SOLVED]

graysky · 2009-10-09 20:47:17

Just installed Arch on a new machine and can't seem to get powerpill working. I installed it and simply commented out the reflect -l 45 line in the /etc/powerpill.conf as I do on all my machines and I also uncommented the Aria2Clean. When I try to d/l packages via powerpill -S package, I get timeout errors after a good 60 sec. I'd post the whole output, but I have to type this from a pic of the screen! The basic error looks like this:

aria2: Exception [AbstractCommand.cc:184] Timeout.
aria2: 2009-10-08 16:37:05.543453 ERROR -CUID#41 - Download aborted. URI=http://archlinux.umflint.edu/extra/os/x86...blahblah/path/to/package
aria2: Exception [AbstractCommand.cc:184] Timeout.
aria2: 2009-10-08 16:37:05.54367 ERROR -CUID#42 - Download aborted. URI=http://archlinux.umflint.edu/extra/os/x86...blahblah/path/to/package

The network is up and works. Pacman works, mirrorlist contains 15 servers that I ranked by speed. Dunno what's up with powerpill.

Here is the /etc/powerpill.conf

[options]

# Set the path to the aria2c binary.
#Aria2Bin = /usr/bin/aria2c

# Clean up leftover aria2 control files.
Aria2Clean

# Suppress aria2c output.
#Aria2Silent

# Powerpill will log output to this file if this
# option is set.
Log = /var/log/powerpill.log

# Suppress colored output.
#NoColor

# Set the path to the pacman binary.
# Change this to your preferred pacman binary,
# e.g. pacman-color
#PacmanBin = /usr/bin/pacman

# Set the path to the pacman configuration file.
# Change this if you want to use a custom configuration
# with powerpill.
#PacmanConf = /etc/pacman.conf

# Use reflector at runtime to get the latest mirrors.
# Depending on the reflector options that you use,
# this may slow down the overall process while retrieving
# the latest list, so use it wisely.
#Reflect = -l 45

# Set the number of times that powerpill will retry a
# download if it fails. This only has an effect when
# run with pacman's noconfirm option.
#RetryLimit = 3

# The minimum size a file must be for powerpill to
# split the file for segmented downloading (in MB).
#SplitSize = 5

# aria2 options
# These follow the same format as the aria2 config file.
# Please refer to the aria2 documentation for more details
# including a complete set of options (proxy settings etc).
# Only the most relevant options have been included here.
[aria2_options]

# maximum number of concurrent downloads
# This must be in the range 1-45. Powerpill sets it to 45
# and configures the metalink to adjust the connections
# per file.
#max-concurrent-downloads=10

# number of servers to connect to simultaneously
# This only has a limiting effect. Leave it unset to
# connect to the maximum number of servers allowed
# by the settings in the metalink.
#metalink-servers=20

# Increasing these settings will lose more time while
# waiting for unresponsive servers but decreasing them
# will miss active servers and lose time while aria2
# tries other servers. 
#timeout=5
#max-tries=2
#retry-wait=2


# lowest acceptable connection speed (0 = disabled)
# Downloads below this speed will be aborted.
# The speed is given in bytes by default. Append "K"
# or "M" for kilobytes or metabytes, resp.
# (1M = 1024K = 1048576)
#lowest-speed-limit=0

# maximum total download speed (0 = disabled)
# Use this to throttle the download.
# The speed is given as for lowest-speed-limit above.
#max-overall-download-limit=0


# Be careful with this. Aria2 can quickly create huge
# log files, depending on the log-level option.
#log=/var/log/aria2.log






# list pkgd-servers on your LAN here
# they will be queried before remote servers
#[PkgD]
#Server = http://xxx.xxx.xxx.xxx:yyyyy/$repo







# Here you can specify additional mirrors for powerpill.
# The mirrors specified here will not be used by pacman
# and thus the database will never be updated from them.
# These sections follow the same syntax as pacman.conf.

# Some of you will recognize this section as the heir
# to the additional_mirrors.conf file. :)

# Note that if you use the "reflect" option. there isn't
# any reason to include reflector mirrorlists down here.

#[core]
# Server = http://example.com/arch/$repo/
# Include = /path/to/mirrorlist

#[extra]
# Server = http://example.com/arch/$repo/
# Include = /path/to/mirrorlist

#[community]
# Server = http://example.com/arch/$repo/
# Include = /path/to/mirrorlist

Last edited by graysky (2009-10-15 21:35:15)

flamelab · 2009-10-09 20:57:01

It's server's problem, it may not be fully synced or doesn't respond to aria2.

Xyne · 2009-10-09 20:58:51

I've never seen the "AbstractCommand" exception before. Have you tried using a different mirror? What happens when you don't uncomment the Reflector line in powerpill.conf?*

Also make sure that the database is up-to-date with "powerpill -Syy" or "pacman -Syy".

* Why are you uncommenting that anyway?

graysky · 2009-10-09 22:10:59

@Xyne - I did a powerpill -Syy prior to attempting the install. The script literally tries all 10 of the servers in my /etc/pacman.d/mirrorlist and the error is true for all of them. I commented out the reflector line because if I leave it in there, powerpill does not work properly. Several of the packages literally d/l @ 0.5 k/sec.

graysky · 2009-10-14 19:05:53

@Xyne - can I post any log files to help diagnose this or do you have any thoughts/suggestions?

Last edited by graysky (2009-10-14 19:06:09)

Xyne · 2009-10-14 20:19:37

@graysky
You can turn on both powerpill and aria2c logging and I can take a look at the output, but if the problem is directly with aria2c or your network connectivity, I might not be able to help at all.

graysky · 2009-10-15 19:24:59

@Xyne - I uncommented the Log = /var/log/powerpill.log line as well as the aria2 line, and ran a powerpill -Syu which experienced the same timeouts. Odd thing is that it never wrote a /var/log/powerpill.log at all. I did get the aria2.log which I'll paste here:

2009-10-15 15:12:07.698856 INFO - <<--- --- --- ---
2009-10-15 15:12:07.698919 INFO -   --- --- --- ---
2009-10-15 15:12:07.698927 INFO -   --- --- --- --->>
2009-10-15 15:12:07.698934 INFO - aria2 1.6.2 x86_64-unknown-linux-gnu
2009-10-15 15:12:07.698944 INFO - Logging started.
2009-10-15 15:12:07.699568 INFO - Metalink: Queueing gzip-1.3.13-1-x86_64.pkg.tar.gz for download.
2009-10-15 15:12:07.699628 INFO - Metalink: Queueing ghostscript-8.70-2-x86_64.pkg.tar.gz for download.
2009-10-15 15:12:07.699662 INFO - Metalink: Queueing libcups-1.4.1-1-x86_64.pkg.tar.gz for download.
2009-10-15 15:12:07.699696 INFO - Metalink: Queueing imagemagick-6.5.6.10-1-x86_64.pkg.tar.gz for download.
2009-10-15 15:12:07.699728 INFO - Metalink: Queueing mlocate-0.22.2-1-x86_64.pkg.tar.gz for download.
2009-10-15 15:12:07.699759 INFO - Metalink: Queueing cups-1.4.1-1-x86_64.pkg.tar.gz for download.
2009-10-15 15:12:07.699792 INFO - Metalink: Queueing conky-1.7.2-4-x86_64.pkg.tar.gz for download.
2009-10-15 15:12:07.791050 INFO - 142 certificate(s) were imported.
2009-10-15 15:12:07.802027 INFO - The segment file /var/cache/pacman/pkg/gzip-1.3.13-1-x86_64.pkg.tar.gz.aria2 does not exist.
2009-10-15 15:12:07.802062 DEBUG - Instantiating DirectDiskAdaptor
2009-10-15 15:12:07.802086 INFO - The segment file /var/cache/pacman/pkg/gzip-1.3.13-1-x86_64.pkg.tar.gz.aria2 does not exist.
2009-10-15 15:12:07.802099 INFO - The segment file /var/cache/pacman/pkg/gzip-1.3.13-1-x86_64.pkg.tar.gz.aria2 does not exist.
2009-10-15 15:12:07.802111 INFO - The segment file /var/cache/pacman/pkg/gzip-1.3.13-1-x86_64.pkg.tar.gz.aria2 does not exist.
2009-10-15 15:12:07.802294 INFO - The segment file /var/cache/pacman/pkg/ghostscript-8.70-2-x86_64.pkg.tar.gz.aria2 does not exist.
2009-10-15 15:12:07.802315 DEBUG - Instantiating DirectDiskAdaptor
2009-10-15 15:12:07.802331 INFO - The segment file /var/cache/pacman/pkg/ghostscript-8.70-2-x86_64.pkg.tar.gz.aria2 does not exist.
2009-10-15 15:12:07.802344 INFO - The segment file /var/cache/pacman/pkg/ghostscript-8.70-2-x86_64.pkg.tar.gz.aria2 does not exist.
2009-10-15 15:12:07.802356 INFO - The segment file /var/cache/pacman/pkg/ghostscript-8.70-2-x86_64.pkg.tar.gz.aria2 does not exist.
2009-10-15 15:12:07.802434 INFO - The segment file /var/cache/pacman/pkg/libcups-1.4.1-1-x86_64.pkg.tar.gz.aria2 does not exist.
2009-10-15 15:12:07.802451 DEBUG - Instantiating DirectDiskAdaptor
2009-10-15 15:12:07.802467 INFO - The segment file /var/cache/pacman/pkg/libcups-1.4.1-1-x86_64.pkg.tar.gz.aria2 does not exist.
2009-10-15 15:12:07.802479 INFO - The segment file /var/cache/pacman/pkg/libcups-1.4.1-1-x86_64.pkg.tar.gz.aria2 does not exist.
2009-10-15 15:12:07.802490 INFO - The segment file /var/cache/pacman/pkg/libcups-1.4.1-1-x86_64.pkg.tar.gz.aria2 does not exist.
2009-10-15 15:12:07.802560 INFO - The segment file /var/cache/pacman/pkg/imagemagick-6.5.6.10-1-x86_64.pkg.tar.gz.aria2 does not exist.
2009-10-15 15:12:07.802577 DEBUG - Instantiating DirectDiskAdaptor
2009-10-15 15:12:07.802593 INFO - The segment file /var/cache/pacman/pkg/imagemagick-6.5.6.10-1-x86_64.pkg.tar.gz.aria2 does not exist.
2009-10-15 15:12:07.802605 INFO - The segment file /var/cache/pacman/pkg/imagemagick-6.5.6.10-1-x86_64.pkg.tar.gz.aria2 does not exist.
2009-10-15 15:12:07.802617 INFO - The segment file /var/cache/pacman/pkg/imagemagick-6.5.6.10-1-x86_64.pkg.tar.gz.aria2 does not exist.
2009-10-15 15:12:07.802771 INFO - The segment file /var/cache/pacman/pkg/mlocate-0.22.2-1-x86_64.pkg.tar.gz.aria2 does not exist.
2009-10-15 15:12:07.802788 DEBUG - Instantiating DirectDiskAdaptor
2009-10-15 15:12:07.802803 INFO - The segment file /var/cache/pacman/pkg/mlocate-0.22.2-1-x86_64.pkg.tar.gz.aria2 does not exist.
2009-10-15 15:12:07.802815 INFO - The segment file /var/cache/pacman/pkg/mlocate-0.22.2-1-x86_64.pkg.tar.gz.aria2 does not exist.
2009-10-15 15:12:07.802827 INFO - The segment file /var/cache/pacman/pkg/mlocate-0.22.2-1-x86_64.pkg.tar.gz.aria2 does not exist.
2009-10-15 15:12:07.803075 INFO - The segment file /var/cache/pacman/pkg/cups-1.4.1-1-x86_64.pkg.tar.gz.aria2 does not exist.
2009-10-15 15:12:07.803100 DEBUG - Instantiating DirectDiskAdaptor
2009-10-15 15:12:07.803115 INFO - The segment file /var/cache/pacman/pkg/cups-1.4.1-1-x86_64.pkg.tar.gz.aria2 does not exist.
2009-10-15 15:12:07.803127 INFO - The segment file /var/cache/pacman/pkg/cups-1.4.1-1-x86_64.pkg.tar.gz.aria2 does not exist.
2009-10-15 15:12:07.803139 INFO - The segment file /var/cache/pacman/pkg/cups-1.4.1-1-x86_64.pkg.tar.gz.aria2 does not exist.
2009-10-15 15:12:07.803208 INFO - The segment file /var/cache/pacman/pkg/conky-1.7.2-4-x86_64.pkg.tar.gz.aria2 does not exist.
2009-10-15 15:12:07.803225 DEBUG - Instantiating DirectDiskAdaptor
2009-10-15 15:12:07.803240 INFO - The segment file /var/cache/pacman/pkg/conky-1.7.2-4-x86_64.pkg.tar.gz.aria2 does not exist.
2009-10-15 15:12:07.803252 INFO - The segment file /var/cache/pacman/pkg/conky-1.7.2-4-x86_64.pkg.tar.gz.aria2 does not exist.
2009-10-15 15:12:07.803264 INFO - The segment file /var/cache/pacman/pkg/conky-1.7.2-4-x86_64.pkg.tar.gz.aria2 does not exist.
2009-10-15 15:12:07.803325 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.803346 DEBUG - usedPieces.size()=1
2009-10-15 15:12:07.803354 DEBUG - Attach segment#0 to CUID#6.
2009-10-15 15:12:07.803362 DEBUG - index=0, length=81683, segmentLength=1048576, writtenLength=0
2009-10-15 15:12:07.803452 DEBUG - CUID#7 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.803463 DEBUG - usedPieces.size()=1
2009-10-15 15:12:07.803471 DEBUG - Attach segment#0 to CUID#7.
2009-10-15 15:12:07.803479 DEBUG - index=0, length=1048576, segmentLength=1048576, writtenLength=0
2009-10-15 15:12:07.803548 DEBUG - CUID#8 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.803559 DEBUG - usedPieces.size()=2
2009-10-15 15:12:07.803567 DEBUG - Attach segment#8 to CUID#8.
2009-10-15 15:12:07.803575 DEBUG - index=8, length=1048576, segmentLength=1048576, writtenLength=0
2009-10-15 15:12:07.803643 DEBUG - CUID#9 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.803655 DEBUG - usedPieces.size()=3
2009-10-15 15:12:07.803663 DEBUG - Attach segment#4 to CUID#9.
2009-10-15 15:12:07.803671 DEBUG - index=4, length=1048576, segmentLength=1048576, writtenLength=0
2009-10-15 15:12:07.803736 DEBUG - CUID#10 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.803747 DEBUG - usedPieces.size()=4
2009-10-15 15:12:07.803755 DEBUG - Attach segment#12 to CUID#10.
2009-10-15 15:12:07.803763 DEBUG - index=12, length=1048576, segmentLength=1048576, writtenLength=0
2009-10-15 15:12:07.803829 DEBUG - CUID#11 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.803841 DEBUG - usedPieces.size()=5
2009-10-15 15:12:07.803849 DEBUG - Attach segment#2 to CUID#11.
2009-10-15 15:12:07.803857 DEBUG - index=2, length=1048576, segmentLength=1048576, writtenLength=0
2009-10-15 15:12:07.803922 DEBUG - CUID#12 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.803933 DEBUG - usedPieces.size()=6
2009-10-15 15:12:07.803940 DEBUG - Attach segment#6 to CUID#12.
2009-10-15 15:12:07.803948 DEBUG - index=6, length=1048576, segmentLength=1048576, writtenLength=0
2009-10-15 15:12:07.804015 DEBUG - CUID#13 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.804026 DEBUG - usedPieces.size()=7
2009-10-15 15:12:07.804033 DEBUG - Attach segment#10 to CUID#13.
2009-10-15 15:12:07.804041 DEBUG - index=10, length=1048576, segmentLength=1048576, writtenLength=0
2009-10-15 15:12:07.804107 DEBUG - CUID#14 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.804117 DEBUG - usedPieces.size()=8
2009-10-15 15:12:07.804125 DEBUG - Attach segment#14 to CUID#14.
2009-10-15 15:12:07.804132 DEBUG - index=14, length=232977, segmentLength=1048576, writtenLength=0
2009-10-15 15:12:07.804194 DEBUG - CUID#15 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.804205 DEBUG - usedPieces.size()=9
2009-10-15 15:12:07.804213 DEBUG - Attach segment#1 to CUID#15.
2009-10-15 15:12:07.804221 DEBUG - index=1, length=1048576, segmentLength=1048576, writtenLength=0
2009-10-15 15:12:07.804278 DEBUG - CUID#16 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.804294 DEBUG - usedPieces.size()=10
2009-10-15 15:12:07.804302 DEBUG - Attach segment#3 to CUID#16.
2009-10-15 15:12:07.804309 DEBUG - index=3, length=1048576, segmentLength=1048576, writtenLength=0
2009-10-15 15:12:07.804363 DEBUG - CUID#17 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.804374 DEBUG - usedPieces.size()=11
2009-10-15 15:12:07.804381 DEBUG - Attach segment#5 to CUID#17.
2009-10-15 15:12:07.804389 DEBUG - index=5, length=1048576, segmentLength=1048576, writtenLength=0
2009-10-15 15:12:07.804438 DEBUG - CUID#18 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.804449 DEBUG - usedPieces.size()=12
2009-10-15 15:12:07.804456 DEBUG - Attach segment#7 to CUID#18.
2009-10-15 15:12:07.804464 DEBUG - index=7, length=1048576, segmentLength=1048576, writtenLength=0
2009-10-15 15:12:07.804508 DEBUG - CUID#19 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.804518 DEBUG - usedPieces.size()=13
2009-10-15 15:12:07.804526 DEBUG - Attach segment#9 to CUID#19.
2009-10-15 15:12:07.804534 DEBUG - index=9, length=1048576, segmentLength=1048576, writtenLength=0
2009-10-15 15:12:07.804574 DEBUG - CUID#20 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.804585 DEBUG - usedPieces.size()=14
2009-10-15 15:12:07.804593 DEBUG - Attach segment#11 to CUID#20.
2009-10-15 15:12:07.804600 DEBUG - index=11, length=1048576, segmentLength=1048576, writtenLength=0
2009-10-15 15:12:07.804636 DEBUG - CUID#21 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.804647 DEBUG - usedPieces.size()=15
2009-10-15 15:12:07.804654 DEBUG - Attach segment#13 to CUID#21.
2009-10-15 15:12:07.804662 DEBUG - index=13, length=1048576, segmentLength=1048576, writtenLength=0
2009-10-15 15:12:07.804694 DEBUG - CUID#22 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.804704 DEBUG - usedPieces.size()=1
2009-10-15 15:12:07.804712 DEBUG - Attach segment#0 to CUID#22.
2009-10-15 15:12:07.804719 DEBUG - index=0, length=340204, segmentLength=1048576, writtenLength=0
2009-10-15 15:12:07.804810 DEBUG - CUID#23 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.804822 DEBUG - usedPieces.size()=1
2009-10-15 15:12:07.804830 DEBUG - Attach segment#0 to CUID#23.
2009-10-15 15:12:07.804838 DEBUG - index=0, length=1048576, segmentLength=1048576, writtenLength=0
2009-10-15 15:12:07.804909 DEBUG - CUID#24 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.804920 DEBUG - usedPieces.size()=1
2009-10-15 15:12:07.804928 DEBUG - Attach segment#0 to CUID#24.
2009-10-15 15:12:07.804935 DEBUG - index=0, length=78621, segmentLength=1048576, writtenLength=0
2009-10-15 15:12:07.805008 DEBUG - CUID#25 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.805019 DEBUG - usedPieces.size()=1
2009-10-15 15:12:07.805026 DEBUG - Attach segment#0 to CUID#25.
2009-10-15 15:12:07.805034 DEBUG - index=0, length=1048576, segmentLength=1048576, writtenLength=0
2009-10-15 15:12:07.805099 DEBUG - CUID#26 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.805109 DEBUG - usedPieces.size()=1
2009-10-15 15:12:07.805117 DEBUG - Attach segment#0 to CUID#26.
2009-10-15 15:12:07.805125 DEBUG - index=0, length=158585, segmentLength=1048576, writtenLength=0
2009-10-15 15:12:07.805205 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.805932 INFO - CUID#6 - Resolving hostname mirrors.portafixe.com
2009-10-15 15:12:07.814465 DEBUG - CUID#7 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.815151 INFO - CUID#7 - Resolving hostname mirror.csclub.uwaterloo.ca
2009-10-15 15:12:07.823586 DEBUG - CUID#8 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.824281 INFO - CUID#8 - Resolving hostname distro.ibiblio.org
2009-10-15 15:12:07.832669 DEBUG - CUID#9 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.833365 INFO - CUID#9 - Resolving hostname archlinux.unixheads.org
2009-10-15 15:12:07.841856 DEBUG - CUID#10 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.842556 INFO - CUID#10 - Resolving hostname mirror.cs.vt.edu
2009-10-15 15:12:07.850975 DEBUG - CUID#11 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.851673 INFO - CUID#11 - Resolving hostname schlunix.org
2009-10-15 15:12:07.860109 DEBUG - CUID#12 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.860813 INFO - CUID#12 - Resolving hostname www.gtlib.gatech.edu
2009-10-15 15:12:07.869447 DEBUG - CUID#13 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.870148 INFO - CUID#13 - Resolving hostname mirrors.hosef.org
2009-10-15 15:12:07.878574 DEBUG - CUID#14 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.879279 INFO - CUID#14 - Resolving hostname mirrors.gigenet.com
2009-10-15 15:12:07.887705 DEBUG - CUID#15 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.888406 INFO - CUID#15 - Resolving hostname mirror.archlinux.com.ve
2009-10-15 15:12:07.897705 DEBUG - CUID#16 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.898410 INFO - CUID#16 - Resolving hostname archlinux.umflint.edu
2009-10-15 15:12:07.906871 DEBUG - CUID#17 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.907577 INFO - CUID#17 - Resolving hostname mirror.rit.edu
2009-10-15 15:12:07.916038 DEBUG - CUID#18 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.916740 INFO - CUID#18 - Resolving hostname locke.suu.edu
2009-10-15 15:12:07.925167 DEBUG - CUID#19 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.925875 INFO - CUID#19 - Resolving hostname mirrors.easynews.com
2009-10-15 15:12:07.934449 DEBUG - CUID#20 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.935197 INFO - CUID#20 - Resolving hostname mirror.umoss.org
2009-10-15 15:12:07.943769 DEBUG - CUID#21 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.944474 INFO - CUID#21 - Resolving hostname mirrors.portafixe.com
2009-10-15 15:12:07.952926 DEBUG - CUID#22 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.953632 INFO - CUID#22 - Resolving hostname mirrors.hosef.org
2009-10-15 15:12:07.962115 DEBUG - CUID#23 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.962822 INFO - CUID#23 - Resolving hostname mirrors.hosef.org
2009-10-15 15:12:07.971531 DEBUG - CUID#24 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.972244 INFO - CUID#24 - Resolving hostname mirror.csclub.uwaterloo.ca
2009-10-15 15:12:07.980682 DEBUG - CUID#25 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.981389 INFO - CUID#25 - Resolving hostname mirror.csclub.uwaterloo.ca
2009-10-15 15:12:07.989859 DEBUG - CUID#26 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.990561 INFO - CUID#26 - Resolving hostname archlinux.unixheads.org
2009-10-15 15:12:07.999372 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.999385 DEBUG - CUID#7 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.999393 DEBUG - CUID#8 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.999401 DEBUG - CUID#9 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.999408 DEBUG - CUID#10 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.999416 DEBUG - CUID#11 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.999424 DEBUG - CUID#12 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.999431 DEBUG - CUID#13 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:07.999439 DEBUG - CUID#14 - socket: read:0, write:0, hup:0, err:0

CUT

2009-10-15 15:12:38.998886 DEBUG - ServerStat: set status ERROR for mirrors.portafixe.com (http)
2009-10-15 15:12:38.998973 INFO - CUID#6 - Restarting the download. URI=http://mirrors.portafixe.com/archlinux/core/os/x86_64/gzip-1.3.13-1-x86_64.pkg.tar.gz
Exception: [AbstractCommand.cc:207] URI=http://mirrors.portafixe.com/archlinux/core/os/x86_64/gzip-1.3.13-1-x86_64.pkg.tar.gz
  -> [AbstractCommand.cc:184] Timeout.
2009-10-15 15:12:38.998994 DEBUG - Memorized segment index=0, writtenLength=0
2009-10-15 15:12:38.999004 DEBUG - CUID#6 - Pooling request URI=http://mirrors.portafixe.com/archlinux/core/os/x86_64/gzip-1.3.13-1-x86_64.pkg.tar.gz
2009-10-15 15:12:38.999060 DEBUG - Failed to delete socket event:No such file or directory
2009-10-15 15:12:38.999103 DEBUG - CUID#7 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:38.999114 DEBUG - ServerStat: set status ERROR for mirror.csclub.uwaterloo.ca (http)
2009-10-15 15:12:38.999134 INFO - CUID#7 - Restarting the download. URI=http://mirror.csclub.uwaterloo.ca/archlinux/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
Exception: [AbstractCommand.cc:207] URI=http://mirror.csclub.uwaterloo.ca/archlinux/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
  -> [AbstractCommand.cc:184] Timeout.
2009-10-15 15:12:38.999155 DEBUG - Memorized segment index=0, writtenLength=0
2009-10-15 15:12:38.999164 DEBUG - CUID#7 - Pooling request URI=http://mirror.csclub.uwaterloo.ca/archlinux/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
2009-10-15 15:12:38.999179 DEBUG - Failed to delete socket event:No such file or directory
2009-10-15 15:12:38.999212 DEBUG - CUID#8 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:38.999222 DEBUG - ServerStat: set status ERROR for distro.ibiblio.org (http)
2009-10-15 15:12:38.999241 INFO - CUID#8 - Restarting the download. URI=http://distro.ibiblio.org/pub/linux/distributions/archlinux/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
Exception: [AbstractCommand.cc:207] URI=http://distro.ibiblio.org/pub/linux/distributions/archlinux/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
  -> [AbstractCommand.cc:184] Timeout.
2009-10-15 15:12:38.999255 DEBUG - Memorized segment index=8, writtenLength=0
2009-10-15 15:12:38.999264 DEBUG - CUID#8 - Pooling request URI=http://distro.ibiblio.org/pub/linux/distributions/archlinux/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
2009-10-15 15:12:38.999278 DEBUG - Failed to delete socket event:No such file or directory
2009-10-15 15:12:38.999311 DEBUG - CUID#9 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:38.999321 DEBUG - ServerStat: set status ERROR for archlinux.unixheads.org (http)
2009-10-15 15:12:38.999340 INFO - CUID#9 - Restarting the download. URI=http://archlinux.unixheads.org/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
Exception: [AbstractCommand.cc:207] URI=http://archlinux.unixheads.org/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
  -> [AbstractCommand.cc:184] Timeout.
2009-10-15 15:12:38.999354 DEBUG - Memorized segment index=4, writtenLength=0
2009-10-15 15:12:38.999363 DEBUG - CUID#9 - Pooling request URI=http://archlinux.unixheads.org/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
2009-10-15 15:12:38.999377 DEBUG - Failed to delete socket event:No such file or directory
2009-10-15 15:12:38.999409 DEBUG - CUID#10 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:38.999422 DEBUG - ServerStat: set status ERROR for mirror.cs.vt.edu (http)
2009-10-15 15:12:38.999441 INFO - CUID#10 - Restarting the download. URI=http://mirror.cs.vt.edu/pub/ArchLinux/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
Exception: [AbstractCommand.cc:207] URI=http://mirror.cs.vt.edu/pub/ArchLinux/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
  -> [AbstractCommand.cc:184] Timeout.
2009-10-15 15:12:38.999455 DEBUG - Memorized segment index=12, writtenLength=0
2009-10-15 15:12:38.999464 DEBUG - CUID#10 - Pooling request URI=http://mirror.cs.vt.edu/pub/ArchLinux/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
2009-10-15 15:12:38.999478 DEBUG - Failed to delete socket event:No such file or directory
2009-10-15 15:12:38.999510 DEBUG - CUID#11 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:38.999521 DEBUG - ServerStat: set status ERROR for schlunix.org (http)
2009-10-15 15:12:38.999539 INFO - CUID#11 - Restarting the download. URI=http://schlunix.org/archlinux/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
Exception: [AbstractCommand.cc:207] URI=http://schlunix.org/archlinux/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
  -> [AbstractCommand.cc:184] Timeout.
2009-10-15 15:12:38.999553 DEBUG - Memorized segment index=2, writtenLength=0
2009-10-15 15:12:38.999562 DEBUG - CUID#11 - Pooling request URI=http://schlunix.org/archlinux/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
2009-10-15 15:12:38.999576 DEBUG - Failed to delete socket event:No such file or directory
2009-10-15 15:12:38.999608 DEBUG - CUID#12 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:38.999619 DEBUG - ServerStat: set status ERROR for www.gtlib.gatech.edu (http)
2009-10-15 15:12:38.999637 INFO - CUID#12 - Restarting the download. URI=http://www.gtlib.gatech.edu/pub/linux/distributions/archlinux/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
Exception: [AbstractCommand.cc:207] URI=http://www.gtlib.gatech.edu/pub/linux/distributions/archlinux/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
  -> [AbstractCommand.cc:184] Timeout.
2009-10-15 15:12:38.999656 DEBUG - Memorized segment index=6, writtenLength=0
2009-10-15 15:12:38.999665 DEBUG - CUID#12 - Pooling request URI=http://www.gtlib.gatech.edu/pub/linux/distributions/archlinux/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
2009-10-15 15:12:38.999679 DEBUG - Failed to delete socket event:No such file or directory
2009-10-15 15:12:38.999711 DEBUG - CUID#13 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:38.999722 DEBUG - ServerStat: set status ERROR for mirrors.hosef.org (http)
2009-10-15 15:12:38.999740 INFO - CUID#13 - Restarting the download. URI=http://mirrors.hosef.org/archlinux/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
Exception: [AbstractCommand.cc:207] URI=http://mirrors.hosef.org/archlinux/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
  -> [AbstractCommand.cc:184] Timeout.
2009-10-15 15:12:38.999755 DEBUG - Memorized segment index=10, writtenLength=0
2009-10-15 15:12:38.999763 DEBUG - CUID#13 - Pooling request URI=http://mirrors.hosef.org/archlinux/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
2009-10-15 15:12:38.999777 DEBUG - Failed to delete socket event:No such file or directory
2009-10-15 15:12:38.999809 DEBUG - CUID#14 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:38.999820 DEBUG - ServerStat: set status ERROR for mirrors.gigenet.com (http)
2009-10-15 15:12:38.999839 INFO - CUID#14 - Restarting the download. URI=http://mirrors.gigenet.com/archlinux/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
Exception: [AbstractCommand.cc:207] URI=http://mirrors.gigenet.com/archlinux/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
  -> [AbstractCommand.cc:184] Timeout.
2009-10-15 15:12:38.999853 DEBUG - Memorized segment index=14, writtenLength=0
2009-10-15 15:12:38.999861 DEBUG - CUID#14 - Pooling request URI=http://mirrors.gigenet.com/archlinux/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
2009-10-15 15:12:38.999875 DEBUG - Failed to delete socket event:No such file or directory
2009-10-15 15:12:38.999907 DEBUG - CUID#15 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:38.999918 DEBUG - ServerStat: set status ERROR for mirror.archlinux.com.ve (http)
2009-10-15 15:12:38.999936 INFO - CUID#15 - Restarting the download. URI=http://mirror.archlinux.com.ve/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
Exception: [AbstractCommand.cc:207] URI=http://mirror.archlinux.com.ve/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
  -> [AbstractCommand.cc:184] Timeout.
2009-10-15 15:12:38.999951 DEBUG - Memorized segment index=1, writtenLength=0
2009-10-15 15:12:38.999959 DEBUG - CUID#15 - Pooling request URI=http://mirror.archlinux.com.ve/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
2009-10-15 15:12:38.999974 DEBUG - Failed to delete socket event:No such file or directory
2009-10-15 15:12:39.000006 DEBUG - CUID#16 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.000017 DEBUG - ServerStat: set status ERROR for archlinux.umflint.edu (http)
2009-10-15 15:12:39.000036 INFO - CUID#16 - Restarting the download. URI=http://archlinux.umflint.edu/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
Exception: [AbstractCommand.cc:207] URI=http://archlinux.umflint.edu/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
  -> [AbstractCommand.cc:184] Timeout.
2009-10-15 15:12:39.000050 DEBUG - Memorized segment index=3, writtenLength=0
2009-10-15 15:12:39.000058 DEBUG - CUID#16 - Pooling request URI=http://archlinux.umflint.edu/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
2009-10-15 15:12:39.000072 DEBUG - Failed to delete socket event:No such file or directory
2009-10-15 15:12:39.000104 DEBUG - CUID#17 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.000115 DEBUG - ServerStat: set status ERROR for mirror.rit.edu (http)
2009-10-15 15:12:39.000138 INFO - CUID#17 - Restarting the download. URI=http://mirror.rit.edu/archlinux/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
Exception: [AbstractCommand.cc:207] URI=http://mirror.rit.edu/archlinux/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
  -> [AbstractCommand.cc:184] Timeout.
2009-10-15 15:12:39.000153 DEBUG - Memorized segment index=5, writtenLength=0
2009-10-15 15:12:39.000161 DEBUG - CUID#17 - Pooling request URI=http://mirror.rit.edu/archlinux/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
2009-10-15 15:12:39.000176 DEBUG - Failed to delete socket event:No such file or directory
2009-10-15 15:12:39.000208 DEBUG - CUID#18 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.000219 DEBUG - ServerStat: set status ERROR for locke.suu.edu (ftp)
2009-10-15 15:12:39.000238 INFO - CUID#18 - Restarting the download. URI=ftp://locke.suu.edu/linux/dist/archlinux/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
Exception: [AbstractCommand.cc:207] URI=ftp://locke.suu.edu/linux/dist/archlinux/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
  -> [AbstractCommand.cc:184] Timeout.
2009-10-15 15:12:39.000252 DEBUG - Memorized segment index=7, writtenLength=0
2009-10-15 15:12:39.000260 DEBUG - CUID#18 - Pooling request URI=ftp://locke.suu.edu/linux/dist/archlinux/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
2009-10-15 15:12:39.000275 DEBUG - Failed to delete socket event:No such file or directory
2009-10-15 15:12:39.000307 DEBUG - CUID#19 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.000318 DEBUG - ServerStat: set status ERROR for mirrors.easynews.com (http)
2009-10-15 15:12:39.000336 INFO - CUID#19 - Restarting the download. URI=http://mirrors.easynews.com/linux/archlinux/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
Exception: [AbstractCommand.cc:207] URI=http://mirrors.easynews.com/linux/archlinux/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
  -> [AbstractCommand.cc:184] Timeout.
2009-10-15 15:12:39.000350 DEBUG - Memorized segment index=9, writtenLength=0
2009-10-15 15:12:39.000359 DEBUG - CUID#19 - Pooling request URI=http://mirrors.easynews.com/linux/archlinux/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
2009-10-15 15:12:39.000373 DEBUG - Failed to delete socket event:No such file or directory
2009-10-15 15:12:39.000406 DEBUG - CUID#20 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.000417 DEBUG - ServerStat: set status ERROR for mirror.umoss.org (http)
2009-10-15 15:12:39.000435 INFO - CUID#20 - Restarting the download. URI=http://mirror.umoss.org/archlinux/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
Exception: [AbstractCommand.cc:207] URI=http://mirror.umoss.org/archlinux/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
  -> [AbstractCommand.cc:184] Timeout.
2009-10-15 15:12:39.000449 DEBUG - Memorized segment index=11, writtenLength=0
2009-10-15 15:12:39.000458 DEBUG - CUID#20 - Pooling request URI=http://mirror.umoss.org/archlinux/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
2009-10-15 15:12:39.000472 DEBUG - Failed to delete socket event:No such file or directory
2009-10-15 15:12:39.000504 DEBUG - CUID#21 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.000514 DEBUG - ServerStat: set status ERROR for mirrors.portafixe.com (http)
2009-10-15 15:12:39.000533 INFO - CUID#21 - Restarting the download. URI=http://mirrors.portafixe.com/archlinux/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
Exception: [AbstractCommand.cc:207] URI=http://mirrors.portafixe.com/archlinux/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
  -> [AbstractCommand.cc:184] Timeout.
2009-10-15 15:12:39.000546 DEBUG - Memorized segment index=13, writtenLength=0
2009-10-15 15:12:39.000555 DEBUG - CUID#21 - Pooling request URI=http://mirrors.portafixe.com/archlinux/extra/os/x86_64/ghostscript-8.70-2-x86_64.pkg.tar.gz
2009-10-15 15:12:39.000569 DEBUG - Failed to delete socket event:No such file or directory
2009-10-15 15:12:39.000601 DEBUG - CUID#22 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.000616 DEBUG - ServerStat: set status ERROR for mirrors.hosef.org (http)
2009-10-15 15:12:39.000635 INFO - CUID#22 - Restarting the download. URI=http://mirrors.hosef.org/archlinux/extra/os/x86_64/libcups-1.4.1-1-x86_64.pkg.tar.gz
Exception: [AbstractCommand.cc:207] URI=http://mirrors.hosef.org/archlinux/extra/os/x86_64/libcups-1.4.1-1-x86_64.pkg.tar.gz
  -> [AbstractCommand.cc:184] Timeout.
2009-10-15 15:12:39.000650 DEBUG - Memorized segment index=0, writtenLength=0
2009-10-15 15:12:39.000658 DEBUG - CUID#22 - Pooling request URI=http://mirrors.hosef.org/archlinux/extra/os/x86_64/libcups-1.4.1-1-x86_64.pkg.tar.gz
2009-10-15 15:12:39.000672 DEBUG - Failed to delete socket event:No such file or directory
2009-10-15 15:12:39.000705 DEBUG - CUID#23 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.000715 DEBUG - ServerStat: set status ERROR for mirrors.hosef.org (http)
2009-10-15 15:12:39.000733 INFO - CUID#23 - Restarting the download. URI=http://mirrors.hosef.org/archlinux/extra/os/x86_64/imagemagick-6.5.6.10-1-x86_64.pkg.tar.gz
Exception: [AbstractCommand.cc:207] URI=http://mirrors.hosef.org/archlinux/extra/os/x86_64/imagemagick-6.5.6.10-1-x86_64.pkg.tar.gz
  -> [AbstractCommand.cc:184] Timeout.
2009-10-15 15:12:39.000748 DEBUG - Memorized segment index=0, writtenLength=0
2009-10-15 15:12:39.000757 DEBUG - CUID#23 - Pooling request URI=http://mirrors.hosef.org/archlinux/extra/os/x86_64/imagemagick-6.5.6.10-1-x86_64.pkg.tar.gz
2009-10-15 15:12:39.000771 DEBUG - Failed to delete socket event:No such file or directory
2009-10-15 15:12:39.000804 DEBUG - CUID#24 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.000814 DEBUG - ServerStat: set status ERROR for mirror.csclub.uwaterloo.ca (http)
2009-10-15 15:12:39.000832 INFO - CUID#24 - Restarting the download. URI=http://mirror.csclub.uwaterloo.ca/archlinux/core/os/x86_64/mlocate-0.22.2-1-x86_64.pkg.tar.gz
Exception: [AbstractCommand.cc:207] URI=http://mirror.csclub.uwaterloo.ca/archlinux/core/os/x86_64/mlocate-0.22.2-1-x86_64.pkg.tar.gz
  -> [AbstractCommand.cc:184] Timeout.
2009-10-15 15:12:39.000847 DEBUG - Memorized segment index=0, writtenLength=0
2009-10-15 15:12:39.000856 DEBUG - CUID#24 - Pooling request URI=http://mirror.csclub.uwaterloo.ca/archlinux/core/os/x86_64/mlocate-0.22.2-1-x86_64.pkg.tar.gz
2009-10-15 15:12:39.000870 DEBUG - Failed to delete socket event:No such file or directory
2009-10-15 15:12:39.000902 DEBUG - CUID#25 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.000913 DEBUG - ServerStat: set status ERROR for mirror.csclub.uwaterloo.ca (ftp)
2009-10-15 15:12:39.000932 INFO - CUID#25 - Restarting the download. URI=ftp://mirror.csclub.uwaterloo.ca/archlinux/extra/os/x86_64/cups-1.4.1-1-x86_64.pkg.tar.gz
Exception: [AbstractCommand.cc:207] URI=ftp://mirror.csclub.uwaterloo.ca/archlinux/extra/os/x86_64/cups-1.4.1-1-x86_64.pkg.tar.gz
  -> [AbstractCommand.cc:184] Timeout.
2009-10-15 15:12:39.000947 DEBUG - Memorized segment index=0, writtenLength=0
2009-10-15 15:12:39.000955 DEBUG - CUID#25 - Pooling request URI=ftp://mirror.csclub.uwaterloo.ca/archlinux/extra/os/x86_64/cups-1.4.1-1-x86_64.pkg.tar.gz
2009-10-15 15:12:39.000970 DEBUG - Failed to delete socket event:No such file or directory
2009-10-15 15:12:39.001002 DEBUG - CUID#26 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.001013 DEBUG - ServerStat: set status ERROR for archlinux.unixheads.org (http)
2009-10-15 15:12:39.001031 INFO - CUID#26 - Restarting the download. URI=http://archlinux.unixheads.org/extra/os/x86_64/conky-1.7.2-4-x86_64.pkg.tar.gz
Exception: [AbstractCommand.cc:207] URI=http://archlinux.unixheads.org/extra/os/x86_64/conky-1.7.2-4-x86_64.pkg.tar.gz
  -> [AbstractCommand.cc:184] Timeout.
2009-10-15 15:12:39.001045 DEBUG - Memorized segment index=0, writtenLength=0
2009-10-15 15:12:39.001054 DEBUG - CUID#26 - Pooling request URI=http://archlinux.unixheads.org/extra/os/x86_64/conky-1.7.2-4-x86_64.pkg.tar.gz
2009-10-15 15:12:39.001068 DEBUG - Failed to delete socket event:No such file or directory
2009-10-15 15:12:39.001113 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.001124 DEBUG - Attach segment#0 to CUID#6.
2009-10-15 15:12:39.001132 DEBUG - index=0, length=81683, segmentLength=1048576, writtenLength=0
2009-10-15 15:12:39.001140 DEBUG - writtenLength(in memo)=0, writtenLength=0
2009-10-15 15:12:39.001156 DEBUG - CUID#7 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.001165 DEBUG - Attach segment#0 to CUID#7.
2009-10-15 15:12:39.001173 DEBUG - index=0, length=1048576, segmentLength=1048576, writtenLength=0
2009-10-15 15:12:39.001181 DEBUG - writtenLength(in memo)=0, writtenLength=0
2009-10-15 15:12:39.001194 DEBUG - CUID#8 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.001203 DEBUG - Attach segment#8 to CUID#8.
2009-10-15 15:12:39.001210 DEBUG - index=8, length=1048576, segmentLength=1048576, writtenLength=0
2009-10-15 15:12:39.001218 DEBUG - writtenLength(in memo)=0, writtenLength=0
2009-10-15 15:12:39.001230 DEBUG - CUID#9 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.001239 DEBUG - Attach segment#4 to CUID#9.
2009-10-15 15:12:39.001248 DEBUG - index=4, length=1048576, segmentLength=1048576, writtenLength=0
2009-10-15 15:12:39.001256 DEBUG - writtenLength(in memo)=0, writtenLength=0
2009-10-15 15:12:39.001268 DEBUG - CUID#10 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.001277 DEBUG - Attach segment#12 to CUID#10.
2009-10-15 15:12:39.001285 DEBUG - index=12, length=1048576, segmentLength=1048576, writtenLength=0
2009-10-15 15:12:39.001292 DEBUG - writtenLength(in memo)=0, writtenLength=0
2009-10-15 15:12:39.001304 DEBUG - CUID#11 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.001313 DEBUG - Attach segment#2 to CUID#11.
2009-10-15 15:12:39.001321 DEBUG - index=2, length=1048576, segmentLength=1048576, writtenLength=0
2009-10-15 15:12:39.001328 DEBUG - writtenLength(in memo)=0, writtenLength=0
2009-10-15 15:12:39.001340 DEBUG - CUID#12 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.001349 DEBUG - Attach segment#6 to CUID#12.
2009-10-15 15:12:39.001357 DEBUG - index=6, length=1048576, segmentLength=1048576, writtenLength=0
2009-10-15 15:12:39.001364 DEBUG - writtenLength(in memo)=0, writtenLength=0
2009-10-15 15:12:39.001376 DEBUG - CUID#13 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.001385 DEBUG - Attach segment#10 to CUID#13.
2009-10-15 15:12:39.001393 DEBUG - index=10, length=1048576, segmentLength=1048576, writtenLength=0
2009-10-15 15:12:39.001401 DEBUG - writtenLength(in memo)=0, writtenLength=0
2009-10-15 15:12:39.001413 DEBUG - CUID#14 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.001422 DEBUG - Attach segment#14 to CUID#14.
2009-10-15 15:12:39.001430 DEBUG - index=14, length=232977, segmentLength=1048576, writtenLength=0
2009-10-15 15:12:39.001437 DEBUG - writtenLength(in memo)=0, writtenLength=0
2009-10-15 15:12:39.001449 DEBUG - CUID#15 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.001458 DEBUG - Attach segment#1 to CUID#15.
2009-10-15 15:12:39.001466 DEBUG - index=1, length=1048576, segmentLength=1048576, writtenLength=0
2009-10-15 15:12:39.001474 DEBUG - writtenLength(in memo)=0, writtenLength=0
2009-10-15 15:12:39.001485 DEBUG - CUID#16 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.001495 DEBUG - Attach segment#3 to CUID#16.
2009-10-15 15:12:39.001503 DEBUG - index=3, length=1048576, segmentLength=1048576, writtenLength=0
2009-10-15 15:12:39.001510 DEBUG - writtenLength(in memo)=0, writtenLength=0
2009-10-15 15:12:39.001522 DEBUG - CUID#17 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.001531 DEBUG - Attach segment#5 to CUID#17.
2009-10-15 15:12:39.001539 DEBUG - index=5, length=1048576, segmentLength=1048576, writtenLength=0
2009-10-15 15:12:39.001547 DEBUG - writtenLength(in memo)=0, writtenLength=0
2009-10-15 15:12:39.001558 DEBUG - CUID#18 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.001568 DEBUG - Attach segment#7 to CUID#18.
2009-10-15 15:12:39.001575 DEBUG - index=7, length=1048576, segmentLength=1048576, writtenLength=0
2009-10-15 15:12:39.001587 DEBUG - writtenLength(in memo)=0, writtenLength=0
2009-10-15 15:12:39.001600 DEBUG - CUID#19 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.001609 DEBUG - Attach segment#9 to CUID#19.
2009-10-15 15:12:39.001617 DEBUG - index=9, length=1048576, segmentLength=1048576, writtenLength=0
2009-10-15 15:12:39.001624 DEBUG - writtenLength(in memo)=0, writtenLength=0
2009-10-15 15:12:39.001636 DEBUG - CUID#20 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.001646 DEBUG - Attach segment#11 to CUID#20.
2009-10-15 15:12:39.001653 DEBUG - index=11, length=1048576, segmentLength=1048576, writtenLength=0
2009-10-15 15:12:39.001661 DEBUG - writtenLength(in memo)=0, writtenLength=0
2009-10-15 15:12:39.001673 DEBUG - CUID#21 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.001682 DEBUG - Attach segment#13 to CUID#21.
2009-10-15 15:12:39.001690 DEBUG - index=13, length=1048576, segmentLength=1048576, writtenLength=0
2009-10-15 15:12:39.001697 DEBUG - writtenLength(in memo)=0, writtenLength=0
2009-10-15 15:12:39.001709 DEBUG - CUID#22 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.001718 DEBUG - Attach segment#0 to CUID#22.
2009-10-15 15:12:39.001726 DEBUG - index=0, length=340204, segmentLength=1048576, writtenLength=0
2009-10-15 15:12:39.001734 DEBUG - writtenLength(in memo)=0, writtenLength=0
2009-10-15 15:12:39.001747 DEBUG - CUID#23 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.001757 DEBUG - Attach segment#0 to CUID#23.
2009-10-15 15:12:39.001765 DEBUG - index=0, length=1048576, segmentLength=1048576, writtenLength=0
2009-10-15 15:12:39.001772 DEBUG - writtenLength(in memo)=0, writtenLength=0
2009-10-15 15:12:39.001785 DEBUG - CUID#24 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.001794 DEBUG - Attach segment#0 to CUID#24.
2009-10-15 15:12:39.001802 DEBUG - index=0, length=78621, segmentLength=1048576, writtenLength=0
2009-10-15 15:12:39.001810 DEBUG - writtenLength(in memo)=0, writtenLength=0
2009-10-15 15:12:39.001822 DEBUG - CUID#25 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.001832 DEBUG - Attach segment#0 to CUID#25.
2009-10-15 15:12:39.001840 DEBUG - index=0, length=1048576, segmentLength=1048576, writtenLength=0
2009-10-15 15:12:39.001847 DEBUG - writtenLength(in memo)=0, writtenLength=0
2009-10-15 15:12:39.001859 DEBUG - CUID#26 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.001868 DEBUG - Attach segment#0 to CUID#26.
2009-10-15 15:12:39.001876 DEBUG - index=0, length=158585, segmentLength=1048576, writtenLength=0
2009-10-15 15:12:39.001884 DEBUG - writtenLength(in memo)=0, writtenLength=0
2009-10-15 15:12:39.001899 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.002604 INFO - CUID#6 - Resolving hostname mirrors.portafixe.com
2009-10-15 15:12:39.011495 DEBUG - CUID#7 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.012232 INFO - CUID#7 - Resolving hostname mirrors.portafixe.com
2009-10-15 15:12:39.020712 DEBUG - CUID#8 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.021389 INFO - CUID#8 - Resolving hostname mirror.umoss.org
2009-10-15 15:12:39.029941 DEBUG - CUID#9 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.030619 INFO - CUID#9 - Resolving hostname mirrors.easynews.com
2009-10-15 15:12:39.039040 DEBUG - CUID#10 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.039740 INFO - CUID#10 - Resolving hostname locke.suu.edu
2009-10-15 15:12:39.048147 DEBUG - CUID#11 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.048828 INFO - CUID#11 - Resolving hostname mirror.rit.edu
2009-10-15 15:12:39.057230 DEBUG - CUID#12 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.057905 INFO - CUID#12 - Resolving hostname archlinux.umflint.edu
2009-10-15 15:12:39.067026 DEBUG - CUID#13 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.067705 INFO - CUID#13 - Resolving hostname mirror.archlinux.com.ve
2009-10-15 15:12:39.076226 DEBUG - CUID#14 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.076906 INFO - CUID#14 - Resolving hostname mirrors.gigenet.com
2009-10-15 15:12:39.085296 DEBUG - CUID#15 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.085976 INFO - CUID#15 - Resolving hostname mirrors.hosef.org
2009-10-15 15:12:39.094375 DEBUG - CUID#16 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.095050 INFO - CUID#16 - Resolving hostname www.gtlib.gatech.edu
2009-10-15 15:12:39.103682 DEBUG - CUID#17 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.104356 INFO - CUID#17 - Resolving hostname schlunix.org
2009-10-15 15:12:39.112824 DEBUG - CUID#18 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.113499 INFO - CUID#18 - Resolving hostname mirror.cs.vt.edu
2009-10-15 15:12:39.121924 DEBUG - CUID#19 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.122605 INFO - CUID#19 - Resolving hostname archlinux.unixheads.org
2009-10-15 15:12:39.131145 DEBUG - CUID#20 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.131819 INFO - CUID#20 - Resolving hostname distro.ibiblio.org
2009-10-15 15:12:39.140220 DEBUG - CUID#21 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.140896 INFO - CUID#21 - Resolving hostname mirror.csclub.uwaterloo.ca
2009-10-15 15:12:39.149422 DEBUG - CUID#22 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.150100 INFO - CUID#22 - Resolving hostname mirrors.hosef.org
2009-10-15 15:12:39.158489 DEBUG - CUID#23 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.159171 INFO - CUID#23 - Resolving hostname mirrors.hosef.org
2009-10-15 15:12:39.167543 DEBUG - CUID#24 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.168220 INFO - CUID#24 - Resolving hostname mirror.csclub.uwaterloo.ca
2009-10-15 15:12:39.176608 DEBUG - CUID#25 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.177284 INFO - CUID#25 - Resolving hostname mirror.csclub.uwaterloo.ca
2009-10-15 15:12:39.185681 DEBUG - CUID#26 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.186379 INFO - CUID#26 - Resolving hostname archlinux.unixheads.org
2009-10-15 15:12:39.195091 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.195104 DEBUG - CUID#7 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.195112 DEBUG - CUID#8 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.195120 DEBUG - CUID#9 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.195127 DEBUG - CUID#10 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.195135 DEBUG - CUID#11 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.195142 DEBUG - CUID#12 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.195150 DEBUG - CUID#13 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.195157 DEBUG - CUID#14 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.195165 DEBUG - CUID#15 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.195173 DEBUG - CUID#16 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.195180 DEBUG - CUID#17 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.195188 DEBUG - CUID#18 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.195196 DEBUG - CUID#19 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.195204 DEBUG - CUID#20 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.195211 DEBUG - CUID#21 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.195219 DEBUG - CUID#22 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.195226 DEBUG - CUID#23 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.195234 DEBUG - CUID#24 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.195242 DEBUG - CUID#25 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:39.195249 DEBUG - CUID#26 - socket: read:0, write:0, hup:0, err:0
2009-10-15 15:12:40.192182 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0

graysky · 2009-10-15 19:28:41

I did a test using just aria2c from the shell and got this disturbing result:

$ aria2c http://aur.archlinux.org/packages/handbrake-svn/handbrake-svn.tar.gz
[#1 SIZE:0B/0B CN:1 SPD:0Bs]

It just hung up there

That maddening thing is that on this partition, I get these problems. If I boot into my other Arch partition on this same machine, powerpill and aria2c work flawlessly.

Last edited by graysky (2009-10-15 19:30:31)

Xyne · 2009-10-15 20:12:10

I'm beginning to suspect that there's something wrong with your overall setup. I just tested powerpill logging here (in case I had somehow borked it a while ago and nobody noticed) but it created the log file as expected. I have no idea what's going on with your log file or with aria2c but it seems like it might be having trouble with disk IO. That might explain the failure to update the databases with "pacman -Syy" and the failure to create the log file but I don't really know.

For aria2c you can try setting the log level to "debug" or whatever the most verbose output level is and then try again with individual packages. I would also try downloading to a different directory (on a separate partition if you have one), e.g. "powerpill -Suw --cachedir /path/to/somewhere/else" or try a different downloader to see if that works.

Have you noticed any other problems on that system? Are you able to create files in /etc and /var? If not, run a file system check and make sure that it's mounted properly.

graysky · 2009-10-15 21:27:03

This seems to be the only problem. samba, pacman, bitorrent, firefox, filezilla, etc. all work. Before I try using powerpill, I want to see that I use just use aria2c from the shell to d/l a file which I can't at this point (wget gets it just fine). I also tried using aria2c from other users including root... same result.

# aria2c -l log http://aur.archlinux.org/packages/handbrake-svn/handbrake-svn.tar.gz
[#1 SIZE:0B/0B CN:1 SPD:0Bs]

The log:

2009-10-15 17:20:25.558884 INFO - <<--- --- --- ---
2009-10-15 17:20:25.558940 INFO -   --- --- --- ---
2009-10-15 17:20:25.558946 INFO -   --- --- --- --->>
2009-10-15 17:20:25.558950 INFO - aria2 1.6.2 x86_64-unknown-linux-gnu
2009-10-15 17:20:25.558956 INFO - Logging started.
2009-10-15 17:20:25.608222 INFO - 142 certificate(s) were imported.
2009-10-15 17:20:25.608269 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:20:25.608343 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:20:25.609101 INFO - CUID#6 - Resolving hostname aur.archlinux.org
2009-10-15 17:20:25.617703 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:20:26.613907 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:20:28.613877 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:20:29.614720 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:20:31.613867 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:20:33.613887 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:20:35.613879 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:20:36.613879 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:20:37.614716 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:20:38.614718 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:20:39.614721 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:20:41.614715 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:20:43.614720 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:20:45.614683 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:20:46.614702 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:20:48.614716 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:20:50.613877 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:20:52.613860 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:20:53.613861 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:20:54.613889 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:20:56.613861 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:20:56.613884 DEBUG - ServerStat: set status ERROR for aur.archlinux.org (http)
2009-10-15 17:20:56.613974 INFO - CUID#6 - Restarting the download. URI=http://aur.archlinux.org/packages/yasnippet/yasnippet.tar.gz
Exception: [AbstractCommand.cc:207] URI=http://aur.archlinux.org/packages/yasnippet/yasnippet.tar.gz
  -> [AbstractCommand.cc:184] Timeout.
2009-10-15 17:20:56.613994 DEBUG - CUID#6 - Pooling request URI=http://aur.archlinux.org/packages/yasnippet/yasnippet.tar.gz
2009-10-15 17:20:56.614049 DEBUG - Failed to delete socket event:No such file or directory
2009-10-15 17:20:56.614097 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:20:56.614119 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:20:56.614855 INFO - CUID#6 - Resolving hostname aur.archlinux.org
2009-10-15 17:20:56.626126 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:20:57.623862 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:20:59.623860 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:21:00.623860 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:21:01.623884 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:21:03.623861 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:21:05.623861 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:21:07.623859 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:21:09.623859 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:21:10.623861 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:21:12.623879 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:21:14.623886 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:21:16.623873 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:21:18.623857 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:21:19.623857 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:21:21.623857 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:21:22.623863 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:21:23.623872 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:21:24.623878 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:21:25.626258 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:21:27.623845 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:21:27.623877 DEBUG - ServerStat: set status ERROR for aur.archlinux.org (http)
2009-10-15 17:21:27.623912 INFO - CUID#6 - Restarting the download. URI=http://aur.archlinux.org/packages/yasnippet/yasnippet.tar.gz
Exception: [AbstractCommand.cc:207] URI=http://aur.archlinux.org/packages/yasnippet/yasnippet.tar.gz
  -> [AbstractCommand.cc:184] Timeout.
2009-10-15 17:21:27.623936 DEBUG - CUID#6 - Pooling request URI=http://aur.archlinux.org/packages/yasnippet/yasnippet.tar.gz
2009-10-15 17:21:27.623955 DEBUG - Failed to delete socket event:No such file or directory
2009-10-15 17:21:27.624020 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:21:27.624042 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:21:27.624819 INFO - CUID#6 - Resolving hostname aur.archlinux.org
2009-10-15 17:21:27.633356 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:21:28.630527 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:21:30.630524 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:21:32.630525 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:21:34.630547 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:21:36.630548 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:21:37.631380 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:21:39.631384 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:21:41.630511 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:21:42.630537 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:21:43.630538 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:21:44.631366 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:21:46.630548 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:21:47.631194 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:21:49.630551 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:21:50.631370 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:21:52.153856 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:21:52.153877 NOTICE - Shutdown sequence commencing... Press Ctrl-C again for emergency shutdown.
2009-10-15 17:21:52.153898 DEBUG - CUID#6 - socket: read:0, write:0, hup:0, err:0
2009-10-15 17:21:52.153914 DEBUG - Failed to delete socket event:No such file or directory

I tried this to a number of different partitions, all with the same result.

Last edited by graysky (2009-10-15 21:28:28)

graysky · 2009-10-15 21:35:04

AH HA! It's something to do w/ my DNS. I replaced a domainname with its IP addy and aria2c grabbed the file just fine. If I replace the IP addy with the domain name, it chokes again.

The root of this problem seems to be an offending line in my /etc/resolv.conf
I had 'nameserver 127.0.0.1' in there back from when I ran dnsmasq which I have now removed and powerpill and aria2c are 100 % fine now

Thanks for helping me troubleshoot this!

Xyne · 2009-10-15 22:25:34

I'm happy that you found the cause of the problem. I wonder why aria2c choked on that but not other apps which require hostname resolution.

Let me know if you figure out why powerpill didn't create a log file.

Arch Linux

#1 2009-10-09 20:47:17

strange powerpill error - aria2 timeouts all the time [SOLVED]

#2 2009-10-09 20:57:01

Re: strange powerpill error - aria2 timeouts all the time [SOLVED]

#3 2009-10-09 20:58:51

Re: strange powerpill error - aria2 timeouts all the time [SOLVED]

#4 2009-10-09 22:10:59

Re: strange powerpill error - aria2 timeouts all the time [SOLVED]

#5 2009-10-14 19:05:53

Re: strange powerpill error - aria2 timeouts all the time [SOLVED]

#6 2009-10-14 20:19:37

Re: strange powerpill error - aria2 timeouts all the time [SOLVED]

#7 2009-10-15 19:24:59

Re: strange powerpill error - aria2 timeouts all the time [SOLVED]

#8 2009-10-15 19:28:41

Re: strange powerpill error - aria2 timeouts all the time [SOLVED]

#9 2009-10-15 20:12:10

Re: strange powerpill error - aria2 timeouts all the time [SOLVED]

#10 2009-10-15 21:27:03

Re: strange powerpill error - aria2 timeouts all the time [SOLVED]

#11 2009-10-15 21:35:04

Re: strange powerpill error - aria2 timeouts all the time [SOLVED]

#12 2009-10-15 22:25:34

Re: strange powerpill error - aria2 timeouts all the time [SOLVED]

Board footer