Ticket #1087: delugedebug

File delugedebug, 18.6 KB (added by seqizz, 14 years ago)
Line 
1[INFO    ] 18:15:17 main:114 Deluge ui 1.2.900-dev
2[DEBUG   ] 18:15:17 main:115 options: {'loglevel': 'debug', 'default_ui': None, 'args': None, 'quiet': False, 'ui': 'web', 'logfile': '/tmp/delugedebug', 'config': None}
3[DEBUG   ] 18:15:17 main:116 args: []
4[DEBUG   ] 18:15:17 main:117 ui_args: []
5[INFO    ] 18:15:17 main:120 Starting ui..
6[DEBUG   ] 18:15:17 ui:109 UI init..
7[DEBUG   ] 18:15:17 configmanager:111 Getting config 'ui.conf'
8[DEBUG   ] 18:15:17 config:377 Config /home/deluge/.config/deluge/ui.conf version: 1.1 loaded: {'default_ui': u'gtk'}
9[WARNING ] 18:15:17 config:405 Unable to open config file: /home/deluge/.config/deluge/ui.conf
10[DEBUG   ] 18:15:17 config:411 Saving new config file /home/deluge/.config/deluge/ui.conf.new
11[DEBUG   ] 18:15:17 config:424 Backing up old config file to /home/deluge/.config/deluge/ui.conf~
12[DEBUG   ] 18:15:17 config:432 Moving new config file /home/deluge/.config/deluge/ui.conf.new to /home/deluge/.config/deluge/ui.conf..
13[INFO    ] 18:15:17 ui:130 Starting WebUI..
14[DEBUG   ] 18:15:17 component:99 Registered TrackerIcons with ComponentRegistry..
15[DEBUG   ] 18:15:17 component:99 Registered DelugeWeb with ComponentRegistry..
16[DEBUG   ] 18:15:17 configmanager:111 Getting config 'web.conf'
17[WARNING ] 18:15:17 config:347 Unable to open config file /home/deluge/.config/deluge/web.conf: [Errno 2] No such file or directory: '/home/deluge/.config/deluge/web.conf'
18[DEBUG   ] 18:15:17 component:99 Registered Css with ComponentRegistry..
19[DEBUG   ] 18:15:17 server:257 Adding directory `/usr/lib64/python2.6/site-packages/deluge/ui/web/css` with path ``
20[DEBUG   ] 18:15:17 component:99 Registered Icons with ComponentRegistry..
21[DEBUG   ] 18:15:17 server:257 Adding directory `/usr/lib64/python2.6/site-packages/deluge/ui/web/icons` with path ``
22[DEBUG   ] 18:15:17 component:99 Registered Images with ComponentRegistry..
23[DEBUG   ] 18:15:17 server:257 Adding directory `/usr/lib64/python2.6/site-packages/deluge/ui/web/images` with path ``
24[DEBUG   ] 18:15:17 component:99 Registered Javascript with ComponentRegistry..
25[DEBUG   ] 18:15:17 server:257 Adding directory `/usr/lib64/python2.6/site-packages/deluge/ui/web/js` with path ``
26[DEBUG   ] 18:15:17 server:257 Adding directory `/usr/lib64/python2.6/site-packages/deluge/ui/web/js/ext-extensions` with path `ext-extensions`
27[DEBUG   ] 18:15:17 server:257 Adding directory `/usr/lib64/python2.6/site-packages/deluge/ui/web/js/deluge-all` with path `deluge-all`
28[DEBUG   ] 18:15:17 component:99 Registered JSON with ComponentRegistry..
29[DEBUG   ] 18:15:17 component:99 Registered Web with ComponentRegistry..
30[DEBUG   ] 18:15:17 json_api:298 Registering method: web.add_host
31[DEBUG   ] 18:15:17 json_api:298 Registering method: web.add_torrents
32[DEBUG   ] 18:15:17 json_api:298 Registering method: web.connect
33[DEBUG   ] 18:15:17 json_api:298 Registering method: web.connected
34[DEBUG   ] 18:15:17 json_api:298 Registering method: web.deregister_event_listener
35[DEBUG   ] 18:15:17 json_api:298 Registering method: web.disconnect
36[DEBUG   ] 18:15:17 json_api:298 Registering method: web.download_torrent_from_url
37[DEBUG   ] 18:15:17 json_api:298 Registering method: web.get_config
38[DEBUG   ] 18:15:17 json_api:298 Registering method: web.get_events
39[DEBUG   ] 18:15:17 json_api:298 Registering method: web.get_host_status
40[DEBUG   ] 18:15:17 json_api:298 Registering method: web.get_hosts
41[DEBUG   ] 18:15:17 json_api:298 Registering method: web.get_plugin_info
42[DEBUG   ] 18:15:17 json_api:298 Registering method: web.get_plugin_resources
43[DEBUG   ] 18:15:17 json_api:298 Registering method: web.get_plugins
44[DEBUG   ] 18:15:17 json_api:298 Registering method: web.get_torrent_files
45[DEBUG   ] 18:15:17 json_api:298 Registering method: web.get_torrent_info
46[DEBUG   ] 18:15:17 json_api:298 Registering method: web.register_event_listener
47[DEBUG   ] 18:15:17 json_api:298 Registering method: web.remove_host
48[DEBUG   ] 18:15:17 json_api:298 Registering method: web.set_config
49[DEBUG   ] 18:15:17 json_api:298 Registering method: web.start_daemon
50[DEBUG   ] 18:15:17 json_api:298 Registering method: web.stop_daemon
51[DEBUG   ] 18:15:17 json_api:298 Registering method: web.update_ui
52[DEBUG   ] 18:15:17 json_api:298 Registering method: web.upload_plugin
53[DEBUG   ] 18:15:17 configmanager:111 Getting config 'hostlist.conf.1.2'
54[WARNING ] 18:15:17 config:347 Unable to open config file /home/deluge/.config/deluge/hostlist.conf.1.2: [Errno 2] No such file or directory: '/home/deluge/.config/deluge/hostlist.conf.1.2'
55[DEBUG   ] 18:15:17 coreconfig:43 CoreConfig init..
56[DEBUG   ] 18:15:17 component:99 Registered CoreConfig with ComponentRegistry..
57[DEBUG   ] 18:15:17 component:99 Registered Auth with ComponentRegistry..
58[DEBUG   ] 18:15:17 json_api:298 Registering method: auth.change_password
59[DEBUG   ] 18:15:17 json_api:298 Registering method: auth.check_session
60[DEBUG   ] 18:15:17 json_api:298 Registering method: auth.delete_session
61[DEBUG   ] 18:15:17 json_api:298 Registering method: auth.login
62[DEBUG   ] 18:15:17 component:99 Registered Web.PluginManager with ComponentRegistry..
63[DEBUG   ] 18:15:17 configmanager:111 Getting config 'web.conf'
64[DEBUG   ] 18:15:17 pluginmanagerbase:65 Plugin manager init..
65[DEBUG   ] 18:15:17 configmanager:111 Getting config 'web.conf'
66[DEBUG   ] 18:15:17 pluginmanagerbase:116 Found plugin: FreeSpace 0.1
67[DEBUG   ] 18:15:17 pluginmanagerbase:116 Found plugin: Execute 1.2
68[DEBUG   ] 18:15:17 pluginmanagerbase:116 Found plugin: Stats 0.1
69[DEBUG   ] 18:15:17 pluginmanagerbase:116 Found plugin: Blocklist 1.2
70[DEBUG   ] 18:15:17 pluginmanagerbase:116 Found plugin: Extractor 0.1
71[DEBUG   ] 18:15:17 pluginmanagerbase:116 Found plugin: Label 0.1
72[DEBUG   ] 18:15:17 pluginmanagerbase:116 Found plugin: Notifications 0.1
73[DEBUG   ] 18:15:17 pluginmanagerbase:116 Found plugin: WebUi 0.1
74[DEBUG   ] 18:15:17 pluginmanagerbase:116 Found plugin: Scheduler 0.1
75[DEBUG   ] 18:15:17 pluginmanagerbase:116 Found plugin: feeder 0.4
76[DEBUG   ] 18:15:17 pluginmanagerbase:116 Found plugin: Example 1.2
77[INFO    ] 18:15:17 server:560 Starting server in PID 4271.
78[INFO    ] 18:15:17 server:574 serving on 0.0.0.0:8112 view at http://127.0.0.1:8112
79[DEBUG   ] 18:15:38 json_api:238 json-request: {"method":"system.listMethods","params":[],"id":0}
80[DEBUG   ] 18:15:38 json_api:238 json-request: {"method":"auth.check_session","params":[],"id":1}
81[DEBUG   ] 18:15:41 json_api:238 json-request: {"method":"auth.login","params":["deluge"],"id":2}
82[DEBUG   ] 18:15:41 auth:194 Received a password via the 1.2 auth method
83[DEBUG   ] 18:15:41 auth:141 Creating session for admin
84[DEBUG   ] 18:15:41 json_api:238 json-request: {"method":"web.register_event_listener","params":["PluginDisabledEvent"],"id":3}
85[DEBUG   ] 18:15:41 json_api:238 json-request: {"method":"web.register_event_listener","params":["PluginEnabledEvent"],"id":4}
86[DEBUG   ] 18:15:41 json_api:238 json-request: {"method":"web.register_event_listener","params":["PluginDisabledEvent"],"id":7}
87[DEBUG   ] 18:15:41 json_api:238 json-request: {"method":"web.connected","params":[],"id":8}
88[DEBUG   ] 18:15:41 json_api:238 json-request: {"method":"web.get_events","params":[],"id":5}
89[DEBUG   ] 18:15:41 json_api:238 json-request: {"method":"web.register_event_listener","params":["PluginEnabledEvent"],"id":6}
90[DEBUG   ] 18:15:41 json_api:238 json-request: {"method":"web.get_hosts","params":[],"id":9}
91[DEBUG   ] 18:15:41 json_api:680 get_hosts called
92[DEBUG   ] 18:15:41 json_api:238 json-request: {"method":"web.get_hosts","params":[],"id":10}
93[DEBUG   ] 18:15:41 json_api:680 get_hosts called
94[DEBUG   ] 18:15:41 json_api:238 json-request: {"method":"web.get_host_status","params":["9f1fdd3f2c1b2422951ac0297c3dc9fa5dbfe1e2"],"id":11}
95[INFO    ] 18:15:41 client:217 Connecting to daemon at 127.0.0.1:58846..
96[INFO    ] 18:15:41 client:121 Connected to daemon at 127.0.0.1:58846..
97[DEBUG   ] 18:15:41 json_api:238 json-request: {"method":"web.get_host_status","params":["9f1fdd3f2c1b2422951ac0297c3dc9fa5dbfe1e2"],"id":12}
98[INFO    ] 18:15:41 client:217 Connecting to daemon at 127.0.0.1:58846..
99[INFO    ] 18:15:41 client:121 Connected to daemon at 127.0.0.1:58846..
100[INFO    ] 18:15:41 client:224 Connection lost to daemon at 127.0.0.1:58846 reason: Connection was closed cleanly.
101[INFO    ] 18:15:41 client:224 Connection lost to daemon at 127.0.0.1:58846 reason: Connection was closed cleanly.
102[DEBUG   ] 18:15:43 json_api:238 json-request: {"method":"web.get_host_status","params":["9f1fdd3f2c1b2422951ac0297c3dc9fa5dbfe1e2"],"id":13}
103[INFO    ] 18:15:43 client:217 Connecting to daemon at 127.0.0.1:58846..
104[DEBUG   ] 18:15:43 json_api:238 json-request: {"method":"web.get_host_status","params":["9f1fdd3f2c1b2422951ac0297c3dc9fa5dbfe1e2"],"id":14}
105[INFO    ] 18:15:43 client:217 Connecting to daemon at 127.0.0.1:58846..
106[INFO    ] 18:15:43 client:121 Connected to daemon at 127.0.0.1:58846..
107[INFO    ] 18:15:43 client:121 Connected to daemon at 127.0.0.1:58846..
108[INFO    ] 18:15:43 client:224 Connection lost to daemon at 127.0.0.1:58846 reason: Connection was closed cleanly.
109[INFO    ] 18:15:43 client:224 Connection lost to daemon at 127.0.0.1:58846 reason: Connection was closed cleanly.
110[DEBUG   ] 18:15:43 json_api:238 json-request: {"method":"web.connect","params":["9f1fdd3f2c1b2422951ac0297c3dc9fa5dbfe1e2"],"id":15}
111[INFO    ] 18:15:43 client:217 Connecting to daemon at 127.0.0.1:58846..
112[INFO    ] 18:15:43 client:121 Connected to daemon at 127.0.0.1:58846..
113[DEBUG   ] 18:15:43 json_api:238 json-request: {"method":"system.listMethods","params":[],"id":16}
114[DEBUG   ] 18:15:43 json_api:238 json-request: {"method":"web.update_ui","params":[["queue","name","total_size","state","progress","num_seeds","total_seeds","num_peers","total_peers","download_payload_rate","upload_payload_rate","eta","ratio","distributed_copies","is_auto_managed","time_added","tracker_host"],{}],"id":17}
115[DEBUG   ] 18:15:43 json_api:238 json-request: {"method":"web.update_ui","params":[["queue","name","total_size","state","progress","num_seeds","total_seeds","num_peers","total_peers","download_payload_rate","upload_payload_rate","eta","ratio","distributed_copies","is_auto_managed","time_added","tracker_host"],{}],"id":18}
116[DEBUG   ] 18:15:45 json_api:238 json-request: {"method":"core.get_config_values","params":[["add_paused","compact_allocation","download_location","max_connections_per_torrent","max_download_speed_per_torrent","max_upload_slots_per_torrent","max_upload_speed_per_torrent","prioritize_first_last_pieces"]],"id":19}
117[DEBUG   ] 18:15:45 json_api:238 json-request: {"method":"core.get_config_values","params":[["add_paused","compact_allocation","download_location","max_connections_per_torrent","max_download_speed_per_torrent","max_upload_slots_per_torrent","max_upload_speed_per_torrent","prioritize_first_last_pieces"]],"id":20}
118[DEBUG   ] 18:15:45 json_api:238 json-request: {"method":"web.get_host_status","params":["9f1fdd3f2c1b2422951ac0297c3dc9fa5dbfe1e2"],"id":21}
119[DEBUG   ] 18:15:45 json_api:238 json-request: {"method":"web.update_ui","params":[["queue","name","total_size","state","progress","num_seeds","total_seeds","num_peers","total_peers","download_payload_rate","upload_payload_rate","eta","ratio","distributed_copies","is_auto_managed","time_added","tracker_host"],{}],"id":22}
120[DEBUG   ] 18:15:47 json_api:238 json-request: {"method":"web.get_host_status","params":["9f1fdd3f2c1b2422951ac0297c3dc9fa5dbfe1e2"],"id":23}
121[DEBUG   ] 18:15:47 json_api:238 json-request: {"method":"web.update_ui","params":[["queue","name","total_size","state","progress","num_seeds","total_seeds","num_peers","total_peers","download_payload_rate","upload_payload_rate","eta","ratio","distributed_copies","is_auto_managed","time_added","tracker_host"],{}],"id":24}
122[DEBUG   ] 18:15:49 json_api:238 json-request: {"method":"web.get_host_status","params":["9f1fdd3f2c1b2422951ac0297c3dc9fa5dbfe1e2"],"id":25}
123[DEBUG   ] 18:15:49 json_api:238 json-request: {"method":"web.update_ui","params":[["queue","name","total_size","state","progress","num_seeds","total_seeds","num_peers","total_peers","download_payload_rate","upload_payload_rate","eta","ratio","distributed_copies","is_auto_managed","time_added","tracker_host"],{}],"id":26}
124[DEBUG   ] 18:15:51 json_api:238 json-request: {"method":"web.get_host_status","params":["9f1fdd3f2c1b2422951ac0297c3dc9fa5dbfe1e2"],"id":27}
125[DEBUG   ] 18:15:51 json_api:238 json-request: {"method":"web.update_ui","params":[["queue","name","total_size","state","progress","num_seeds","total_seeds","num_peers","total_peers","download_payload_rate","upload_payload_rate","eta","ratio","distributed_copies","is_auto_managed","time_added","tracker_host"],{}],"id":28}
126[DEBUG   ] 18:15:53 json_api:238 json-request: {"method":"web.get_host_status","params":["9f1fdd3f2c1b2422951ac0297c3dc9fa5dbfe1e2"],"id":29}
127[DEBUG   ] 18:15:53 json_api:238 json-request: {"method":"web.update_ui","params":[["queue","name","total_size","state","progress","num_seeds","total_seeds","num_peers","total_peers","download_payload_rate","upload_payload_rate","eta","ratio","distributed_copies","is_auto_managed","time_added","tracker_host"],{}],"id":30}
128[DEBUG   ] 18:15:55 json_api:238 json-request: {"method":"web.get_host_status","params":["9f1fdd3f2c1b2422951ac0297c3dc9fa5dbfe1e2"],"id":31}
129[DEBUG   ] 18:15:55 json_api:238 json-request: {"method":"web.update_ui","params":[["queue","name","total_size","state","progress","num_seeds","total_seeds","num_peers","total_peers","download_payload_rate","upload_payload_rate","eta","ratio","distributed_copies","is_auto_managed","time_added","tracker_host"],{}],"id":32}
130[DEBUG   ] 18:15:57 json_api:238 json-request: {"method":"web.get_host_status","params":["9f1fdd3f2c1b2422951ac0297c3dc9fa5dbfe1e2"],"id":33}
131[DEBUG   ] 18:15:57 json_api:238 json-request: {"method":"web.update_ui","params":[["queue","name","total_size","state","progress","num_seeds","total_seeds","num_peers","total_peers","download_payload_rate","upload_payload_rate","eta","ratio","distributed_copies","is_auto_managed","time_added","tracker_host"],{}],"id":34}
132[DEBUG   ] 18:15:59 json_api:238 json-request: {"method":"web.get_host_status","params":["9f1fdd3f2c1b2422951ac0297c3dc9fa5dbfe1e2"],"id":35}
133[DEBUG   ] 18:15:59 json_api:238 json-request: {"method":"web.update_ui","params":[["queue","name","total_size","state","progress","num_seeds","total_seeds","num_peers","total_peers","download_payload_rate","upload_payload_rate","eta","ratio","distributed_copies","is_auto_managed","time_added","tracker_host"],{}],"id":36}
134[DEBUG   ] 18:16:01 json_api:238 json-request: {"method":"web.get_host_status","params":["9f1fdd3f2c1b2422951ac0297c3dc9fa5dbfe1e2"],"id":37}
135[DEBUG   ] 18:16:01 json_api:238 json-request: {"method":"web.update_ui","params":[["queue","name","total_size","state","progress","num_seeds","total_seeds","num_peers","total_peers","download_payload_rate","upload_payload_rate","eta","ratio","distributed_copies","is_auto_managed","time_added","tracker_host"],{}],"id":38}
136[DEBUG   ] 18:16:03 json_api:238 json-request: {"method":"web.get_host_status","params":["9f1fdd3f2c1b2422951ac0297c3dc9fa5dbfe1e2"],"id":39}
137[DEBUG   ] 18:16:03 json_api:238 json-request: {"method":"web.update_ui","params":[["queue","name","total_size","state","progress","num_seeds","total_seeds","num_peers","total_peers","download_payload_rate","upload_payload_rate","eta","ratio","distributed_copies","is_auto_managed","time_added","tracker_host"],{}],"id":40}
138[DEBUG   ] 18:16:05 json_api:238 json-request: {"method":"web.get_host_status","params":["9f1fdd3f2c1b2422951ac0297c3dc9fa5dbfe1e2"],"id":41}
139[DEBUG   ] 18:16:05 json_api:238 json-request: {"method":"web.update_ui","params":[["queue","name","total_size","state","progress","num_seeds","total_seeds","num_peers","total_peers","download_payload_rate","upload_payload_rate","eta","ratio","distributed_copies","is_auto_managed","time_added","tracker_host"],{}],"id":42}
140[DEBUG   ] 18:16:07 json_api:238 json-request: {"method":"web.get_host_status","params":["9f1fdd3f2c1b2422951ac0297c3dc9fa5dbfe1e2"],"id":43}
141[DEBUG   ] 18:16:07 json_api:238 json-request: {"method":"web.update_ui","params":[["queue","name","total_size","state","progress","num_seeds","total_seeds","num_peers","total_peers","download_payload_rate","upload_payload_rate","eta","ratio","distributed_copies","is_auto_managed","time_added","tracker_host"],{}],"id":44}
142[DEBUG   ] 18:16:09 json_api:238 json-request: {"method":"web.get_host_status","params":["9f1fdd3f2c1b2422951ac0297c3dc9fa5dbfe1e2"],"id":45}
143[DEBUG   ] 18:16:09 json_api:238 json-request: {"method":"web.update_ui","params":[["queue","name","total_size","state","progress","num_seeds","total_seeds","num_peers","total_peers","download_payload_rate","upload_payload_rate","eta","ratio","distributed_copies","is_auto_managed","time_added","tracker_host"],{}],"id":46}
144[DEBUG   ] 18:16:11 json_api:238 json-request: {"method":"web.get_events","params":[],"id":47}
145[DEBUG   ] 18:16:11 json_api:238 json-request: {"method":"web.get_host_status","params":["9f1fdd3f2c1b2422951ac0297c3dc9fa5dbfe1e2"],"id":48}
146[DEBUG   ] 18:16:11 json_api:238 json-request: {"method":"web.update_ui","params":[["queue","name","total_size","state","progress","num_seeds","total_seeds","num_peers","total_peers","download_payload_rate","upload_payload_rate","eta","ratio","distributed_copies","is_auto_managed","time_added","tracker_host"],{}],"id":49}
147[DEBUG   ] 18:16:13 json_api:238 json-request: {"method":"web.get_host_status","params":["9f1fdd3f2c1b2422951ac0297c3dc9fa5dbfe1e2"],"id":50}
148[DEBUG   ] 18:16:13 json_api:238 json-request: {"method":"web.update_ui","params":[["queue","name","total_size","state","progress","num_seeds","total_seeds","num_peers","total_peers","download_payload_rate","upload_payload_rate","eta","ratio","distributed_copies","is_auto_managed","time_added","tracker_host"],{}],"id":51}
149[DEBUG   ] 18:16:15 json_api:238 json-request: {"method":"web.get_host_status","params":["9f1fdd3f2c1b2422951ac0297c3dc9fa5dbfe1e2"],"id":52}
150[DEBUG   ] 18:16:15 json_api:238 json-request: {"method":"web.update_ui","params":[["queue","name","total_size","state","progress","num_seeds","total_seeds","num_peers","total_peers","download_payload_rate","upload_payload_rate","eta","ratio","distributed_copies","is_auto_managed","time_added","tracker_host"],{}],"id":53}
151[DEBUG   ] 18:16:17 json_api:238 json-request: {"method":"web.get_host_status","params":["9f1fdd3f2c1b2422951ac0297c3dc9fa5dbfe1e2"],"id":54}
152[DEBUG   ] 18:16:17 json_api:238 json-request: {"method":"web.update_ui","params":[["queue","name","total_size","state","progress","num_seeds","total_seeds","num_peers","total_peers","download_payload_rate","upload_payload_rate","eta","ratio","distributed_copies","is_auto_managed","time_added","tracker_host"],{}],"id":55}
153[INFO    ] 18:16:23 client:224 Connection lost to daemon at 127.0.0.1:58846 reason: Connection to the other side was lost in a non-clean fashion.