Attachment 829765 Details for Bug 1035319 – Strace log

Strace log

strace_run (text/plain), 9.88 KB, created by Marek Goldmann on 2013-11-27 14:36:19 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Marek Goldmann

Created: 2013-11-27 14:36:19 UTC

Size: 9.88 KB

patch

obsolete

>[debug] api.go:1008 Calling POST /containers/create
>2013/11/27 15:32:09 POST /v1.7/containers/create
>[/var/lib/docker|f2a67558] +job create()
>[debug] deviceset.go:209 activateDeviceIfNeeded(5c47c08926951ad84aa164b300f13dc3ebfbb72b626163806415bbe7b3e30d2c)
>)    = 0
>clock_gettime(CLOCK_REALTIME, {1385562729, 389010293}) = 0
>clock_gettime(CLOCK_REALTIME, {1385562729, 389066958}) = 0
>clock_gettime(CLOCK_REALTIME, {1385562729, 389111199}) = 0
>epoll_wait(8, {}, 128, 0)               = 0
>epoll_wait(8, [debug] deviceset.go:188 registerDevice(16, 385194d085aa0b492dca3807bd5ba5a33afe04a687aab907229d41a8798ee88d-init)
>[debug] deviceset.go:209 activateDeviceIfNeeded(385194d085aa0b492dca3807bd5ba5a33afe04a687aab907229d41a8798ee88d-init)
>[debug] deviceset.go:188 registerDevice(17, 385194d085aa0b492dca3807bd5ba5a33afe04a687aab907229d41a8798ee88d)
>[debug] deviceset.go:209 activateDeviceIfNeeded(385194d085aa0b492dca3807bd5ba5a33afe04a687aab907229d41a8798ee88d)
>385194d085aa0b492dca3807bd5ba5a33afe04a687aab907229d41a8798ee88d
>[/var/lib/docker|f2a67558] -job create() = OK (0)
>{{EPOLLIN|EPOLLOUT|EPOLLHUP|0x2000, {u32=3735109216, u64=139865050136160}}, {EPOLLIN|EPOLLOUT, {u32=3735109376, u64=139865050136320}}}, 128, 4294967295) = 2
>clock_gettime(CLOCK_REALTIME, {1385562729, 712382428}) = 0
>futex(0xd058d8, FUTEX_WAKE, 1)          = 1
>accept4(7, {sa_family=AF_FILE, NULL}, [2], SOCK_CLOEXEC|SOCK_NONBLOCK) = 10
>epoll_ctl(8, EPOLL_CTL_ADD, 10, {EPOLLIN|EPOLLOUT|EPOLLET|0x2000, {u32=3735109056, u64=139865050136000}}) = 0
>getsockname(10, {sa_family=AF_FILE, path="/var/run/docker.sock"}, [23]) = 0
>clock_gettime(CLOCK_REALTIME, {1385562729, 712558589}) = 0
>clock_gettime(CLOCK_REALTIME, {1385562729, 712586907}) = 0
>accept4(7, 0xc20021e5b0, [112], SOCK_CLOEXEC|SOCK_NONBLOCK) = -1 EAGAIN (Resource temporarily unavailable)
>read(10, "POST /v1.7/containers/385194d085"..., 4096) = 247
>clock_gettime(CLOCK_REALTIME, {1385562729, 713074219}) = 0
>write(2, "[debug] api.go:1008 Calling POST"..., 62[debug] api.go:1008 Calling POST /containers/{name:.*}/attach
>) = 62
>clock_gettime(CLOCK_REALTIME, {1385562729, 713191105}) = 0
>write(2, "2013/11/27 15:32:09 POST /v1.7/c"..., 1492013/11/27 15:32:09 POST /v1.7/containers/385194d085aa0b492dca3807bd5ba5a33afe04a687aab907229d41a8798ee88d/attach?stderr=1&stdin=1&stdout=1&stream=1
>) = 149
>clock_gettime(CLOCK_REALTIME, {1385562729, 713319824}) = 0
>clock_gettime(CLOCK_REALTIME, {1385562729, 713462172}) = 0
>fcntl(5, F_SETLK, {type=F_RDLCK, whence=SEEK_SET, start=1073741824, len=1}) = 0
>fcntl(5, F_SETLK, {type=F_RDLCK, whence=SEEK_SET, start=1073741826, len=510}) = 0
>fcntl(5, F_SETLK, {type=F_UNLCK, whence=SEEK_SET, start=1073741824, len=1}) = 0
>access("/var/lib/docker/linkgraph.db-journal", F_OK) = -1 ENOENT (No such file or directory)
>fstat(5, {st_mode=S_IFREG|0644, st_size=5120, ...}) = 0
>lseek(5, 24, SEEK_SET)                  = 24
>read(5, "\0\0\0\f\0\0\0\0\0\0\0\0\0\0\0\0", 16) = 16
>fcntl(5, F_SETLK, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0
>futex(0xd0bef8, FUTEX_WAIT, 0, NULL)    = 0
>write(10, "", 0)                        = 0
>futex(0xd0bef8, FUTEX_WAIT, 0, NULL[debug] container.go:372 attach: stdin: begin
>[debug] container.go:407 attach: stdout: begin
>[debug] container.go:445 attach: stderr: begin
>[debug] container.go:488 attach: waiting for job 1/3
>[debug] api.go:1008 Calling POST /containers/{name:.*}/start
>2013/11/27 15:32:09 POST /v1.7/containers/385194d085aa0b492dca3807bd5ba5a33afe04a687aab907229d41a8798ee88d/start
>[/var/lib/docker|f2a67558] +job start(385194d085aa0b492dca3807bd5ba5a33afe04a687aab907229d41a8798ee88d)
>)    = 0
>epoll_wait(8, {}, 128, 0)               = 0
>epoll_wait(8, [debug] container.go:320 startPty: begin of stdout pipe
>[debug] container.go:331 startPty: begin of stdin pipe
>[debug] container.go:1083 monitor: waiting for container 385194d085aa0b492dca3807bd5ba5a33afe04a687aab907229d41a8798ee88d using cmd.Wait
>[debug] container.go:1087 monitor: cmd.Wait reported exit status exit status 255 for container 385194d085aa0b492dca3807bd5ba5a33afe04a687aab907229d41a8798ee88d
>[debug] container.go:1090 monitor: container 385194d085aa0b492dca3807bd5ba5a33afe04a687aab907229d41a8798ee88d finished
>[debug] container.go:333 startPty: end of stdin pipe
>[debug] container.go:423 attach: stdout: end
>[debug] container.go:461 attach: stderr: end
>[debug] container.go:493 attach: job 1 completed successfully
>[debug] container.go:488 attach: waiting for job 2/3
>[debug] container.go:493 attach: job 2 completed successfully
>[debug] container.go:488 attach: waiting for job 3/3
>[debug] server.go:1767 Closing buffered stdin pipe
>[debug] container.go:396 attach: stdin: end
>[debug] container.go:493 attach: job 3 completed successfully
>[debug] container.go:495 attach: all jobs completed successfully
>[debug] container.go:322 startPty: end of stdout pipe
>[debug] container.go:870 Waiting for the container to start (running: false): lxc-info: failed to receive answer for the command
>lxc-info: failed to send command
>state:   STOPPED
>[debug] container.go:505 Container running: true
>[/var/lib/docker|f2a67558] -job start(385194d085aa0b492dca3807bd5ba5a33afe04a687aab907229d41a8798ee88d) = OK (0)
>{{EPOLLOUT, {u32=3735109216, u64=139865050136160}}}, 128, 4294967295) = 1
>epoll_wait(8, {{EPOLLIN|EPOLLOUT|EPOLLHUP|0x2000, {u32=3735109216, u64=139865050136160}}}, 128, 4294967295) = 1
>clock_gettime(CLOCK_REALTIME, {1385562729, 793973222}) = 0
>read(9, "", 4096)                       = 0
>epoll_ctl(8, EPOLL_CTL_DEL, 9, {EPOLLOUT|EPOLLWRNORM|EPOLLWRBAND|EPOLLMSG|EPOLLHUP|0x7820, {u32=5321461, u64=5321461}}) = 0
>close(9)                                = 0
>epoll_wait(8, {{EPOLLIN|EPOLLOUT, {u32=3735109376, u64=139865050136320}}}, 128, 0) = 1
>accept4(7, {sa_family=AF_FILE, NULL}, [2], SOCK_CLOEXEC|SOCK_NONBLOCK) = 9
>epoll_ctl(8, EPOLL_CTL_ADD, 9, {EPOLLIN|EPOLLOUT|EPOLLET|0x2000, {u32=3735109216, u64=139865050136160}}) = 0
>clock_gettime(CLOCK_REALTIME, {1385562729, 794186811}) = 0
>clock_gettime(CLOCK_REALTIME, {1385562729, 794229027}) = 0
>clock_gettime(CLOCK_REALTIME, {1385562729, 794282254}) = 0
>clock_gettime(CLOCK_REALTIME, {1385562729, 794364771}) = 0
>clock_gettime(CLOCK_REALTIME, {1385562729, 795063110}) = 0
>clock_gettime(CLOCK_REALTIME, {1385562729, 795344663}) = 0
>clock_gettime(CLOCK_REALTIME, {1385562729, 795511301}) = 0
>clock_gettime(CLOCK_REALTIME, {1385562729, 795603106}) = 0
>clock_gettime(CLOCK_REALTIME, {1385562729, 795633245}) = 0
>clock_gettime(CLOCK_REALTIME, {1385562729, 795660380}) = 0
>clock_gettime(CLOCK_REALTIME, {1385562729, 795763483}) = 0
>clock_gettime(CLOCK_REALTIME, {1385562729, 795796645}) = 0
>clock_gettime(CLOCK_REALTIME, {1385562729, 795823690}) = 0
>clock_gettime(CLOCK_REALTIME, {1385562729, 795844146}) = 0
>clock_gettime(CLOCK_REALTIME, {1385562729, 795866131}) = 0
>clock_gettime(CLOCK_REALTIME, {1385562729, 795901503}) = 0
>clock_gettime(CLOCK_REALTIME, {1385562729, 795933333}) = 0
>clock_gettime(CLOCK_REALTIME, {1385562729, 795953666}) = 0
>clock_gettime(CLOCK_REALTIME, {1385562729, 795974566}) = 0
>clock_gettime(CLOCK_REALTIME, {1385562729, 795994776}) = 0
>clock_gettime(CLOCK_REALTIME, {1385562729, 796015760}) = 0
>clock_gettime(CLOCK_REALTIME, {1385562729, 796036120}) = 0
>clock_gettime(CLOCK_REALTIME, {1385562729, 796079006}) = 0
>clock_gettime(CLOCK_REALTIME, {1385562729, 796099563}) = 0
>clock_gettime(CLOCK_REALTIME, {1385562729, 796119502}) = 0
>clock_gettime(CLOCK_REALTIME, {1385562729, 796142746}) = 0
>clock_gettime(CLOCK_REALTIME, {1385562729, 796163313}) = 0
>clock_gettime(CLOCK_REALTIME, {1385562729, 796183096}) = 0
>clock_gettime(CLOCK_REALTIME, {1385562729, 796206733}) = 0
>epoll_wait(8, {}, 128, 0)               = 0
>futex(0xd058d8, FUTEX_WAKE, 1)          = 1
>madvise(0x7f34b802b000, 98304, MADV_DONTNEED) = 0
>futex(0xd0bef8, FUTEX_WAIT, 0, NULL)    = 0
>clock_gettime(CLOCK_REALTIME, {1385562729, 796526684}) = 0
>clock_gettime(CLOCK_REALTIME, {1385562729, 796560144}) = 0
>clock_gettime(CLOCK_REALTIME, {1385562729, 797146834}) = 0
>clock_gettime(CLOCK_REALTIME, {1385562729, 797259662}) = 0
>clock_gettime(CLOCK_REALTIME, {1385562729, 797279512}) = 0
>epoll_wait(8, {}, 128, 0)               = 0
>futex(0xd058d8, FUTEX_WAKE, 1)          = 1
>read(9, "POST /v1.7/containers/385194d085"..., 4096) = 223
>clock_gettime(CLOCK_REALTIME, {1385562729, 797757855}) = 0
>write(2, "[debug] api.go:1008 Calling POST"..., 62[debug] api.go:1008 Calling POST /containers/{name:.*}/resize
>) = 62
>clock_gettime(CLOCK_REALTIME, {1385562729, 797841464}) = 0
>write(2, "2013/11/27 15:32:09 POST /v1.7/c"..., 1252013/11/27 15:32:09 POST /v1.7/containers/385194d085aa0b492dca3807bd5ba5a33afe04a687aab907229d41a8798ee88d/resize?h=74&w=282
>) = 125
>clock_gettime(CLOCK_REALTIME, {1385562729, 798008953}) = 0
>fcntl(5, F_SETLK, {type=F_RDLCK, whence=SEEK_SET, start=1073741824, len=1}) = 0
>fcntl(5, F_SETLK, {type=F_RDLCK, whence=SEEK_SET, start=1073741826, len=510}) = 0
>fcntl(5, F_SETLK, {type=F_UNLCK, whence=SEEK_SET, start=1073741824, len=1}) = 0
>access("/var/lib/docker/linkgraph.db-journal", F_OK) = -1 ENOENT (No such file or directory)
>fstat(5, {st_mode=S_IFREG|0644, st_size=5120, ...}) = 0
>lseek(5, 24, SEEK_SET)                  = 24
>read(5, "\0\0\0\f\0\0\0\0\0\0\0\0\0\0\0\0", 16) = 16
>fcntl(5, F_SETLK, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0
>futex(0xd058d8, FUTEX_WAKE, 1)          = 1
>ioctl(-1, TIOCSWINSZ, {ws_row=74, ws_col=282, ws_xpixel=0, ws_ypixel=0}) = -1 EBADF (Bad file descriptor)
>write(2, "[error] api.go:1034 Error: bad f"..., 47[error] api.go:1034 Error: bad file descriptor
>) = 47
>write(2, "[error] api.go:82 HTTP Error: st"..., 65[error] api.go:82 HTTP Error: statusCode=500 bad file descriptor
>) = 65
>clock_gettime(CLOCK_REALTIME, {1385562729, 798520841}) = 0
>write(9, "HTTP/1.1 500 Internal Server Err"..., 156) = 156
>read(9, 0xc2001f7000, 4096)             = -1 EAGAIN (Resource temporarily unavailable)
>epoll_wait(8, {}, 128, 0)               = 0
>futex(0xd0bef8, FUTEX_WAIT, 0, NULL[debug] api.go:1008 Calling GET /containers/{name:.*}/json
>2013/11/27 15:32:09 GET /v1.7/containers/385194d085aa0b492dca3807bd5ba5a33afe04a687aab907229d41a8798ee88d/json

Actions: View

Attachments on bug 1035319: 829765