ysdUhs4U

1tarting balrog_balrogdb_1
2Starting balrog_balrogpub_1
3Starting balrog_balrogadmin_1
4Starting balrog_balrogagent_1
5Attaching to balrog_balrogdb_1, balrog_balrogadmin_1, balrog_balrogpub_1, balrog_balrogagent_1
6balrogdb_1     | 2016-09-29T18:16:09.404509Z 0 [Warning] TIMESTAMP with implicit DEFAULT value is deprecated. Please use --explicit_defaults_for_timestamp server option (see documentation for more details).
7balrogdb_1     | 2016-09-29T18:16:09.515552Z 0 [Note] mysqld (mysqld 5.7.15) starting as process 1 ...
8balrogdb_1     | 2016-09-29T18:16:09.537419Z 0 [Warning] Setting lower_case_table_names=2 because file system for /var/lib/mysql/ is case insensitive
9balrogdb_1     | 2016-09-29T18:16:09.541882Z 0 [Note] InnoDB: PUNCH HOLE support available
10balrogdb_1     | 2016-09-29T18:16:09.541965Z 0 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
11balrogdb_1     | 2016-09-29T18:16:09.541971Z 0 [Note] InnoDB: Uses event mutexes
12balrogdb_1     | 2016-09-29T18:16:09.542415Z 0 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier
13balrogdb_1     | 2016-09-29T18:16:09.542431Z 0 [Note] InnoDB: Compressed tables use zlib 1.2.3
14balrogdb_1     | 2016-09-29T18:16:09.542436Z 0 [Note] InnoDB: Using Linux native AIO
15balrogdb_1     | 2016-09-29T18:16:09.546602Z 0 [Note] InnoDB: Number of pools: 1
16balrogadmin_1  | + apt-get -q update
17balrogdb_1     | 2016-09-29T18:16:09.548453Z 0 [Note] InnoDB: Using CPU crc32 instructions
18balrogpub_1    | [uWSGI] getting INI configuration from /app/uwsgi/public.ini
19balrogdb_1     | 2016-09-29T18:16:09.694929Z 0 [Note] InnoDB: Initializing buffer pool, total size = 128M, instances = 1, chunk size = 128M
20balrogdb_1     | 2016-09-29T18:16:09.785525Z 0 [Note] InnoDB: Completed initialization of buffer pool
21balrogdb_1     | 2016-09-29T18:16:09.803501Z 0 [Note] InnoDB: If the mysqld execution user is authorized, page cleaner thread priority can be changed. See the man page of setpriority().
22balrogdb_1     | 2016-09-29T18:16:10.395790Z 0 [Note] InnoDB: Highest supported file format is Barracuda.
23balrogdb_1     | 2016-09-29T18:16:10.422321Z 0 [Note] InnoDB: Log scan progressed past the checkpoint lsn 117633738
24balrogdb_1     | 2016-09-29T18:16:10.422389Z 0 [Note] InnoDB: Doing recovery: scanned up to log sequence number 117633747
25balrogpub_1    | *** Starting uWSGI 2.0.13.1 (64bit) on [Thu Sep 29 18:16:08 2016] ***
26balrogdb_1     | 2016-09-29T18:16:10.423118Z 0 [Note] InnoDB: Doing recovery: scanned up to log sequence number 117633747
27balrogpub_1    | compiled with version: 4.9.2 on 15 September 2016 18:41:38
28balrogdb_1     | 2016-09-29T18:16:10.423166Z 0 [Note] InnoDB: Database was not shutdown normally!
29balrogpub_1    | os: Linux-4.4.20-moby #1 SMP Thu Sep 15 12:10:20 UTC 2016
30balrogdb_1     | 2016-09-29T18:16:10.423178Z 0 [Note] InnoDB: Starting crash recovery.
31balrogpub_1    | nodename: caeef8955785
32balrogpub_1    | machine: x86_64
33balrogpub_1    | clock source: unix
34balrogpub_1    | pcre jit disabled
35balrogpub_1    | detected number of CPU cores: 2
36balrogpub_1    | current working directory: /app
37balrogpub_1    | detected binary path: /usr/local/bin/uwsgi
38balrogpub_1    | uWSGI running as root, you can use --uid/--gid/--chroot options
39balrogpub_1    | *** WARNING: you are running uWSGI as root !!! (use the --uid flag) ***
40balrogpub_1    | your memory page size is 4096 bytes
41balrogpub_1    | detected max file descriptor number: 1048576
42balrogpub_1    | lock engine: pthread robust mutexes
43balrogpub_1    | thunder lock: disabled (you can enable it with --thunder-lock)
44balrogpub_1    | uWSGI http bound on :9090 fd 4
45balrogpub_1    | uwsgi socket 0 bound to TCP address 127.0.0.1:45620 (port auto-assigned) fd 3
46balrogpub_1    | Python version: 2.7.12 (default, Aug 26 2016, 20:43:47)  [GCC 4.9.2]
47balrogpub_1    | Python main interpreter initialized at 0x2199300
48balrogpub_1    | python threads support enabled
49balrogpub_1    | your server socket listen backlog is limited to 100 connections
50balrogpub_1    | your mercy for graceful operations on workers is 60 seconds
51balrogpub_1    | mapped 145536 bytes (142 KB) for 1 cores
52balrogpub_1    | *** Operational MODE: single process ***
53balrogdb_1     | 2016-09-29T18:16:13.462260Z 0 [Note] InnoDB: Removed temporary tablespace data file: "ibtmp1"
54balrogdb_1     | 2016-09-29T18:16:13.462343Z 0 [Note] InnoDB: Creating shared tablespace for temporary tables
55balrogdb_1     | 2016-09-29T18:16:13.463968Z 0 [Note] InnoDB: Setting file './ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
56balrogdb_1     | 2016-09-29T18:16:14.062737Z 0 [Note] InnoDB: File './ibtmp1' size is now 12 MB.
57balrogdb_1     | 2016-09-29T18:16:14.108159Z 0 [Note] InnoDB: 96 redo rollback segment(s) found. 96 redo rollback segment(s) are active.
58balrogdb_1     | 2016-09-29T18:16:14.108185Z 0 [Note] InnoDB: 32 non-redo rollback segment(s) are active.
59balrogdb_1     | 2016-09-29T18:16:14.108556Z 0 [Note] InnoDB: Waiting for purge to start
60balrogdb_1     | 2016-09-29T18:16:14.164272Z 0 [Note] InnoDB: 5.7.15 started; log sequence number 117633747
61balrogdb_1     | 2016-09-29T18:16:14.165290Z 0 [Note] Plugin 'FEDERATED' is disabled.
62balrogdb_1     | 2016-09-29T18:16:14.242583Z 0 [Note] InnoDB: page_cleaner: 1000ms intended loop took 4439ms. The settings might not be optimal. (flushed=0 and evicted=0, during the time.)
63balrogdb_1     | 2016-09-29T18:16:14.259495Z 0 [Note] InnoDB: Loading buffer pool(s) from /var/lib/mysql/ib_buffer_pool
64balrogdb_1     | 2016-09-29T18:16:14.601437Z 0 [Warning] Failed to set up SSL because of the following SSL library error: SSL context is not usable without certificate and private key
65balrogdb_1     | 2016-09-29T18:16:14.604666Z 0 [Note] Server hostname (bind-address): '*'; port: 3306
66balrogdb_1     | 2016-09-29T18:16:14.605834Z 0 [Note] IPv6 is available.
67balrogdb_1     | 2016-09-29T18:16:14.605970Z 0 [Note]   - '::' resolves to '::';
68balrogdb_1     | 2016-09-29T18:16:14.607233Z 0 [Note] Server socket created on IP: '::'.
69balrogdb_1     | 2016-09-29T18:16:15.019139Z 0 [Warning] 'db' entry 'sys mysql.sys@localhost' ignored in --skip-name-resolve mode.
70balrogdb_1     | 2016-09-29T18:16:15.023433Z 0 [Warning] 'proxies_priv' entry '@ root@localhost' ignored in --skip-name-resolve mode.
71balrogdb_1     | 2016-09-29T18:16:15.024532Z 0 [Note] InnoDB: Buffer pool(s) load completed at 160929 18:16:15
72balrogagent_1  | 2016-09-29 18:16:15,551 - DEBUG - asyncio.__init__#53: Using selector: EpollSelector
73balrogagent_1  | 2016-09-29 18:16:15,553 - DEBUG - root.run_agent#17: Looking for active scheduled changes...
74balrogagent_1  | 2016-09-29 18:16:15,554 - DEBUG - root.request#31: Sending GET request to http://balrogadmin:8080/api/scheduled_changes/rules
75balrogdb_1     | 2016-09-29T18:16:15.556995Z 0 [Warning] 'tables_priv' entry 'sys_config mysql.sys@localhost' ignored in --skip-name-resolve mode.
76balrogagent_1  | 2016-09-29 18:16:15,645 - ERROR - root.run_agent#49: Encountered exception:
77balrogagent_1  | Traceback (most recent call last):
78balrogagent_1  |   File "/usr/local/lib/python3.5/site-packages/aiohttp/connector.py", line 607, in _create_connection
79balrogagent_1  |     local_addr=self._local_addr)
80balrogagent_1  |   File "/usr/local/lib/python3.5/asyncio/base_events.py", line 695, in create_connection
81balrogagent_1  |     raise exceptions[0]
82balrogagent_1  |   File "/usr/local/lib/python3.5/asyncio/base_events.py", line 682, in create_connection
83balrogagent_1  |     yield from self.sock_connect(sock, address)
84balrogagent_1  |   File "/usr/local/lib/python3.5/asyncio/futures.py", line 361, in __iter__
85balrogagent_1  |     yield self  # This tells Task to wait for completion.
86balrogagent_1  |   File "/usr/local/lib/python3.5/asyncio/tasks.py", line 296, in _wakeup
87balrogagent_1  |     future.result()
88balrogagent_1  |   File "/usr/local/lib/python3.5/asyncio/futures.py", line 274, in result
89balrogagent_1  |     raise self._exception
90balrogagent_1  |   File "/usr/local/lib/python3.5/asyncio/selector_events.py", line 439, in _sock_connect_cb
91balrogagent_1  |     raise OSError(err, 'Connect call failed %s' % (address,))
92balrogagent_1  | ConnectionRefusedError: [Errno 111] Connect call failed ('172.17.0.3', 8080)
93balrogagent_1  |
94balrogagent_1  | The above exception was the direct cause of the following exception:
95balrogagent_1  |
96balrogagent_1  | Traceback (most recent call last):
97balrogagent_1  |   File "/usr/local/lib/python3.5/site-packages/aiohttp/connector.py", line 321, in connect
98balrogagent_1  |     yield from self._create_connection(req)
99balrogagent_1  |   File "/usr/local/lib/python3.5/site-packages/aiohttp/connector.py", line 630, in _create_connection
100balrogagent_1  |     (req.host, req.port, exc.strerror)) from exc
101balrogagent_1  | aiohttp.errors.ClientOSError: [Errno 111] Can not connect to balrogadmin:8080 [Connect call failed ('172.17.0.3', 8080)]
102balrogagent_1  |
103balrogagent_1  | The above exception was the direct cause of the following exception:
104balrogagent_1  |
105balrogagent_1  | Traceback (most recent call last):
106balrogagent_1  |   File "/app/balrogagent/cmd.py", line 18, in run_agent
107balrogagent_1  |     resp = await client.request(balrog_api_root, "/scheduled_changes/rules", auth=auth, loop=loop)
108balrogagent_1  |   File "/app/balrogagent/client.py", line 32, in request
109balrogagent_1  |     resp = await aiohttp.request(method, url, data=json.dumps(data), headers=headers, auth=auth, loop=loop)
110balrogagent_1  |   File "/usr/local/lib/python3.5/site-packages/aiohttp/client.py", line 585, in __await__
111balrogagent_1  |     return (yield from self._coro)
112balrogagent_1  |   File "/usr/local/lib/python3.5/site-packages/aiohttp/client.py", line 186, in _request
113balrogagent_1  |     conn = yield from self._connector.connect(req)
114balrogagent_1  |   File "/usr/local/lib/python3.5/site-packages/aiohttp/connector.py", line 331, in connect
115balrogagent_1  |     .format(key, exc.strerror)) from exc
116balrogagent_1  | aiohttp.errors.ClientOSError: [Errno 111] Cannot connect to host balrogadmin:8080 ssl:False [Can not connect to balrogadmin:8080 [Connect call failed ('172.17.0.3', 8080)]]
117balrogdb_1     | 2016-09-29T18:16:16.488816Z 0 [Note] Event Scheduler: Loaded 0 events
118balrogdb_1     | 2016-09-29T18:16:16.489664Z 0 [Note] mysqld: ready for connections.
119balrogdb_1     | Version: '5.7.15'  socket: '/var/run/mysqld/mysqld.sock'  port: 3306  MySQL Community Server (GPL)
120balrogpub_1    | WSGI app 0 (mountpoint='') ready in 9 seconds on interpreter 0x2199300 pid: 1 (default app)
121balrogpub_1    | *** uWSGI is running in multiple interpreter mode ***
122balrogpub_1    | spawned uWSGI master process (pid: 1)
123balrogpub_1    | spawned uWSGI worker 1 (pid: 8, cores: 1)
124balrogpub_1    | spawned uWSGI http 1 (pid: 10)
125balrogpub_1    | Python auto-reloader enabled
126balrogadmin_1  | Hit http://security.debian.org jessie/updates InRelease
127balrogadmin_1  | Get:1 http://security.debian.org jessie/updates/main amd64 Packages [390 kB]
128balrogadmin_1  | Hit http://httpredir.debian.org jessie-updates InRelease
129balrogadmin_1  | Ign http://httpredir.debian.org jessie InRelease
130balrogadmin_1  | Hit http://httpredir.debian.org jessie Release.gpg
131balrogadmin_1  | Ign http://httpredir.debian.org jessie Release
132balrogadmin_1  | Get:2 http://httpredir.debian.org jessie/main amd64 Packages [9064 kB]
133balrogadmin_1  | Get:3 http://httpredir.debian.org jessie-updates/main amd64 Packages [17.6 kB]
134balrogagent_1  | 2016-09-29 18:16:45,741 - DEBUG - root.run_agent#17: Looking for active scheduled changes...
135balrogagent_1  | 2016-09-29 18:16:45,742 - DEBUG - root.request#31: Sending GET request to http://balrogadmin:8080/api/scheduled_changes/rules
136balrogagent_1  | 2016-09-29 18:16:45,748 - ERROR - root.run_agent#49: Encountered exception:
137balrogagent_1  | Traceback (most recent call last):
138balrogagent_1  |   File "/usr/local/lib/python3.5/site-packages/aiohttp/connector.py", line 607, in _create_connection
139balrogagent_1  |     local_addr=self._local_addr)
140balrogagent_1  |   File "/usr/local/lib/python3.5/asyncio/base_events.py", line 695, in create_connection
141balrogagent_1  |     raise exceptions[0]
142balrogagent_1  |   File "/usr/local/lib/python3.5/asyncio/base_events.py", line 682, in create_connection
143balrogagent_1  |     yield from self.sock_connect(sock, address)
144balrogagent_1  |   File "/usr/local/lib/python3.5/asyncio/futures.py", line 361, in __iter__
145balrogagent_1  |     yield self  # This tells Task to wait for completion.
146balrogagent_1  |   File "/usr/local/lib/python3.5/asyncio/tasks.py", line 296, in _wakeup
147balrogagent_1  |     future.result()
148balrogagent_1  |   File "/usr/local/lib/python3.5/asyncio/futures.py", line 274, in result
149balrogagent_1  |     raise self._exception
150balrogagent_1  |   File "/usr/local/lib/python3.5/asyncio/selector_events.py", line 439, in _sock_connect_cb
151balrogagent_1  |     raise OSError(err, 'Connect call failed %s' % (address,))
152balrogagent_1  | ConnectionRefusedError: [Errno 111] Connect call failed ('172.17.0.3', 8080)
153balrogagent_1  |
154balrogagent_1  | The above exception was the direct cause of the following exception:
155balrogagent_1  |
156balrogagent_1  | Traceback (most recent call last):
157balrogagent_1  |   File "/usr/local/lib/python3.5/site-packages/aiohttp/connector.py", line 321, in connect
158balrogagent_1  |     yield from self._create_connection(req)
159balrogagent_1  |   File "/usr/local/lib/python3.5/site-packages/aiohttp/connector.py", line 630, in _create_connection
160balrogagent_1  |     (req.host, req.port, exc.strerror)) from exc
161balrogagent_1  | aiohttp.errors.ClientOSError: [Errno 111] Can not connect to balrogadmin:8080 [Connect call failed ('172.17.0.3', 8080)]
162balrogagent_1  |
163balrogagent_1  | The above exception was the direct cause of the following exception:
164balrogagent_1  |
165balrogagent_1  | Traceback (most recent call last):
166balrogagent_1  |   File "/app/balrogagent/cmd.py", line 18, in run_agent
167balrogagent_1  |     resp = await client.request(balrog_api_root, "/scheduled_changes/rules", auth=auth, loop=loop)
168balrogagent_1  |   File "/app/balrogagent/client.py", line 32, in request
169balrogagent_1  |     resp = await aiohttp.request(method, url, data=json.dumps(data), headers=headers, auth=auth, loop=loop)
170balrogagent_1  |   File "/usr/local/lib/python3.5/site-packages/aiohttp/client.py", line 585, in __await__
171balrogagent_1  |     return (yield from self._coro)
172balrogagent_1  |   File "/usr/local/lib/python3.5/site-packages/aiohttp/client.py", line 186, in _request
173balrogagent_1  |     conn = yield from self._connector.connect(req)
174balrogagent_1  |   File "/usr/local/lib/python3.5/site-packages/aiohttp/connector.py", line 331, in connect
175balrogagent_1  |     .format(key, exc.strerror)) from exc
176balrogagent_1  | aiohttp.errors.ClientOSError: [Errno 111] Cannot connect to host balrogadmin:8080 ssl:False [Can not connect to balrogadmin:8080 [Connect call failed ('172.17.0.3', 8080)]]
177balrogadmin_1  | Fetched 9472 kB in 24s (386 kB/s)
178balrogadmin_1  | Reading package lists...
179balrogadmin_1  | + apt-get -q --yes install mysql-client
180balrogadmin_1  | Reading package lists...
181balrogadmin_1  | Building dependency tree...
182balrogadmin_1  | Reading state information...
183balrogadmin_1  | mysql-client is already the newest version.
184balrogadmin_1  | 0 upgraded, 0 newly installed, 0 to remove and 54 not upgraded.
185balrogadmin_1  | + apt-get clean
186balrogadmin_1  | + '[' '!' -e /app/.cache/mysql/db.done ']'
187balrogadmin_1  | + python scripts/manage-db.py -d mysql://balrogadmin:balrogadmin@balrogdb/balrog upgrade
188balrogadmin_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().
189balrogadmin_1  |   Pool.__init__(self, creator, **kw)
190balrogadmin_1  | /usr/local/lib/python2.7/site-packages/sqlalchemy/pool.py:145: SADeprecationWarning: Pool.add_listener is deprecated.  Use event.listen()
191balrogadmin_1  |   self.add_listener(l)
192balrogdb_1     | 2016-09-29T18:16:54.146212Z 2 [Note] Aborted connection 2 to db: 'balrog' user: 'balrogadmin' host: '172.17.0.3' (Got an error reading communication packets)
193balrogadmin_1  | + /app/uwsgi/run.sh admin-dev
194balrogadmin_1  | npm WARN package.json backbone@1.0.0 No repository field.
195balrogadmin_1  | npm WARN package.json bytes@0.1.0 No repository field.
196balrogadmin_1  | npm WARN package.json consolidate@0.8.0 No repository field.
197balrogadmin_1  | npm WARN package.json cookie-signature@0.0.1 No repository field.
198balrogadmin_1  | npm WARN package.json did_it_work@0.0.6 No repository field.
199balrogadmin_1  | npm WARN package.json fresh@0.1.0 No repository field.
200balrogadmin_1  | npm WARN package.json formidable@1.0.11 No repository field.
201balrogadmin_1  | npm WARN package.json growl@1.7.0 No repository field.
202balrogadmin_1  | npm WARN package.json methods@0.0.1 No repository field.
203balrogadmin_1  | npm WARN package.json mustache@0.4.0 No repository field.
204balrogadmin_1  | npm WARN package.json pause@0.0.1 No repository field.
205balrogadmin_1  | npm WARN package.json policyfile@0.0.4 'repositories' (plural) Not supported.
206balrogadmin_1  | npm WARN package.json Please pick one as the 'repository' field
207balrogadmin_1  | npm WARN package.json range-parser@0.0.4 No repository field.
208balrogadmin_1  | npm WARN package.json send@0.1.0 No repository field.
209balrogadmin_1  | npm WARN package.json styled_string@0.0.1 No repository field.
210balrogadmin_1  |
211balrogadmin_1  | > ws@0.4.32 install /app/ui/node_modules/ws
212balrogadmin_1  | > (node-gyp rebuild 2> builderror.log) || (exit 0)
213balrogadmin_1  |
214balrogadmin_1  | make: Entering directory '/app/ui/node_modules/ws/build'
215balrogadmin_1  |   CXX(target) Release/obj.target/bufferutil/src/bufferutil.o
216balrogadmin_1  |   SOLINK_MODULE(target) Release/obj.target/bufferutil.node
217balrogadmin_1  |   SOLINK_MODULE(target) Release/obj.target/bufferutil.node: Finished
218balrogadmin_1  |   COPY Release/bufferutil.node
219balrogadmin_1  |   CXX(target) Release/obj.target/validation/src/validation.o
220balrogadmin_1  |   SOLINK_MODULE(target) Release/obj.target/validation.node
221balrogadmin_1  |   SOLINK_MODULE(target) Release/obj.target/validation.node: Finished
222balrogadmin_1  |   COPY Release/validation.node
223balrogadmin_1  | make: Leaving directory '/app/ui/node_modules/ws/build'
224balrogadmin_1  |
225balrogadmin_1  | > balrog-ui@0.0.1 build /app/ui
226balrogadmin_1  | > lineman build
227balrogadmin_1  |
228balrogadmin_1  | sh: 1: lineman: not found
229balrogadmin_1  |
230balrogadmin_1  | npm ERR! balrog-ui@0.0.1 build: `lineman build`
231balrogadmin_1  | npm ERR! Exit status 127
232balrogadmin_1  | npm ERR!
233balrogadmin_1  | npm ERR! Failed at the balrog-ui@0.0.1 build script.
234balrogadmin_1  | npm ERR! This is most likely a problem with the balrog-ui package,
235balrogadmin_1  | npm ERR! not with npm itself.
236balrogadmin_1  | npm ERR! Tell the author that this fails on your system:
237balrogadmin_1  | npm ERR!     lineman build
238balrogadmin_1  | npm ERR! You can get their info via:
239balrogadmin_1  | npm ERR!     npm owner ls balrog-ui
240balrogadmin_1  | npm ERR! There is likely additional logging output above.
241balrogadmin_1  | npm ERR! System Linux 4.4.20-moby
242balrogadmin_1  | npm ERR! command "/usr/bin/nodejs" "/usr/bin/npm" "run" "build"
243balrogadmin_1  | npm ERR! cwd /app/ui
244balrogadmin_1  | npm ERR! node -v v0.10.29
245balrogadmin_1  | npm ERR! npm -v 1.4.21
246balrogadmin_1  | npm ERR! code ELIFECYCLE
247balrogadmin_1  | npm WARN This failure might be due to the use of legacy binary "node"
248balrogadmin_1  | npm WARN For further explanations, please read
249balrogadmin_1  | /usr/share/doc/nodejs/README.Debian
250balrogadmin_1  |
251balrogadmin_1  | npm ERR!
252balrogadmin_1  | npm ERR! Additional logging details can be found in:
253balrogadmin_1  | npm ERR!     /app/ui/npm-debug.log
254balrogadmin_1  | npm ERR! not ok code 0
255balrogadmin_1  | /app
256balrogadmin_1  | [uWSGI] getting INI configuration from /app/uwsgi/admin.dev.ini
257balrogadmin_1  | [uWSGI] getting INI configuration from /app/uwsgi/admin.ini
258balrogadmin_1  | [uwsgi-static] added check for /app/ui/dist
259balrogadmin_1  | *** Starting uWSGI 2.0.13.1 (64bit) on [Thu Sep 29 18:17:12 2016] ***
260balrogadmin_1  | compiled with version: 4.9.2 on 15 September 2016 18:41:38
261balrogadmin_1  | os: Linux-4.4.20-moby #1 SMP Thu Sep 15 12:10:20 UTC 2016
262balrogadmin_1  | nodename: 3141ab60cddc
263balrogadmin_1  | machine: x86_64
264balrogadmin_1  | clock source: unix
265balrogadmin_1  | pcre jit disabled
266balrogadmin_1  | detected number of CPU cores: 2
267balrogadmin_1  | current working directory: /app
268balrogadmin_1  | detected binary path: /usr/local/bin/uwsgi
269balrogadmin_1  | *** dumping internal routing table ***
270balrogadmin_1  | [rule: 0] subject: path_info regexp: ^/.*$ action: addvar:REMOTE_USER=balrogadmin
271balrogadmin_1  | [rule: 1] subject: request_uri regexp: ^/api/(.*)$ action: rewrite:/$1
272balrogadmin_1  | [rule: 2] subject: request_uri regexp: ^/api/(.*)$ action: continue:
273balrogadmin_1  | [rule: 3] subject: ${REQUEST_URI};/__ func: startswith action: continue:
274balrogadmin_1  | [rule: 4] subject: /app/ui/dist${PATH_INFO} func: !exists action: static:/app/ui/dist/index.html
275balrogadmin_1  | *** end of the internal routing table ***
276balrogadmin_1  | uWSGI running as root, you can use --uid/--gid/--chroot options
277balrogadmin_1  | *** WARNING: you are running uWSGI as root !!! (use the --uid flag) ***
278balrogadmin_1  | your memory page size is 4096 bytes
279balrogadmin_1  | detected max file descriptor number: 1048576
280balrogadmin_1  | building mime-types dictionary from file /etc/mime.types...547 entry found
281balrogadmin_1  | lock engine: pthread robust mutexes
282balrogadmin_1  | thunder lock: disabled (you can enable it with --thunder-lock)
283balrogadmin_1  | uWSGI http bound on :8080 fd 4
284balrogadmin_1  | uwsgi socket 0 bound to TCP address 127.0.0.1:36334 (port auto-assigned) fd 3
285balrogadmin_1  | Python version: 2.7.12 (default, Aug 26 2016, 20:43:47)  [GCC 4.9.2]
286balrogadmin_1  | Python main interpreter initialized at 0xe81db0
287balrogadmin_1  | python threads support enabled
288balrogadmin_1  | your server socket listen backlog is limited to 100 connections
289balrogadmin_1  | your mercy for graceful operations on workers is 60 seconds
290balrogadmin_1  | mapped 145536 bytes (142 KB) for 1 cores
291balrogadmin_1  | *** Operational MODE: single process ***
292balrogadmin_1  | mounting /app/uwsgi/admin.wsgi on /
293balrogadmin_1  | ./auslib/admin/views/forms.py:139: DeprecationWarning: Required is going away in WTForms 3.0, use DataRequired
294balrogadmin_1  |   data_version = IntegerField('data_version', validators=[Required()], widget=HiddenInput())
295balrogadmin_1  | ./auslib/admin/views/forms.py:162: DeprecationWarning: Required is going away in WTForms 3.0, use DataRequired
296balrogadmin_1  |   product = StringField('Product', validators=[Required()])
297balrogadmin_1  | ./auslib/admin/views/forms.py:164: DeprecationWarning: Required is going away in WTForms 3.0, use DataRequired
298balrogadmin_1  |   data = JSONStringField({}, 'Data', validators=[Required()])
299balrogadmin_1  | ./auslib/admin/views/forms.py:171: DeprecationWarning: Required is going away in WTForms 3.0, use DataRequired
300balrogadmin_1  |   backgroundRate = IntegerField('Background Rate', validators=[Required(), NumberRange(0, 100)])
301balrogadmin_1  | ./auslib/admin/views/forms.py:172: DeprecationWarning: Required is going away in WTForms 3.0, use DataRequired
302balrogadmin_1  |   priority = IntegerField('Priority', validators=[Required()])
303balrogadmin_1  | 2016-09-29 18:17:13,342 - DEBUG - PID: 35 - Request: None - auslib.admin.views.forms.version_validator#106: starting in version_validator for version
304balrogadmin_1  | 2016-09-29 18:17:13,343 - DEBUG - PID: 35 - Request: None - auslib.admin.views.forms.operator_validator#89: starting in operator_validator for buildID
305balrogadmin_1  | 2016-09-29 18:17:13,344 - DEBUG - PID: 35 - Request: None - auslib.admin.views.forms.version_validator#106: starting in version_validator for version
306balrogadmin_1  | 2016-09-29 18:17:13,344 - DEBUG - PID: 35 - Request: None - auslib.admin.views.forms.operator_validator#89: starting in operator_validator for buildID
307balrogadmin_1  | ./auslib/admin/views/forms.py:221: DeprecationWarning: Required is going away in WTForms 3.0, use DataRequired
308balrogadmin_1  |   rule_id = IntegerField('Rule ID', validators=[Required()])
309balrogadmin_1  | ./auslib/admin/views/forms.py:225: DeprecationWarning: Required is going away in WTForms 3.0, use DataRequired
310balrogadmin_1  |   sc_data_version = IntegerField('sc_data_version', validators=[Required()], widget=HiddenInput())
311balrogadmin_1  | ./auslib/admin/views/forms.py:232: DeprecationWarning: Required is going away in WTForms 3.0, use DataRequired
312balrogadmin_1  |   sc_data_version = IntegerField('sc_data_version', validators=[Required()], widget=HiddenInput())
313balrogadmin_1  | ./auslib/admin/views/forms.py:236: DeprecationWarning: Required is going away in WTForms 3.0, use DataRequired
314balrogadmin_1  |   name = StringField('Name', validators=[Required()])
315balrogadmin_1  | ./auslib/admin/views/forms.py:237: DeprecationWarning: Required is going away in WTForms 3.0, use DataRequired
316balrogadmin_1  |   product = StringField('Product', validators=[Required()])
317balrogadmin_1  | ./auslib/admin/views/forms.py:238: DeprecationWarning: Required is going away in WTForms 3.0, use DataRequired
318balrogadmin_1  |   blob = JSONStringField({}, 'Data', validators=[Required()], widget=FileInput())
319balrogadmin_1  | ./auslib/admin/views/forms.py:243: DeprecationWarning: Required is going away in WTForms 3.0, use DataRequired
320balrogadmin_1  |   name = StringField('Name', validators=[Required()])
321balrogadmin_1  | ./auslib/admin/views/forms.py:244: DeprecationWarning: Required is going away in WTForms 3.0, use DataRequired
322balrogadmin_1  |   product = StringField('Product', validators=[Required()])
323balrogadmin_1  | WSGI app 0 (mountpoint='/') ready in 1 seconds on interpreter 0xe81db0 pid: 35 (default app)
324balrogadmin_1  | *** uWSGI is running in multiple interpreter mode ***
325balrogadmin_1  | spawned uWSGI master process (pid: 35)
326balrogadmin_1  | spawned uWSGI worker 1 (pid: 136, cores: 1)
327balrogadmin_1  | Python auto-reloader enabled
328balrogadmin_1  | spawned uWSGI http 1 (pid: 138)
329balrogagent_1  | 2016-09-29 18:17:15,791 - DEBUG - root.run_agent#17: Looking for active scheduled changes...
330balrogagent_1  | 2016-09-29 18:17:15,791 - DEBUG - root.request#31: Sending GET request to http://balrogadmin:8080/api/scheduled_changes/rules
331balrogadmin_1  | [pid: 136|app: 0|req: 1/1] 172.17.0.5 () {42 vars in 608 bytes} [Thu Sep 29 18:17:15 2016] GET /api/scheduled_changes/rules => generated 44 bytes in 46 msecs (HTTP/1.1 200) 4 headers in 127 bytes (1 switches on core 0)
332balrogagent_1  | 2016-09-29 18:17:15,853 - DEBUG - root.run_agent#21: Found 0
333balrogagent_1  | 2016-09-29 18:17:45,889 - DEBUG - root.run_agent#17: Looking for active scheduled changes...
334balrogagent_1  | 2016-09-29 18:17:45,890 - DEBUG - root.request#31: Sending GET request to http://balrogadmin:8080/api/scheduled_changes/rules
335balrogadmin_1  | [pid: 136|app: 0|req: 2/2] 172.17.0.5 () {42 vars in 608 bytes} [Thu Sep 29 18:17:45 2016] GET /api/scheduled_changes/rules => generated 44 bytes in 4 msecs (HTTP/1.1 200) 4 headers in 127 bytes (1 switches on core 0)
336balrogagent_1  | 2016-09-29 18:17:45,902 - DEBUG - root.run_agent#21: Found 0
337balrogadmin_1  | [pid: 136|app: -1|req: -1/3] 172.17.0.1 () {38 vars in 611 bytes} [Thu Sep 29 18:17:47 2016] GET / => generated 0 bytes in 3 msecs (HTTP/1.1 500) 0 headers in 0 bytes (0 switches on core 0)
338balrogadmin_1  | [pid: 136|app: -1|req: -1/4] 172.17.0.1 () {38 vars in 611 bytes} [Thu Sep 29 18:17:47 2016] GET / => generated 0 bytes in 3 msecs (HTTP/1.1 500) 0 headers in 0 bytes (0 switches on core 0)
339balrogadmin_1  | [pid: 136|app: -1|req: -1/5] 172.17.0.1 () {38 vars in 611 bytes} [Thu Sep 29 18:17:47 2016] GET / => generated 0 bytes in 3 msecs (HTTP/1.1 500) 0 headers in 0 bytes (0 switches on core 0)
340balrogadmin_1  | [pid: 136|app: -1|req: -1/6] 172.17.0.1 () {38 vars in 611 bytes} [Thu Sep 29 18:17:47 2016] GET / => generated 0 bytes in 3 msecs (HTTP/1.1 500) 0 headers in 0 bytes (0 switches on core 0)
341balrogadmin_1  | [pid: 136|app: -1|req: -1/7] 172.17.0.1 () {38 vars in 611 bytes} [Thu Sep 29 18:17:47 2016] GET / => generated 0 bytes in 3 msecs (HTTP/1.1 500) 0 headers in 0 bytes (0 switches on core 0)
342balrogadmin_1  | [pid: 136|app: -1|req: -1/8] 172.17.0.1 () {38 vars in 611 bytes} [Thu Sep 29 18:17:47 2016] GET / => generated 0 bytes in 3 msecs (HTTP/1.1 500) 0 headers in 0 bytes (0 switches on core 0)
343balrogadmin_1  | [pid: 136|app: -1|req: -1/9] 172.17.0.1 () {38 vars in 611 bytes} [Thu Sep 29 18:17:47 2016] GET / => generated 0 bytes in 3 msecs (HTTP/1.1 500) 0 headers in 0 bytes (0 switches on core 0)
344balrogadmin_1  | [pid: 136|app: -1|req: -1/10] 172.17.0.1 () {38 vars in 611 bytes} [Thu Sep 29 18:17:47 2016] GET / => generated 0 bytes in 5 msecs (HTTP/1.1 500) 0 headers in 0 bytes (0 switches on core 0)
345balrogadmin_1  | [pid: 136|app: -1|req: -1/11] 172.17.0.1 () {38 vars in 611 bytes} [Thu Sep 29 18:17:47 2016] GET / => generated 0 bytes in 4 msecs (HTTP/1.1 500) 0 headers in 0 bytes (0 switches on core 0)
346balrogadmin_1  | [pid: 136|app: -1|req: -1/12] 172.17.0.1 () {38 vars in 612 bytes} [Thu Sep 29 18:17:47 2016] GET / => generated 0 bytes in 6 msecs (HTTP/1.1 500) 0 headers in 0 bytes (0 switches on core 0)
347balrogadmin_1  | [pid: 136|app: -1|req: -1/13] 172.17.0.1 () {38 vars in 612 bytes} [Thu Sep 29 18:17:48 2016] GET / => generated 0 bytes in 6 msecs (HTTP/1.1 500) 0 headers in 0 bytes (0 switches on core 0)
348balrogadmin_1  | [pid: 136|app: -1|req: -1/14] 172.17.0.1 () {38 vars in 612 bytes} [Thu Sep 29 18:17:48 2016] GET / => generated 0 bytes in 7 msecs (HTTP/1.1 500) 0 headers in 0 bytes (0 switches on core 0)
349balrogadmin_1  | [pid: 136|app: -1|req: -1/15] 172.17.0.1 () {38 vars in 612 bytes} [Thu Sep 29 18:17:48 2016] GET / => generated 0 bytes in 10 msecs (HTTP/1.1 500) 0 headers in 0 bytes (0 switches on core 0)
350balrogadmin_1  | [pid: 136|app: -1|req: -1/16] 172.17.0.1 () {38 vars in 612 bytes} [Thu Sep 29 18:17:48 2016] GET / => generated 0 bytes in 5 msecs (HTTP/1.1 500) 0 headers in 0 bytes (0 switches on core 0)
351balrogadmin_1  | [pid: 136|app: -1|req: -1/17] 172.17.0.1 () {38 vars in 612 bytes} [Thu Sep 29 18:17:48 2016] GET / => generated 0 bytes in 4 msecs (HTTP/1.1 500) 0 headers in 0 bytes (0 switches on core 0)
352balrogadmin_1  | [pid: 136|app: -1|req: -1/18] 172.17.0.1 () {38 vars in 612 bytes} [Thu Sep 29 18:17:48 2016] GET / => generated 0 bytes in 5 msecs (HTTP/1.1 500) 0 headers in 0 bytes (0 switches on core 0)
353balrogadmin_1  | [pid: 136|app: -1|req: -1/19] 172.17.0.1 () {38 vars in 612 bytes} [Thu Sep 29 18:17:48 2016] GET / => generated 0 bytes in 4 msecs (HTTP/1.1 500) 0 headers in 0 bytes (0 switches on core 0)
354balrogadmin_1  | [pid: 136|app: -1|req: -1/20] 172.17.0.1 () {38 vars in 612 bytes} [Thu Sep 29 18:17:48 2016] GET / => generated 0 bytes in 4 msecs (HTTP/1.1 500) 0 headers in 0 bytes (0 switches on core 0)
355balrogadmin_1  | [pid: 136|app: -1|req: -1/21] 172.17.0.1 () {38 vars in 612 bytes} [Thu Sep 29 18:17:48 2016] GET / => generated 0 bytes in 10 msecs (HTTP/1.1 500) 0 headers in 0 bytes (0 switches on core 0)
356balrogadmin_1  | [pid: 136|app: -1|req: -1/22] 172.17.0.1 () {38 vars in 612 bytes} [Thu Sep 29 18:17:48 2016] GET / => generated 0 bytes in 3 msecs (HTTP/1.1 500) 0 headers in 0 bytes (0 switches on core 0)
357balrogpub_1    | 2016-09-29 18:17:53,757 - DEBUG - PID: 8 - Request: 139656690523152 - ClientRequestView.get#54: Got query: {'product': u'Firefox', 'queryVersion': 3, 'buildID': u'20141202185629', 'locale': u'en-US', 'version': u'33.0', 'headerArchitecture': 'Intel', 'buildTarget': u'Darwin_x86_64-gcc3-u-i386-x86_64', 'force': True, 'distribution': u'default', 'osVersion': u'default', 'channel': u'release', 'distVersion': u'default'}
358balrogpub_1    | 2016-09-29 18:17:53,758 - DEBUG - PID: 8 - Request: 139656690523152 - AUS.evaluateRules#58: Looking for rules that apply to:
359balrogpub_1    | 2016-09-29 18:17:53,759 - DEBUG - PID: 8 - Request: 139656690523152 - AUS.evaluateRules#59: {'product': u'Firefox', 'queryVersion': 3, 'buildID': u'20141202185629', 'locale': u'en-US', 'version': u'33.0', 'headerArchitecture': 'Intel', 'buildTarget': u'Darwin_x86_64-gcc3-u-i386-x86_64', 'force': True, 'distribution': u'default', 'osVersion': u'default', 'channel': u'release', 'distVersion': u'default'}
360balrogpub_1    | 2016-09-29 18:17:53,764 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRawMatches#1143: where: [<sqlalchemy.sql.expression.BooleanClauseList object at 0x7f045ae7f490>, <sqlalchemy.sql.expression.BooleanClauseList object at 0x7f045ae7f850>]
361balrogpub_1    | 2016-09-29 18:17:53,869 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1156: Raw matches:
362balrogpub_1    | 2016-09-29 18:17:53,869 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'OS Blocking rule for de-supported OS on all nightly channels', u'product': None, u'systemCapabilities': None, u'data_version': 14L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'No-Update', u'priority': 100L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': 'Darwin 6,Darwin 7,Darwin 8,Darwin 9,Windows_95,Windows_98,Windows_NT 4,Windows_NT 5.0,Windows_NT 5.1.0,Windows_NT 5.1.1,GTK 2.0.,GTK 2.1.,GTK 2.2.,GTK 2.3.,GTK 2.4.,GTK 2.5.,GTK 2.6.,GTK 2.7.,GTK 2.8.,GTK 2.9.,GTK 2.10.,GTK 2.11.,GTK 2.12.,GTK 2.13.,GTK 2.14.,GTK 2.15.,GTK 2.16.,GTK 2.17.', u'rule_id': 1L, u'channel': 'nightly*', u'distVersion': None}
363balrogpub_1    | 2016-09-29 18:17:53,870 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: nightly* doesn't match release
364balrogpub_1    | 2016-09-29 18:17:53,871 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'OS Blocking rule for de-supported OS on Aurora.', u'product': None, u'systemCapabilities': None, u'data_version': 3L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'No-Update', u'priority': 100L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': 'Darwin 6,Darwin 7,Darwin 8,Darwin 9,Windows_95,Windows_98,Windows_NT 4,Windows_NT 5.0,Windows_NT 5.1.0,Windows_NT 5.1.1,GTK 2.0.,GTK 2.1.,GTK 2.2.,GTK 2.3.,GTK 2.4.,GTK 2.5.,GTK 2.6.,GTK 2.7.,GTK 2.8.,GTK 2.9.,GTK 2.10.,GTK 2.11.,GTK 2.12.,GTK 2.13.,GTK 2.14.,GTK 2.15.,GTK 2.16.,GTK 2.17.', u'rule_id': 2L, u'channel': 'aurora', u'distVersion': None}
365balrogpub_1    | 2016-09-29 18:17:53,872 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: aurora doesn't match release
366balrogpub_1    | 2016-09-29 18:17:53,873 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 24L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-mozilla-central-nightly-latest', u'priority': 90L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 3L, u'channel': 'nightly', u'distVersion': None}
367balrogpub_1    | 2016-09-29 18:17:53,874 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: nightly doesn't match release
368balrogpub_1    | 2016-09-29 18:17:53,874 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 8L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-oak-nightly-latest', u'priority': 90L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 5L, u'channel': 'nightly-oak', u'distVersion': None}
369balrogpub_1    | 2016-09-29 18:17:53,875 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: nightly-oak doesn't match release
370balrogpub_1    | 2016-09-29 18:17:53,876 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 5L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-ux-nightly-20140612040202', u'priority': 90L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 7L, u'channel': 'nightly-ux', u'distVersion': None}
371balrogpub_1    | 2016-09-29 18:17:53,876 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: nightly-ux doesn't match release
372balrogpub_1    | 2016-09-29 18:17:53,877 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 3L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-elm-nightly-20131221040201', u'priority': 90L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 9L, u'channel': 'nightly-elm', u'distVersion': None}
373balrogpub_1    | 2016-09-29 18:17:53,877 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: nightly-elm doesn't match release
374balrogpub_1    | 2016-09-29 18:17:53,878 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 69L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-mozilla-aurora-nightly-latest', u'priority': 90L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 10L, u'channel': 'aurora', u'distVersion': None}
375balrogpub_1    | 2016-09-29 18:17:53,878 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: aurora doesn't match release
376balrogpub_1    | 2016-09-29 18:17:53,878 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 7L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-mozilla-aurora-nightly-latest', u'priority': 90L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 16L, u'channel': 'auroratest', u'distVersion': None}
377balrogpub_1    | 2016-09-29 18:17:53,879 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: auroratest doesn't match release
378balrogpub_1    | 2016-09-29 18:17:53,879 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'Global shut off rule. Bump priority of this rule to 100 to shut off all updates', u'product': None, u'systemCapabilities': None, u'data_version': 9L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'No-Update', u'priority': 1L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 19L, u'channel': 'nightly', u'distVersion': None}
379balrogpub_1    | 2016-09-29 18:17:53,880 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: nightly doesn't match release
380balrogpub_1    | 2016-09-29 18:17:53,880 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 3L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-holly-nightly-20140321040202', u'priority': 90L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 20L, u'channel': 'nightly-holly', u'distVersion': None}
381balrogpub_1    | 2016-09-29 18:17:53,881 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: nightly-holly doesn't match release
382balrogpub_1    | 2016-09-29 18:17:53,881 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 19L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-mozilla-central-nightly-latest', u'priority': 90L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 22L, u'channel': 'nightlytest', u'distVersion': None}
383balrogpub_1    | 2016-09-29 18:17:53,881 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: nightlytest doesn't match release
384balrogpub_1    | 2016-09-29 18:17:53,882 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 171L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-44.0b2-build1', u'priority': 50L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 25L, u'channel': 'beta-localtest', u'distVersion': None}
385balrogpub_1    | 2016-09-29 18:17:53,882 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: beta-localtest doesn't match release
386balrogpub_1    | 2016-09-29 18:17:53,882 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'OS Blocking rule for de-supported OS on Beta channel', u'product': None, u'systemCapabilities': None, u'data_version': 3L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'No-Update', u'priority': 100L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': 'Darwin 6,Darwin 7,Darwin 8,Darwin 9,Windows_95,Windows_98,Windows_NT 4,Windows_NT 5.0,Windows_NT 5.1.0,Windows_NT 5.1.1,GTK 2.0.,GTK 2.1.,GTK 2.2.,GTK 2.3.,GTK 2.4.,GTK 2.5.,GTK 2.6.,GTK 2.7.,GTK 2.8.,GTK 2.9.,GTK 2.10.,GTK 2.11.,GTK 2.12.,GTK 2.13.,GTK 2.14.,GTK 2.15.,GTK 2.16.,GTK 2.17.', u'rule_id': 31L, u'channel': 'beta*', u'distVersion': None}
387balrogpub_1    | 2016-09-29 18:17:53,883 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: beta* doesn't match release
388balrogpub_1    | 2016-09-29 18:17:53,883 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 165L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-44.0b2-build1', u'priority': 90L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 32L, u'channel': 'beta', u'distVersion': None}
389balrogpub_1    | 2016-09-29 18:17:53,883 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: beta doesn't match release
390balrogpub_1    | 2016-09-29 18:17:53,883 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 135L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-44.0b2-build1', u'priority': 50L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 45L, u'channel': 'beta-cdntest', u'distVersion': None}
391balrogpub_1    | 2016-09-29 18:17:53,884 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: beta-cdntest doesn't match release
392balrogpub_1    | 2016-09-29 18:17:53,884 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'watershed', u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 5L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-11.0b5-build1', u'priority': 150L, u'alias': None, u'version': '<10.0', u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 51L, u'channel': 'beta*', u'distVersion': None}
393balrogpub_1    | 2016-09-29 18:17:53,884 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: beta* doesn't match release
394balrogpub_1    | 2016-09-29 18:17:53,884 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'watershed', u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 3L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-29.0b8-build1-schema2', u'priority': 140L, u'alias': None, u'version': '<29.0', u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 52L, u'channel': 'beta*', u'distVersion': None}
395balrogpub_1    | 2016-09-29 18:17:53,884 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: beta* doesn't match release
396balrogpub_1    | 2016-09-29 18:17:53,884 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 66L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-43.0.2-build1', u'priority': 50L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 56L, u'channel': 'release-localtest', u'distVersion': None}
397balrogpub_1    | 2016-09-29 18:17:53,885 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: release-localtest doesn't match release
398balrogpub_1    | 2016-09-29 18:17:53,885 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 71L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-43.0.2-build1', u'priority': 50L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 57L, u'channel': 'release-cdntest', u'distVersion': None}
399balrogpub_1    | 2016-09-29 18:17:53,885 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: release-cdntest doesn't match release
400balrogpub_1    | 2016-09-29 18:17:53,886 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 1L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-maple-nightly-latest', u'priority': 90L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 63L, u'channel': 'nightly-maple', u'distVersion': None}
401balrogpub_1    | 2016-09-29 18:17:53,886 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: nightly-maple doesn't match release
402balrogpub_1    | 2016-09-29 18:17:53,886 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'OS Blocking rule for de-supported OS on Release channel.', u'product': None, u'systemCapabilities': None, u'data_version': 3L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'No-Update', u'priority': 150L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': 'Darwin 6,Darwin 7,Darwin 8,Darwin 9,Windows_95,Windows_98,Windows_NT 4,Windows_NT 5.0,Windows_NT 5.1.0,Windows_NT 5.1.1,GTK 2.0.,GTK 2.1.,GTK 2.2.,GTK 2.3.,GTK 2.4.,GTK 2.5.,GTK 2.6.,GTK 2.7.,GTK 2.8.,GTK 2.9.,GTK 2.10.,GTK 2.11.,GTK 2.12.,GTK 2.13.,GTK 2.14.,GTK 2.15.,GTK 2.16.,GTK 2.17.', u'rule_id': 69L, u'channel': 'release*', u'distVersion': None}
403balrogpub_1    | 2016-09-29 18:17:53,887 - DEBUG - PID: 8 - Request: 139656690523152 - Rules._versionMatchesRule#1064: ruleVersion: None, queryVersion: 33.0
404balrogpub_1    | 2016-09-29 18:17:53,887 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1177: Darwin 6,Darwin 7,Darwin 8,Darwin 9,Windows_95,Windows_98,Windows_NT 4,Windows_NT 5.0,Windows_NT 5.1.0,Windows_NT 5.1.1,GTK 2.0.,GTK 2.1.,GTK 2.2.,GTK 2.3.,GTK 2.4.,GTK 2.5.,GTK 2.6.,GTK 2.7.,GTK 2.8.,GTK 2.9.,GTK 2.10.,GTK 2.11.,GTK 2.12.,GTK 2.13.,GTK 2.14.,GTK 2.15.,GTK 2.16.,GTK 2.17. doesn't match default
405balrogpub_1    | 2016-09-29 18:17:53,887 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'watershed', u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 2L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-12.0-build1', u'priority': 110L, u'alias': None, u'version': '<10.0', u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 73L, u'channel': 'release*', u'distVersion': None}
406balrogpub_1    | 2016-09-29 18:17:53,887 - DEBUG - PID: 8 - Request: 139656690523152 - Rules._versionMatchesRule#1064: ruleVersion: <10.0, queryVersion: 33.0
407balrogpub_1    | 2016-09-29 18:17:53,888 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1168: <10.0 doesn't match 33.0
408balrogpub_1    | 2016-09-29 18:17:53,888 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 3L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-gum-nightly-latest', u'priority': 90L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 79L, u'channel': 'nightly-gum', u'distVersion': None}
409balrogpub_1    | 2016-09-29 18:17:53,889 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: nightly-gum doesn't match release
410balrogpub_1    | 2016-09-29 18:17:53,889 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 1L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-ash-nightly-latest', u'priority': 90L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 80L, u'channel': 'nightly-ash', u'distVersion': None}
411balrogpub_1    | 2016-09-29 18:17:53,890 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: nightly-ash doesn't match release
412balrogpub_1    | 2016-09-29 18:17:53,890 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'OS Blocking rule for de-supported OS on ESR channel', u'product': None, u'systemCapabilities': None, u'data_version': 1L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'No-Update', u'priority': 100L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': 'Darwin 6,Darwin 7,Darwin 8,Darwin 9,Windows_95,Windows_98,Windows_NT 4,Windows_NT 5.0,Windows_NT 5.1.0,Windows_NT 5.1.1,GTK 2.0.,GTK 2.1.,GTK 2.2.,GTK 2.3.,GTK 2.4.,GTK 2.5.,GTK 2.6.,GTK 2.7.,GTK 2.8.,GTK 2.9.,GTK 2.10.,GTK 2.11.,GTK 2.12.,GTK 2.13.,GTK 2.14.,GTK 2.15.,GTK 2.16.,GTK 2.17.', u'rule_id': 99L, u'channel': 'esr*', u'distVersion': None}
413balrogpub_1    | 2016-09-29 18:17:53,890 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: esr* doesn't match release
414balrogpub_1    | 2016-09-29 18:17:53,890 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'block updates for 3.6 and earlier until we sort out bug 998721, and because they cant handle appVersion style xml', u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 6L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'No-Update', u'priority': 116L, u'alias': None, u'version': '<4.0', u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 103L, u'channel': 'release', u'distVersion': None}
415balrogpub_1    | 2016-09-29 18:17:53,891 - DEBUG - PID: 8 - Request: 139656690523152 - Rules._versionMatchesRule#1064: ruleVersion: <4.0, queryVersion: 33.0
416balrogpub_1    | 2016-09-29 18:17:53,891 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1168: <4.0 doesn't match 33.0
417balrogpub_1    | 2016-09-29 18:17:53,891 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 47L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 25L, u'mapping': 'Firefox-43.0.2-build1', u'priority': 90L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 145L, u'channel': 'release', u'distVersion': None}
418balrogpub_1    | 2016-09-29 18:17:53,892 - DEBUG - PID: 8 - Request: 139656690523152 - Rules._versionMatchesRule#1064: ruleVersion: None, queryVersion: 33.0
419balrogpub_1    | 2016-09-29 18:17:53,892 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 19L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-38.5.2esr-build1', u'priority': 40L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 166L, u'channel': 'esr-localtest', u'distVersion': None}
420balrogpub_1    | 2016-09-29 18:17:53,892 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: esr-localtest doesn't match release
421balrogpub_1    | 2016-09-29 18:17:53,893 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 18L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-38.5.2esr-build1', u'priority': 50L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 167L, u'channel': 'esr-cdntest', u'distVersion': None}
422balrogpub_1    | 2016-09-29 18:17:53,893 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: esr-cdntest doesn't match release
423balrogpub_1    | 2016-09-29 18:17:53,893 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 18L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-38.5.2esr-build1', u'priority': 91L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 168L, u'channel': 'esr', u'distVersion': None}
424balrogpub_1    | 2016-09-29 18:17:53,894 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: esr doesn't match release
425balrogpub_1    | 2016-09-29 18:17:53,894 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'block updates earlier than 2.0, bug 998721 doesnt ask for them, and they cant handle appVersion style xml', u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 3L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'No-Update', u'priority': 140L, u'alias': None, u'version': '<2.0', u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 195L, u'channel': 'release*', u'distVersion': None}
426balrogpub_1    | 2016-09-29 18:17:53,897 - DEBUG - PID: 8 - Request: 139656690523152 - Rules._versionMatchesRule#1064: ruleVersion: <2.0, queryVersion: 33.0
427balrogpub_1    | 2016-09-29 18:17:53,899 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1168: <2.0 doesn't match 33.0
428balrogpub_1    | 2016-09-29 18:17:53,899 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'bug 998721, old versions need a schema 1 blob', u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 3L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-12.0-build1-schema1', u'priority': 60L, u'alias': None, u'version': '<4.0', u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 196L, u'channel': 'release-cdntest', u'distVersion': None}
429balrogpub_1    | 2016-09-29 18:17:53,900 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: release-cdntest doesn't match release
430balrogpub_1    | 2016-09-29 18:17:53,900 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'Funsize dummy channel, Rail is testing it', u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 9L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-mozilla-aurora-nightly-latest', u'priority': 90L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 202L, u'channel': 'aurora-funsize', u'distVersion': None}
431balrogpub_1    | 2016-09-29 18:17:53,900 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: aurora-funsize doesn't match release
432balrogpub_1    | 2016-09-29 18:17:53,900 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'Funsize testing channel', u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 4L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-mozilla-central-nightly-latest', u'priority': 90L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 203L, u'channel': 'nightly-funsize', u'distVersion': None}
433balrogpub_1    | 2016-09-29 18:17:53,900 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: nightly-funsize doesn't match release
434balrogpub_1    | 2016-09-29 18:17:53,901 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 8L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-38.5.2esr-build1-whatsnew', u'priority': 51L, u'alias': None, u'version': '<38.0', u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 206L, u'channel': 'esr-cdntest', u'distVersion': None}
435balrogpub_1    | 2016-09-29 18:17:53,901 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: esr-cdntest doesn't match release
436balrogpub_1    | 2016-09-29 18:17:53,901 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 9L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-38.5.2esr-build1-whatsnew', u'priority': 92L, u'alias': None, u'version': '<38.0', u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 207L, u'channel': 'esr', u'distVersion': None}
437balrogpub_1    | 2016-09-29 18:17:53,901 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: esr doesn't match release
438balrogpub_1    | 2016-09-29 18:17:53,902 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 3L, u'buildID': '<20150901000000', u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-oak-nightly-20150911040219', u'priority': 92L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 212L, u'channel': 'nightly-oak-disabled', u'distVersion': None}
439balrogpub_1    | 2016-09-29 18:17:53,902 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: nightly-oak-disabled doesn't match release
440balrogpub_1    | 2016-09-29 18:17:53,902 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'bug 1079858 - Windows watershed to add both certificates to registry for SHA2 transition', u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 7L, u'buildID': '<20151209095500', u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-mozilla-central-nightly-20151209095500', u'priority': 92L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': 'Windows_NT', u'rule_id': 258L, u'channel': 'nightly*', u'distVersion': None}
441balrogpub_1    | 2016-09-29 18:17:53,902 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: nightly* doesn't match release
442balrogpub_1    | 2016-09-29 18:17:53,903 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'SHA1 watershed bug 1234277', u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 3L, u'buildID': '<20151217102820', u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-44.0b1-build2', u'priority': 130L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': 'Windows_NT', u'rule_id': 264L, u'channel': 'beta-localtest', u'distVersion': None}
443balrogpub_1    | 2016-09-29 18:17:53,903 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: beta-localtest doesn't match release
444balrogpub_1    | 2016-09-29 18:17:53,903 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'SHA1 watershed bug 1234277', u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 3L, u'buildID': '<20151217102820', u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-44.0b1-build2', u'priority': 130L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': 'Windows_NT', u'rule_id': 265L, u'channel': 'beta-cdntest', u'distVersion': None}
445balrogpub_1    | 2016-09-29 18:17:53,903 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: beta-cdntest doesn't match release
446balrogpub_1    | 2016-09-29 18:17:53,903 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'SHA1 watershed bug 1234277', u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 3L, u'buildID': '<20151217102820', u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-44.0b1-build2', u'priority': 130L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': 'Windows_NT', u'rule_id': 266L, u'channel': 'beta', u'distVersion': None}
447balrogpub_1    | 2016-09-29 18:17:53,903 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: beta doesn't match release
448balrogpub_1    | 2016-09-29 18:17:53,903 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'SHA1 watershed bug 1234277', u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 3L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-43.0.1-build1', u'priority': 55L, u'alias': None, u'version': '<43.0.1', u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': 'Windows_NT', u'rule_id': 267L, u'channel': 'release-localtest', u'distVersion': None}
449balrogpub_1    | 2016-09-29 18:17:53,904 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: release-localtest doesn't match release
450balrogpub_1    | 2016-09-29 18:17:53,907 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'SHA1 watershed bug 1234277', u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 2L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-43.0.1-build1', u'priority': 55L, u'alias': None, u'version': '<43.0.1', u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': 'Windows_NT', u'rule_id': 268L, u'channel': 'release-cdntest', u'distVersion': None}
451balrogpub_1    | 2016-09-29 18:17:53,908 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: release-cdntest doesn't match release
452balrogpub_1    | 2016-09-29 18:17:53,909 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'SHA1 watershed bug 1234277', u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 3L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-43.0.1-build1', u'priority': 100L, u'alias': None, u'version': '<43.0.1', u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': 'Windows_NT', u'rule_id': 269L, u'channel': 'release', u'distVersion': None}
453balrogpub_1    | 2016-09-29 18:17:53,909 - DEBUG - PID: 8 - Request: 139656690523152 - Rules._versionMatchesRule#1064: ruleVersion: <43.0.1, queryVersion: 33.0
454balrogpub_1    | 2016-09-29 18:17:53,909 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1177: Windows_NT doesn't match default
455balrogpub_1    | 2016-09-29 18:17:53,910 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'SHA1 watershed bug 1234277', u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 2L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-38.5.1esr-build1', u'priority': 50L, u'alias': None, u'version': '<38.5.1', u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': 'Windows_NT', u'rule_id': 270L, u'channel': 'esr-localtest', u'distVersion': None}
456balrogpub_1    | 2016-09-29 18:17:53,910 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: esr-localtest doesn't match release
457balrogpub_1    | 2016-09-29 18:17:53,910 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'SHA1 watershed bug 1234277', u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 2L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-38.5.1esr-build1', u'priority': 55L, u'alias': None, u'version': '<38.5.1', u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': 'Windows_NT', u'rule_id': 271L, u'channel': 'esr-cdntest', u'distVersion': None}
458balrogpub_1    | 2016-09-29 18:17:53,911 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: esr-cdntest doesn't match release
459balrogpub_1    | 2016-09-29 18:17:53,911 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'SHA1 watershed bug 1234277', u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 1L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-38.5.1esr-build1-whatsnew', u'priority': 60L, u'alias': None, u'version': '<38.0', u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': 'Windows_NT', u'rule_id': 272L, u'channel': 'esr-cdntest', u'distVersion': None}
460balrogpub_1    | 2016-09-29 18:17:53,911 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: esr-cdntest doesn't match release
461balrogpub_1    | 2016-09-29 18:17:53,912 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'SHA1 watershed bug 1234277', u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 3L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-38.5.1esr-build1-whatsnew', u'priority': 120L, u'alias': None, u'version': '<38.0', u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': 'Windows_NT', u'rule_id': 273L, u'channel': 'esr', u'distVersion': None}
462balrogpub_1    | 2016-09-29 18:17:53,912 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: esr doesn't match release
463balrogpub_1    | 2016-09-29 18:17:53,912 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'SHA1 watershed bug 1234277', u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 5L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-38.5.1esr-build1', u'priority': 110L, u'alias': None, u'version': '<38.5.1', u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': 'Windows_NT', u'rule_id': 274L, u'channel': 'esr', u'distVersion': None}
464balrogpub_1    | 2016-09-29 18:17:53,913 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: esr doesn't match release
465balrogpub_1    | 2016-09-29 18:17:53,913 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'Bug 1234277 - Create SHA1 watersheds', u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 1L, u'buildID': '<20151223004004', u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-mozilla-aurora-nightly-20151223004004', u'priority': 150L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': 'Windows_NT', u'rule_id': 277L, u'channel': 'aurora', u'distVersion': None}
466balrogpub_1    | 2016-09-29 18:17:53,913 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: aurora doesn't match release
467balrogpub_1    | 2016-09-29 18:17:53,914 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1203: Reduced matches:
468balrogpub_1    | 2016-09-29 18:17:53,915 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1206: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 47L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 25L, u'mapping': 'Firefox-43.0.2-build1', u'priority': 90L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 145L, u'channel': 'release', u'distVersion': None}
469balrogpub_1    | 2016-09-29 18:17:53,918 - DEBUG - PID: 8 - Request: 139656690523152 - AUS.evaluateRules#71: Matching rule: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 47L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 25L, u'mapping': 'Firefox-43.0.2-build1', u'priority': 90L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 145L, u'channel': 'release', u'distVersion': None}
470balrogpub_1    | 2016-09-29 18:17:53,919 - DEBUG - PID: 8 - Request: 139656690523152 - Releases.getReleases#1282: Looking for releases with:
471balrogpub_1    | 2016-09-29 18:17:53,920 - DEBUG - PID: 8 - Request: 139656690523152 - Releases.getReleases#1283: name: Firefox-43.0.2-build1
472balrogpub_1    | 2016-09-29 18:17:53,921 - DEBUG - PID: 8 - Request: 139656690523152 - Releases.getReleases#1284: product: None
473balrogpub_1    | 2016-09-29 18:17:54,161 - DEBUG - PID: 8 - Request: 139656690523152 - AUS.evaluateRules#98: Returning release Firefox-43.0.2-build1
474balrogpub_1    | 2016-09-29 18:17:54,199 - DEBUG - PID: 8 - Request: 139656690523152 - auslib.blobs.apprelease.ReleaseBlobV4.matchesUpdateQuery#14: Trying to match update query to Firefox-43.0.1-build1
475balrogpub_1    | 2016-09-29 18:17:54,200 - DEBUG - PID: 8 - Request: 139656690523152 - auslib.blobs.apprelease.ReleaseBlobV4.matchesUpdateQuery#25: releasePlat buildID is: 20151216175450
476balrogpub_1    | 2016-09-29 18:17:54,208 - DEBUG - PID: 8 - Request: 139656690523152 - ClientRequestView.get#114: Sending XML: <?xml version="1.0"?>
477balrogpub_1    | <updates>
478balrogpub_1    |     <update type="minor" displayVersion="43.0.2" appVersion="43.0.2" platformVersion="43.0.2" buildID="20151221130713" detailsURL="https://www.mozilla.org/en-US/firefox/43.0.2/releasenotes/">
479balrogpub_1    |         <patch type="complete" URL="http://download.mozilla.org/?product=firefox-43.0.2-complete&amp;os=osx&amp;lang=en-US&amp;force=1" hashFunction="sha512" hashValue="781478556846b719ebc906a8a9613a421e24449b4456c4ccee990e878b3be9fb0478a78821a499a4c1f1a76d75078acf3fdfa3d0be69d2f6c94e3b6340fc935b" size="80329415"/>
480balrogpub_1    |     </update>
481balrogpub_1    | </updates>
482balrogpub_1    | [pid: 8|app: 0|req: 1/1] 172.17.0.1 () {38 vars in 867 bytes} [Thu Sep 29 18:17:53 2016] GET /update/3/Firefox/33.0/20141202185629/Darwin_x86_64-gcc3-u-i386-x86_64/en-US/release/default/default/default/update.xml?force=1 => generated 565 bytes in 455 msecs (HTTP/1.1 200) 3 headers in 114 bytes (1 switches on core 0)
483balrogagent_1  | 2016-09-29 18:18:15,936 - DEBUG - root.run_agent#17: Looking for active scheduled changes...
484balrogagent_1  | 2016-09-29 18:18:15,936 - DEBUG - root.request#31: Sending GET request to http://balrogadmin:8080/api/scheduled_changes/rules
485balrogagent_1  | 2016-09-29 18:18:15,950 - DEBUG - root.run_agent#21: Found 0
486balrogadmin_1  | [pid: 136|app: 0|req: 3/23] 172.17.0.5 () {42 vars in 607 bytes} [Thu Sep 29 18:18:15 2016] GET /api/scheduled_changes/rules => generated 44 bytes in 9 msecs (HTTP/1.1 200) 4 headers in 127 bytes (1 switches on core 0)
487balrogagent_1  | 2016-09-29 18:18:45,986 - DEBUG - root.run_agent#17: Looking for active scheduled changes...
488balrogagent_1  | 2016-09-29 18:18:45,986 - DEBUG - root.request#31: Sending GET request to http://balrogadmin:8080/api/scheduled_changes/rules
489balrogadmin_1  | [pid: 136|app: 0|req: 4/24] 172.17.0.5 () {42 vars in 607 bytes} [Thu Sep 29 18:18:45 2016] GET /api/scheduled_changes/rules => generated 44 bytes in 3 msecs (HTTP/1.1 200) 4 headers in 127 bytes (1 switches on core 0)
490balrogagent_1  | 2016-09-29 18:18:45,995 - DEBUG - root.run_agent#21: Found 0
491balrogagent_1  | 2016-09-29 18:19:16,006 - DEBUG - root.run_agent#17: Looking for active scheduled changes...
492balrogagent_1  | 2016-09-29 18:19:16,006 - DEBUG - root.request#31: Sending GET request to http://balrogadmin:8080/api/scheduled_changes/rules
493balrogagent_1  | 2016-09-29 18:19:16,078 - DEBUG - root.run_agent#21: Found 0
494balrogadmin_1  | [pid: 136|app: 0|req: 5/25] 172.17.0.5 () {42 vars in 607 bytes} [Thu Sep 29 18:19:16 2016] GET /api/scheduled_changes/rules => generated 44 bytes in 62 msecs (HTTP/1.1 200) 4 headers in 127 bytes (1 switches on core 0)
495balrogagent_1  | 2016-09-29 18:19:46,113 - DEBUG - root.run_agent#17: Looking for active scheduled changes...
496balrogagent_1  | 2016-09-29 18:19:46,113 - DEBUG - root.request#31: Sending GET request to http://balrogadmin:8080/api/scheduled_changes/rules
497balrogadmin_1  | [pid: 136|app: 0|req: 6/26] 172.17.0.5 () {42 vars in 607 bytes} [Thu Sep 29 18:19:46 2016] GET /api/scheduled_changes/rules => generated 44 bytes in 9 msecs (HTTP/1.1 200) 4 headers in 127 bytes (1 switches on core 0)
498balrogagent_1  | 2016-09-29 18:19:46,126 - DEBUG - root.run_agent#21: Found 0
499balrogpub_1    | 2016-09-29 18:19:58,509 - DEBUG - PID: 8 - Request: 139656690523152 - ClientRequestView.get#54: Got query: {'product': u'Firefox', 'queryVersion': 3, 'buildID': u'20141202185629', 'locale': u'en-US', 'version': u'33.0', 'headerArchitecture': 'Intel', 'buildTarget': u'Darwin_x86_64-gcc3-u-i386-x86_64', 'force': True, 'distribution': u'default', 'osVersion': u'default', 'channel': u'release', 'distVersion': u'default'}
500balrogpub_1    | 2016-09-29 18:19:58,509 - DEBUG - PID: 8 - Request: 139656690523152 - AUS.evaluateRules#58: Looking for rules that apply to:
501balrogpub_1    | 2016-09-29 18:19:58,510 - DEBUG - PID: 8 - Request: 139656690523152 - AUS.evaluateRules#59: {'product': u'Firefox', 'queryVersion': 3, 'buildID': u'20141202185629', 'locale': u'en-US', 'version': u'33.0', 'headerArchitecture': 'Intel', 'buildTarget': u'Darwin_x86_64-gcc3-u-i386-x86_64', 'force': True, 'distribution': u'default', 'osVersion': u'default', 'channel': u'release', 'distVersion': u'default'}
502balrogpub_1    | 2016-09-29 18:19:58,513 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRawMatches#1143: where: [<sqlalchemy.sql.expression.BooleanClauseList object at 0x7f045a24d110>, <sqlalchemy.sql.expression.BooleanClauseList object at 0x7f045a272a90>]
503balrogpub_1    | 2016-09-29 18:19:58,527 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1156: Raw matches:
504balrogpub_1    | 2016-09-29 18:19:58,528 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'OS Blocking rule for de-supported OS on all nightly channels', u'product': None, u'systemCapabilities': None, u'data_version': 14L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'No-Update', u'priority': 100L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': 'Darwin 6,Darwin 7,Darwin 8,Darwin 9,Windows_95,Windows_98,Windows_NT 4,Windows_NT 5.0,Windows_NT 5.1.0,Windows_NT 5.1.1,GTK 2.0.,GTK 2.1.,GTK 2.2.,GTK 2.3.,GTK 2.4.,GTK 2.5.,GTK 2.6.,GTK 2.7.,GTK 2.8.,GTK 2.9.,GTK 2.10.,GTK 2.11.,GTK 2.12.,GTK 2.13.,GTK 2.14.,GTK 2.15.,GTK 2.16.,GTK 2.17.', u'rule_id': 1L, u'channel': 'nightly*', u'distVersion': None}
505balrogpub_1    | 2016-09-29 18:19:58,528 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: nightly* doesn't match release
506balrogpub_1    | 2016-09-29 18:19:58,529 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'OS Blocking rule for de-supported OS on Aurora.', u'product': None, u'systemCapabilities': None, u'data_version': 3L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'No-Update', u'priority': 100L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': 'Darwin 6,Darwin 7,Darwin 8,Darwin 9,Windows_95,Windows_98,Windows_NT 4,Windows_NT 5.0,Windows_NT 5.1.0,Windows_NT 5.1.1,GTK 2.0.,GTK 2.1.,GTK 2.2.,GTK 2.3.,GTK 2.4.,GTK 2.5.,GTK 2.6.,GTK 2.7.,GTK 2.8.,GTK 2.9.,GTK 2.10.,GTK 2.11.,GTK 2.12.,GTK 2.13.,GTK 2.14.,GTK 2.15.,GTK 2.16.,GTK 2.17.', u'rule_id': 2L, u'channel': 'aurora', u'distVersion': None}
507balrogpub_1    | 2016-09-29 18:19:58,529 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: aurora doesn't match release
508balrogpub_1    | 2016-09-29 18:19:58,529 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 24L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-mozilla-central-nightly-latest', u'priority': 90L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 3L, u'channel': 'nightly', u'distVersion': None}
509balrogpub_1    | 2016-09-29 18:19:58,530 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: nightly doesn't match release
510balrogpub_1    | 2016-09-29 18:19:58,530 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 8L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-oak-nightly-latest', u'priority': 90L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 5L, u'channel': 'nightly-oak', u'distVersion': None}
511balrogpub_1    | 2016-09-29 18:19:58,531 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: nightly-oak doesn't match release
512balrogpub_1    | 2016-09-29 18:19:58,532 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 5L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-ux-nightly-20140612040202', u'priority': 90L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 7L, u'channel': 'nightly-ux', u'distVersion': None}
513balrogpub_1    | 2016-09-29 18:19:58,532 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: nightly-ux doesn't match release
514balrogpub_1    | 2016-09-29 18:19:58,533 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 3L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-elm-nightly-20131221040201', u'priority': 90L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 9L, u'channel': 'nightly-elm', u'distVersion': None}
515balrogpub_1    | 2016-09-29 18:19:58,533 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: nightly-elm doesn't match release
516balrogpub_1    | 2016-09-29 18:19:58,534 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 69L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-mozilla-aurora-nightly-latest', u'priority': 90L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 10L, u'channel': 'aurora', u'distVersion': None}
517balrogpub_1    | 2016-09-29 18:19:58,534 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: aurora doesn't match release
518balrogpub_1    | 2016-09-29 18:19:58,535 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 7L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-mozilla-aurora-nightly-latest', u'priority': 90L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 16L, u'channel': 'auroratest', u'distVersion': None}
519balrogpub_1    | 2016-09-29 18:19:58,535 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: auroratest doesn't match release
520balrogpub_1    | 2016-09-29 18:19:58,536 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'Global shut off rule. Bump priority of this rule to 100 to shut off all updates', u'product': None, u'systemCapabilities': None, u'data_version': 9L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'No-Update', u'priority': 1L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 19L, u'channel': 'nightly', u'distVersion': None}
521balrogpub_1    | 2016-09-29 18:19:58,536 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: nightly doesn't match release
522balrogpub_1    | 2016-09-29 18:19:58,537 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 3L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-holly-nightly-20140321040202', u'priority': 90L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 20L, u'channel': 'nightly-holly', u'distVersion': None}
523balrogpub_1    | 2016-09-29 18:19:58,537 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: nightly-holly doesn't match release
524balrogpub_1    | 2016-09-29 18:19:58,538 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 19L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-mozilla-central-nightly-latest', u'priority': 90L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 22L, u'channel': 'nightlytest', u'distVersion': None}
525balrogpub_1    | 2016-09-29 18:19:58,538 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: nightlytest doesn't match release
526balrogpub_1    | 2016-09-29 18:19:58,539 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 171L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-44.0b2-build1', u'priority': 50L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 25L, u'channel': 'beta-localtest', u'distVersion': None}
527balrogpub_1    | 2016-09-29 18:19:58,539 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: beta-localtest doesn't match release
528balrogpub_1    | 2016-09-29 18:19:58,540 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'OS Blocking rule for de-supported OS on Beta channel', u'product': None, u'systemCapabilities': None, u'data_version': 3L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'No-Update', u'priority': 100L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': 'Darwin 6,Darwin 7,Darwin 8,Darwin 9,Windows_95,Windows_98,Windows_NT 4,Windows_NT 5.0,Windows_NT 5.1.0,Windows_NT 5.1.1,GTK 2.0.,GTK 2.1.,GTK 2.2.,GTK 2.3.,GTK 2.4.,GTK 2.5.,GTK 2.6.,GTK 2.7.,GTK 2.8.,GTK 2.9.,GTK 2.10.,GTK 2.11.,GTK 2.12.,GTK 2.13.,GTK 2.14.,GTK 2.15.,GTK 2.16.,GTK 2.17.', u'rule_id': 31L, u'channel': 'beta*', u'distVersion': None}
529balrogpub_1    | 2016-09-29 18:19:58,540 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: beta* doesn't match release
530balrogpub_1    | 2016-09-29 18:19:58,541 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 165L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-44.0b2-build1', u'priority': 90L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 32L, u'channel': 'beta', u'distVersion': None}
531balrogpub_1    | 2016-09-29 18:19:58,541 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: beta doesn't match release
532balrogpub_1    | 2016-09-29 18:19:58,542 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 135L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-44.0b2-build1', u'priority': 50L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 45L, u'channel': 'beta-cdntest', u'distVersion': None}
533balrogpub_1    | 2016-09-29 18:19:58,543 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: beta-cdntest doesn't match release
534balrogpub_1    | 2016-09-29 18:19:58,544 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'watershed', u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 5L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-11.0b5-build1', u'priority': 150L, u'alias': None, u'version': '<10.0', u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 51L, u'channel': 'beta*', u'distVersion': None}
535balrogpub_1    | 2016-09-29 18:19:58,544 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: beta* doesn't match release
536balrogpub_1    | 2016-09-29 18:19:58,545 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'watershed', u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 3L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-29.0b8-build1-schema2', u'priority': 140L, u'alias': None, u'version': '<29.0', u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 52L, u'channel': 'beta*', u'distVersion': None}
537balrogpub_1    | 2016-09-29 18:19:58,545 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: beta* doesn't match release
538balrogpub_1    | 2016-09-29 18:19:58,546 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 66L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-43.0.2-build1', u'priority': 50L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 56L, u'channel': 'release-localtest', u'distVersion': None}
539balrogpub_1    | 2016-09-29 18:19:58,547 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: release-localtest doesn't match release
540balrogpub_1    | 2016-09-29 18:19:58,547 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 71L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-43.0.2-build1', u'priority': 50L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 57L, u'channel': 'release-cdntest', u'distVersion': None}
541balrogpub_1    | 2016-09-29 18:19:58,547 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: release-cdntest doesn't match release
542balrogpub_1    | 2016-09-29 18:19:58,548 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 1L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-maple-nightly-latest', u'priority': 90L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 63L, u'channel': 'nightly-maple', u'distVersion': None}
543balrogpub_1    | 2016-09-29 18:19:58,548 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: nightly-maple doesn't match release
544balrogpub_1    | 2016-09-29 18:19:58,549 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'OS Blocking rule for de-supported OS on Release channel.', u'product': None, u'systemCapabilities': None, u'data_version': 3L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'No-Update', u'priority': 150L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': 'Darwin 6,Darwin 7,Darwin 8,Darwin 9,Windows_95,Windows_98,Windows_NT 4,Windows_NT 5.0,Windows_NT 5.1.0,Windows_NT 5.1.1,GTK 2.0.,GTK 2.1.,GTK 2.2.,GTK 2.3.,GTK 2.4.,GTK 2.5.,GTK 2.6.,GTK 2.7.,GTK 2.8.,GTK 2.9.,GTK 2.10.,GTK 2.11.,GTK 2.12.,GTK 2.13.,GTK 2.14.,GTK 2.15.,GTK 2.16.,GTK 2.17.', u'rule_id': 69L, u'channel': 'release*', u'distVersion': None}
545balrogpub_1    | 2016-09-29 18:19:58,549 - DEBUG - PID: 8 - Request: 139656690523152 - Rules._versionMatchesRule#1064: ruleVersion: None, queryVersion: 33.0
546balrogpub_1    | 2016-09-29 18:19:58,549 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1177: Darwin 6,Darwin 7,Darwin 8,Darwin 9,Windows_95,Windows_98,Windows_NT 4,Windows_NT 5.0,Windows_NT 5.1.0,Windows_NT 5.1.1,GTK 2.0.,GTK 2.1.,GTK 2.2.,GTK 2.3.,GTK 2.4.,GTK 2.5.,GTK 2.6.,GTK 2.7.,GTK 2.8.,GTK 2.9.,GTK 2.10.,GTK 2.11.,GTK 2.12.,GTK 2.13.,GTK 2.14.,GTK 2.15.,GTK 2.16.,GTK 2.17. doesn't match default
547balrogpub_1    | 2016-09-29 18:19:58,550 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'watershed', u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 2L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-12.0-build1', u'priority': 110L, u'alias': None, u'version': '<10.0', u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 73L, u'channel': 'release*', u'distVersion': None}
548balrogpub_1    | 2016-09-29 18:19:58,550 - DEBUG - PID: 8 - Request: 139656690523152 - Rules._versionMatchesRule#1064: ruleVersion: <10.0, queryVersion: 33.0
549balrogpub_1    | 2016-09-29 18:19:58,551 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1168: <10.0 doesn't match 33.0
550balrogpub_1    | 2016-09-29 18:19:58,551 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 3L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-gum-nightly-latest', u'priority': 90L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 79L, u'channel': 'nightly-gum', u'distVersion': None}
551balrogpub_1    | 2016-09-29 18:19:58,552 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: nightly-gum doesn't match release
552balrogpub_1    | 2016-09-29 18:19:58,553 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 1L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-ash-nightly-latest', u'priority': 90L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 80L, u'channel': 'nightly-ash', u'distVersion': None}
553balrogpub_1    | 2016-09-29 18:19:58,553 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: nightly-ash doesn't match release
554balrogpub_1    | 2016-09-29 18:19:58,554 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'OS Blocking rule for de-supported OS on ESR channel', u'product': None, u'systemCapabilities': None, u'data_version': 1L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'No-Update', u'priority': 100L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': 'Darwin 6,Darwin 7,Darwin 8,Darwin 9,Windows_95,Windows_98,Windows_NT 4,Windows_NT 5.0,Windows_NT 5.1.0,Windows_NT 5.1.1,GTK 2.0.,GTK 2.1.,GTK 2.2.,GTK 2.3.,GTK 2.4.,GTK 2.5.,GTK 2.6.,GTK 2.7.,GTK 2.8.,GTK 2.9.,GTK 2.10.,GTK 2.11.,GTK 2.12.,GTK 2.13.,GTK 2.14.,GTK 2.15.,GTK 2.16.,GTK 2.17.', u'rule_id': 99L, u'channel': 'esr*', u'distVersion': None}
555balrogpub_1    | 2016-09-29 18:19:58,554 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: esr* doesn't match release
556balrogpub_1    | 2016-09-29 18:19:58,555 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'block updates for 3.6 and earlier until we sort out bug 998721, and because they cant handle appVersion style xml', u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 6L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'No-Update', u'priority': 116L, u'alias': None, u'version': '<4.0', u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 103L, u'channel': 'release', u'distVersion': None}
557balrogpub_1    | 2016-09-29 18:19:58,556 - DEBUG - PID: 8 - Request: 139656690523152 - Rules._versionMatchesRule#1064: ruleVersion: <4.0, queryVersion: 33.0
558balrogpub_1    | 2016-09-29 18:19:58,556 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1168: <4.0 doesn't match 33.0
559balrogpub_1    | 2016-09-29 18:19:58,557 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 47L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 25L, u'mapping': 'Firefox-43.0.2-build1', u'priority': 90L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 145L, u'channel': 'release', u'distVersion': None}
560balrogpub_1    | 2016-09-29 18:19:58,557 - DEBUG - PID: 8 - Request: 139656690523152 - Rules._versionMatchesRule#1064: ruleVersion: None, queryVersion: 33.0
561balrogpub_1    | 2016-09-29 18:19:58,558 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 19L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-38.5.2esr-build1', u'priority': 40L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 166L, u'channel': 'esr-localtest', u'distVersion': None}
562balrogpub_1    | 2016-09-29 18:19:58,558 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: esr-localtest doesn't match release
563balrogpub_1    | 2016-09-29 18:19:58,559 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 18L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-38.5.2esr-build1', u'priority': 50L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 167L, u'channel': 'esr-cdntest', u'distVersion': None}
564balrogpub_1    | 2016-09-29 18:19:58,560 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: esr-cdntest doesn't match release
565balrogpub_1    | 2016-09-29 18:19:58,563 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 18L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-38.5.2esr-build1', u'priority': 91L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 168L, u'channel': 'esr', u'distVersion': None}
566balrogpub_1    | 2016-09-29 18:19:58,565 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: esr doesn't match release
567balrogpub_1    | 2016-09-29 18:19:58,567 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'block updates earlier than 2.0, bug 998721 doesnt ask for them, and they cant handle appVersion style xml', u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 3L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'No-Update', u'priority': 140L, u'alias': None, u'version': '<2.0', u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 195L, u'channel': 'release*', u'distVersion': None}
568balrogpub_1    | 2016-09-29 18:19:58,568 - DEBUG - PID: 8 - Request: 139656690523152 - Rules._versionMatchesRule#1064: ruleVersion: <2.0, queryVersion: 33.0
569balrogpub_1    | 2016-09-29 18:19:58,568 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1168: <2.0 doesn't match 33.0
570balrogpub_1    | 2016-09-29 18:19:58,569 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'bug 998721, old versions need a schema 1 blob', u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 3L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-12.0-build1-schema1', u'priority': 60L, u'alias': None, u'version': '<4.0', u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 196L, u'channel': 'release-cdntest', u'distVersion': None}
571balrogpub_1    | 2016-09-29 18:19:58,569 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: release-cdntest doesn't match release
572balrogpub_1    | 2016-09-29 18:19:58,570 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'Funsize dummy channel, Rail is testing it', u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 9L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-mozilla-aurora-nightly-latest', u'priority': 90L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 202L, u'channel': 'aurora-funsize', u'distVersion': None}
573balrogpub_1    | 2016-09-29 18:19:58,570 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: aurora-funsize doesn't match release
574balrogpub_1    | 2016-09-29 18:19:58,570 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'Funsize testing channel', u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 4L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-mozilla-central-nightly-latest', u'priority': 90L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 203L, u'channel': 'nightly-funsize', u'distVersion': None}
575balrogpub_1    | 2016-09-29 18:19:58,571 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: nightly-funsize doesn't match release
576balrogpub_1    | 2016-09-29 18:19:58,571 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 8L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-38.5.2esr-build1-whatsnew', u'priority': 51L, u'alias': None, u'version': '<38.0', u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 206L, u'channel': 'esr-cdntest', u'distVersion': None}
577balrogpub_1    | 2016-09-29 18:19:58,572 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: esr-cdntest doesn't match release
578balrogpub_1    | 2016-09-29 18:19:58,572 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 9L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-38.5.2esr-build1-whatsnew', u'priority': 92L, u'alias': None, u'version': '<38.0', u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 207L, u'channel': 'esr', u'distVersion': None}
579balrogpub_1    | 2016-09-29 18:19:58,573 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: esr doesn't match release
580balrogpub_1    | 2016-09-29 18:19:58,573 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 3L, u'buildID': '<20150901000000', u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-oak-nightly-20150911040219', u'priority': 92L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 212L, u'channel': 'nightly-oak-disabled', u'distVersion': None}
581balrogpub_1    | 2016-09-29 18:19:58,574 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: nightly-oak-disabled doesn't match release
582balrogpub_1    | 2016-09-29 18:19:58,575 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'bug 1079858 - Windows watershed to add both certificates to registry for SHA2 transition', u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 7L, u'buildID': '<20151209095500', u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-mozilla-central-nightly-20151209095500', u'priority': 92L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': 'Windows_NT', u'rule_id': 258L, u'channel': 'nightly*', u'distVersion': None}
583balrogpub_1    | 2016-09-29 18:19:58,575 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: nightly* doesn't match release
584balrogpub_1    | 2016-09-29 18:19:58,576 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'SHA1 watershed bug 1234277', u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 3L, u'buildID': '<20151217102820', u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-44.0b1-build2', u'priority': 130L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': 'Windows_NT', u'rule_id': 264L, u'channel': 'beta-localtest', u'distVersion': None}
585balrogpub_1    | 2016-09-29 18:19:58,577 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: beta-localtest doesn't match release
586balrogpub_1    | 2016-09-29 18:19:58,578 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'SHA1 watershed bug 1234277', u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 3L, u'buildID': '<20151217102820', u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-44.0b1-build2', u'priority': 130L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': 'Windows_NT', u'rule_id': 265L, u'channel': 'beta-cdntest', u'distVersion': None}
587balrogpub_1    | 2016-09-29 18:19:58,579 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: beta-cdntest doesn't match release
588balrogpub_1    | 2016-09-29 18:19:58,581 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'SHA1 watershed bug 1234277', u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 3L, u'buildID': '<20151217102820', u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-44.0b1-build2', u'priority': 130L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': 'Windows_NT', u'rule_id': 266L, u'channel': 'beta', u'distVersion': None}
589balrogpub_1    | 2016-09-29 18:19:58,582 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: beta doesn't match release
590balrogpub_1    | 2016-09-29 18:19:58,583 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'SHA1 watershed bug 1234277', u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 3L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-43.0.1-build1', u'priority': 55L, u'alias': None, u'version': '<43.0.1', u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': 'Windows_NT', u'rule_id': 267L, u'channel': 'release-localtest', u'distVersion': None}
591balrogpub_1    | 2016-09-29 18:19:58,585 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: release-localtest doesn't match release
592balrogpub_1    | 2016-09-29 18:19:58,586 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'SHA1 watershed bug 1234277', u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 2L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-43.0.1-build1', u'priority': 55L, u'alias': None, u'version': '<43.0.1', u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': 'Windows_NT', u'rule_id': 268L, u'channel': 'release-cdntest', u'distVersion': None}
593balrogpub_1    | 2016-09-29 18:19:58,587 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: release-cdntest doesn't match release
594balrogpub_1    | 2016-09-29 18:19:58,588 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'SHA1 watershed bug 1234277', u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 3L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-43.0.1-build1', u'priority': 100L, u'alias': None, u'version': '<43.0.1', u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': 'Windows_NT', u'rule_id': 269L, u'channel': 'release', u'distVersion': None}
595balrogpub_1    | 2016-09-29 18:19:58,589 - DEBUG - PID: 8 - Request: 139656690523152 - Rules._versionMatchesRule#1064: ruleVersion: <43.0.1, queryVersion: 33.0
596balrogpub_1    | 2016-09-29 18:19:58,589 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1177: Windows_NT doesn't match default
597balrogpub_1    | 2016-09-29 18:19:58,590 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'SHA1 watershed bug 1234277', u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 2L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-38.5.1esr-build1', u'priority': 50L, u'alias': None, u'version': '<38.5.1', u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': 'Windows_NT', u'rule_id': 270L, u'channel': 'esr-localtest', u'distVersion': None}
598balrogpub_1    | 2016-09-29 18:19:58,590 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: esr-localtest doesn't match release
599balrogpub_1    | 2016-09-29 18:19:58,591 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'SHA1 watershed bug 1234277', u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 2L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-38.5.1esr-build1', u'priority': 55L, u'alias': None, u'version': '<38.5.1', u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': 'Windows_NT', u'rule_id': 271L, u'channel': 'esr-cdntest', u'distVersion': None}
600balrogpub_1    | 2016-09-29 18:19:58,591 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: esr-cdntest doesn't match release
601balrogpub_1    | 2016-09-29 18:19:58,592 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'SHA1 watershed bug 1234277', u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 1L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-38.5.1esr-build1-whatsnew', u'priority': 60L, u'alias': None, u'version': '<38.0', u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': 'Windows_NT', u'rule_id': 272L, u'channel': 'esr-cdntest', u'distVersion': None}
602balrogpub_1    | 2016-09-29 18:19:58,593 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: esr-cdntest doesn't match release
603balrogpub_1    | 2016-09-29 18:19:58,594 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'SHA1 watershed bug 1234277', u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 3L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-38.5.1esr-build1-whatsnew', u'priority': 120L, u'alias': None, u'version': '<38.0', u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': 'Windows_NT', u'rule_id': 273L, u'channel': 'esr', u'distVersion': None}
604balrogpub_1    | 2016-09-29 18:19:58,596 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: esr doesn't match release
605balrogpub_1    | 2016-09-29 18:19:58,598 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'SHA1 watershed bug 1234277', u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 5L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-38.5.1esr-build1', u'priority': 110L, u'alias': None, u'version': '<38.5.1', u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': 'Windows_NT', u'rule_id': 274L, u'channel': 'esr', u'distVersion': None}
606balrogpub_1    | 2016-09-29 18:19:58,600 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: esr doesn't match release
607balrogpub_1    | 2016-09-29 18:19:58,601 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1160: {u'comment': 'Bug 1234277 - Create SHA1 watersheds', u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 1L, u'buildID': '<20151223004004', u'distribution': None, u'whitelist': None, u'backgroundRate': 100L, u'mapping': 'Firefox-mozilla-aurora-nightly-20151223004004', u'priority': 150L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': 'Windows_NT', u'rule_id': 277L, u'channel': 'aurora', u'distVersion': None}
608balrogpub_1    | 2016-09-29 18:19:58,601 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1165: aurora doesn't match release
609balrogpub_1    | 2016-09-29 18:19:58,602 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1203: Reduced matches:
610balrogpub_1    | 2016-09-29 18:19:58,602 - DEBUG - PID: 8 - Request: 139656690523152 - Rules.getRulesMatchingQuery#1206: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 47L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 25L, u'mapping': 'Firefox-43.0.2-build1', u'priority': 90L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 145L, u'channel': 'release', u'distVersion': None}
611balrogpub_1    | 2016-09-29 18:19:58,602 - DEBUG - PID: 8 - Request: 139656690523152 - AUS.evaluateRules#71: Matching rule: {u'comment': None, u'product': 'Firefox', u'systemCapabilities': None, u'data_version': 47L, u'buildID': None, u'distribution': None, u'whitelist': None, u'backgroundRate': 25L, u'mapping': 'Firefox-43.0.2-build1', u'priority': 90L, u'alias': None, u'version': None, u'headerArchitecture': None, u'update_type': 'minor', u'buildTarget': None, u'locale': None, u'osVersion': None, u'rule_id': 145L, u'channel': 'release', u'distVersion': None}
612balrogpub_1    | 2016-09-29 18:19:58,603 - DEBUG - PID: 8 - Request: 139656690523152 - Releases.getReleases#1282: Looking for releases with:
613balrogpub_1    | 2016-09-29 18:19:58,603 - DEBUG - PID: 8 - Request: 139656690523152 - Releases.getReleases#1283: name: Firefox-43.0.2-build1
614balrogpub_1    | 2016-09-29 18:19:58,603 - DEBUG - PID: 8 - Request: 139656690523152 - Releases.getReleases#1284: product: None
615balrogpub_1    | 2016-09-29 18:19:58,613 - DEBUG - PID: 8 - Request: 139656690523152 - AUS.evaluateRules#98: Returning release Firefox-43.0.2-build1
616balrogpub_1    | 2016-09-29 18:19:58,619 - DEBUG - PID: 8 - Request: 139656690523152 - auslib.blobs.apprelease.ReleaseBlobV4.matchesUpdateQuery#14: Trying to match update query to Firefox-43.0.1-build1
617balrogpub_1    | 2016-09-29 18:19:58,619 - DEBUG - PID: 8 - Request: 139656690523152 - auslib.blobs.apprelease.ReleaseBlobV4.matchesUpdateQuery#25: releasePlat buildID is: 20151216175450
618balrogpub_1    | 2016-09-29 18:19:58,631 - DEBUG - PID: 8 - Request: 139656690523152 - ClientRequestView.get#114: Sending XML: <?xml version="1.0"?>
619balrogpub_1    | <updates>
620balrogpub_1    |     <update type="minor" displayVersion="43.0.2" appVersion="43.0.2" platformVersion="43.0.2" buildID="20151221130713" detailsURL="https://www.mozilla.org/en-US/firefox/43.0.2/releasenotes/">
621balrogpub_1    |         <patch type="complete" URL="http://download.mozilla.org/?product=firefox-43.0.2-complete&amp;os=osx&amp;lang=en-US&amp;force=1" hashFunction="sha512" hashValue="781478556846b719ebc906a8a9613a421e24449b4456c4ccee990e878b3be9fb0478a78821a499a4c1f1a76d75078acf3fdfa3d0be69d2f6c94e3b6340fc935b" size="80329415"/>
622balrogpub_1    |     </update>
623balrogpub_1    | </updates>
624balrogpub_1    | [pid: 8|app: 0|req: 2/2] 172.17.0.1 () {36 vars in 837 bytes} [Thu Sep 29 18:19:58 2016] GET /update/3/Firefox/33.0/20141202185629/Darwin_x86_64-gcc3-u-i386-x86_64/en-US/release/default/default/default/update.xml?force=1 => generated 565 bytes in 124 msecs (HTTP/1.1 200) 3 headers in 114 bytes (1 switches on core 0)
625balrogagent_1  | 2016-09-29 18:20:16,153 - DEBUG - root.run_agent#17: Looking for active scheduled changes...
626balrogagent_1  | 2016-09-29 18:20:16,154 - DEBUG - root.request#31: Sending GET request to http://balrogadmin:8080/api/scheduled_changes/rules
627balrogadmin_1  | [pid: 136|app: 0|req: 7/27] 172.17.0.5 () {42 vars in 608 bytes} [Thu Sep 29 18:20:16 2016] GET /api/scheduled_changes/rules => generated 44 bytes in 29 msecs (HTTP/1.1 200) 4 headers in 127 bytes (1 switches on core 0)
628balrogagent_1  | 2016-09-29 18:20:16,204 - DEBUG - root.run_agent#21: Found 0
629balrogagent_1  | 2016-09-29 18:20:46,240 - DEBUG - root.run_agent#17: Looking for active scheduled changes...
630balrogagent_1  | 2016-09-29 18:20:46,241 - DEBUG - root.request#31: Sending GET request to http://balrogadmin:8080/api/scheduled_changes/rules
631balrogadmin_1  | [pid: 136|app: 0|req: 8/28] 172.17.0.5 () {42 vars in 608 bytes} [Thu Sep 29 18:20:46 2016] GET /api/scheduled_changes/rules => generated 44 bytes in 70 msecs (HTTP/1.1 200) 4 headers in 127 bytes (1 switches on core 0)
632balrogagent_1  | 2016-09-29 18:20:46,328 - DEBUG - root.run_agent#21: Found 0
633balrogagent_1  | 2016-09-29 18:21:16,356 - DEBUG - root.run_agent#17: Looking for active scheduled changes...
634balrogagent_1  | 2016-09-29 18:21:16,356 - DEBUG - root.request#31: Sending GET request to http://balrogadmin:8080/api/scheduled_changes/rules
635balrogadmin_1  | [pid: 136|app: 0|req: 9/29] 172.17.0.5 () {42 vars in 608 bytes} [Thu Sep 29 18:21:16 2016] GET /api/scheduled_changes/rules => generated 44 bytes in 13 msecs (HTTP/1.1 200) 4 headers in 127 bytes (1 switches on core 0)
636balrogagent_1  | 2016-09-29 18:21:16,374 - DEBUG - root.run_agent#21: Found 0
637balrogagent_1  | 2016-09-29 18:21:46,406 - DEBUG - root.run_agent#17: Looking for active scheduled changes...
638balrogagent_1  | 2016-09-29 18:21:46,406 - DEBUG - root.request#31: Sending GET request to http://balrogadmin:8080/api/scheduled_changes/rules
639balrogagent_1  | 2016-09-29 18:21:46,415 - DEBUG - root.run_agent#21: Found 0
640balrogadmin_1  | [pid: 136|app: 0|req: 10/30] 172.17.0.5 () {42 vars in 608 bytes} [Thu Sep 29 18:21:46 2016] GET /api/scheduled_changes/rules => generated 44 bytes in 5 msecs (HTTP/1.1 200) 4 headers in 127 bytes (1 switches on core 0)
641balrogagent_1  | 2016-09-29 18:22:16,446 - DEBUG - root.run_agent#17: Looking for active scheduled changes...
642balrogagent_1  | 2016-09-29 18:22:16,448 - DEBUG - root.request#31: Sending GET request to http://balrogadmin:8080/api/scheduled_changes/rules
643balrogadmin_1  | [pid: 136|app: 0|req: 11/31] 172.17.0.5 () {42 vars in 608 bytes} [Thu Sep 29 18:22:16 2016] GET /api/scheduled_changes/rules => generated 44 bytes in 40 msecs (HTTP/1.1 200) 4 headers in 127 bytes (1 switches on core 0)
644balrogagent_1  | 2016-09-29 18:22:16,520 - DEBUG - root.run_agent#21: Found 0