Last active
February 26, 2020 07:15
-
-
Save freijon/be5b9c28534ef4635e9ba700b3e48ff8 to your computer and use it in GitHub Desktop.
ncspot.log
This file contains bidirectional Unicode text that may be interpreted or compiled differently than what appears below. To review, open the file in an editor that reveals hidden Unicode characters.
Learn more about bidirectional Unicode characters
| [2020-02-26][07:18:36] [ncspot::config] [TRACE] "C:\\Users\\jonas.frei\\.config\\ncspot\\config" | |
| [2020-02-26][07:18:36] [ncspot::config] [TRACE] "C:\\Users\\jonas.frei\\.config\\ncspot\\config" | |
| [2020-02-26][07:18:36] [mio::poll] [TRACE] registering with poller | |
| [2020-02-26][07:18:36] [mio::poll] [TRACE] registering with poller | |
| [2020-02-26][07:18:36] [mio::poll] [TRACE] registering with poller | |
| [2020-02-26][07:18:36] [mio::poll] [TRACE] registering with poller | |
| [2020-02-26][07:18:36] [mio::poll] [TRACE] registering with poller | |
| [2020-02-26][07:18:36] [mio::poll] [TRACE] registering with poller | |
| [2020-02-26][07:18:36] [mio::poll] [TRACE] registering with poller | |
| [2020-02-26][07:18:36] [mio::poll] [TRACE] registering with poller | |
| [2020-02-26][07:18:36] [tokio_threadpool::builder] [TRACE] build; num-workers=8 | |
| [2020-02-26][07:18:36] [mio::poll] [TRACE] registering with poller | |
| [2020-02-26][07:18:36] [ncspot::spotify] [INFO] Setting HTTP proxy http://127.0.0.1:9999 | |
| [2020-02-26][07:18:36] [tokio_reactor::background] [DEBUG] starting background reactor | |
| [2020-02-26][07:18:36] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:36] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:36] [hyper::client::pool] [TRACE] park; waiting for idle connection: "http://apresolve.spotify.com" | |
| [2020-02-26][07:18:36] [hyper::client::connect] [TRACE] Http::connect("http://127.0.0.1:9999/") | |
| [2020-02-26][07:18:36] [hyper::client::connect] [DEBUG] connecting to 127.0.0.1:9999 | |
| [2020-02-26][07:18:36] [tokio_reactor] [DEBUG] adding I/O source: 0 | |
| [2020-02-26][07:18:36] [mio::poll] [TRACE] registering with poller | |
| [2020-02-26][07:18:36] [mio::sys::windows::selector] [TRACE] register Token(0) Readable | Writable | |
| [2020-02-26][07:18:36] [mio::sys::windows::tcp] [TRACE] scheduling a connect | |
| [2020-02-26][07:18:36] [mio::sys::windows::selector] [TRACE] select; -> got overlapped | |
| [2020-02-26][07:18:36] [tokio_reactor::registration] [DEBUG] scheduling Write for: 0 | |
| [2020-02-26][07:18:36] [mio::sys::windows::tcp] [TRACE] finished a connect | |
| [2020-02-26][07:18:36] [mio::sys::windows::selector] [TRACE] set readiness to Writable | |
| [2020-02-26][07:18:36] [mio::sys::windows::selector] [TRACE] set readiness to Writable | |
| [2020-02-26][07:18:36] [tokio_core::reactor] [DEBUG] consuming notification queue | |
| [2020-02-26][07:18:36] [mio::sys::windows::tcp] [TRACE] scheduling a read | |
| [2020-02-26][07:18:36] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:36] [tokio_reactor] [TRACE] event Writable Token(0) | |
| [2020-02-26][07:18:36] [tokio_core::reactor] [DEBUG] loop poll - 105.697µs | |
| [2020-02-26][07:18:36] [tokio_reactor] [TRACE] loop process - 1 events, 0.000s | |
| [2020-02-26][07:18:36] [tokio_core::reactor] [DEBUG] loop time - Instant { t: 766198.764434424s } | |
| [2020-02-26][07:18:36] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:36] [tokio_core::reactor] [DEBUG] loop process, 126.545µs | |
| [2020-02-26][07:18:36] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:36] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:36] [tokio_reactor] [TRACE] loop process - 0 events, 0.000s | |
| [2020-02-26][07:18:36] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:36] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:36] [hyper::proto::h1::conn] [TRACE] read_keep_alive; is_mid_message=false | |
| [2020-02-26][07:18:36] [tokio_reactor::registration] [DEBUG] scheduling Read for: 0 | |
| [2020-02-26][07:18:36] [hyper::proto] [TRACE] should_keep_alive(version=Http11, header=None) = true | |
| [2020-02-26][07:18:36] [hyper::proto::h1::role] [TRACE] Client::encode has_body=false, method=None | |
| [2020-02-26][07:18:36] [mio::sys::windows::selector] [TRACE] set readiness to (empty) | |
| [2020-02-26][07:18:36] [mio::sys::windows::tcp] [TRACE] scheduling a write of 75 bytes | |
| [2020-02-26][07:18:36] [mio::sys::windows::tcp] [TRACE] done immediately with 75 bytes | |
| [2020-02-26][07:18:36] [mio::sys::windows::selector] [TRACE] set readiness to Writable | |
| [2020-02-26][07:18:36] [hyper::proto::h1::io] [TRACE] reclaiming write buf Vec | |
| [2020-02-26][07:18:36] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:36] [hyper::proto::h1::io] [DEBUG] flushed 75 bytes | |
| [2020-02-26][07:18:36] [tokio_reactor] [TRACE] event Writable Token(0) | |
| [2020-02-26][07:18:36] [hyper::proto::h1::conn] [TRACE] flushed State { reading: Init, writing: KeepAlive, keep_alive: Busy, error: None } | |
| [2020-02-26][07:18:36] [tokio_reactor] [TRACE] loop process - 1 events, 0.000s | |
| [2020-02-26][07:18:36] [hyper::proto::h1::conn] [TRACE] wants_read_again? false | |
| [2020-02-26][07:18:36] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:36] [tokio_core::reactor] [DEBUG] loop poll - 926.06µs | |
| [2020-02-26][07:18:36] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:36] [tokio_core::reactor] [DEBUG] loop time - Instant { t: 766198.765824484s } | |
| [2020-02-26][07:18:36] [tokio_core::reactor] [DEBUG] loop process, 89.212µs | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] select; -> got overlapped | |
| [2020-02-26][07:18:37] [mio::sys::windows::tcp] [TRACE] finished a read: 0 | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] set readiness to Readable | Writable | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] event Readable | Writable Token(0) | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] loop process - 1 events, 0.000s | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:37] [hyper::proto::h1::conn] [TRACE] Conn::read_head | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] loop process - 0 events, 0.000s | |
| [2020-02-26][07:18:37] [hyper::proto::h1::io] [DEBUG] read 736 bytes | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:37] [hyper::proto::h1::role] [TRACE] Response.parse([Header; 100], [u8; 736]) | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:37] [hyper::proto::h1::role] [TRACE] Response.parse Complete(318) | |
| [2020-02-26][07:18:37] [hyper::header] [TRACE] maybe_literal not found, copying "Via" | |
| [2020-02-26][07:18:37] [hyper::header] [TRACE] maybe_literal not found, copying "Keep-Alive" | |
| [2020-02-26][07:18:37] [hyper::header] [TRACE] maybe_literal not found, copying "Via" | |
| [2020-02-26][07:18:37] [hyper::proto::h1::io] [DEBUG] parsed 9 headers (318 bytes) | |
| [2020-02-26][07:18:37] [hyper::proto::h1::conn] [DEBUG] incoming body is content-length (418 bytes) | |
| [2020-02-26][07:18:37] [hyper::proto] [TRACE] expecting_continue(version=Http11, header=None) = false | |
| [2020-02-26][07:18:37] [hyper::proto] [TRACE] should_keep_alive(version=Http11, header=Some(Connection([KeepAlive]))) = true | |
| [2020-02-26][07:18:37] [hyper::proto::h1::conn] [TRACE] Conn::read_body | |
| [2020-02-26][07:18:37] [hyper::proto::h1::decode] [TRACE] decode; state=Length(418) | |
| [2020-02-26][07:18:37] [hyper::proto::h1::conn] [TRACE] flushed State { reading: Body(Length(0)), writing: KeepAlive, keep_alive: Busy, error: None } | |
| [2020-02-26][07:18:37] [hyper::proto::h1::conn] [TRACE] wants_read_again? false | |
| [2020-02-26][07:18:37] [tokio_core::reactor] [DEBUG] loop poll - 179.228122ms | |
| [2020-02-26][07:18:37] [tokio_core::reactor] [DEBUG] loop time - Instant { t: 766198.945186909s } | |
| [2020-02-26][07:18:37] [tokio_core::reactor] [DEBUG] loop process, 59.151µs | |
| [2020-02-26][07:18:37] [hyper::proto::h1::conn] [TRACE] Conn::read_body | |
| [2020-02-26][07:18:37] [hyper::proto::h1::decode] [TRACE] decode; state=Length(0) | |
| [2020-02-26][07:18:37] [hyper::proto::h1::conn] [DEBUG] incoming body completed | |
| [2020-02-26][07:18:37] [hyper::proto::h1::io] [DEBUG] read 0 bytes | |
| [2020-02-26][07:18:37] [hyper::proto::h1::conn] [TRACE] read_keep_alive; is_mid_message=false | |
| [2020-02-26][07:18:37] [hyper::proto::h1::io] [DEBUG] read 0 bytes | |
| [2020-02-26][07:18:37] [hyper::proto::h1::conn] [TRACE] try_io_read; found EOF on connection: State { reading: Init, writing: Init, keep_alive: Idle, error: None } | |
| [2020-02-26][07:18:37] [hyper::proto::h1::conn] [TRACE] State::close_read() | |
| [2020-02-26][07:18:37] [hyper::proto::h1::conn] [TRACE] flushed State { reading: Closed, writing: Init, keep_alive: Disabled, error: None } | |
| [2020-02-26][07:18:37] [hyper::proto::h1::conn] [TRACE] wants_read_again? false | |
| [2020-02-26][07:18:37] [hyper::proto::h1::conn] [TRACE] shut down IO | |
| [2020-02-26][07:18:37] [mio::poll] [TRACE] deregistering handle with poller | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] deregistering | |
| [2020-02-26][07:18:37] [tokio_reactor] [DEBUG] dropping I/O source: 0 | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:37] [want] [TRACE] signal: Closed | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] loop process - 0 events, 0.000s | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:37] [tokio_core::reactor] [DEBUG] loop poll - 874.667µs | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:37] [tokio_core::reactor] [DEBUG] loop time - Instant { t: 766198.946350545s } | |
| [2020-02-26][07:18:37] [tokio_core::reactor] [DEBUG] loop process, 91.636µs | |
| [2020-02-26][07:18:37] [librespot_core::session] [INFO] Connecting to AP "gew1-accesspoint-b-fk5s.ap.spotify.com:443" | |
| [2020-02-26][07:18:37] [librespot_core::connection] [INFO] Using proxy "http://127.0.0.1:9999/" | |
| [2020-02-26][07:18:37] [tokio_reactor] [DEBUG] adding I/O source: 4194304 | |
| [2020-02-26][07:18:37] [mio::poll] [TRACE] registering with poller | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] register Token(4194304) Readable | Writable | |
| [2020-02-26][07:18:37] [mio::sys::windows::tcp] [TRACE] scheduling a connect | |
| [2020-02-26][07:18:37] [tokio_reactor::registration] [DEBUG] scheduling Write for: 0 | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] select; -> got overlapped | |
| [2020-02-26][07:18:37] [tokio_core::reactor] [DEBUG] loop poll - 8.242µs | |
| [2020-02-26][07:18:37] [mio::sys::windows::tcp] [TRACE] finished a connect | |
| [2020-02-26][07:18:37] [tokio_core::reactor] [DEBUG] loop time - Instant { t: 766198.947357575s } | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] set readiness to Writable | |
| [2020-02-26][07:18:37] [tokio_core::reactor] [DEBUG] loop process, 99.394µs | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] set readiness to Writable | |
| [2020-02-26][07:18:37] [mio::sys::windows::tcp] [TRACE] scheduling a read | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] event Writable Token(4194304) | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] loop process - 1 events, 0.000s | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:37] [tokio_core::reactor] [DEBUG] loop poll - 163.393µs | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:37] [tokio_core::reactor] [DEBUG] loop time - Instant { t: 766198.947673696s } | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:37] [tokio_core::reactor] [DEBUG] loop process, 102.788µs | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] loop process - 0 events, 0.000s | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] set readiness to (empty) | |
| [2020-02-26][07:18:37] [mio::sys::windows::tcp] [TRACE] scheduling a write of 63 bytes | |
| [2020-02-26][07:18:37] [mio::sys::windows::tcp] [TRACE] done immediately with 63 bytes | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] set readiness to Writable | |
| [2020-02-26][07:18:37] [tokio_reactor::registration] [DEBUG] scheduling Read for: 0 | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] event Writable Token(4194304) | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] loop process - 1 events, 0.000s | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] select; -> got overlapped | |
| [2020-02-26][07:18:37] [mio::sys::windows::tcp] [TRACE] finished a read: 0 | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] set readiness to Readable | Writable | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] event Readable | Writable Token(4194304) | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] loop process - 1 events, 0.000s | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:37] [tokio_core::reactor] [DEBUG] loop poll - 160.68606ms | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:37] [tokio_core::reactor] [DEBUG] loop time - Instant { t: 766199.108841696s } | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:37] [tokio_core::reactor] [DEBUG] loop process, 130.425µs | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] loop process - 0 events, 0.000s | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] set readiness to Readable | |
| [2020-02-26][07:18:37] [mio::sys::windows::tcp] [TRACE] scheduling a write of 151 bytes | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] event Readable Token(4194304) | |
| [2020-02-26][07:18:37] [mio::sys::windows::tcp] [TRACE] done immediately with 151 bytes | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] loop process - 1 events, 0.000s | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] set readiness to Readable | Writable | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] set readiness to Writable | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:37] [mio::sys::windows::tcp] [TRACE] scheduling a read | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] event Writable Token(4194304) | |
| [2020-02-26][07:18:37] [tokio_reactor::registration] [DEBUG] scheduling Read for: 0 | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] loop process - 1 events, 0.000s | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] select; -> got overlapped | |
| [2020-02-26][07:18:37] [mio::sys::windows::tcp] [TRACE] finished a read: 0 | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] set readiness to Readable | Writable | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] event Readable | Writable Token(4194304) | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] loop process - 1 events, 0.000s | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:37] [tokio_core::reactor] [DEBUG] loop poll - 68.339879ms | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:37] [tokio_core::reactor] [DEBUG] loop time - Instant { t: 766199.319052121s } | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:37] [tokio_core::reactor] [DEBUG] loop process, 129.454µs | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] loop process - 0 events, 0.000s | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] set readiness to Readable | |
| [2020-02-26][07:18:37] [mio::sys::windows::tcp] [TRACE] scheduling a write of 36 bytes | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] event Readable Token(4194304) | |
| [2020-02-26][07:18:37] [mio::sys::windows::tcp] [TRACE] done immediately with 36 bytes | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] loop process - 1 events, 0.000s | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] set readiness to Readable | Writable | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] event Readable | Writable Token(4194304) | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] loop process - 1 events, 0.000s | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:37] [tokio_io::_tokio_codec::framed_write] [TRACE] flushing framed transport | |
| [2020-02-26][07:18:37] [tokio_io::_tokio_codec::framed_write] [TRACE] writing; remaining=125 | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] set readiness to Readable | |
| [2020-02-26][07:18:37] [mio::sys::windows::tcp] [TRACE] scheduling a write of 125 bytes | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:37] [mio::sys::windows::tcp] [TRACE] done immediately with 125 bytes | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] event Readable Token(4194304) | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] set readiness to Readable | Writable | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] loop process - 1 events, 0.000s | |
| [2020-02-26][07:18:37] [tokio_io::_tokio_codec::framed_write] [TRACE] framed transport flushed | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] select; timeout=Some(0ns) | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] set readiness to Writable | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:37] [mio::sys::windows::tcp] [TRACE] scheduling a read | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] event Writable Token(4194304) | |
| [2020-02-26][07:18:37] [tokio_reactor::registration] [DEBUG] scheduling Read for: 0 | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] loop process - 1 events, 0.000s | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] select; -> got overlapped | |
| [2020-02-26][07:18:37] [mio::sys::windows::tcp] [TRACE] finished a read: 0 | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] set readiness to Readable | Writable | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] event Readable | Writable Token(4194304) | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] loop process - 1 events, 0.000s | |
| [2020-02-26][07:18:37] [tokio_core::reactor] [DEBUG] loop poll - 103.838061ms | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:37] [tokio_core::reactor] [DEBUG] loop time - Instant { t: 766199.566620606s } | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:37] [tokio_core::reactor] [DEBUG] loop process, 97.939µs | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] loop process - 0 events, 0.000s | |
| [2020-02-26][07:18:37] [tokio_io::_tokio_codec::framed_read] [TRACE] attempting to decode a frame | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:37] [tokio_io::_tokio_codec::framed_read] [TRACE] frame decoded from buffer | |
| [2020-02-26][07:18:37] [librespot_core::session] [INFO] Authenticated as "xxx6800" ! | |
| [2020-02-26][07:18:37] [librespot_core::session] [DEBUG] new Session[0] | |
| [2020-02-26][07:18:37] [librespot_core::session] [DEBUG] drop Session[0] | |
| [2020-02-26][07:18:37] [tokio_threadpool::pool] [TRACE] shutdown; state=pool::State { lifecycle: Running, num_futures: 0 } | |
| [2020-02-26][07:18:37] [tokio_threadpool::pool] [TRACE] -> transitioned to shutdown | |
| [2020-02-26][07:18:37] [tokio_threadpool::pool] [TRACE] -> shutting down workers | |
| [2020-02-26][07:18:37] [librespot_core::session] [DEBUG] drop Dispatch | |
| [2020-02-26][07:18:37] [mio::poll] [TRACE] deregistering handle with poller | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] deregistering | |
| [2020-02-26][07:18:37] [tokio_reactor] [DEBUG] dropping I/O source: 0 | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] loop process - 0 events, 0.000s | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:37] [mio::poll] [TRACE] registering with poller | |
| [2020-02-26][07:18:37] [mio::poll] [TRACE] registering with poller | |
| [2020-02-26][07:18:37] [mio::poll] [TRACE] registering with poller | |
| [2020-02-26][07:18:37] [mio::poll] [TRACE] registering with poller | |
| [2020-02-26][07:18:37] [mio::poll] [TRACE] registering with poller | |
| [2020-02-26][07:18:37] [mio::poll] [TRACE] registering with poller | |
| [2020-02-26][07:18:37] [mio::poll] [TRACE] registering with poller | |
| [2020-02-26][07:18:37] [mio::poll] [TRACE] registering with poller | |
| [2020-02-26][07:18:37] [tokio_threadpool::builder] [TRACE] build; num-workers=8 | |
| [2020-02-26][07:18:37] [mio::poll] [TRACE] registering with poller | |
| [2020-02-26][07:18:37] [ncspot::spotify] [INFO] Setting HTTP proxy http://127.0.0.1:9999 | |
| [2020-02-26][07:18:37] [ncspot::spotify] [DEBUG] opening spotify session | |
| [2020-02-26][07:18:37] [tokio_reactor::background] [DEBUG] starting background reactor | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:37] [hyper::client::pool] [TRACE] park; waiting for idle connection: "http://apresolve.spotify.com" | |
| [2020-02-26][07:18:37] [hyper::client::connect] [TRACE] Http::connect("http://127.0.0.1:9999/") | |
| [2020-02-26][07:18:37] [hyper::client::connect] [DEBUG] connecting to 127.0.0.1:9999 | |
| [2020-02-26][07:18:37] [tokio_reactor] [DEBUG] adding I/O source: 0 | |
| [2020-02-26][07:18:37] [mio::poll] [TRACE] registering with poller | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] register Token(0) Readable | Writable | |
| [2020-02-26][07:18:37] [mio::sys::windows::tcp] [TRACE] scheduling a connect | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] select; -> got overlapped | |
| [2020-02-26][07:18:37] [tokio_reactor::registration] [DEBUG] scheduling Write for: 0 | |
| [2020-02-26][07:18:37] [mio::sys::windows::tcp] [TRACE] finished a connect | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] set readiness to Writable | |
| [2020-02-26][07:18:37] [tokio_core::reactor] [DEBUG] consuming notification queue | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] set readiness to Writable | |
| [2020-02-26][07:18:37] [mio::sys::windows::tcp] [TRACE] scheduling a read | |
| [2020-02-26][07:18:37] [tokio_core::reactor] [DEBUG] loop poll - 121.212µs | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:37] [tokio_core::reactor] [DEBUG] loop time - Instant { t: 766199.647048727s } | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] event Writable Token(0) | |
| [2020-02-26][07:18:37] [tokio_core::reactor] [DEBUG] loop process, 120.242µs | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] loop process - 1 events, 0.000s | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:37] [hyper::proto::h1::conn] [TRACE] read_keep_alive; is_mid_message=false | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:37] [tokio_reactor::registration] [DEBUG] scheduling Read for: 0 | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] loop process - 0 events, 0.000s | |
| [2020-02-26][07:18:37] [hyper::proto] [TRACE] should_keep_alive(version=Http11, header=None) = true | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:37] [hyper::proto::h1::role] [TRACE] Client::encode has_body=false, method=None | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] set readiness to (empty) | |
| [2020-02-26][07:18:37] [mio::sys::windows::tcp] [TRACE] scheduling a write of 75 bytes | |
| [2020-02-26][07:18:37] [mio::sys::windows::tcp] [TRACE] done immediately with 75 bytes | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] set readiness to Writable | |
| [2020-02-26][07:18:37] [hyper::proto::h1::io] [TRACE] reclaiming write buf Vec | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:37] [hyper::proto::h1::io] [DEBUG] flushed 75 bytes | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] event Writable Token(0) | |
| [2020-02-26][07:18:37] [hyper::proto::h1::conn] [TRACE] flushed State { reading: Init, writing: KeepAlive, keep_alive: Busy, error: None } | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] loop process - 1 events, 0.000s | |
| [2020-02-26][07:18:37] [hyper::proto::h1::conn] [TRACE] wants_read_again? false | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:37] [tokio_core::reactor] [DEBUG] loop poll - 704.969µs | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:37] [tokio_core::reactor] [DEBUG] loop time - Instant { t: 766199.648042181s } | |
| [2020-02-26][07:18:37] [tokio_core::reactor] [DEBUG] loop process, 119.273µs | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] select; -> got overlapped | |
| [2020-02-26][07:18:37] [mio::sys::windows::tcp] [TRACE] finished a read: 0 | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] set readiness to Readable | Writable | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] event Readable | Writable Token(0) | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] loop process - 1 events, 0.000s | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:37] [hyper::proto::h1::conn] [TRACE] Conn::read_head | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:37] [hyper::proto::h1::io] [DEBUG] read 736 bytes | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] loop process - 0 events, 0.000s | |
| [2020-02-26][07:18:37] [hyper::proto::h1::role] [TRACE] Response.parse([Header; 100], [u8; 736]) | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:37] [hyper::proto::h1::role] [TRACE] Response.parse Complete(318) | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:37] [hyper::header] [TRACE] maybe_literal not found, copying "Via" | |
| [2020-02-26][07:18:37] [hyper::header] [TRACE] maybe_literal not found, copying "Keep-Alive" | |
| [2020-02-26][07:18:37] [hyper::header] [TRACE] maybe_literal not found, copying "Via" | |
| [2020-02-26][07:18:37] [hyper::proto::h1::io] [DEBUG] parsed 9 headers (318 bytes) | |
| [2020-02-26][07:18:37] [hyper::proto::h1::conn] [DEBUG] incoming body is content-length (418 bytes) | |
| [2020-02-26][07:18:37] [hyper::proto] [TRACE] expecting_continue(version=Http11, header=None) = false | |
| [2020-02-26][07:18:37] [hyper::proto] [TRACE] should_keep_alive(version=Http11, header=Some(Connection([KeepAlive]))) = true | |
| [2020-02-26][07:18:37] [hyper::proto::h1::conn] [TRACE] Conn::read_body | |
| [2020-02-26][07:18:37] [hyper::proto::h1::decode] [TRACE] decode; state=Length(418) | |
| [2020-02-26][07:18:37] [hyper::proto::h1::conn] [TRACE] flushed State { reading: Body(Length(0)), writing: KeepAlive, keep_alive: Busy, error: None } | |
| [2020-02-26][07:18:37] [hyper::proto::h1::conn] [TRACE] wants_read_again? false | |
| [2020-02-26][07:18:37] [tokio_core::reactor] [DEBUG] loop poll - 100.531394ms | |
| [2020-02-26][07:18:37] [tokio_core::reactor] [DEBUG] loop time - Instant { t: 766199.748731636s } | |
| [2020-02-26][07:18:37] [tokio_core::reactor] [DEBUG] loop process, 64.97µs | |
| [2020-02-26][07:18:37] [hyper::proto::h1::conn] [TRACE] Conn::read_body | |
| [2020-02-26][07:18:37] [hyper::proto::h1::decode] [TRACE] decode; state=Length(0) | |
| [2020-02-26][07:18:37] [hyper::proto::h1::conn] [DEBUG] incoming body completed | |
| [2020-02-26][07:18:37] [hyper::proto::h1::io] [DEBUG] read 0 bytes | |
| [2020-02-26][07:18:37] [hyper::proto::h1::conn] [TRACE] read_keep_alive; is_mid_message=false | |
| [2020-02-26][07:18:37] [hyper::proto::h1::io] [DEBUG] read 0 bytes | |
| [2020-02-26][07:18:37] [hyper::proto::h1::conn] [TRACE] try_io_read; found EOF on connection: State { reading: Init, writing: Init, keep_alive: Idle, error: None } | |
| [2020-02-26][07:18:37] [hyper::proto::h1::conn] [TRACE] State::close_read() | |
| [2020-02-26][07:18:37] [hyper::proto::h1::conn] [TRACE] flushed State { reading: Closed, writing: Init, keep_alive: Disabled, error: None } | |
| [2020-02-26][07:18:37] [hyper::proto::h1::conn] [TRACE] wants_read_again? false | |
| [2020-02-26][07:18:37] [hyper::proto::h1::conn] [TRACE] shut down IO | |
| [2020-02-26][07:18:37] [mio::poll] [TRACE] deregistering handle with poller | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] deregistering | |
| [2020-02-26][07:18:37] [tokio_reactor] [DEBUG] dropping I/O source: 0 | |
| [2020-02-26][07:18:37] [want] [TRACE] signal: Closed | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:37] [tokio_core::reactor] [DEBUG] loop poll - 852.364µs | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] loop process - 0 events, 0.000s | |
| [2020-02-26][07:18:37] [tokio_core::reactor] [DEBUG] loop time - Instant { t: 766199.749862303s } | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:37] [tokio_core::reactor] [DEBUG] loop process, 88.727µs | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:37] [librespot_core::session] [INFO] Connecting to AP "gew1-accesspoint-b-rx4r.ap.spotify.com:443" | |
| [2020-02-26][07:18:37] [librespot_core::connection] [INFO] Using proxy "http://127.0.0.1:9999/" | |
| [2020-02-26][07:18:37] [tokio_reactor] [DEBUG] adding I/O source: 4194304 | |
| [2020-02-26][07:18:37] [mio::poll] [TRACE] registering with poller | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] register Token(4194304) Readable | Writable | |
| [2020-02-26][07:18:37] [mio::sys::windows::tcp] [TRACE] scheduling a connect | |
| [2020-02-26][07:18:37] [tokio_reactor::registration] [DEBUG] scheduling Write for: 0 | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] select; -> got overlapped | |
| [2020-02-26][07:18:37] [mio::sys::windows::tcp] [TRACE] finished a connect | |
| [2020-02-26][07:18:37] [tokio_core::reactor] [DEBUG] loop poll - 9.697µs | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] set readiness to Writable | |
| [2020-02-26][07:18:37] [tokio_core::reactor] [DEBUG] loop time - Instant { t: 766199.750565333s } | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] set readiness to Writable | |
| [2020-02-26][07:18:37] [tokio_core::reactor] [DEBUG] loop process, 111.03µs | |
| [2020-02-26][07:18:37] [mio::sys::windows::tcp] [TRACE] scheduling a read | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] event Writable Token(4194304) | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] loop process - 1 events, 0.000s | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:37] [tokio_core::reactor] [DEBUG] loop poll - 128µs | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:37] [tokio_core::reactor] [DEBUG] loop time - Instant { t: 766199.750863515s } | |
| [2020-02-26][07:18:37] [tokio_core::reactor] [DEBUG] loop process, 152.727µs | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] loop process - 0 events, 0.000s | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] set readiness to (empty) | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:37] [mio::sys::windows::tcp] [TRACE] scheduling a write of 63 bytes | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:37] [mio::sys::windows::tcp] [TRACE] done immediately with 63 bytes | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] set readiness to Writable | |
| [2020-02-26][07:18:37] [tokio_reactor::registration] [DEBUG] scheduling Read for: 0 | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] event Writable Token(4194304) | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] loop process - 1 events, 0.000s | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] select; -> got overlapped | |
| [2020-02-26][07:18:37] [mio::sys::windows::tcp] [TRACE] finished a read: 0 | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] set readiness to Readable | Writable | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] event Readable | Writable Token(4194304) | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] loop process - 1 events, 0.000s | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:37] [tokio_core::reactor] [DEBUG] loop poll - 120.016485ms | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:37] [tokio_core::reactor] [DEBUG] loop time - Instant { t: 766199.871377939s } | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:37] [tokio_core::reactor] [DEBUG] loop process, 101.333µs | |
| [2020-02-26][07:18:37] [tokio_reactor] [TRACE] loop process - 0 events, 0.000s | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:37] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] set readiness to Readable | |
| [2020-02-26][07:18:38] [mio::sys::windows::tcp] [TRACE] scheduling a write of 151 bytes | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:38] [tokio_reactor] [TRACE] event Readable Token(4194304) | |
| [2020-02-26][07:18:38] [mio::sys::windows::tcp] [TRACE] done immediately with 151 bytes | |
| [2020-02-26][07:18:38] [tokio_reactor] [TRACE] loop process - 1 events, 0.000s | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] set readiness to Readable | Writable | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] set readiness to Writable | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:38] [mio::sys::windows::tcp] [TRACE] scheduling a read | |
| [2020-02-26][07:18:38] [tokio_reactor] [TRACE] event Writable Token(4194304) | |
| [2020-02-26][07:18:38] [tokio_reactor::registration] [DEBUG] scheduling Read for: 0 | |
| [2020-02-26][07:18:38] [tokio_reactor] [TRACE] loop process - 1 events, 0.000s | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] select; -> got overlapped | |
| [2020-02-26][07:18:38] [mio::sys::windows::tcp] [TRACE] finished a read: 0 | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] set readiness to Readable | Writable | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:38] [tokio_reactor] [TRACE] event Readable | Writable Token(4194304) | |
| [2020-02-26][07:18:38] [tokio_reactor] [TRACE] loop process - 1 events, 0.000s | |
| [2020-02-26][07:18:38] [tokio_core::reactor] [DEBUG] loop poll - 54.406303ms | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:38] [tokio_core::reactor] [DEBUG] loop time - Instant { t: 766200.079033212s } | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:38] [tokio_core::reactor] [DEBUG] loop process, 99.394µs | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:38] [tokio_reactor] [TRACE] loop process - 0 events, 0.000s | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] set readiness to Readable | |
| [2020-02-26][07:18:38] [mio::sys::windows::tcp] [TRACE] scheduling a write of 36 bytes | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:38] [tokio_reactor] [TRACE] event Readable Token(4194304) | |
| [2020-02-26][07:18:38] [mio::sys::windows::tcp] [TRACE] done immediately with 36 bytes | |
| [2020-02-26][07:18:38] [tokio_reactor] [TRACE] loop process - 1 events, 0.000s | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] set readiness to Readable | Writable | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:38] [tokio_reactor] [TRACE] event Readable | Writable Token(4194304) | |
| [2020-02-26][07:18:38] [tokio_reactor] [TRACE] loop process - 1 events, 0.000s | |
| [2020-02-26][07:18:38] [tokio_io::_tokio_codec::framed_write] [TRACE] flushing framed transport | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:38] [tokio_io::_tokio_codec::framed_write] [TRACE] writing; remaining=125 | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] set readiness to Readable | |
| [2020-02-26][07:18:38] [mio::sys::windows::tcp] [TRACE] scheduling a write of 125 bytes | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:38] [mio::sys::windows::tcp] [TRACE] done immediately with 125 bytes | |
| [2020-02-26][07:18:38] [tokio_reactor] [TRACE] event Readable Token(4194304) | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] set readiness to Readable | Writable | |
| [2020-02-26][07:18:38] [tokio_reactor] [TRACE] loop process - 1 events, 0.000s | |
| [2020-02-26][07:18:38] [tokio_io::_tokio_codec::framed_write] [TRACE] framed transport flushed | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] select; timeout=Some(0ns) | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] set readiness to Writable | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:38] [mio::sys::windows::tcp] [TRACE] scheduling a read | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:38] [tokio_reactor::registration] [DEBUG] scheduling Read for: 0 | |
| [2020-02-26][07:18:38] [tokio_reactor] [TRACE] event Writable Token(4194304) | |
| [2020-02-26][07:18:38] [tokio_reactor] [TRACE] loop process - 1 events, 0.000s | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] select; -> got overlapped | |
| [2020-02-26][07:18:38] [mio::sys::windows::tcp] [TRACE] finished a read: 0 | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] set readiness to Readable | Writable | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:38] [tokio_reactor] [TRACE] event Readable | Writable Token(4194304) | |
| [2020-02-26][07:18:38] [tokio_reactor] [TRACE] loop process - 1 events, 0.000s | |
| [2020-02-26][07:18:38] [tokio_core::reactor] [DEBUG] loop poll - 91.948606ms | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:38] [tokio_core::reactor] [DEBUG] loop time - Instant { t: 766200.330666181s } | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:38] [tokio_core::reactor] [DEBUG] loop process, 126.061µs | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:38] [tokio_reactor] [TRACE] loop process - 0 events, 0.000s | |
| [2020-02-26][07:18:38] [tokio_io::_tokio_codec::framed_read] [TRACE] attempting to decode a frame | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:38] [tokio_io::_tokio_codec::framed_read] [TRACE] frame decoded from buffer | |
| [2020-02-26][07:18:38] [librespot_core::session] [INFO] Authenticated as "xx6800" ! | |
| [2020-02-26][07:18:38] [librespot_core::session] [DEBUG] new Session[1] | |
| [2020-02-26][07:18:38] [ncspot::spotify] [DEBUG] worker thread ready. | |
| [2020-02-26][07:18:38] [ncspot::spotify] [DEBUG] message received! | |
| [2020-02-26][07:18:38] [librespot::component] [DEBUG] new MercuryManager | |
| [2020-02-26][07:18:38] [librespot_playback::player] [DEBUG] new Player[1] | |
| [2020-02-26][07:18:38] [librespot_playback::audio_backend::rodio] [DEBUG] Using rodio sink | |
| [2020-02-26][07:18:38] [tokio_io::_tokio_codec::framed_read] [TRACE] attempting to decode a frame | |
| [2020-02-26][07:18:38] [tokio_io::_tokio_codec::framed_read] [TRACE] frame decoded from buffer | |
| [2020-02-26][07:18:38] [librespot_core::session] [DEBUG] Session[1] strong=3 weak=2 | |
| [2020-02-26][07:18:38] [tokio_io::_tokio_codec::framed_read] [TRACE] attempting to decode a frame | |
| [2020-02-26][07:18:38] [tokio_io::_tokio_codec::framed_read] [TRACE] frame decoded from buffer | |
| [2020-02-26][07:18:38] [tokio_io::_tokio_codec::framed_read] [TRACE] attempting to decode a frame | |
| [2020-02-26][07:18:38] [tokio_io::_tokio_codec::framed_read] [TRACE] frame decoded from buffer | |
| [2020-02-26][07:18:38] [tokio_io::_tokio_codec::framed_read] [TRACE] attempting to decode a frame | |
| [2020-02-26][07:18:38] [tokio_io::_tokio_codec::framed_read] [TRACE] frame decoded from buffer | |
| [2020-02-26][07:18:38] [librespot_core::session] [INFO] Country: "CH" | |
| [2020-02-26][07:18:38] [tokio_io::_tokio_codec::framed_read] [TRACE] attempting to decode a frame | |
| [2020-02-26][07:18:38] [tokio_io::_tokio_codec::framed_read] [TRACE] attempting to decode a frame | |
| [2020-02-26][07:18:38] [tokio_io::_tokio_codec::framed_read] [TRACE] frame decoded from buffer | |
| [2020-02-26][07:18:38] [tokio_io::_tokio_codec::framed_read] [TRACE] attempting to decode a frame | |
| [2020-02-26][07:18:38] [tokio_io::_tokio_codec::framed_read] [TRACE] frame decoded from buffer | |
| [2020-02-26][07:18:38] [tokio_io::_tokio_codec::framed_read] [TRACE] attempting to decode a frame | |
| [2020-02-26][07:18:38] [tokio_io::_tokio_codec::framed_read] [TRACE] frame decoded from buffer | |
| [2020-02-26][07:18:38] [tokio_io::_tokio_codec::framed_read] [TRACE] attempting to decode a frame | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] set readiness to Writable | |
| [2020-02-26][07:18:38] [mio::sys::windows::tcp] [TRACE] scheduling a read | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:38] [tokio_reactor::registration] [DEBUG] scheduling Read for: 0 | |
| [2020-02-26][07:18:38] [tokio_reactor] [TRACE] event Writable Token(4194304) | |
| [2020-02-26][07:18:38] [tokio_reactor] [TRACE] loop process - 1 events, 0.000s | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:38] [tokio_io::_tokio_codec::framed_write] [TRACE] flushing framed transport | |
| [2020-02-26][07:18:38] [tokio_io::_tokio_codec::framed_write] [TRACE] writing; remaining=353 | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] set readiness to (empty) | |
| [2020-02-26][07:18:38] [mio::sys::windows::tcp] [TRACE] scheduling a write of 353 bytes | |
| [2020-02-26][07:18:38] [mio::sys::windows::tcp] [TRACE] done immediately with 353 bytes | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] set readiness to Writable | |
| [2020-02-26][07:18:38] [tokio_io::_tokio_codec::framed_write] [TRACE] framed transport flushed | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:38] [tokio_core::reactor] [DEBUG] loop poll - 5.236364ms | |
| [2020-02-26][07:18:38] [tokio_reactor] [TRACE] event Writable Token(4194304) | |
| [2020-02-26][07:18:38] [tokio_core::reactor] [DEBUG] loop time - Instant { t: 766200.342446545s } | |
| [2020-02-26][07:18:38] [tokio_reactor] [TRACE] loop process - 1 events, 0.000s | |
| [2020-02-26][07:18:38] [tokio_core::reactor] [DEBUG] loop process, 121.212µs | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] select; -> got overlapped | |
| [2020-02-26][07:18:38] [mio::sys::windows::tcp] [TRACE] finished a read: 0 | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] set readiness to Readable | Writable | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:38] [tokio_reactor] [TRACE] event Readable | Writable Token(4194304) | |
| [2020-02-26][07:18:38] [tokio_reactor] [TRACE] loop process - 1 events, 0.000s | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:38] [tokio_io::_tokio_codec::framed_read] [TRACE] attempting to decode a frame | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:38] [tokio_io::_tokio_codec::framed_read] [TRACE] frame decoded from buffer | |
| [2020-02-26][07:18:38] [tokio_reactor] [TRACE] loop process - 0 events, 0.000s | |
| [2020-02-26][07:18:38] [tokio_io::_tokio_codec::framed_read] [TRACE] attempting to decode a frame | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] set readiness to Writable | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:38] [mio::sys::windows::tcp] [TRACE] scheduling a read | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:38] [tokio_reactor::registration] [DEBUG] scheduling Read for: 0 | |
| [2020-02-26][07:18:38] [tokio_reactor] [TRACE] event Writable Token(4194304) | |
| [2020-02-26][07:18:38] [tokio_io::_tokio_codec::framed_write] [TRACE] flushing framed transport | |
| [2020-02-26][07:18:38] [tokio_reactor] [TRACE] loop process - 1 events, 0.000s | |
| [2020-02-26][07:18:38] [tokio_io::_tokio_codec::framed_write] [TRACE] framed transport flushed | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:38] [tokio_core::reactor] [DEBUG] loop poll - 54.015031ms | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:38] [tokio_core::reactor] [DEBUG] loop time - Instant { t: 766200.396668121s } | |
| [2020-02-26][07:18:38] [tokio_core::reactor] [DEBUG] loop process, 101.333µs | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] select; -> got overlapped | |
| [2020-02-26][07:18:38] [mio::sys::windows::tcp] [TRACE] finished a read: 0 | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] set readiness to Readable | Writable | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:38] [tokio_reactor] [TRACE] event Readable | Writable Token(4194304) | |
| [2020-02-26][07:18:38] [tokio_reactor] [TRACE] loop process - 1 events, 0.000s | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:38] [tokio_io::_tokio_codec::framed_read] [TRACE] attempting to decode a frame | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:38] [tokio_reactor] [TRACE] loop process - 0 events, 0.000s | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:38] [tokio_io::_tokio_codec::framed_read] [TRACE] frame decoded from buffer | |
| [2020-02-26][07:18:38] [tokio_io::_tokio_codec::framed_read] [TRACE] attempting to decode a frame | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] set readiness to Writable | |
| [2020-02-26][07:18:38] [mio::sys::windows::tcp] [TRACE] scheduling a read | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:38] [tokio_reactor::registration] [DEBUG] scheduling Read for: 0 | |
| [2020-02-26][07:18:38] [tokio_reactor] [TRACE] event Writable Token(4194304) | |
| [2020-02-26][07:18:38] [tokio_io::_tokio_codec::framed_write] [TRACE] flushing framed transport | |
| [2020-02-26][07:18:38] [tokio_reactor] [TRACE] loop process - 1 events, 0.000s | |
| [2020-02-26][07:18:38] [tokio_io::_tokio_codec::framed_write] [TRACE] framed transport flushed | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:38] [tokio_core::reactor] [DEBUG] loop poll - 106.36897ms | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:38] [tokio_core::reactor] [DEBUG] loop time - Instant { t: 766200.503184s } | |
| [2020-02-26][07:18:38] [tokio_core::reactor] [DEBUG] loop process, 104.242µs | |
| [2020-02-26][07:18:38] [ncspot::spotify] [INFO] new token received: Token { access_token: "BQDo_RqJHiTpjd_Q_-rdOdEI-YN_aA4xB7n9X2e4rLBqcbGoIv_kNaSN0Zm9PIzS2XCRHpQPqufJQ0P8aYG7cllzAf4HSc7_t-gPRwZxqNDET8FQfU3fiCt1oBSPj3HG4M2bUqjqep301nfkHtEdxIHg-8VSUFjOMx9SDlXwr4zyqkGY6oG-kHOjhCvUV-fOvNYUoarYIp2mNlrTNTKRy3m3gc1cS0zih1sN_jejHjKxn4BQyiwUid9foZEyEyJf6WA3", expires_in: 3600, token_type: "Bearer", scope: ["user-read-private", "playlist-read-private", "playlist-read-collaborative", "playlist-modify-public", "playlist-modify-private", "user-follow-modify", "user-follow-read", "user-library-read", "user-library-modify", "user-top-read", "user-read-recently-played"] } | |
| [2020-02-26][07:18:38] [ncspot::spotify] [INFO] token updated! | |
| [2020-02-26][07:18:38] [tokio_core::reactor] [DEBUG] loop poll - 14.061µs | |
| [2020-02-26][07:18:38] [tokio_core::reactor] [DEBUG] loop time - Instant { t: 766200.503665454s } | |
| [2020-02-26][07:18:38] [tokio_core::reactor] [DEBUG] loop process, 89.212µs | |
| [2020-02-26][07:18:38] [mio::poll] [TRACE] registering with poller | |
| [2020-02-26][07:18:38] [reqwest::blocking::wait] [TRACE] (ThreadId(1)) park without timeout | |
| [2020-02-26][07:18:38] [reqwest::blocking::client] [TRACE] (ThreadId(16)) start runtime::block_on | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:38] [reqwest::blocking::wait] [TRACE] wait at most 30s | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:38] [reqwest::blocking::wait] [TRACE] (ThreadId(1)) park timeout 29.999973818s | |
| [2020-02-26][07:18:38] [hyper::client::pool] [TRACE] checkout waiting for idle connection: ("https", api.spotify.com) | |
| [2020-02-26][07:18:38] [reqwest::connect] [TRACE] proxy(Https { auth: None, host: 127.0.0.1:9999 }) intercepts https://api.spotify.com/ | |
| [2020-02-26][07:18:38] [hyper::client::connect::http] [TRACE] Http::connect; scheme=Some("https"), host=Some("127.0.0.1"), port=Some(Port(9999)) | |
| [2020-02-26][07:18:38] [hyper::client::connect::http] [DEBUG] connecting to 127.0.0.1:9999 | |
| [2020-02-26][07:18:38] [mio::poll] [TRACE] registering with poller | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] register Token(0) Readable | Writable | |
| [2020-02-26][07:18:38] [mio::sys::windows::tcp] [TRACE] scheduling a connect | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] select; timeout=Some(0ns) | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] select; -> got overlapped | |
| [2020-02-26][07:18:38] [mio::sys::windows::tcp] [TRACE] finished a connect | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] set readiness to Writable | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] set readiness to Writable | |
| [2020-02-26][07:18:38] [mio::sys::windows::tcp] [TRACE] scheduling a read | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:38] [hyper::client::connect::http] [DEBUG] connected to 127.0.0.1:9999 | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] set readiness to (empty) | |
| [2020-02-26][07:18:38] [mio::sys::windows::tcp] [TRACE] scheduling a write of 147 bytes | |
| [2020-02-26][07:18:38] [mio::sys::windows::tcp] [TRACE] done immediately with 147 bytes | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] set readiness to Writable | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] select; timeout=Some(0ns) | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] select; -> got overlapped | |
| [2020-02-26][07:18:38] [mio::sys::windows::tcp] [TRACE] finished a read: 0 | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] set readiness to Readable | Writable | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] set readiness to Writable | |
| [2020-02-26][07:18:38] [mio::sys::windows::tcp] [TRACE] scheduling a read | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] set readiness to Readable | Writable | |
| [2020-02-26][07:18:38] [mio::poll] [TRACE] deregistering handle with poller | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] deregistering | |
| [2020-02-26][07:18:38] [hyper::client::pool] [TRACE] checkout dropped for ("https", api.spotify.com) | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] select; timeout=Some(0ns) | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] returning | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] select; timeout=None | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] polling IOCP | |
| [2020-02-26][07:18:38] [tokio_core::reactor] [DEBUG] loop poll - 8.91297ms | |
| [2020-02-26][07:18:38] [tokio_core::reactor] [DEBUG] loop time - Instant { t: 766200.512712727s } | |
| [2020-02-26][07:18:38] [tokio_core::reactor] [DEBUG] loop process, 81.939µs | |
| [2020-02-26][07:18:38] [mio::sys::windows::selector] [TRACE] select; -> got overlapped |
Sign up for free
to join this conversation on GitHub.
Already have an account?
Sign in to comment