· 9 years ago · Feb 22, 2017, 04:38 PM
1Starting balrog_balrogdb_1
2Starting balrog_balrogadmin_1
3Starting balrog_balrogagent_1
4Starting balrog_balrogpub_1
5Starting balrog_balrogui_1
6Attaching to balrog_balrogdb_1, balrog_balrogadmin_1, balrog_balrogagent_1, balrog_balrogpub_1, balrog_balrogui_1
7balrogdb_1 | Initializing database
8balrogdb_1 | 2017-02-22T16:29:01.087650Z 0 [Warning] TIMESTAMP with implicit DEFAULT value is deprecated. Please use --explicit_defaults_for_timestamp server option (see documentation for more details).
9balrogdb_1 | 2017-02-22T16:29:04.473069Z 0 [Warning] InnoDB: New log files created, LSN=45790
10balrogdb_1 | 2017-02-22T16:29:05.283559Z 0 [Warning] InnoDB: Creating foreign key constraint system tables.
11balrogadmin_1 | + export LOCAL_DUMP=/app/scripts/prod_db_dump.sql
12balrogadmin_1 | + LOCAL_DUMP=/app/scripts/prod_db_dump.sql
13balrogagent_1 | 2017-02-22 16:29:06,092 - DEBUG - asyncio.__init__#53: Using selector: EpollSelector
14balrogpub_1 | [uWSGI] getting INI configuration from /app/uwsgi/public.ini
15balrogdb_1 | 2017-02-22T16:29:05.688041Z 0 [Warning] No existing UUID has been found, so we assume that this is the first time that this server has been started. Generating a new UUID: 0737dacd-f91c-11e6-9b27-0242ac110002.
16balrogadmin_1 | + '[' '!' -e /app/.cache/mysql/db.done ']'
17balrogagent_1 | 2017-02-22 16:29:06,093 - DEBUG - root.run_agent#22: Looking for active scheduled changes for endpoint rules...
18balrogpub_1 | *** Starting uWSGI 2.0.13.1 (64bit) on [Wed Feb 22 16:29:07 2017] ***
19balrogadmin_1 | + sleep 45
20balrogdb_1 | 2017-02-22T16:29:05.813440Z 0 [Warning] Gtid table is not ready to be used. Table 'mysql.gtid_executed' cannot be opened.
21balrogpub_1 | compiled with version: 4.9.2 on 26 December 2016 23:38:03
22balrogagent_1 | 2017-02-22 16:29:06,093 - DEBUG - root.request#31: Sending GET request to http://balrogadmin:7070/api/scheduled_changes/rules
23balrogpub_1 | os: Linux-4.4.0-63-generic #84-Ubuntu SMP Wed Feb 1 17:20:32 UTC 2017
24balrogdb_1 | 2017-02-22T16:29:05.814132Z 1 [Warning] root@localhost is created with an empty password ! Please consider switching off the --initialize-insecure option.
25balrogagent_1 | 2017-02-22 16:29:06,099 - ERROR - root.run_agent#56: Encountered exception:
26balrogpub_1 | nodename: 2039fc4cf361
27balrogpub_1 | machine: x86_64
28balrogagent_1 | Traceback (most recent call last):
29balrogpub_1 | clock source: unix
30balrogagent_1 | File "/usr/local/lib/python3.5/site-packages/aiohttp/connector.py", line 607, in _create_connection
31balrogpub_1 | pcre jit disabled
32balrogagent_1 | local_addr=self._local_addr)
33balrogagent_1 | File "/usr/local/lib/python3.5/asyncio/base_events.py", line 695, in create_connection
34balrogagent_1 | raise exceptions[0]
35balrogagent_1 | File "/usr/local/lib/python3.5/asyncio/base_events.py", line 682, in create_connection
36balrogagent_1 | yield from self.sock_connect(sock, address)
37balrogagent_1 | File "/usr/local/lib/python3.5/asyncio/futures.py", line 361, in __iter__
38balrogagent_1 | yield self # This tells Task to wait for completion.
39balrogagent_1 | File "/usr/local/lib/python3.5/asyncio/tasks.py", line 296, in _wakeup
40balrogagent_1 | future.result()
41balrogpub_1 | detected number of CPU cores: 4
42balrogagent_1 | File "/usr/local/lib/python3.5/asyncio/futures.py", line 274, in result
43balrogpub_1 | current working directory: /app
44balrogagent_1 | raise self._exception
45balrogpub_1 | detected binary path: /usr/local/bin/uwsgi
46balrogpub_1 | uWSGI running as root, you can use --uid/--gid/--chroot options
47balrogpub_1 | *** WARNING: you are running uWSGI as root !!! (use the --uid flag) ***
48balrogpub_1 | your memory page size is 4096 bytes
49balrogpub_1 | detected max file descriptor number: 1048576
50balrogpub_1 | lock engine: pthread robust mutexes
51balrogagent_1 | File "/usr/local/lib/python3.5/asyncio/selector_events.py", line 439, in _sock_connect_cb
52balrogagent_1 | raise OSError(err, 'Connect call failed %s' % (address,))
53balrogagent_1 | ConnectionRefusedError: [Errno 111] Connect call failed ('172.17.0.3', 7070)
54balrogagent_1 |
55balrogpub_1 | thunder lock: disabled (you can enable it with --thunder-lock)
56balrogagent_1 | The above exception was the direct cause of the following exception:
57balrogagent_1 |
58balrogpub_1 | uWSGI http bound on :9090 fd 4
59balrogagent_1 | Traceback (most recent call last):
60balrogpub_1 | uwsgi socket 0 bound to TCP address 127.0.0.1:38689 (port auto-assigned) fd 3
61balrogagent_1 | File "/usr/local/lib/python3.5/site-packages/aiohttp/connector.py", line 321, in connect
62balrogpub_1 | Python version: 2.7.13 (default, Dec 22 2016, 20:42:56) [GCC 4.9.2]
63balrogagent_1 | yield from self._create_connection(req)
64balrogagent_1 | File "/usr/local/lib/python3.5/site-packages/aiohttp/connector.py", line 630, in _create_connection
65balrogagent_1 | (req.host, req.port, exc.strerror)) from exc
66balrogpub_1 | Python main interpreter initialized at 0xfd0120
67balrogagent_1 | aiohttp.errors.ClientOSError: [Errno 111] Can not connect to balrogadmin:7070 [Connect call failed ('172.17.0.3', 7070)]
68balrogpub_1 | python threads support enabled
69balrogagent_1 |
70balrogagent_1 | The above exception was the direct cause of the following exception:
71balrogagent_1 |
72balrogagent_1 | Traceback (most recent call last):
73balrogagent_1 | File "/app/balrogagent/cmd.py", line 25, in run_agent
74balrogagent_1 | auth=auth, loop=loop)
75balrogagent_1 | File "/app/balrogagent/client.py", line 32, in request
76balrogagent_1 | resp = await aiohttp.request(method, url, data=json.dumps(data), headers=headers, auth=auth, loop=loop)
77balrogagent_1 | File "/usr/local/lib/python3.5/site-packages/aiohttp/client.py", line 585, in __await__
78balrogagent_1 | return (yield from self._coro)
79balrogagent_1 | File "/usr/local/lib/python3.5/site-packages/aiohttp/client.py", line 186, in _request
80balrogagent_1 | conn = yield from self._connector.connect(req)
81balrogagent_1 | File "/usr/local/lib/python3.5/site-packages/aiohttp/connector.py", line 331, in connect
82balrogagent_1 | .format(key, exc.strerror)) from exc
83balrogagent_1 | aiohttp.errors.ClientOSError: [Errno 111] Cannot connect to host balrogadmin:7070 ssl:False [Can not connect to balrogadmin:7070 [Connect call failed ('172.17.0.3', 7070)]]
84balrogpub_1 | your server socket listen backlog is limited to 100 connections
85balrogpub_1 | your mercy for graceful operations on workers is 60 seconds
86balrogpub_1 | mapped 145536 bytes (142 KB) for 1 cores
87balrogpub_1 | *** Operational MODE: single process ***
88balrogpub_1 | WSGI app 0 (mountpoint='') ready in 0 seconds on interpreter 0xfd0120 pid: 1 (default app)
89balrogpub_1 | *** uWSGI is running in multiple interpreter mode ***
90balrogpub_1 | spawned uWSGI master process (pid: 1)
91balrogpub_1 | spawned uWSGI worker 1 (pid: 8, cores: 1)
92balrogpub_1 | spawned uWSGI http 1 (pid: 9)
93balrogpub_1 | Python auto-reloader enabled
94balrogui_1 |
95balrogui_1 | > balrog-ui@0.0.1 start /app
96balrogui_1 | > lineman run
97balrogui_1 |
98balrogui_1 | Running "common" task
99balrogui_1 |
100balrogui_1 | Running "ngtemplates:app" (ngtemplates) task
101balrogui_1 | File generated/angular/template-cache.js created.
102balrogui_1 |
103balrogui_1 | Running "less:compile" (less) task
104balrogui_1 | File generated/css/app.less.css created.
105balrogui_1 |
106balrogui_1 | Running "coffee:compile" (coffee) task
107balrogui_1 | >> Destination (generated/js/app.coffee.js) not written because compiled files were empty.
108balrogui_1 | >> Destination (generated/js/spec.coffee.js) not written because compiled files were empty.
109balrogui_1 | >> Destination (generated/js/spec-helpers.coffee.js) not written because compiled files were empty.
110balrogui_1 |
111balrogui_1 | Running "jshint:files" (jshint) task
112balrogui_1 | >> 49 files lint free.
113balrogui_1 |
114balrogui_1 | Running "concat_sourcemap:js" (concat_sourcemap) task
115balrogui_1 | File "generated/js/app.js" created.
116balrogui_1 |
117balrogui_1 | Running "concat_sourcemap:spec" (concat_sourcemap) task
118balrogui_1 | File "generated/js/spec.js" created.
119balrogui_1 |
120balrogui_1 | Running "concat_sourcemap:css" (concat_sourcemap) task
121balrogui_1 | File "generated/css/app.css" created.
122balrogui_1 |
123balrogui_1 | Running "copy:dev" (copy) task
124balrogui_1 | Created 1 directories, copied 5 files
125balrogui_1 |
126balrogui_1 | Running "images:dev" (images) task
127balrogui_1 | Copying images to 'generated/img'
128balrogui_1 |
129balrogui_1 | Running "webfonts:dev" (webfonts) task
130balrogui_1 | Copying webfonts to 'generated/webfonts'
131balrogui_1 |
132balrogui_1 | Running "pages:dev" (pages) task
133balrogui_1 | generated/index.html generated from app/pages/index.us
134balrogui_1 |
135balrogui_1 | Running "dev" task
136balrogui_1 |
137balrogui_1 | Running "server" task
138balrogui_1 | Proxying API requests prefixed with 'api' to balrogadmin:7070
139balrogui_1 | Starting express web server in 'generated' on port 8080
140balrogui_1 | Simulating HTML5 pushState: Serving up 'generated/index.html' for all other unmatched paths
141balrogui_1 |
142balrogui_1 | Running "watch" task
143balrogui_1 | Waiting...
144balrogdb_1 | 2017-02-22T16:29:21.990319Z 1 [Warning] 'user' entry 'root@localhost' ignored in --skip-name-resolve mode.
145balrogdb_1 | 2017-02-22T16:29:21.990353Z 1 [Warning] 'user' entry 'mysql.sys@localhost' ignored in --skip-name-resolve mode.
146balrogdb_1 | 2017-02-22T16:29:21.990373Z 1 [Warning] 'db' entry 'sys mysql.sys@localhost' ignored in --skip-name-resolve mode.
147balrogdb_1 | 2017-02-22T16:29:21.990392Z 1 [Warning] 'proxies_priv' entry '@ root@localhost' ignored in --skip-name-resolve mode.
148balrogdb_1 | 2017-02-22T16:29:21.990434Z 1 [Warning] 'tables_priv' entry 'sys_config mysql.sys@localhost' ignored in --skip-name-resolve mode.
149balrogdb_1 | Database initialized
150balrogdb_1 | 2017-02-22T16:29:30.418435Z 0 [Warning] TIMESTAMP with implicit DEFAULT value is deprecated. Please use --explicit_defaults_for_timestamp server option (see documentation for more details).
151balrogdb_1 | 2017-02-22T16:29:30.419548Z 0 [Note] mysqld (mysqld 5.7.17) starting as process 57 ...
152balrogdb_1 | 2017-02-22T16:29:30.422356Z 0 [Note] InnoDB: PUNCH HOLE support available
153balrogdb_1 | 2017-02-22T16:29:30.422377Z 0 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
154balrogdb_1 | 2017-02-22T16:29:30.422383Z 0 [Note] InnoDB: Uses event mutexes
155balrogdb_1 | 2017-02-22T16:29:30.422387Z 0 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier
156balrogdb_1 | 2017-02-22T16:29:30.422408Z 0 [Note] InnoDB: Compressed tables use zlib 1.2.3
157balrogdb_1 | 2017-02-22T16:29:30.422412Z 0 [Note] InnoDB: Using Linux native AIO
158balrogdb_1 | 2017-02-22T16:29:30.422719Z 0 [Note] InnoDB: Number of pools: 1
159balrogdb_1 | 2017-02-22T16:29:30.422836Z 0 [Note] InnoDB: Using CPU crc32 instructions
160balrogdb_1 | MySQL init process in progress...
161balrogdb_1 | 2017-02-22T16:29:30.860563Z 0 [Note] InnoDB: Initializing buffer pool, total size = 128M, instances = 1, chunk size = 128M
162balrogdb_1 | 2017-02-22T16:29:30.868745Z 0 [Note] InnoDB: Completed initialization of buffer pool
163balrogdb_1 | 2017-02-22T16:29:30.870268Z 0 [Note] InnoDB: If the mysqld execution user is authorized, page cleaner thread priority can be changed. See the man page of setpriority().
164balrogdb_1 | 2017-02-22T16:29:30.882247Z 0 [Note] InnoDB: Highest supported file format is Barracuda.
165balrogdb_1 | 2017-02-22T16:29:31.027279Z 0 [Note] InnoDB: Creating shared tablespace for temporary tables
166balrogdb_1 | 2017-02-22T16:29:31.027367Z 0 [Note] InnoDB: Setting file './ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
167balrogdb_1 | 2017-02-22T16:29:31.342837Z 0 [Note] InnoDB: File './ibtmp1' size is now 12 MB.
168balrogdb_1 | 2017-02-22T16:29:31.343987Z 0 [Note] InnoDB: 96 redo rollback segment(s) found. 96 redo rollback segment(s) are active.
169balrogdb_1 | 2017-02-22T16:29:31.344003Z 0 [Note] InnoDB: 32 non-redo rollback segment(s) are active.
170balrogdb_1 | 2017-02-22T16:29:31.344988Z 0 [Note] InnoDB: 5.7.17 started; log sequence number 2535752
171balrogdb_1 | 2017-02-22T16:29:31.346287Z 0 [Note] InnoDB: Loading buffer pool(s) from /var/lib/mysql/ib_buffer_pool
172balrogdb_1 | 2017-02-22T16:29:31.346395Z 0 [Note] Plugin 'FEDERATED' is disabled.
173balrogdb_1 | 2017-02-22T16:29:31.349040Z 0 [Note] InnoDB: Buffer pool(s) load completed at 170222 16:29:31
174balrogdb_1 | 2017-02-22T16:29:31.351122Z 0 [Warning] Failed to set up SSL because of the following SSL library error: SSL context is not usable without certificate and private key
175balrogdb_1 | 2017-02-22T16:29:31.403551Z 0 [Warning] 'user' entry 'root@localhost' ignored in --skip-name-resolve mode.
176balrogdb_1 | 2017-02-22T16:29:31.403594Z 0 [Warning] 'user' entry 'mysql.sys@localhost' ignored in --skip-name-resolve mode.
177balrogdb_1 | 2017-02-22T16:29:31.403608Z 0 [Warning] 'db' entry 'sys mysql.sys@localhost' ignored in --skip-name-resolve mode.
178balrogdb_1 | 2017-02-22T16:29:31.403616Z 0 [Warning] 'proxies_priv' entry '@ root@localhost' ignored in --skip-name-resolve mode.
179balrogdb_1 | 2017-02-22T16:29:31.404807Z 0 [Warning] 'tables_priv' entry 'sys_config mysql.sys@localhost' ignored in --skip-name-resolve mode.
180balrogdb_1 | 2017-02-22T16:29:31.409516Z 0 [Note] Event Scheduler: Loaded 0 events
181balrogdb_1 | 2017-02-22T16:29:31.409615Z 0 [Note] Executing 'SELECT * FROM INFORMATION_SCHEMA.TABLES;' to get a list of tables using the deprecated partition engine. You may use the startup option '--disable-partition-engine-check' to skip this check.
182balrogdb_1 | 2017-02-22T16:29:31.409626Z 0 [Note] Beginning of list of non-natively partitioned tables
183balrogdb_1 | 2017-02-22T16:29:31.417539Z 0 [Note] End of list of non-natively partitioned tables
184balrogdb_1 | 2017-02-22T16:29:31.417649Z 0 [Note] mysqld: ready for connections.
185balrogdb_1 | Version: '5.7.17' socket: '/var/run/mysqld/mysqld.sock' port: 0 MySQL Community Server (GPL)
186balrogdb_1 | Warning: Unable to load '/usr/share/zoneinfo/iso3166.tab' as time zone. Skipping it.
187balrogdb_1 | Warning: Unable to load '/usr/share/zoneinfo/leap-seconds.list' as time zone. Skipping it.
188balrogagent_1 | 2017-02-22 16:29:36,128 - DEBUG - root.run_agent#22: Looking for active scheduled changes for endpoint rules...
189balrogagent_1 | 2017-02-22 16:29:36,128 - DEBUG - root.request#31: Sending GET request to http://balrogadmin:7070/api/scheduled_changes/rules
190balrogagent_1 | 2017-02-22 16:29:36,131 - ERROR - root.run_agent#56: Encountered exception:
191balrogagent_1 | Traceback (most recent call last):
192balrogagent_1 | File "/usr/local/lib/python3.5/site-packages/aiohttp/connector.py", line 607, in _create_connection
193balrogagent_1 | local_addr=self._local_addr)
194balrogagent_1 | File "/usr/local/lib/python3.5/asyncio/base_events.py", line 695, in create_connection
195balrogagent_1 | raise exceptions[0]
196balrogagent_1 | File "/usr/local/lib/python3.5/asyncio/base_events.py", line 682, in create_connection
197balrogagent_1 | yield from self.sock_connect(sock, address)
198balrogagent_1 | File "/usr/local/lib/python3.5/asyncio/futures.py", line 361, in __iter__
199balrogagent_1 | yield self # This tells Task to wait for completion.
200balrogagent_1 | File "/usr/local/lib/python3.5/asyncio/tasks.py", line 296, in _wakeup
201balrogagent_1 | future.result()
202balrogagent_1 | File "/usr/local/lib/python3.5/asyncio/futures.py", line 274, in result
203balrogagent_1 | raise self._exception
204balrogagent_1 | File "/usr/local/lib/python3.5/asyncio/selector_events.py", line 439, in _sock_connect_cb
205balrogagent_1 | raise OSError(err, 'Connect call failed %s' % (address,))
206balrogagent_1 | ConnectionRefusedError: [Errno 111] Connect call failed ('172.17.0.3', 7070)
207balrogagent_1 |
208balrogagent_1 | The above exception was the direct cause of the following exception:
209balrogagent_1 |
210balrogagent_1 | Traceback (most recent call last):
211balrogagent_1 | File "/usr/local/lib/python3.5/site-packages/aiohttp/connector.py", line 321, in connect
212balrogagent_1 | yield from self._create_connection(req)
213balrogagent_1 | File "/usr/local/lib/python3.5/site-packages/aiohttp/connector.py", line 630, in _create_connection
214balrogagent_1 | (req.host, req.port, exc.strerror)) from exc
215balrogagent_1 | aiohttp.errors.ClientOSError: [Errno 111] Can not connect to balrogadmin:7070 [Connect call failed ('172.17.0.3', 7070)]
216balrogagent_1 |
217balrogagent_1 | The above exception was the direct cause of the following exception:
218balrogagent_1 |
219balrogagent_1 | Traceback (most recent call last):
220balrogagent_1 | File "/app/balrogagent/cmd.py", line 25, in run_agent
221balrogagent_1 | auth=auth, loop=loop)
222balrogagent_1 | File "/app/balrogagent/client.py", line 32, in request
223balrogagent_1 | resp = await aiohttp.request(method, url, data=json.dumps(data), headers=headers, auth=auth, loop=loop)
224balrogagent_1 | File "/usr/local/lib/python3.5/site-packages/aiohttp/client.py", line 585, in __await__
225balrogagent_1 | return (yield from self._coro)
226balrogagent_1 | File "/usr/local/lib/python3.5/site-packages/aiohttp/client.py", line 186, in _request
227balrogagent_1 | conn = yield from self._connector.connect(req)
228balrogagent_1 | File "/usr/local/lib/python3.5/site-packages/aiohttp/connector.py", line 331, in connect
229balrogagent_1 | .format(key, exc.strerror)) from exc
230balrogagent_1 | aiohttp.errors.ClientOSError: [Errno 111] Cannot connect to host balrogadmin:7070 ssl:False [Can not connect to balrogadmin:7070 [Connect call failed ('172.17.0.3', 7070)]]
231balrogdb_1 | Warning: Unable to load '/usr/share/zoneinfo/zone.tab' as time zone. Skipping it.
232balrogdb_1 | 2017-02-22T16:29:39.766008Z 5 [Warning] 'db' entry 'sys mysql.sys@localhost' ignored in --skip-name-resolve mode.
233balrogdb_1 | 2017-02-22T16:29:39.766025Z 5 [Warning] 'proxies_priv' entry '@ root@localhost' ignored in --skip-name-resolve mode.
234balrogdb_1 | 2017-02-22T16:29:39.766049Z 5 [Warning] 'tables_priv' entry 'sys_config mysql.sys@localhost' ignored in --skip-name-resolve mode.
235balrogdb_1 | mysql: [Warning] Using a password on the command line interface can be insecure.
236balrogdb_1 | mysql: [Warning] Using a password on the command line interface can be insecure.
237balrogdb_1 | mysql: [Warning] Using a password on the command line interface can be insecure.
238balrogdb_1 | mysql: [Warning] Using a password on the command line interface can be insecure.
239balrogdb_1 | 2017-02-22T16:29:39.795198Z 9 [Warning] 'db' entry 'sys mysql.sys@localhost' ignored in --skip-name-resolve mode.
240balrogdb_1 | 2017-02-22T16:29:39.795218Z 9 [Warning] 'proxies_priv' entry '@ root@localhost' ignored in --skip-name-resolve mode.
241balrogdb_1 | 2017-02-22T16:29:39.795344Z 9 [Warning] 'tables_priv' entry 'sys_config mysql.sys@localhost' ignored in --skip-name-resolve mode.
242balrogdb_1 |
243balrogdb_1 | 2017-02-22T16:29:39.796256Z 0 [Note] Giving 0 client threads a chance to die gracefully
244balrogdb_1 | 2017-02-22T16:29:39.796276Z 0 [Note] Shutting down slave threads
245balrogdb_1 | 2017-02-22T16:29:39.796283Z 0 [Note] Forcefully disconnecting 0 remaining clients
246balrogdb_1 | 2017-02-22T16:29:39.796287Z 0 [Note] Event Scheduler: Purging the queue. 0 events
247balrogdb_1 | 2017-02-22T16:29:39.796341Z 0 [Note] Binlog end
248balrogdb_1 | 2017-02-22T16:29:39.797197Z 0 [Note] Shutting down plugin 'ngram'
249balrogdb_1 | 2017-02-22T16:29:39.797211Z 0 [Note] Shutting down plugin 'BLACKHOLE'
250balrogdb_1 | 2017-02-22T16:29:39.797215Z 0 [Note] Shutting down plugin 'partition'
251balrogdb_1 | 2017-02-22T16:29:39.797218Z 0 [Note] Shutting down plugin 'ARCHIVE'
252balrogdb_1 | 2017-02-22T16:29:39.797221Z 0 [Note] Shutting down plugin 'INNODB_SYS_VIRTUAL'
253balrogdb_1 | 2017-02-22T16:29:39.797223Z 0 [Note] Shutting down plugin 'INNODB_SYS_DATAFILES'
254balrogdb_1 | 2017-02-22T16:29:39.797231Z 0 [Note] Shutting down plugin 'INNODB_SYS_TABLESPACES'
255balrogdb_1 | 2017-02-22T16:29:39.797233Z 0 [Note] Shutting down plugin 'INNODB_SYS_FOREIGN_COLS'
256balrogdb_1 | 2017-02-22T16:29:39.797236Z 0 [Note] Shutting down plugin 'INNODB_SYS_FOREIGN'
257balrogdb_1 | 2017-02-22T16:29:39.797238Z 0 [Note] Shutting down plugin 'INNODB_SYS_FIELDS'
258balrogdb_1 | 2017-02-22T16:29:39.797241Z 0 [Note] Shutting down plugin 'INNODB_SYS_COLUMNS'
259balrogdb_1 | 2017-02-22T16:29:39.797243Z 0 [Note] Shutting down plugin 'INNODB_SYS_INDEXES'
260balrogdb_1 | 2017-02-22T16:29:39.797246Z 0 [Note] Shutting down plugin 'INNODB_SYS_TABLESTATS'
261balrogdb_1 | 2017-02-22T16:29:39.797252Z 0 [Note] Shutting down plugin 'INNODB_SYS_TABLES'
262balrogdb_1 | 2017-02-22T16:29:39.797255Z 0 [Note] Shutting down plugin 'INNODB_FT_INDEX_TABLE'
263balrogdb_1 | 2017-02-22T16:29:39.797258Z 0 [Note] Shutting down plugin 'INNODB_FT_INDEX_CACHE'
264balrogdb_1 | 2017-02-22T16:29:39.797262Z 0 [Note] Shutting down plugin 'INNODB_FT_CONFIG'
265balrogdb_1 | 2017-02-22T16:29:39.797265Z 0 [Note] Shutting down plugin 'INNODB_FT_BEING_DELETED'
266balrogdb_1 | 2017-02-22T16:29:39.797269Z 0 [Note] Shutting down plugin 'INNODB_FT_DELETED'
267balrogdb_1 | 2017-02-22T16:29:39.797272Z 0 [Note] Shutting down plugin 'INNODB_FT_DEFAULT_STOPWORD'
268balrogdb_1 | 2017-02-22T16:29:39.797274Z 0 [Note] Shutting down plugin 'INNODB_METRICS'
269balrogdb_1 | 2017-02-22T16:29:39.797279Z 0 [Note] Shutting down plugin 'INNODB_TEMP_TABLE_INFO'
270balrogdb_1 | 2017-02-22T16:29:39.797281Z 0 [Note] Shutting down plugin 'INNODB_BUFFER_POOL_STATS'
271balrogdb_1 | 2017-02-22T16:29:39.797286Z 0 [Note] Shutting down plugin 'INNODB_BUFFER_PAGE_LRU'
272balrogdb_1 | 2017-02-22T16:29:39.797288Z 0 [Note] Shutting down plugin 'INNODB_BUFFER_PAGE'
273balrogdb_1 | 2017-02-22T16:29:39.797291Z 0 [Note] Shutting down plugin 'INNODB_CMP_PER_INDEX_RESET'
274balrogdb_1 | 2017-02-22T16:29:39.797295Z 0 [Note] Shutting down plugin 'INNODB_CMP_PER_INDEX'
275balrogdb_1 | 2017-02-22T16:29:39.797298Z 0 [Note] Shutting down plugin 'INNODB_CMPMEM_RESET'
276balrogdb_1 | 2017-02-22T16:29:39.797301Z 0 [Note] Shutting down plugin 'INNODB_CMPMEM'
277balrogdb_1 | 2017-02-22T16:29:39.797304Z 0 [Note] Shutting down plugin 'INNODB_CMP_RESET'
278balrogdb_1 | 2017-02-22T16:29:39.797307Z 0 [Note] Shutting down plugin 'INNODB_CMP'
279balrogdb_1 | 2017-02-22T16:29:39.797310Z 0 [Note] Shutting down plugin 'INNODB_LOCK_WAITS'
280balrogdb_1 | 2017-02-22T16:29:39.797313Z 0 [Note] Shutting down plugin 'INNODB_LOCKS'
281balrogdb_1 | 2017-02-22T16:29:39.797315Z 0 [Note] Shutting down plugin 'INNODB_TRX'
282balrogdb_1 | 2017-02-22T16:29:39.797320Z 0 [Note] Shutting down plugin 'InnoDB'
283balrogdb_1 | 2017-02-22T16:29:39.797382Z 0 [Note] InnoDB: FTS optimize thread exiting.
284balrogdb_1 | 2017-02-22T16:29:39.797493Z 0 [Note] InnoDB: Starting shutdown...
285balrogdb_1 | 2017-02-22T16:29:39.897724Z 0 [Note] InnoDB: Dumping buffer pool(s) to /var/lib/mysql/ib_buffer_pool
286balrogdb_1 | 2017-02-22T16:29:39.898056Z 0 [Note] InnoDB: Buffer pool(s) dump completed at 170222 16:29:39
287balrogdb_1 | 2017-02-22T16:29:42.009085Z 0 [Note] InnoDB: Shutdown completed; log sequence number 12168603
288balrogdb_1 | 2017-02-22T16:29:42.011101Z 0 [Note] InnoDB: Removed temporary tablespace data file: "ibtmp1"
289balrogdb_1 | 2017-02-22T16:29:42.011120Z 0 [Note] Shutting down plugin 'MRG_MYISAM'
290balrogdb_1 | 2017-02-22T16:29:42.011124Z 0 [Note] Shutting down plugin 'MyISAM'
291balrogdb_1 | 2017-02-22T16:29:42.011130Z 0 [Note] Shutting down plugin 'CSV'
292balrogdb_1 | 2017-02-22T16:29:42.011135Z 0 [Note] Shutting down plugin 'MEMORY'
293balrogdb_1 | 2017-02-22T16:29:42.011137Z 0 [Note] Shutting down plugin 'PERFORMANCE_SCHEMA'
294balrogdb_1 | 2017-02-22T16:29:42.011158Z 0 [Note] Shutting down plugin 'sha256_password'
295balrogdb_1 | 2017-02-22T16:29:42.011161Z 0 [Note] Shutting down plugin 'mysql_native_password'
296balrogdb_1 | 2017-02-22T16:29:42.011284Z 0 [Note] Shutting down plugin 'binlog'
297balrogdb_1 | 2017-02-22T16:29:42.011751Z 0 [Note] mysqld: Shutdown complete
298balrogdb_1 |
299balrogdb_1 |
300balrogdb_1 | MySQL init process done. Ready for start up.
301balrogdb_1 |
302balrogdb_1 | 2017-02-22T16:29:42.243890Z 0 [Warning] TIMESTAMP with implicit DEFAULT value is deprecated. Please use --explicit_defaults_for_timestamp server option (see documentation for more details).
303balrogdb_1 | 2017-02-22T16:29:42.245021Z 0 [Note] mysqld (mysqld 5.7.17) starting as process 1 ...
304balrogdb_1 | 2017-02-22T16:29:42.247888Z 0 [Note] InnoDB: PUNCH HOLE support available
305balrogdb_1 | 2017-02-22T16:29:42.247910Z 0 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
306balrogdb_1 | 2017-02-22T16:29:42.247917Z 0 [Note] InnoDB: Uses event mutexes
307balrogdb_1 | 2017-02-22T16:29:42.247921Z 0 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier
308balrogdb_1 | 2017-02-22T16:29:42.247927Z 0 [Note] InnoDB: Compressed tables use zlib 1.2.3
309balrogdb_1 | 2017-02-22T16:29:42.247932Z 0 [Note] InnoDB: Using Linux native AIO
310balrogdb_1 | 2017-02-22T16:29:42.248168Z 0 [Note] InnoDB: Number of pools: 1
311balrogdb_1 | 2017-02-22T16:29:42.248263Z 0 [Note] InnoDB: Using CPU crc32 instructions
312balrogdb_1 | 2017-02-22T16:29:42.249604Z 0 [Note] InnoDB: Initializing buffer pool, total size = 128M, instances = 1, chunk size = 128M
313balrogdb_1 | 2017-02-22T16:29:42.256634Z 0 [Note] InnoDB: Completed initialization of buffer pool
314balrogdb_1 | 2017-02-22T16:29:42.258846Z 0 [Note] InnoDB: If the mysqld execution user is authorized, page cleaner thread priority can be changed. See the man page of setpriority().
315balrogdb_1 | 2017-02-22T16:29:42.270714Z 0 [Note] InnoDB: Highest supported file format is Barracuda.
316balrogdb_1 | 2017-02-22T16:29:42.317253Z 0 [Note] InnoDB: Creating shared tablespace for temporary tables
317balrogdb_1 | 2017-02-22T16:29:42.317439Z 0 [Note] InnoDB: Setting file './ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
318balrogdb_1 | 2017-02-22T16:29:42.511309Z 0 [Note] InnoDB: File './ibtmp1' size is now 12 MB.
319balrogdb_1 | 2017-02-22T16:29:42.518477Z 0 [Note] InnoDB: 96 redo rollback segment(s) found. 96 redo rollback segment(s) are active.
320balrogdb_1 | 2017-02-22T16:29:42.518510Z 0 [Note] InnoDB: 32 non-redo rollback segment(s) are active.
321balrogdb_1 | 2017-02-22T16:29:42.519526Z 0 [Note] InnoDB: Waiting for purge to start
322balrogdb_1 | 2017-02-22T16:29:42.569675Z 0 [Note] InnoDB: 5.7.17 started; log sequence number 12168603
323balrogdb_1 | 2017-02-22T16:29:42.570581Z 0 [Note] InnoDB: Loading buffer pool(s) from /var/lib/mysql/ib_buffer_pool
324balrogdb_1 | 2017-02-22T16:29:42.570729Z 0 [Note] Plugin 'FEDERATED' is disabled.
325balrogdb_1 | 2017-02-22T16:29:42.576302Z 0 [Note] InnoDB: Buffer pool(s) load completed at 170222 16:29:42
326balrogdb_1 | 2017-02-22T16:29:42.577558Z 0 [Warning] Failed to set up SSL because of the following SSL library error: SSL context is not usable without certificate and private key
327balrogdb_1 | 2017-02-22T16:29:42.577959Z 0 [Note] Server hostname (bind-address): '*'; port: 3306
328balrogdb_1 | 2017-02-22T16:29:42.577985Z 0 [Note] IPv6 is available.
329balrogdb_1 | 2017-02-22T16:29:42.577992Z 0 [Note] - '::' resolves to '::';
330balrogdb_1 | 2017-02-22T16:29:42.578008Z 0 [Note] Server socket created on IP: '::'.
331balrogdb_1 | 2017-02-22T16:29:42.611145Z 0 [Warning] 'db' entry 'sys mysql.sys@localhost' ignored in --skip-name-resolve mode.
332balrogdb_1 | 2017-02-22T16:29:42.611167Z 0 [Warning] 'proxies_priv' entry '@ root@localhost' ignored in --skip-name-resolve mode.
333balrogdb_1 | 2017-02-22T16:29:42.613978Z 0 [Warning] 'tables_priv' entry 'sys_config mysql.sys@localhost' ignored in --skip-name-resolve mode.
334balrogdb_1 | 2017-02-22T16:29:42.618697Z 0 [Note] Event Scheduler: Loaded 0 events
335balrogdb_1 | 2017-02-22T16:29:42.618791Z 0 [Note] Executing 'SELECT * FROM INFORMATION_SCHEMA.TABLES;' to get a list of tables using the deprecated partition engine. You may use the startup option '--disable-partition-engine-check' to skip this check.
336balrogdb_1 | 2017-02-22T16:29:42.618800Z 0 [Note] Beginning of list of non-natively partitioned tables
337balrogdb_1 | 2017-02-22T16:29:42.627730Z 0 [Note] End of list of non-natively partitioned tables
338balrogdb_1 | 2017-02-22T16:29:42.627820Z 0 [Note] mysqld: ready for connections.
339balrogdb_1 | Version: '5.7.17' socket: '/var/run/mysqld/mysqld.sock' port: 3306 MySQL Community Server (GPL)
340balrogadmin_1 | + echo 'Initializing DB...'
341balrogadmin_1 | Initializing DB...
342balrogadmin_1 | + python scripts/manage-db.py -d mysql://balrogadmin:balrogadmin@balrogdb/balrog create
343balrogadmin_1 | /usr/local/lib/python2.7/site-packages/sqlalchemy/pool.py:638: SADeprecationWarning: The 'listeners' argument to Pool (and create_engine()) is deprecated. Use event.listen().
344balrogadmin_1 | Pool.__init__(self, creator, **kw)
345balrogadmin_1 | /usr/local/lib/python2.7/site-packages/sqlalchemy/pool.py:145: SADeprecationWarning: Pool.add_listener is deprecated. Use event.listen()
346balrogadmin_1 | self.add_listener(l)
347balrogagent_1 | 2017-02-22 16:30:06,160 - DEBUG - root.run_agent#22: Looking for active scheduled changes for endpoint rules...
348balrogagent_1 | 2017-02-22 16:30:06,160 - DEBUG - root.request#31: Sending GET request to http://balrogadmin:7070/api/scheduled_changes/rules
349balrogagent_1 | 2017-02-22 16:30:06,163 - ERROR - root.run_agent#56: Encountered exception:
350balrogagent_1 | Traceback (most recent call last):
351balrogagent_1 | File "/usr/local/lib/python3.5/site-packages/aiohttp/connector.py", line 607, in _create_connection
352balrogagent_1 | local_addr=self._local_addr)
353balrogagent_1 | File "/usr/local/lib/python3.5/asyncio/base_events.py", line 695, in create_connection
354balrogagent_1 | raise exceptions[0]
355balrogagent_1 | File "/usr/local/lib/python3.5/asyncio/base_events.py", line 682, in create_connection
356balrogagent_1 | yield from self.sock_connect(sock, address)
357balrogagent_1 | File "/usr/local/lib/python3.5/asyncio/futures.py", line 361, in __iter__
358balrogagent_1 | yield self # This tells Task to wait for completion.
359balrogagent_1 | File "/usr/local/lib/python3.5/asyncio/tasks.py", line 296, in _wakeup
360balrogagent_1 | future.result()
361balrogagent_1 | File "/usr/local/lib/python3.5/asyncio/futures.py", line 274, in result
362balrogagent_1 | raise self._exception
363balrogagent_1 | File "/usr/local/lib/python3.5/asyncio/selector_events.py", line 439, in _sock_connect_cb
364balrogagent_1 | raise OSError(err, 'Connect call failed %s' % (address,))
365balrogagent_1 | ConnectionRefusedError: [Errno 111] Connect call failed ('172.17.0.3', 7070)
366balrogagent_1 |
367balrogagent_1 | The above exception was the direct cause of the following exception:
368balrogagent_1 |
369balrogagent_1 | Traceback (most recent call last):
370balrogagent_1 | File "/usr/local/lib/python3.5/site-packages/aiohttp/connector.py", line 321, in connect
371balrogagent_1 | yield from self._create_connection(req)
372balrogagent_1 | File "/usr/local/lib/python3.5/site-packages/aiohttp/connector.py", line 630, in _create_connection
373balrogagent_1 | (req.host, req.port, exc.strerror)) from exc
374balrogagent_1 | aiohttp.errors.ClientOSError: [Errno 111] Can not connect to balrogadmin:7070 [Connect call failed ('172.17.0.3', 7070)]
375balrogagent_1 |
376balrogagent_1 | The above exception was the direct cause of the following exception:
377balrogagent_1 |
378balrogagent_1 | Traceback (most recent call last):
379balrogagent_1 | File "/app/balrogagent/cmd.py", line 25, in run_agent
380balrogagent_1 | auth=auth, loop=loop)
381balrogagent_1 | File "/app/balrogagent/client.py", line 32, in request
382balrogagent_1 | resp = await aiohttp.request(method, url, data=json.dumps(data), headers=headers, auth=auth, loop=loop)
383balrogagent_1 | File "/usr/local/lib/python3.5/site-packages/aiohttp/client.py", line 585, in __await__
384balrogagent_1 | return (yield from self._coro)
385balrogagent_1 | File "/usr/local/lib/python3.5/site-packages/aiohttp/client.py", line 186, in _request
386balrogagent_1 | conn = yield from self._connector.connect(req)
387balrogagent_1 | File "/usr/local/lib/python3.5/site-packages/aiohttp/connector.py", line 331, in connect
388balrogagent_1 | .format(key, exc.strerror)) from exc
389balrogagent_1 | aiohttp.errors.ClientOSError: [Errno 111] Cannot connect to host balrogadmin:7070 ssl:False [Can not connect to balrogadmin:7070 [Connect call failed ('172.17.0.3', 7070)]]
390abalrogdb_1 | 2017-02-22T16:30:31.414549Z 4 [Note] Aborted connection 4 to db: 'balrog' user: 'balrogadmin' host: '172.17.0.3' (Got an error reading communication packets)
391balrogdb_1 | 2017-02-22T16:30:31.414556Z 3 [Note] Aborted connection 3 to db: 'balrog' user: 'balrogadmin' host: '172.17.0.3' (Got an error reading communication packets)
392balrogadmin_1 | + python scripts/import-db.py
393balrogadmin_1 | 2017-02-22 16:30:31,460: Getting timestamp of database dump at 'https://balrog-public-dump-prod.s3.amazonaws.com/dump.sql.txt'
394balrogagent_1 | 2017-02-22 16:30:36,194 - DEBUG - root.run_agent#22: Looking for active scheduled changes for endpoint rules...
395balrogagent_1 | 2017-02-22 16:30:36,195 - DEBUG - root.request#31: Sending GET request to http://balrogadmin:7070/api/scheduled_changes/rules
396balrogagent_1 | 2017-02-22 16:30:36,197 - ERROR - root.run_agent#56: Encountered exception:
397balrogagent_1 | Traceback (most recent call last):
398balrogagent_1 | File "/usr/local/lib/python3.5/site-packages/aiohttp/connector.py", line 607, in _create_connection
399balrogagent_1 | local_addr=self._local_addr)
400balrogagent_1 | File "/usr/local/lib/python3.5/asyncio/base_events.py", line 695, in create_connection
401balrogagent_1 | raise exceptions[0]
402balrogagent_1 | File "/usr/local/lib/python3.5/asyncio/base_events.py", line 682, in create_connection
403balrogagent_1 | yield from self.sock_connect(sock, address)
404balrogagent_1 | File "/usr/local/lib/python3.5/asyncio/futures.py", line 361, in __iter__
405balrogagent_1 | yield self # This tells Task to wait for completion.
406balrogagent_1 | File "/usr/local/lib/python3.5/asyncio/tasks.py", line 296, in _wakeup
407balrogagent_1 | future.result()
408balrogagent_1 | File "/usr/local/lib/python3.5/asyncio/futures.py", line 274, in result
409balrogagent_1 | raise self._exception
410balrogagent_1 | File "/usr/local/lib/python3.5/asyncio/selector_events.py", line 439, in _sock_connect_cb
411balrogagent_1 | raise OSError(err, 'Connect call failed %s' % (address,))
412balrogagent_1 | ConnectionRefusedError: [Errno 111] Connect call failed ('172.17.0.3', 7070)
413balrogagent_1 |
414balrogagent_1 | The above exception was the direct cause of the following exception:
415balrogagent_1 |
416balrogagent_1 | Traceback (most recent call last):
417balrogagent_1 | File "/usr/local/lib/python3.5/site-packages/aiohttp/connector.py", line 321, in connect
418balrogagent_1 | yield from self._create_connection(req)
419balrogagent_1 | File "/usr/local/lib/python3.5/site-packages/aiohttp/connector.py", line 630, in _create_connection
420balrogagent_1 | (req.host, req.port, exc.strerror)) from exc
421balrogagent_1 | aiohttp.errors.ClientOSError: [Errno 111] Can not connect to balrogadmin:7070 [Connect call failed ('172.17.0.3', 7070)]
422balrogagent_1 |
423balrogagent_1 | The above exception was the direct cause of the following exception:
424balrogagent_1 |
425balrogagent_1 | Traceback (most recent call last):
426balrogagent_1 | File "/app/balrogagent/cmd.py", line 25, in run_agent
427balrogagent_1 | auth=auth, loop=loop)
428balrogagent_1 | File "/app/balrogagent/client.py", line 32, in request
429balrogagent_1 | resp = await aiohttp.request(method, url, data=json.dumps(data), headers=headers, auth=auth, loop=loop)
430balrogagent_1 | File "/usr/local/lib/python3.5/site-packages/aiohttp/client.py", line 585, in __await__
431balrogagent_1 | return (yield from self._coro)
432balrogagent_1 | File "/usr/local/lib/python3.5/site-packages/aiohttp/client.py", line 186, in _request
433balrogagent_1 | conn = yield from self._connector.connect(req)
434balrogagent_1 | File "/usr/local/lib/python3.5/site-packages/aiohttp/connector.py", line 331, in connect
435balrogagent_1 | .format(key, exc.strerror)) from exc
436balrogagent_1 | aiohttp.errors.ClientOSError: [Errno 111] Cannot connect to host balrogadmin:7070 ssl:False [Can not connect to balrogadmin:7070 [Connect call failed ('172.17.0.3', 7070)]]
437balrogagent_1 | 2017-02-22 16:31:06,218 - DEBUG - root.run_agent#22: Looking for active scheduled changes for endpoint rules...
438balrogagent_1 | 2017-02-22 16:31:06,218 - DEBUG - root.request#31: Sending GET request to http://balrogadmin:7070/api/scheduled_changes/rules
439balrogagent_1 | 2017-02-22 16:31:06,220 - ERROR - root.run_agent#56: Encountered exception:
440balrogagent_1 | Traceback (most recent call last):
441balrogagent_1 | File "/usr/local/lib/python3.5/site-packages/aiohttp/connector.py", line 607, in _create_connection
442balrogagent_1 | local_addr=self._local_addr)
443balrogagent_1 | File "/usr/local/lib/python3.5/asyncio/base_events.py", line 695, in create_connection
444balrogagent_1 | raise exceptions[0]
445balrogagent_1 | File "/usr/local/lib/python3.5/asyncio/base_events.py", line 682, in create_connection
446balrogagent_1 | yield from self.sock_connect(sock, address)
447balrogagent_1 | File "/usr/local/lib/python3.5/asyncio/futures.py", line 361, in __iter__
448balrogagent_1 | yield self # This tells Task to wait for completion.
449balrogagent_1 | File "/usr/local/lib/python3.5/asyncio/tasks.py", line 296, in _wakeup
450balrogagent_1 | future.result()
451balrogagent_1 | File "/usr/local/lib/python3.5/asyncio/futures.py", line 274, in result
452balrogagent_1 | raise self._exception
453balrogagent_1 | File "/usr/local/lib/python3.5/asyncio/selector_events.py", line 439, in _sock_connect_cb
454balrogagent_1 | raise OSError(err, 'Connect call failed %s' % (address,))
455balrogagent_1 | ConnectionRefusedError: [Errno 111] Connect call failed ('172.17.0.3', 7070)
456balrogagent_1 |
457balrogagent_1 | The above exception was the direct cause of the following exception:
458balrogagent_1 |
459balrogagent_1 | Traceback (most recent call last):
460balrogagent_1 | File "/usr/local/lib/python3.5/site-packages/aiohttp/connector.py", line 321, in connect
461balrogagent_1 | yield from self._create_connection(req)
462balrogagent_1 | File "/usr/local/lib/python3.5/site-packages/aiohttp/connector.py", line 630, in _create_connection
463balrogagent_1 | (req.host, req.port, exc.strerror)) from exc
464balrogagent_1 | aiohttp.errors.ClientOSError: [Errno 111] Can not connect to balrogadmin:7070 [Connect call failed ('172.17.0.3', 7070)]
465balrogagent_1 |
466balrogagent_1 | The above exception was the direct cause of the following exception:
467balrogagent_1 |
468balrogagent_1 | Traceback (most recent call last):
469balrogagent_1 | File "/app/balrogagent/cmd.py", line 25, in run_agent
470balrogagent_1 | auth=auth, loop=loop)
471balrogagent_1 | File "/app/balrogagent/client.py", line 32, in request
472balrogagent_1 | resp = await aiohttp.request(method, url, data=json.dumps(data), headers=headers, auth=auth, loop=loop)
473balrogagent_1 | File "/usr/local/lib/python3.5/site-packages/aiohttp/client.py", line 585, in __await__
474balrogagent_1 | return (yield from self._coro)
475balrogagent_1 | File "/usr/local/lib/python3.5/site-packages/aiohttp/client.py", line 186, in _request
476balrogagent_1 | conn = yield from self._connector.connect(req)
477balrogagent_1 | File "/usr/local/lib/python3.5/site-packages/aiohttp/connector.py", line 331, in connect
478balrogagent_1 | .format(key, exc.strerror)) from exc
479balrogagent_1 | aiohttp.errors.ClientOSError: [Errno 111] Cannot connect to host balrogadmin:7070 ssl:False [Can not connect to balrogadmin:7070 [Connect call failed ('172.17.0.3', 7070)]]
480balrogadmin_1 | 2017-02-22 16:31:11,531: Unable to get timestamp of remote database dump - Cannot connect to 'https://balrog-public-dump-prod.s3.amazonaws.com/dump.sql.txt', error: [Errno -2] Name or service not known
481balrogadmin_1 | + '[' -e /app/scripts/prod_db_dump.sql ']'
482balrogadmin_1 | + db_source='cat /app/scripts/prod_db_dump.sql'
483balrogadmin_1 | + eval 'cat /app/scripts/prod_db_dump.sql'
484balrogadmin_1 | ++ cat /app/scripts/prod_db_dump.sql
485balrogadmin_1 | + mysql -h balrogdb -u balrogadmin --password=balrogadmin balrog
486balrogadmin_1 | + mysql -h balrogdb -u balrogadmin --password=balrogadmin -e 'insert into permissions (username, permission, data_version) values ("balrogadmin", "admin", 1)' balrog
487balrogadmin_1 | + touch /app/.cache/mysql/db.done
488balrogadmin_1 | Done
489balrogadmin_1 | + echo Done
490balrogadmin_1 | + python scripts/manage-db.py -d mysql://balrogadmin:balrogadmin@balrogdb/balrog upgrade
491balrogadmin_1 | /usr/local/lib/python2.7/site-packages/sqlalchemy/pool.py:638: SADeprecationWarning: The 'listeners' argument to Pool (and create_engine()) is deprecated. Use event.listen().
492balrogadmin_1 | Pool.__init__(self, creator, **kw)
493balrogadmin_1 | /usr/local/lib/python2.7/site-packages/sqlalchemy/pool.py:145: SADeprecationWarning: Pool.add_listener is deprecated. Use event.listen()
494balrogadmin_1 | self.add_listener(l)
495balrogdb_1 | 2017-02-22T16:31:12.200216Z 7 [Note] Aborted connection 7 to db: 'balrog' user: 'balrogadmin' host: '172.17.0.3' (Got an error reading communication packets)
496balrogadmin_1 | + /app/scripts/run.sh admin-dev
497balrogadmin_1 | [uWSGI] getting INI configuration from /app/uwsgi/admin.dev.ini
498balrogadmin_1 | [uWSGI] getting INI configuration from /app/uwsgi/admin.ini
499balrogadmin_1 | [uwsgi-static] added check for /app/ui/dist
500balrogadmin_1 | *** Starting uWSGI 2.0.13.1 (64bit) on [Wed Feb 22 16:31:12 2017] ***
501balrogadmin_1 | compiled with version: 4.9.2 on 26 December 2016 23:38:03
502balrogadmin_1 | os: Linux-4.4.0-63-generic #84-Ubuntu SMP Wed Feb 1 17:20:32 UTC 2017
503balrogadmin_1 | nodename: 2b3f79490308
504balrogadmin_1 | machine: x86_64
505balrogadmin_1 | clock source: unix
506balrogadmin_1 | pcre jit disabled
507balrogadmin_1 | detected number of CPU cores: 4
508balrogadmin_1 | current working directory: /app
509balrogadmin_1 | detected binary path: /usr/local/bin/uwsgi
510balrogadmin_1 | *** dumping internal routing table ***
511balrogadmin_1 | [rule: 0] subject: path_info regexp: ^/.*$ action: addvar:REMOTE_USER=balrogadmin
512balrogadmin_1 | [rule: 1] subject: request_uri regexp: ^/api/(.*)$ action: rewrite:/$1
513balrogadmin_1 | [rule: 2] subject: request_uri regexp: ^/api/(.*)$ action: continue:
514balrogadmin_1 | [rule: 3] subject: ${REQUEST_URI};/__ func: startswith action: continue:
515balrogadmin_1 | [rule: 4] subject: /app/ui/dist${PATH_INFO} func: !exists action: static:/app/ui/dist/index.html
516balrogadmin_1 | *** end of the internal routing table ***
517balrogadmin_1 | uWSGI running as root, you can use --uid/--gid/--chroot options
518balrogadmin_1 | *** WARNING: you are running uWSGI as root !!! (use the --uid flag) ***
519balrogadmin_1 | your memory page size is 4096 bytes
520balrogadmin_1 | detected max file descriptor number: 1048576
521balrogadmin_1 | building mime-types dictionary from file /etc/mime.types...547 entry found
522balrogadmin_1 | lock engine: pthread robust mutexes
523balrogadmin_1 | thunder lock: disabled (you can enable it with --thunder-lock)
524balrogadmin_1 | uWSGI http bound on :7070 fd 4
525balrogadmin_1 | uwsgi socket 0 bound to TCP address 127.0.0.1:43072 (port auto-assigned) fd 3
526balrogadmin_1 | Python version: 2.7.13 (default, Dec 22 2016, 20:42:56) [GCC 4.9.2]
527balrogadmin_1 | Python main interpreter initialized at 0x1b25db0
528balrogadmin_1 | python threads support enabled
529balrogadmin_1 | your server socket listen backlog is limited to 100 connections
530balrogadmin_1 | your mercy for graceful operations on workers is 60 seconds
531balrogadmin_1 | mapped 145536 bytes (142 KB) for 1 cores
532balrogadmin_1 | *** Operational MODE: single process ***
533balrogadmin_1 | mounting /app/uwsgi/admin.wsgi on /
534balrogadmin_1 | 2017-02-22 16:31:12,741 - DEBUG - PID: 22 - Request: None - auslib.admin.views.forms.version_validator#108: starting in version_validator for version
535balrogadmin_1 | 2017-02-22 16:31:12,741 - DEBUG - PID: 22 - Request: None - auslib.admin.views.forms.operator_validator#91: starting in operator_validator for buildID
536balrogadmin_1 | 2017-02-22 16:31:12,742 - DEBUG - PID: 22 - Request: None - auslib.admin.views.forms.version_validator#108: starting in version_validator for version
537balrogadmin_1 | 2017-02-22 16:31:12,742 - DEBUG - PID: 22 - Request: None - auslib.admin.views.forms.operator_validator#91: starting in operator_validator for buildID
538balrogadmin_1 | WSGI app 0 (mountpoint='/') ready in 0 seconds on interpreter 0x1b25db0 pid: 22 (default app)
539balrogadmin_1 | *** uWSGI is running in multiple interpreter mode ***
540balrogadmin_1 | spawned uWSGI master process (pid: 22)
541balrogadmin_1 | spawned uWSGI worker 1 (pid: 24, cores: 1)
542balrogadmin_1 | spawned uWSGI http 1 (pid: 25)
543balrogadmin_1 | Python auto-reloader enabled
544abalrogagent_1 | 2017-02-22 16:31:36,249 - DEBUG - root.run_agent#22: Looking for active scheduled changes for endpoint rules...
545balrogagent_1 | 2017-02-22 16:31:36,249 - DEBUG - root.request#31: Sending GET request to http://balrogadmin:7070/api/scheduled_changes/rules
546balrogadmin_1 | 2017-02-22 16:31:36,308 - DEBUG - PID: 24 - Request: 139631525023184 - AUSTransaction.execute#198: Attempting to execute SELECT rules_scheduled_changes.sc_id, rules_scheduled_changes.scheduled_by, rules_scheduled_changes.complete, rules_scheduled_changes.change_type, rules_scheduled_changes.base_rule_id, rules_scheduled_changes.base_alias, rules_scheduled_changes.base_priority, rules_scheduled_changes.base_mapping, rules_scheduled_changes.`base_fallbackMapping`, rules_scheduled_changes.`base_backgroundRate`, rules_scheduled_changes.base_update_type, rules_scheduled_changes.base_product, rules_scheduled_changes.base_version, rules_scheduled_changes.base_channel, rules_scheduled_changes.`base_buildTarget`, rules_scheduled_changes.`base_buildID`, rules_scheduled_changes.base_locale, rules_scheduled_changes.`base_osVersion`, rules_scheduled_changes.`base_systemCapabilities`, rules_scheduled_changes.base_distribution, rules_scheduled_changes.`base_distVersion`, rules_scheduled_changes.`base_headerArchitecture`, rules_scheduled_changes.base_comment, rules_scheduled_changes.base_whitelist, rules_scheduled_changes.base_data_version, rules_scheduled_changes.data_version
547balrogadmin_1 | FROM rules_scheduled_changes
548balrogadmin_1 | WHERE rules_scheduled_changes.complete = %s
549balrogadmin_1 | [pid: 24|app: 0|req: 1/1] 172.17.0.4 () {42 vars in 608 bytes} [Wed Feb 22 16:31:36 2017] GET /api/scheduled_changes/rules => generated 44 bytes in 59 msecs (HTTP/1.1 200) 6 headers in 242 bytes (1 switches on core 0)
550balrogagent_1 | 2017-02-22 16:31:36,369 - DEBUG - root.run_agent#28: Found 0
551balrogagent_1 | 2017-02-22 16:31:36,369 - DEBUG - root.run_agent#22: Looking for active scheduled changes for endpoint releases...
552balrogagent_1 | 2017-02-22 16:31:36,370 - DEBUG - root.request#31: Sending GET request to http://balrogadmin:7070/api/scheduled_changes/releases
553balrogadmin_1 | 2017-02-22 16:31:36,373 - DEBUG - PID: 24 - Request: 139631525023120 - AUSTransaction.execute#198: Attempting to execute SELECT releases_scheduled_changes.sc_id, releases_scheduled_changes.scheduled_by, releases_scheduled_changes.complete, releases_scheduled_changes.change_type, releases_scheduled_changes.base_name, releases_scheduled_changes.base_product, releases_scheduled_changes.base_read_only, releases_scheduled_changes.base_data, releases_scheduled_changes.base_data_version, releases_scheduled_changes.data_version
554balrogadmin_1 | FROM releases_scheduled_changes
555balrogadmin_1 | WHERE releases_scheduled_changes.complete = %s
556balrogadmin_1 | [pid: 24|app: 0|req: 2/2] 172.17.0.4 () {42 vars in 617 bytes} [Wed Feb 22 16:31:36 2017] GET /api/scheduled_changes/releases => generated 44 bytes in 3 msecs (HTTP/1.1 200) 6 headers in 242 bytes (1 switches on core 0)
557balrogagent_1 | 2017-02-22 16:31:36,377 - DEBUG - root.run_agent#28: Found 0
558balrogagent_1 | 2017-02-22 16:31:36,377 - DEBUG - root.run_agent#22: Looking for active scheduled changes for endpoint permissions...
559balrogagent_1 | 2017-02-22 16:31:36,377 - DEBUG - root.request#31: Sending GET request to http://balrogadmin:7070/api/scheduled_changes/permissions
560balrogadmin_1 | 2017-02-22 16:31:36,380 - DEBUG - PID: 24 - Request: 139631525023376 - AUSTransaction.execute#198: Attempting to execute SELECT permissions_scheduled_changes.sc_id, permissions_scheduled_changes.scheduled_by, permissions_scheduled_changes.complete, permissions_scheduled_changes.change_type, permissions_scheduled_changes.base_permission, permissions_scheduled_changes.base_username, permissions_scheduled_changes.base_options, permissions_scheduled_changes.base_data_version, permissions_scheduled_changes.data_version
561balrogadmin_1 | FROM permissions_scheduled_changes
562balrogadmin_1 | WHERE permissions_scheduled_changes.complete = %s
563balrogadmin_1 | [pid: 24|app: 0|req: 3/3] 172.17.0.4 () {42 vars in 626 bytes} [Wed Feb 22 16:31:36 2017] GET /api/scheduled_changes/permissions => generated 44 bytes in 3 msecs (HTTP/1.1 200) 6 headers in 242 bytes (1 switches on core 0)
564balrogagent_1 | 2017-02-22 16:31:36,384 - DEBUG - root.run_agent#28: Found 0
565^[[1;2Bbalrogagent_1 | 2017-02-22 16:32:06,412 - DEBUG - root.run_agent#22: Looking for active scheduled changes for endpoint rules...
566balrogagent_1 | 2017-02-22 16:32:06,412 - DEBUG - root.request#31: Sending GET request to http://balrogadmin:7070/api/scheduled_changes/rules
567balrogadmin_1 | 2017-02-22 16:32:06,416 - DEBUG - PID: 24 - Request: 139631525023376 - AUSTransaction.execute#198: Attempting to execute SELECT rules_scheduled_changes.sc_id, rules_scheduled_changes.scheduled_by, rules_scheduled_changes.complete, rules_scheduled_changes.change_type, rules_scheduled_changes.base_rule_id, rules_scheduled_changes.base_alias, rules_scheduled_changes.base_priority, rules_scheduled_changes.base_mapping, rules_scheduled_changes.`base_fallbackMapping`, rules_scheduled_changes.`base_backgroundRate`, rules_scheduled_changes.base_update_type, rules_scheduled_changes.base_product, rules_scheduled_changes.base_version, rules_scheduled_changes.base_channel, rules_scheduled_changes.`base_buildTarget`, rules_scheduled_changes.`base_buildID`, rules_scheduled_changes.base_locale, rules_scheduled_changes.`base_osVersion`, rules_scheduled_changes.`base_systemCapabilities`, rules_scheduled_changes.base_distribution, rules_scheduled_changes.`base_distVersion`, rules_scheduled_changes.`base_headerArchitecture`, rules_scheduled_changes.base_comment, rules_scheduled_changes.base_whitelist, rules_scheduled_changes.base_data_version, rules_scheduled_changes.data_version
568balrogadmin_1 | FROM rules_scheduled_changes
569balrogadmin_1 | WHERE rules_scheduled_changes.complete = %s
570balrogadmin_1 | [pid: 24|app: 0|req: 4/4] 172.17.0.4 () {42 vars in 608 bytes} [Wed Feb 22 16:32:06 2017] GET /api/scheduled_changes/rules => generated 44 bytes in 2 msecs (HTTP/1.1 200) 6 headers in 242 bytes (1 switches on core 0)
571balrogagent_1 | 2017-02-22 16:32:06,418 - DEBUG - root.run_agent#28: Found 0
572balrogagent_1 | 2017-02-22 16:32:06,419 - DEBUG - root.run_agent#22: Looking for active scheduled changes for endpoint releases...
573balrogagent_1 | 2017-02-22 16:32:06,419 - DEBUG - root.request#31: Sending GET request to http://balrogadmin:7070/api/scheduled_changes/releases
574balrogadmin_1 | 2017-02-22 16:32:06,422 - DEBUG - PID: 24 - Request: 139631525021840 - AUSTransaction.execute#198: Attempting to execute SELECT releases_scheduled_changes.sc_id, releases_scheduled_changes.scheduled_by, releases_scheduled_changes.complete, releases_scheduled_changes.change_type, releases_scheduled_changes.base_name, releases_scheduled_changes.base_product, releases_scheduled_changes.base_read_only, releases_scheduled_changes.base_data, releases_scheduled_changes.base_data_version, releases_scheduled_changes.data_version
575balrogadmin_1 | FROM releases_scheduled_changes
576balrogadmin_1 | WHERE releases_scheduled_changes.complete = %s
577balrogadmin_1 | [pid: 24|app: 0|req: 5/5] 172.17.0.4 () {42 vars in 617 bytes} [Wed Feb 22 16:32:06 2017] GET /api/scheduled_changes/releases => generated 44 bytes in 2 msecs (HTTP/1.1 200) 6 headers in 242 bytes (1 switches on core 0)
578balrogagent_1 | 2017-02-22 16:32:06,424 - DEBUG - root.run_agent#28: Found 0
579balrogagent_1 | 2017-02-22 16:32:06,424 - DEBUG - root.run_agent#22: Looking for active scheduled changes for endpoint permissions...
580balrogagent_1 | 2017-02-22 16:32:06,424 - DEBUG - root.request#31: Sending GET request to http://balrogadmin:7070/api/scheduled_changes/permissions
581balrogadmin_1 | 2017-02-22 16:32:06,427 - DEBUG - PID: 24 - Request: 139631525023184 - AUSTransaction.execute#198: Attempting to execute SELECT permissions_scheduled_changes.sc_id, permissions_scheduled_changes.scheduled_by, permissions_scheduled_changes.complete, permissions_scheduled_changes.change_type, permissions_scheduled_changes.base_permission, permissions_scheduled_changes.base_username, permissions_scheduled_changes.base_options, permissions_scheduled_changes.base_data_version, permissions_scheduled_changes.data_version
582balrogadmin_1 | FROM permissions_scheduled_changes
583balrogadmin_1 | WHERE permissions_scheduled_changes.complete = %s
584balrogadmin_1 | [pid: 24|app: 0|req: 6/6] 172.17.0.4 () {42 vars in 626 bytes} [Wed Feb 22 16:32:06 2017] GET /api/scheduled_changes/permissions => generated 44 bytes in 2 msecs (HTTP/1.1 200) 6 headers in 242 bytes (1 switches on core 0)
585balrogagent_1 | 2017-02-22 16:32:06,429 - DEBUG - root.run_agent#28: Found 0
586balrogui_1 |
587balrogui_1 | PushState: '/rules' not found in /app/generated - Serving up 'generated/index.html'
588balrogadmin_1 | 2017-02-22 16:32:21,618 - DEBUG - PID: 24 - Request: 139631507582544 - AUSTransaction.execute#198: Attempting to execute SELECT rules.rule_id, rules.alias, rules.priority, rules.mapping, rules.`fallbackMapping`, rules.`backgroundRate`, rules.update_type, rules.product, rules.version, rules.channel, rules.`buildTarget`, rules.`buildID`, rules.locale, rules.`osVersion`, rules.`systemCapabilities`, rules.distribution, rules.`distVersion`, rules.`headerArchitecture`, rules.comment, rules.whitelist, rules.data_version
589balrogadmin_1 | FROM rules ORDER BY rules.priority, rules.version, rules.mapping
590balrogadmin_1 | [pid: 24|app: 0|req: 7/7] 172.17.0.6 () {50 vars in 976 bytes} [Wed Feb 22 16:32:21 2017] GET /api/rules => generated 32 bytes in 4 msecs (HTTP/1.1 200) 6 headers in 242 bytes (1 switches on core 0)
591balrogadmin_1 | 2017-02-22 16:32:21,714 - DEBUG - PID: 24 - Request: 139631507582352 - AUSTransaction.execute#198: Attempting to execute SELECT rules.rule_id, rules.alias, rules.priority, rules.mapping, rules.`fallbackMapping`, rules.`backgroundRate`, rules.update_type, rules.product, rules.version, rules.channel, rules.`buildTarget`, rules.`buildID`, rules.locale, rules.`osVersion`, rules.`systemCapabilities`, rules.distribution, rules.`distVersion`, rules.`headerArchitecture`, rules.comment, rules.whitelist, rules.data_version
592balrogadmin_1 | FROM rules ORDER BY rules.priority, rules.version, rules.mapping
593balrogadmin_1 | 2017-02-22 16:32:21,729 - ERROR - PID: 24 - Request: 139631507582352 - auslib.admin.base.log_exception#1423: Exception on /rules/columns/product [GET]
594balrogadmin_1 | Traceback (most recent call last):
595balrogadmin_1 | File "/usr/local/lib/python2.7/site-packages/flask/app.py", line 1817, in wsgi_app
596balrogadmin_1 | response = self.full_dispatch_request()
597balrogadmin_1 | File "/usr/local/lib/python2.7/site-packages/flask/app.py", line 1477, in full_dispatch_request
598balrogadmin_1 | rv = self.handle_user_exception(e)
599balrogadmin_1 | File "/usr/local/lib/python2.7/site-packages/flask/app.py", line 1381, in handle_user_exception
600balrogadmin_1 | reraise(exc_type, exc_value, tb)
601balrogadmin_1 | File "/usr/local/lib/python2.7/site-packages/flask/app.py", line 1475, in full_dispatch_request
602balrogadmin_1 | rv = self.dispatch_request()
603balrogadmin_1 | File "/usr/local/lib/python2.7/site-packages/flask/app.py", line 1461, in dispatch_request
604balrogadmin_1 | return self.view_functions[rule.endpoint](**req.view_args)
605balrogadmin_1 | File "/usr/local/lib/python2.7/site-packages/flask/views.py", line 84, in view
606balrogadmin_1 | return self.dispatch_request(*args, **kwargs)
607balrogadmin_1 | File "/usr/local/lib/python2.7/site-packages/flask/views.py", line 149, in dispatch_request
608balrogadmin_1 | return meth(*args, **kwargs)
609balrogadmin_1 | File "./auslib/admin/views/rules.py", line 266, in get
610balrogadmin_1 | if column not in rules[0].keys():
611balrogadmin_1 | IndexError: list index out of range
612balrogadmin_1 | 2017-02-22 16:32:21,743 - ERROR - PID: 24 - Request: 139631507582352 - auslib.admin.base.ise#68: Caught ISE 500 error.
613balrogadmin_1 | 2017-02-22 16:32:21,744 - DEBUG - PID: 24 - Request: 139631507582352 - auslib.admin.base.ise#69: Request path is: /rules/columns/product
614balrogadmin_1 | 2017-02-22 16:32:21,744 - DEBUG - PID: 24 - Request: 139631507582352 - auslib.admin.base.ise#70: Request environment is: {'wsgi.multiprocess': False, 'HTTP_REFERER': 'http://localhost:8080/rules', 'uwsgi.version': '2.0.13.1', 'SCRIPT_NAME': '', 'HTTP_X_FORWARDED_PORT': '56272', 'UWSGI_ROUTER': 'http', 'SERVER_PROTOCOL': 'HTTP/1.1', 'QUERY_STRING': '', 'HTTP_USER_AGENT': 'Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/56.0.2924.87 Safari/537.36', 'HTTP_CONNECTION': 'keep-alive', 'HTTP_COOKIE': 'phaseInterval=120000; previewCols=url%20status%20size%20timeline; stats=true; _ga=GA1.1.2117610979.1487281587; csrftoken=PbQBQeV7GKkZvdWL8kpIBUfSdq8PDA5j; sessionid=dbboakaqfjise7oi2r5udd5z16c13lb7; gsScrollPos=', 'SERVER_NAME': '2b3f79490308', 'REMOTE_ADDR': '172.17.0.6', 'wsgi.url_scheme': 'http', 'SERVER_PORT': '7070', 'uwsgi.node': '2b3f79490308', 'werkzeug.request': <Request 'http://balrogadmin:7070/rules/columns/product' [GET]>, 'HTTP_X_FORWARDED_PROTO': 'http', 'wsgi.input': <uwsgi._Input object at 0x7efe806e7510>, 'REMOTE_USER': 'balrogadmin', 'HTTP_HOST': 'balrogadmin:7070', 'wsgi.multithread': False, 'REQUEST_URI': '/api/rules/columns/product', 'HTTP_ACCEPT': 'application/json, text/plain, */*', 'wsgi.version': (1, 0), 'HTTP_X_FORWARDED_FOR': '172.17.0.1', 'wsgi.errors': <open file 'wsgi_errors', mode 'w' at 0x7efe80605270>, 'REMOTE_PORT': '21677', 'HTTP_ACCEPT_LANGUAGE': 'en-US,en;q=0.8', 'wsgi.run_once': False, 'REQUEST_METHOD': 'GET', 'wsgi.file_wrapper': <built-in function uwsgi_sendfile>, 'HTTP_ACCEPT_ENCODING': 'gzip, deflate, sdch, br', 'PATH_INFO': '/rules/columns/product'}
615balrogadmin_1 | 2017-02-22 16:32:21,785 - DEBUG - PID: 24 - Request: 139631507582352 - auslib.admin.base.ise#71: Request headers are: Referer: http://localhost:8080/rules
616balrogadmin_1 | X-Forwarded-Port: 56272
617balrogadmin_1 | User-Agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/56.0.2924.87 Safari/537.36
618balrogadmin_1 | Connection: keep-alive
619balrogadmin_1 | Cookie: phaseInterval=120000; previewCols=url%20status%20size%20timeline; stats=true; _ga=GA1.1.2117610979.1487281587; csrftoken=PbQBQeV7GKkZvdWL8kpIBUfSdq8PDA5j; sessionid=dbboakaqfjise7oi2r5udd5z16c13lb7; gsScrollPos=
620balrogadmin_1 | X-Forwarded-Proto: http
621balrogadmin_1 | Host: balrogadmin:7070
622balrogadmin_1 | Accept: application/json, text/plain, */*
623balrogadmin_1 | X-Forwarded-For: 172.17.0.1
624balrogadmin_1 | Accept-Language: en-US,en;q=0.8
625balrogadmin_1 | Accept-Encoding: gzip, deflate, sdch, br
626balrogadmin_1 |
627balrogadmin_1 |
628balrogadmin_1 | Traceback (most recent call last):
629balrogadmin_1 | File "/usr/local/lib/python2.7/site-packages/flask/app.py", line 1836, in __call__
630balrogadmin_1 | return self.wsgi_app(environ, start_response)
631balrogadmin_1 | File "./auslib/admin/base.py", line 60, in __call__
632balrogadmin_1 | return self.app(environ, start_response)
633balrogadmin_1 | File "/usr/local/lib/python2.7/site-packages/flask/app.py", line 1820, in wsgi_app
634balrogadmin_1 | response = self.make_response(self.handle_exception(e))
635balrogadmin_1 | File "/usr/local/lib/python2.7/site-packages/flask/app.py", line 1577, in make_response
636balrogadmin_1 | rv = self.response_class.force_type(rv, request.environ)
637balrogadmin_1 | File "/usr/local/lib/python2.7/site-packages/werkzeug/wrappers.py", line 847, in force_type
638balrogadmin_1 | response = BaseResponse(*_run_wsgi_app(response, environ))
639balrogadmin_1 | File "/usr/local/lib/python2.7/site-packages/werkzeug/wrappers.py", line 57, in _run_wsgi_app
640balrogadmin_1 | return _run_wsgi_app(*args)
641balrogadmin_1 | File "/usr/local/lib/python2.7/site-packages/werkzeug/test.py", line 871, in run_wsgi_app
642balrogadmin_1 | app_rv = app(environ, start_response)
643balrogadmin_1 | TypeError: 'exceptions.IndexError' object is not callable
644balrogadmin_1 | [pid: 24|app: 0|req: 8/8] 172.17.0.6 () {50 vars in 1024 bytes} [Wed Feb 22 16:32:21 2017] GET /api/rules/columns/product => generated 0 bytes in 139 msecs (HTTP/1.1 500) 0 headers in 0 bytes (0 switches on core 0)
645balrogagent_1 | 2017-02-22 16:32:36,460 - DEBUG - root.run_agent#22: Looking for active scheduled changes for endpoint rules...
646balrogagent_1 | 2017-02-22 16:32:36,460 - DEBUG - root.request#31: Sending GET request to http://balrogadmin:7070/api/scheduled_changes/rules
647balrogadmin_1 | 2017-02-22 16:32:36,487 - DEBUG - PID: 24 - Request: 139631525021840 - AUSTransaction.execute#198: Attempting to execute SELECT rules_scheduled_changes.sc_id, rules_scheduled_changes.scheduled_by, rules_scheduled_changes.complete, rules_scheduled_changes.change_type, rules_scheduled_changes.base_rule_id, rules_scheduled_changes.base_alias, rules_scheduled_changes.base_priority, rules_scheduled_changes.base_mapping, rules_scheduled_changes.`base_fallbackMapping`, rules_scheduled_changes.`base_backgroundRate`, rules_scheduled_changes.base_update_type, rules_scheduled_changes.base_product, rules_scheduled_changes.base_version, rules_scheduled_changes.base_channel, rules_scheduled_changes.`base_buildTarget`, rules_scheduled_changes.`base_buildID`, rules_scheduled_changes.base_locale, rules_scheduled_changes.`base_osVersion`, rules_scheduled_changes.`base_systemCapabilities`, rules_scheduled_changes.base_distribution, rules_scheduled_changes.`base_distVersion`, rules_scheduled_changes.`base_headerArchitecture`, rules_scheduled_changes.base_comment, rules_scheduled_changes.base_whitelist, rules_scheduled_changes.base_data_version, rules_scheduled_changes.data_version
648balrogadmin_1 | FROM rules_scheduled_changes
649balrogadmin_1 | WHERE rules_scheduled_changes.complete = %s
650balrogadmin_1 | [pid: 24|app: 0|req: 9/9] 172.17.0.4 () {42 vars in 608 bytes} [Wed Feb 22 16:32:36 2017] GET /api/scheduled_changes/rules => generated 44 bytes in 17 msecs (HTTP/1.1 200) 6 headers in 242 bytes (1 switches on core 0)
651balrogagent_1 | 2017-02-22 16:32:36,497 - DEBUG - root.run_agent#28: Found 0
652balrogagent_1 | 2017-02-22 16:32:36,498 - DEBUG - root.run_agent#22: Looking for active scheduled changes for endpoint releases...
653balrogagent_1 | 2017-02-22 16:32:36,498 - DEBUG - root.request#31: Sending GET request to http://balrogadmin:7070/api/scheduled_changes/releases
654balrogadmin_1 | 2017-02-22 16:32:36,501 - DEBUG - PID: 24 - Request: 139631525021840 - AUSTransaction.execute#198: Attempting to execute SELECT releases_scheduled_changes.sc_id, releases_scheduled_changes.scheduled_by, releases_scheduled_changes.complete, releases_scheduled_changes.change_type, releases_scheduled_changes.base_name, releases_scheduled_changes.base_product, releases_scheduled_changes.base_read_only, releases_scheduled_changes.base_data, releases_scheduled_changes.base_data_version, releases_scheduled_changes.data_version
655balrogadmin_1 | FROM releases_scheduled_changes
656balrogadmin_1 | WHERE releases_scheduled_changes.complete = %s
657balrogadmin_1 | [pid: 24|app: 0|req: 10/10] 172.17.0.4 () {42 vars in 617 bytes} [Wed Feb 22 16:32:36 2017] GET /api/scheduled_changes/releases => generated 44 bytes in 2 msecs (HTTP/1.1 200) 6 headers in 242 bytes (1 switches on core 0)
658balrogagent_1 | 2017-02-22 16:32:36,503 - DEBUG - root.run_agent#28: Found 0
659balrogagent_1 | 2017-02-22 16:32:36,503 - DEBUG - root.run_agent#22: Looking for active scheduled changes for endpoint permissions...
660balrogagent_1 | 2017-02-22 16:32:36,504 - DEBUG - root.request#31: Sending GET request to http://balrogadmin:7070/api/scheduled_changes/permissions
661balrogadmin_1 | 2017-02-22 16:32:36,506 - DEBUG - PID: 24 - Request: 139631525023376 - AUSTransaction.execute#198: Attempting to execute SELECT permissions_scheduled_changes.sc_id, permissions_scheduled_changes.scheduled_by, permissions_scheduled_changes.complete, permissions_scheduled_changes.change_type, permissions_scheduled_changes.base_permission, permissions_scheduled_changes.base_username, permissions_scheduled_changes.base_options, permissions_scheduled_changes.base_data_version, permissions_scheduled_changes.data_version
662balrogadmin_1 | FROM permissions_scheduled_changes
663balrogadmin_1 | WHERE permissions_scheduled_changes.complete = %s
664balrogadmin_1 | [pid: 24|app: 0|req: 11/11] 172.17.0.4 () {42 vars in 626 bytes} [Wed Feb 22 16:32:36 2017] GET /api/scheduled_changes/permissions => generated 44 bytes in 2 msecs (HTTP/1.1 200) 6 headers in 242 bytes (1 switches on core 0)
665balrogagent_1 | 2017-02-22 16:32:36,508 - DEBUG - root.run_agent#28: Found 0