builder: b2g-inbound_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-030 starttime: 1449973235.46 results: success (0) buildid: 20151212170331 builduid: 99049d9ba8684ddd98a39d93f294b516 revision: f8168f43b47fb3e1c18b25f91b014006aa092e1b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-12 18:20:35.455844) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-12 18:20:35.456296) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-12 18:20:35.456658) ========= bash -c pwd in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'pwd'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=23f5739b2d6aa5d9caf5c428000001ae-1449973169.107041-1994106989 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.006347 basedir: '/builds/slave/test' ========= master_lag: 0.03 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-12 18:20:35.495550) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-12 18:20:35.495923) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-12 18:20:35.523294) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-12 18:20:35.523645) ========= rm -rf properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=23f5739b2d6aa5d9caf5c428000001ae-1449973169.107041-1994106989 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004634 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-12 18:20:35.573577) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-12 18:20:35.573983) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-12 18:20:35.574420) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-12 18:20:35.574722) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=23f5739b2d6aa5d9caf5c428000001ae-1449973169.107041-1994106989 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-12 18:20:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 227M=0s 2015-12-12 18:20:36 (227 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.206088 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-12 18:20:35.793977) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-12 18:20:35.794287) ========= rm -rf scripts in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=23f5739b2d6aa5d9caf5c428000001ae-1449973169.107041-1994106989 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015664 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-12 18:20:35.844344) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-12 18:20:35.844725) ========= bash -c 'python archiver_client.py mozharness --repo integration/b2g-inbound --rev f8168f43b47fb3e1c18b25f91b014006aa092e1b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/b2g-inbound --rev f8168f43b47fb3e1c18b25f91b014006aa092e1b --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=23f5739b2d6aa5d9caf5c428000001ae-1449973169.107041-1994106989 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-12 18:20:36,362 truncating revision to first 12 chars 2015-12-12 18:20:36,362 Setting DEBUG logging. 2015-12-12 18:20:36,362 attempt 1/10 2015-12-12 18:20:36,362 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/b2g-inbound/f8168f43b47f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-12 18:20:36,600 unpacking tar archive at: b2g-inbound-f8168f43b47f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.504956 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-12 18:20:36.362545) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-12 18:20:36.362863) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-12 18:20:36.370923) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-12 18:20:36.371264) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-12 18:20:36.371726) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2015-12-12 18:20:36.372019) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name B2G-Inbound-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch B2G-Inbound-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'B2G-Inbound-Non-PGO', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'B2G-Inbound-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=23f5739b2d6aa5d9caf5c428000001ae-1449973169.107041-1994106989 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:20:36 INFO - MultiFileLogger online at 20151212 18:20:36 in /builds/slave/test 18:20:36 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name B2G-Inbound-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch B2G-Inbound-Non-PGO 18:20:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:20:36 INFO - {'append_to_log': False, 18:20:36 INFO - 'base_work_dir': '/builds/slave/test', 18:20:36 INFO - 'blob_upload_branch': 'B2G-Inbound-Non-PGO', 18:20:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:20:36 INFO - 'branch': 'B2G-Inbound-Non-PGO', 18:20:36 INFO - 'buildbot_json_path': 'buildprops.json', 18:20:36 INFO - 'config_files': ('talos/linux_config.py',), 18:20:36 INFO - 'default_actions': ('clobber', 18:20:36 INFO - 'read-buildbot-config', 18:20:36 INFO - 'download-and-extract', 18:20:36 INFO - 'populate-webroot', 18:20:36 INFO - 'create-virtualenv', 18:20:36 INFO - 'install', 18:20:36 INFO - 'run-tests'), 18:20:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:20:36 INFO - 'download_minidump_stackwalk': True, 18:20:36 INFO - 'download_symbols': 'ondemand', 18:20:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:20:36 INFO - 'tooltool.py': '/tools/tooltool.py', 18:20:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:20:36 INFO - '/tools/misc-python/virtualenv.py')}, 18:20:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:20:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:20:36 INFO - 'installer_path': 'installer.exe', 18:20:36 INFO - 'log_level': 'info', 18:20:36 INFO - 'log_name': 'talos', 18:20:36 INFO - 'log_to_console': True, 18:20:36 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:20:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:20:36 INFO - 'opt_config_files': (), 18:20:36 INFO - 'pip_index': False, 18:20:36 INFO - 'sps_profile': False, 18:20:36 INFO - 'sps_profile_interval': 0, 18:20:36 INFO - 'suite': 'dromaeojs', 18:20:36 INFO - 'system_bits': '64', 18:20:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:20:36 INFO - 'title': 'talos-linux64-ix-030', 18:20:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:20:36 INFO - 'use_talos_json': True, 18:20:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:20:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:20:36 INFO - 'work_dir': 'build'} 18:20:36 INFO - ##### 18:20:36 INFO - ##### Running clobber step. 18:20:36 INFO - ##### 18:20:36 INFO - Running pre-action listener: _resource_record_pre_action 18:20:36 INFO - Running main action method: clobber 18:20:36 INFO - rmtree: /builds/slave/test/build 18:20:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:20:37 INFO - Running post-action listener: _resource_record_post_action 18:20:37 INFO - ##### 18:20:37 INFO - ##### Running read-buildbot-config step. 18:20:37 INFO - ##### 18:20:37 INFO - Running pre-action listener: _resource_record_pre_action 18:20:37 INFO - Running main action method: read_buildbot_config 18:20:37 INFO - Using buildbot properties: 18:20:37 INFO - { 18:20:37 INFO - "properties": { 18:20:37 INFO - "buildnumber": 304, 18:20:37 INFO - "product": "firefox", 18:20:37 INFO - "script_repo_revision": "production", 18:20:37 INFO - "builddir": "b2g-inbound_ubuntu64_hw_test-dromaeojs", 18:20:37 INFO - "repository": "", 18:20:37 INFO - "buildername": "Ubuntu HW 12.04 x64 b2g-inbound talos dromaeojs", 18:20:37 INFO - "buildid": "20151212170331", 18:20:37 INFO - "slavename": "talos-linux64-ix-030", 18:20:37 INFO - "pgo_build": "False", 18:20:37 INFO - "basedir": "/builds/slave/test", 18:20:37 INFO - "project": "", 18:20:37 INFO - "platform": "ubuntu64_hw", 18:20:37 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:20:37 INFO - "slavebuilddir": "test", 18:20:37 INFO - "scheduler": "tests-b2g-inbound-linux64-talos", 18:20:37 INFO - "branch": "b2g-inbound", 18:20:37 INFO - "repo_path": "integration/b2g-inbound", 18:20:37 INFO - "stage_platform": "linux64", 18:20:37 INFO - "builduid": "99049d9ba8684ddd98a39d93f294b516", 18:20:37 INFO - "revision": "f8168f43b47fb3e1c18b25f91b014006aa092e1b" 18:20:37 INFO - }, 18:20:37 INFO - "sourcestamp": { 18:20:37 INFO - "repository": "", 18:20:37 INFO - "hasPatch": false, 18:20:37 INFO - "project": "", 18:20:37 INFO - "branch": "b2g-inbound-linux64-talos", 18:20:37 INFO - "changes": [ 18:20:37 INFO - { 18:20:37 INFO - "category": null, 18:20:37 INFO - "files": [ 18:20:37 INFO - { 18:20:37 INFO - "url": null, 18:20:37 INFO - "name": "https://queue.taskcluster.net/v1/task/P0lPiNyARI6ZkYZyxsdEyg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 18:20:37 INFO - } 18:20:37 INFO - ], 18:20:37 INFO - "repository": "", 18:20:37 INFO - "rev": "f8168f43b47fb3e1c18b25f91b014006aa092e1b", 18:20:37 INFO - "who": "philringnalda@gmail.com", 18:20:37 INFO - "when": 1449973180, 18:20:37 INFO - "number": 7355472, 18:20:37 INFO - "comments": "Merge m-c to b-i", 18:20:37 INFO - "project": "", 18:20:37 INFO - "at": "Sat 12 Dec 2015 18:19:40", 18:20:37 INFO - "branch": "b2g-inbound-linux64-talos", 18:20:37 INFO - "revlink": "", 18:20:37 INFO - "properties": [ 18:20:37 INFO - [ 18:20:37 INFO - "buildid", 18:20:37 INFO - "20151212170331", 18:20:37 INFO - "Change" 18:20:37 INFO - ], 18:20:37 INFO - [ 18:20:37 INFO - "builduid", 18:20:37 INFO - "99049d9ba8684ddd98a39d93f294b516", 18:20:37 INFO - "Change" 18:20:37 INFO - ], 18:20:37 INFO - [ 18:20:37 INFO - "pgo_build", 18:20:37 INFO - "False", 18:20:37 INFO - "Change" 18:20:37 INFO - ] 18:20:37 INFO - ], 18:20:37 INFO - "revision": "f8168f43b47fb3e1c18b25f91b014006aa092e1b" 18:20:37 INFO - } 18:20:37 INFO - ], 18:20:37 INFO - "revision": "f8168f43b47fb3e1c18b25f91b014006aa092e1b" 18:20:37 INFO - } 18:20:37 INFO - } 18:20:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/P0lPiNyARI6ZkYZyxsdEyg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 18:20:37 INFO - Running post-action listener: _resource_record_post_action 18:20:37 INFO - ##### 18:20:37 INFO - ##### Running download-and-extract step. 18:20:37 INFO - ##### 18:20:37 INFO - Running pre-action listener: _resource_record_pre_action 18:20:37 INFO - Running main action method: download_and_extract 18:20:37 INFO - mkdir: /builds/slave/test/build/tests 18:20:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:20:37 INFO - https://queue.taskcluster.net/v1/task/P0lPiNyARI6ZkYZyxsdEyg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 18:20:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0lPiNyARI6ZkYZyxsdEyg/artifacts/public/build/test_packages.json 18:20:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0lPiNyARI6ZkYZyxsdEyg/artifacts/public/build/test_packages.json 18:20:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0lPiNyARI6ZkYZyxsdEyg/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 18:20:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0lPiNyARI6ZkYZyxsdEyg/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 18:20:38 INFO - Downloaded 1302 bytes. 18:20:38 INFO - Reading from file /builds/slave/test/build/test_packages.json 18:20:38 INFO - Using the following test package requirements: 18:20:38 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:20:38 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:20:38 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:20:38 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:20:38 INFO - u'jsshell-linux-x86_64.zip'], 18:20:38 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:20:38 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:20:38 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:20:38 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:20:38 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:20:38 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:20:38 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:20:38 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:20:38 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:20:38 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:20:38 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:20:38 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:20:38 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:20:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:20:38 INFO - https://queue.taskcluster.net/v1/task/P0lPiNyARI6ZkYZyxsdEyg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:20:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0lPiNyARI6ZkYZyxsdEyg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:20:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0lPiNyARI6ZkYZyxsdEyg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:20:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0lPiNyARI6ZkYZyxsdEyg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:20:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0lPiNyARI6ZkYZyxsdEyg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:20:40 INFO - Downloaded 21869918 bytes. 18:20:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 18:20:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:20:40 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 18:20:41 INFO - Return code: 0 18:20:41 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:20:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:20:41 INFO - https://queue.taskcluster.net/v1/task/P0lPiNyARI6ZkYZyxsdEyg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:20:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0lPiNyARI6ZkYZyxsdEyg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:20:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0lPiNyARI6ZkYZyxsdEyg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:20:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0lPiNyARI6ZkYZyxsdEyg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:20:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0lPiNyARI6ZkYZyxsdEyg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:20:42 INFO - Downloaded 21869918 bytes. 18:20:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 18:20:42 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:20:42 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 18:20:43 INFO - Return code: 0 18:20:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:20:43 INFO - https://queue.taskcluster.net/v1/task/P0lPiNyARI6ZkYZyxsdEyg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:20:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0lPiNyARI6ZkYZyxsdEyg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:20:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0lPiNyARI6ZkYZyxsdEyg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:20:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0lPiNyARI6ZkYZyxsdEyg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:20:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0lPiNyARI6ZkYZyxsdEyg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:20:45 INFO - Downloaded 11132038 bytes. 18:20:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test/build/tests 18:20:45 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:20:45 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 18:20:45 INFO - Return code: 0 18:20:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:20:45 INFO - https://queue.taskcluster.net/v1/task/P0lPiNyARI6ZkYZyxsdEyg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:20:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0lPiNyARI6ZkYZyxsdEyg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:20:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0lPiNyARI6ZkYZyxsdEyg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:20:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0lPiNyARI6ZkYZyxsdEyg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:20:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0lPiNyARI6ZkYZyxsdEyg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:20:47 INFO - Downloaded 57739203 bytes. 18:20:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/P0lPiNyARI6ZkYZyxsdEyg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:20:47 INFO - mkdir: /builds/slave/test/properties 18:20:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:20:47 INFO - Writing to file /builds/slave/test/properties/build_url 18:20:47 INFO - Contents: 18:20:47 INFO - build_url:https://queue.taskcluster.net/v1/task/P0lPiNyARI6ZkYZyxsdEyg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:20:47 INFO - Running post-action listener: _resource_record_post_action 18:20:47 INFO - Running post-action listener: set_extra_try_arguments 18:20:47 INFO - ##### 18:20:47 INFO - ##### Running populate-webroot step. 18:20:47 INFO - ##### 18:20:47 INFO - Running pre-action listener: _resource_record_pre_action 18:20:47 INFO - Running main action method: populate_webroot 18:20:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:20:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:20:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:20:47 INFO - '--tppagecycles', 18:20:47 INFO - '7'], 18:20:47 INFO - 'tests': ['tsvgm']}}, 18:20:47 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 18:20:47 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 18:20:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:20:47 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:20:47 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 18:20:47 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:20:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:20:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:20:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:20:47 INFO - 'talos_options': ['--e10s'], 18:20:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:20:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:20:47 INFO - 'tests': ['damp', 'tps']}, 18:20:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:20:47 INFO - 'talos_options': ['--e10s'], 18:20:47 INFO - 'tests': ['damp', 'tps']}, 18:20:47 INFO - 'g3': {'tests': ['dromaeo_dom']}, 18:20:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:20:47 INFO - 'other': {'tests': ['a11yr', 18:20:47 INFO - 'ts_paint', 18:20:47 INFO - 'tpaint', 18:20:47 INFO - 'sessionrestore', 18:20:47 INFO - 'sessionrestore_no_auto_restore']}, 18:20:47 INFO - 'other-e10s': {'talos_options': ['--e10s'], 18:20:47 INFO - 'tests': ['a11yr', 18:20:47 INFO - 'ts_paint', 18:20:47 INFO - 'tpaint', 18:20:47 INFO - 'sessionrestore', 18:20:47 INFO - 'sessionrestore_no_auto_restore']}, 18:20:47 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 18:20:47 INFO - 'tests': ['a11yr', 18:20:47 INFO - 'ts_paint', 18:20:47 INFO - 'tpaint', 18:20:47 INFO - 'sessionrestore', 18:20:47 INFO - 'sessionrestore_no_auto_restore']}, 18:20:47 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 18:20:47 INFO - 'tests': ['a11yr', 18:20:47 INFO - 'ts_paint', 18:20:47 INFO - 'tpaint', 18:20:47 INFO - 'sessionrestore', 18:20:47 INFO - 'sessionrestore_no_auto_restore']}, 18:20:47 INFO - 'other_l64': {'tests': ['a11yr', 18:20:47 INFO - 'ts_paint', 18:20:47 INFO - 'tpaint', 18:20:47 INFO - 'sessionrestore', 18:20:47 INFO - 'sessionrestore_no_auto_restore']}, 18:20:47 INFO - 'other_nol64': {'tests': ['a11yr', 18:20:47 INFO - 'ts_paint', 18:20:47 INFO - 'tpaint', 18:20:47 INFO - 'sessionrestore', 18:20:47 INFO - 'sessionrestore_no_auto_restore']}, 18:20:47 INFO - 'svgr': {'tests': ['tsvgx', 18:20:47 INFO - 'tsvgr_opacity', 18:20:47 INFO - 'tart', 18:20:47 INFO - 'tscrollx', 18:20:47 INFO - 'cart']}, 18:20:47 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 18:20:47 INFO - 'tests': ['tsvgx', 18:20:47 INFO - 'tsvgr_opacity', 18:20:47 INFO - 'tart', 18:20:47 INFO - 'tscrollx', 18:20:47 INFO - 'cart']}, 18:20:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:20:47 INFO - 'tests': ['tp5o']}, 18:20:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:20:47 INFO - 'talos_options': ['--e10s'], 18:20:47 INFO - 'tests': ['tp5o']}, 18:20:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:20:47 INFO - 'talos_options': ['--xperf_path', 18:20:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:20:47 INFO - 'tests': ['tp5n']}, 18:20:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:20:47 INFO - 'talos_options': ['--e10s', 18:20:47 INFO - '--xperf_path', 18:20:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:20:47 INFO - 'tests': ['tp5n']}}, 18:20:47 INFO - 'talos.zip': {'path': '', 18:20:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:20:47 INFO - Running post-action listener: _resource_record_post_action 18:20:47 INFO - ##### 18:20:47 INFO - ##### Running create-virtualenv step. 18:20:47 INFO - ##### 18:20:47 INFO - Running pre-action listener: _resource_record_pre_action 18:20:47 INFO - Running main action method: create_virtualenv 18:20:47 INFO - Creating virtualenv /builds/slave/test/build/venv 18:20:47 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:20:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:20:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:20:47 INFO - Using real prefix '/usr' 18:20:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:20:48 INFO - Installing distribute.............................................................................................................................................................................................done. 18:20:50 INFO - Installing pip.................done. 18:20:50 INFO - Return code: 0 18:20:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:20:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:20:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:20:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6d313c7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeb1240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf400b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf40fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf41440>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '23f5739b2d6aa5d9caf5c428000001ae-1449973169.107041-1994106989', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:20:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:20:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:20:50 INFO - Using env: {'DISPLAY': ':0', 18:20:50 INFO - 'HOME': '/home/cltbld', 18:20:50 INFO - 'LANG': 'en_US.UTF-8', 18:20:50 INFO - 'LANGUAGE': 'en_US:en', 18:20:50 INFO - 'LOGNAME': 'cltbld', 18:20:50 INFO - 'MAIL': '/var/mail/cltbld', 18:20:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:50 INFO - 'MOZ_NO_REMOTE': '1', 18:20:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:50 INFO - 'NO_EM_RESTART': '1', 18:20:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:50 INFO - 'PWD': '/builds/slave/test', 18:20:50 INFO - 'SHELL': '/bin/bash', 18:20:50 INFO - 'SHLVL': '1', 18:20:50 INFO - 'TERM': 'linux', 18:20:50 INFO - 'TMOUT': '86400', 18:20:50 INFO - 'USER': 'cltbld', 18:20:50 INFO - 'XDG_SESSION_COOKIE': '23f5739b2d6aa5d9caf5c428000001ae-1449973169.107041-1994106989', 18:20:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:50 INFO - '_': '/tools/buildbot/bin/python'} 18:20:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:50 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:20:50 INFO - Cleaning up... 18:20:50 INFO - Return code: 0 18:20:50 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 18:20:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:20:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:20:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6d313c7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeb1240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf400b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf40fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf41440>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '23f5739b2d6aa5d9caf5c428000001ae-1449973169.107041-1994106989', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:20:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 18:20:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 18:20:50 INFO - Using env: {'DISPLAY': ':0', 18:20:50 INFO - 'HOME': '/home/cltbld', 18:20:50 INFO - 'LANG': 'en_US.UTF-8', 18:20:50 INFO - 'LANGUAGE': 'en_US:en', 18:20:50 INFO - 'LOGNAME': 'cltbld', 18:20:50 INFO - 'MAIL': '/var/mail/cltbld', 18:20:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:50 INFO - 'MOZ_NO_REMOTE': '1', 18:20:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:50 INFO - 'NO_EM_RESTART': '1', 18:20:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:50 INFO - 'PWD': '/builds/slave/test', 18:20:50 INFO - 'SHELL': '/bin/bash', 18:20:50 INFO - 'SHLVL': '1', 18:20:50 INFO - 'TERM': 'linux', 18:20:50 INFO - 'TMOUT': '86400', 18:20:50 INFO - 'USER': 'cltbld', 18:20:50 INFO - 'XDG_SESSION_COOKIE': '23f5739b2d6aa5d9caf5c428000001ae-1449973169.107041-1994106989', 18:20:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:50 INFO - '_': '/tools/buildbot/bin/python'} 18:20:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:50 INFO - Downloading/unpacking psutil>=0.7.1 18:20:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:20:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:20:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:52 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 18:20:52 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 18:20:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:20:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:20:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:20:52 INFO - Installing collected packages: psutil 18:20:52 INFO - Running setup.py install for psutil 18:20:52 INFO - building 'psutil._psutil_linux' extension 18:20:52 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 18:20:52 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 18:20:52 INFO - building 'psutil._psutil_posix' extension 18:20:52 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 18:20:52 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 18:20:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:20:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:20:52 INFO - Successfully installed psutil 18:20:52 INFO - Cleaning up... 18:20:53 INFO - Return code: 0 18:20:53 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 18:20:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:20:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:20:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6d313c7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeb1240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf400b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf40fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf41440>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '23f5739b2d6aa5d9caf5c428000001ae-1449973169.107041-1994106989', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:20:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 18:20:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 18:20:53 INFO - Using env: {'DISPLAY': ':0', 18:20:53 INFO - 'HOME': '/home/cltbld', 18:20:53 INFO - 'LANG': 'en_US.UTF-8', 18:20:53 INFO - 'LANGUAGE': 'en_US:en', 18:20:53 INFO - 'LOGNAME': 'cltbld', 18:20:53 INFO - 'MAIL': '/var/mail/cltbld', 18:20:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:53 INFO - 'MOZ_NO_REMOTE': '1', 18:20:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:53 INFO - 'NO_EM_RESTART': '1', 18:20:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:53 INFO - 'PWD': '/builds/slave/test', 18:20:53 INFO - 'SHELL': '/bin/bash', 18:20:53 INFO - 'SHLVL': '1', 18:20:53 INFO - 'TERM': 'linux', 18:20:53 INFO - 'TMOUT': '86400', 18:20:53 INFO - 'USER': 'cltbld', 18:20:53 INFO - 'XDG_SESSION_COOKIE': '23f5739b2d6aa5d9caf5c428000001ae-1449973169.107041-1994106989', 18:20:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:53 INFO - '_': '/tools/buildbot/bin/python'} 18:20:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:53 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:20:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:20:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:20:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:54 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:20:54 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 18:20:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:20:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:20:54 INFO - Installing collected packages: mozsystemmonitor 18:20:54 INFO - Running setup.py install for mozsystemmonitor 18:20:55 INFO - Successfully installed mozsystemmonitor 18:20:55 INFO - Cleaning up... 18:20:55 INFO - Return code: 0 18:20:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:20:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:20:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:20:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6d313c7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeb1240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf400b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf40fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf41440>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '23f5739b2d6aa5d9caf5c428000001ae-1449973169.107041-1994106989', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:20:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:20:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:20:55 INFO - Using env: {'DISPLAY': ':0', 18:20:55 INFO - 'HOME': '/home/cltbld', 18:20:55 INFO - 'LANG': 'en_US.UTF-8', 18:20:55 INFO - 'LANGUAGE': 'en_US:en', 18:20:55 INFO - 'LOGNAME': 'cltbld', 18:20:55 INFO - 'MAIL': '/var/mail/cltbld', 18:20:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:55 INFO - 'MOZ_NO_REMOTE': '1', 18:20:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:55 INFO - 'NO_EM_RESTART': '1', 18:20:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:55 INFO - 'PWD': '/builds/slave/test', 18:20:55 INFO - 'SHELL': '/bin/bash', 18:20:55 INFO - 'SHLVL': '1', 18:20:55 INFO - 'TERM': 'linux', 18:20:55 INFO - 'TMOUT': '86400', 18:20:55 INFO - 'USER': 'cltbld', 18:20:55 INFO - 'XDG_SESSION_COOKIE': '23f5739b2d6aa5d9caf5c428000001ae-1449973169.107041-1994106989', 18:20:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:55 INFO - '_': '/tools/buildbot/bin/python'} 18:20:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:55 INFO - Downloading/unpacking blobuploader==1.2.4 18:20:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:20:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:20:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:56 INFO - Downloading blobuploader-1.2.4.tar.gz 18:20:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 18:20:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:20:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:20:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:20:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:20:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:57 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 18:20:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:20:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:20:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:20:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:20:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:57 INFO - Downloading docopt-0.6.1.tar.gz 18:20:57 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 18:20:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:20:57 INFO - Installing collected packages: blobuploader, requests, docopt 18:20:57 INFO - Running setup.py install for blobuploader 18:20:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:20:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:20:57 INFO - Running setup.py install for requests 18:20:58 INFO - Running setup.py install for docopt 18:20:58 INFO - Successfully installed blobuploader requests docopt 18:20:58 INFO - Cleaning up... 18:20:58 INFO - Return code: 0 18:20:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:20:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:20:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:20:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6d313c7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeb1240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf400b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf40fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf41440>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '23f5739b2d6aa5d9caf5c428000001ae-1449973169.107041-1994106989', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:20:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:20:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test/build/venv/cache --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:20:58 INFO - Using env: {'DISPLAY': ':0', 18:20:58 INFO - 'HOME': '/home/cltbld', 18:20:58 INFO - 'LANG': 'en_US.UTF-8', 18:20:58 INFO - 'LANGUAGE': 'en_US:en', 18:20:58 INFO - 'LOGNAME': 'cltbld', 18:20:58 INFO - 'MAIL': '/var/mail/cltbld', 18:20:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:58 INFO - 'MOZ_NO_REMOTE': '1', 18:20:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:58 INFO - 'NO_EM_RESTART': '1', 18:20:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:58 INFO - 'PWD': '/builds/slave/test', 18:20:58 INFO - 'SHELL': '/bin/bash', 18:20:58 INFO - 'SHLVL': '1', 18:20:58 INFO - 'TERM': 'linux', 18:20:58 INFO - 'TMOUT': '86400', 18:20:58 INFO - 'USER': 'cltbld', 18:20:58 INFO - 'XDG_SESSION_COOKIE': '23f5739b2d6aa5d9caf5c428000001ae-1449973169.107041-1994106989', 18:20:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:58 INFO - '_': '/tools/buildbot/bin/python'} 18:20:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:20:58 INFO - Running setup.py (path:/tmp/pip-eyiZyZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:20:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:20:58 INFO - Running setup.py (path:/tmp/pip-XXUpG6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:20:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:20:58 INFO - Running setup.py (path:/tmp/pip-AnpF0G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:20:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:20:58 INFO - Running setup.py (path:/tmp/pip-KjGsRa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:20:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:20:58 INFO - Running setup.py (path:/tmp/pip-_zDmYh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:20:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:20:58 INFO - Running setup.py (path:/tmp/pip-_nnbxS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:20:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:20:58 INFO - Running setup.py (path:/tmp/pip-uXCMlP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:20:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:20:58 INFO - Running setup.py (path:/tmp/pip-0eoP5c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:20:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:20:59 INFO - Running setup.py (path:/tmp/pip-YBID_n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:20:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:20:59 INFO - Running setup.py (path:/tmp/pip-JVPEAp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:20:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:20:59 INFO - Running setup.py (path:/tmp/pip-VjfbDd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:20:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:20:59 INFO - Running setup.py (path:/tmp/pip-Zc1wNc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:20:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:20:59 INFO - Running setup.py (path:/tmp/pip-MS5IG0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:20:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:20:59 INFO - Running setup.py (path:/tmp/pip-x6KbOc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:20:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:20:59 INFO - Running setup.py (path:/tmp/pip-8Y1SWX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:20:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:20:59 INFO - Running setup.py (path:/tmp/pip-5vKTR7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:20:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:20:59 INFO - Running setup.py (path:/tmp/pip-nvGGQs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:20:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:20:59 INFO - Running setup.py install for manifestparser 18:20:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:20:59 INFO - Running setup.py install for mozcrash 18:20:59 INFO - Running setup.py install for mozdebug 18:21:00 INFO - Running setup.py install for mozdevice 18:21:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:21:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:21:00 INFO - Running setup.py install for mozfile 18:21:00 INFO - Running setup.py install for mozhttpd 18:21:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:21:00 INFO - Running setup.py install for mozinfo 18:21:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:21:00 INFO - Running setup.py install for mozInstall 18:21:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:21:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:21:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:21:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:21:00 INFO - Running setup.py install for mozleak 18:21:00 INFO - Running setup.py install for mozlog 18:21:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:21:00 INFO - Running setup.py install for moznetwork 18:21:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:21:00 INFO - Running setup.py install for mozprocess 18:21:00 INFO - Running setup.py install for mozprofile 18:21:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:21:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:21:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:21:01 INFO - Running setup.py install for mozrunner 18:21:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:21:01 INFO - Running setup.py install for mozscreenshot 18:21:01 INFO - Running setup.py install for moztest 18:21:01 INFO - Running setup.py install for mozversion 18:21:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:21:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:21:01 INFO - Cleaning up... 18:21:01 INFO - Return code: 0 18:21:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:21:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:21:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:21:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:21:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:21:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6d313c7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeb1240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf400b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf40fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf41440>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '23f5739b2d6aa5d9caf5c428000001ae-1449973169.107041-1994106989', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:21:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:21:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:21:01 INFO - Using env: {'DISPLAY': ':0', 18:21:01 INFO - 'HOME': '/home/cltbld', 18:21:01 INFO - 'LANG': 'en_US.UTF-8', 18:21:01 INFO - 'LANGUAGE': 'en_US:en', 18:21:01 INFO - 'LOGNAME': 'cltbld', 18:21:01 INFO - 'MAIL': '/var/mail/cltbld', 18:21:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:01 INFO - 'MOZ_NO_REMOTE': '1', 18:21:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:21:01 INFO - 'NO_EM_RESTART': '1', 18:21:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:21:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:01 INFO - 'PWD': '/builds/slave/test', 18:21:01 INFO - 'SHELL': '/bin/bash', 18:21:01 INFO - 'SHLVL': '1', 18:21:01 INFO - 'TERM': 'linux', 18:21:01 INFO - 'TMOUT': '86400', 18:21:01 INFO - 'USER': 'cltbld', 18:21:01 INFO - 'XDG_SESSION_COOKIE': '23f5739b2d6aa5d9caf5c428000001ae-1449973169.107041-1994106989', 18:21:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:01 INFO - '_': '/tools/buildbot/bin/python'} 18:21:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:21:01 INFO - Running setup.py (path:/tmp/pip-lBBqq6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:21:01 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:21:01 INFO - Running setup.py (path:/tmp/pip-ncXASW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:21:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:21:01 INFO - Running setup.py (path:/tmp/pip-wjJ6JC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:21:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:21:01 INFO - Running setup.py (path:/tmp/pip-OugcBQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:21:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:21:02 INFO - Running setup.py (path:/tmp/pip-KVQXuo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:21:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:21:02 INFO - Running setup.py (path:/tmp/pip-GDIt5x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:21:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:21:02 INFO - Running setup.py (path:/tmp/pip-2tzTmh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:21:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:21:02 INFO - Running setup.py (path:/tmp/pip-wkkE4X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:21:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:21:02 INFO - Running setup.py (path:/tmp/pip-boG0pl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:21:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:21:02 INFO - Running setup.py (path:/tmp/pip-kXgyh_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:21:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:21:02 INFO - Running setup.py (path:/tmp/pip-HGHrBW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:21:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:21:02 INFO - Running setup.py (path:/tmp/pip-Sz_oOn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:21:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:21:02 INFO - Running setup.py (path:/tmp/pip-WqYPDA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:21:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:21:02 INFO - Running setup.py (path:/tmp/pip-rLgtzZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:21:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:21:02 INFO - Running setup.py (path:/tmp/pip-HByR0r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:21:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:21:02 INFO - Running setup.py (path:/tmp/pip-yLBXS8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:21:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:21:02 INFO - Running setup.py (path:/tmp/pip-VS136Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:21:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:21:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:21:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:21:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:21:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:21:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:21:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:21:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:21:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:21:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:21:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:21:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:21:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:21:04 INFO - Downloading blessings-1.5.1.tar.gz 18:21:04 INFO - Storing download in cache at /builds/slave/test/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 18:21:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:21:04 INFO - Installing collected packages: blessings 18:21:04 INFO - Running setup.py install for blessings 18:21:04 INFO - Successfully installed blessings 18:21:04 INFO - Cleaning up... 18:21:04 INFO - Return code: 0 18:21:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:21:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:21:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:21:05 INFO - Reading from file tmpfile_stdout 18:21:05 INFO - Current package versions: 18:21:05 INFO - argparse == 1.2.1 18:21:05 INFO - blessings == 1.5.1 18:21:05 INFO - blobuploader == 1.2.4 18:21:05 INFO - docopt == 0.6.1 18:21:05 INFO - manifestparser == 1.1 18:21:05 INFO - mozInstall == 1.12 18:21:05 INFO - mozcrash == 0.16 18:21:05 INFO - mozdebug == 0.1 18:21:05 INFO - mozdevice == 0.47 18:21:05 INFO - mozfile == 1.2 18:21:05 INFO - mozhttpd == 0.7 18:21:05 INFO - mozinfo == 0.9 18:21:05 INFO - mozleak == 0.1 18:21:05 INFO - mozlog == 3.1 18:21:05 INFO - moznetwork == 0.27 18:21:05 INFO - mozprocess == 0.22 18:21:05 INFO - mozprofile == 0.27 18:21:05 INFO - mozrunner == 6.11 18:21:05 INFO - mozscreenshot == 0.1 18:21:05 INFO - mozsystemmonitor == 0.0 18:21:05 INFO - moztest == 0.7 18:21:05 INFO - mozversion == 1.4 18:21:05 INFO - psutil == 3.1.1 18:21:05 INFO - requests == 1.2.3 18:21:05 INFO - wsgiref == 0.1.2 18:21:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:21:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:21:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:21:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:21:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:21:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6d313c7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeb1240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf400b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf40fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf41440>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '23f5739b2d6aa5d9caf5c428000001ae-1449973169.107041-1994106989', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:21:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:21:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:21:05 INFO - Using env: {'DISPLAY': ':0', 18:21:05 INFO - 'HOME': '/home/cltbld', 18:21:05 INFO - 'LANG': 'en_US.UTF-8', 18:21:05 INFO - 'LANGUAGE': 'en_US:en', 18:21:05 INFO - 'LOGNAME': 'cltbld', 18:21:05 INFO - 'MAIL': '/var/mail/cltbld', 18:21:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:05 INFO - 'MOZ_NO_REMOTE': '1', 18:21:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:21:05 INFO - 'NO_EM_RESTART': '1', 18:21:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:21:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:05 INFO - 'PWD': '/builds/slave/test', 18:21:05 INFO - 'SHELL': '/bin/bash', 18:21:05 INFO - 'SHLVL': '1', 18:21:05 INFO - 'TERM': 'linux', 18:21:05 INFO - 'TMOUT': '86400', 18:21:05 INFO - 'USER': 'cltbld', 18:21:05 INFO - 'XDG_SESSION_COOKIE': '23f5739b2d6aa5d9caf5c428000001ae-1449973169.107041-1994106989', 18:21:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:05 INFO - '_': '/tools/buildbot/bin/python'} 18:21:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:21:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:21:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:21:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:21:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:21:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:21:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:21:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:21:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:21:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:21:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:21:05 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:21:05 INFO - Cleaning up... 18:21:05 INFO - Return code: 0 18:21:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:21:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:21:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:21:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:21:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:21:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6d313c7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeb1240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf400b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf40fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf41440>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '23f5739b2d6aa5d9caf5c428000001ae-1449973169.107041-1994106989', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:21:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:21:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:21:05 INFO - Using env: {'DISPLAY': ':0', 18:21:05 INFO - 'HOME': '/home/cltbld', 18:21:05 INFO - 'LANG': 'en_US.UTF-8', 18:21:05 INFO - 'LANGUAGE': 'en_US:en', 18:21:05 INFO - 'LOGNAME': 'cltbld', 18:21:05 INFO - 'MAIL': '/var/mail/cltbld', 18:21:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:05 INFO - 'MOZ_NO_REMOTE': '1', 18:21:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:21:05 INFO - 'NO_EM_RESTART': '1', 18:21:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:21:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:05 INFO - 'PWD': '/builds/slave/test', 18:21:05 INFO - 'SHELL': '/bin/bash', 18:21:05 INFO - 'SHLVL': '1', 18:21:05 INFO - 'TERM': 'linux', 18:21:05 INFO - 'TMOUT': '86400', 18:21:05 INFO - 'USER': 'cltbld', 18:21:05 INFO - 'XDG_SESSION_COOKIE': '23f5739b2d6aa5d9caf5c428000001ae-1449973169.107041-1994106989', 18:21:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:05 INFO - '_': '/tools/buildbot/bin/python'} 18:21:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:21:05 INFO - Downloading/unpacking jsonschema 18:21:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:21:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:21:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:21:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:21:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:21:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:21:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:21:07 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 18:21:07 INFO - Downloading/unpacking functools32 (from jsonschema) 18:21:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:21:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:21:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:21:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:21:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:21:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:21:07 INFO - Downloading functools32-3.2.3-2.tar.gz 18:21:07 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 18:21:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:21:07 INFO - warning: no files found matching '*.txt' 18:21:07 INFO - no previously-included directories found matching 'build' 18:21:07 INFO - no previously-included directories found matching 'dist' 18:21:07 INFO - no previously-included directories found matching '.git*' 18:21:07 INFO - Installing collected packages: jsonschema, functools32 18:21:07 INFO - Running setup.py install for functools32 18:21:07 INFO - warning: no files found matching '*.txt' 18:21:07 INFO - no previously-included directories found matching 'build' 18:21:07 INFO - no previously-included directories found matching 'dist' 18:21:07 INFO - no previously-included directories found matching '.git*' 18:21:07 INFO - Successfully installed jsonschema functools32 18:21:07 INFO - Cleaning up... 18:21:07 INFO - Return code: 0 18:21:07 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:21:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:21:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:21:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:21:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:21:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6d313c7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xde38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeb1240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf400b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf40fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf41440>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '23f5739b2d6aa5d9caf5c428000001ae-1449973169.107041-1994106989', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:21:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 18:21:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 18:21:07 INFO - Using env: {'DISPLAY': ':0', 18:21:07 INFO - 'HOME': '/home/cltbld', 18:21:07 INFO - 'LANG': 'en_US.UTF-8', 18:21:07 INFO - 'LANGUAGE': 'en_US:en', 18:21:07 INFO - 'LOGNAME': 'cltbld', 18:21:07 INFO - 'MAIL': '/var/mail/cltbld', 18:21:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:07 INFO - 'MOZ_NO_REMOTE': '1', 18:21:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:21:07 INFO - 'NO_EM_RESTART': '1', 18:21:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:21:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:07 INFO - 'PWD': '/builds/slave/test', 18:21:07 INFO - 'SHELL': '/bin/bash', 18:21:07 INFO - 'SHLVL': '1', 18:21:07 INFO - 'TERM': 'linux', 18:21:07 INFO - 'TMOUT': '86400', 18:21:07 INFO - 'USER': 'cltbld', 18:21:07 INFO - 'XDG_SESSION_COOKIE': '23f5739b2d6aa5d9caf5c428000001ae-1449973169.107041-1994106989', 18:21:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:07 INFO - '_': '/tools/buildbot/bin/python'} 18:21:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:21:07 INFO - Downloading/unpacking flake8 18:21:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:21:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:21:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:21:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:21:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:21:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:21:09 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:21:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fflake8-2.5.0-py2.py3-none-any.whl 18:21:09 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:21:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:21:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:21:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:21:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:21:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:21:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:21:09 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:21:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmccabe-0.3.1-py2.py3-none-any.whl 18:21:09 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:21:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:21:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:21:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:21:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:21:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:21:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:21:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpyflakes-1.0.0-py2.py3-none-any.whl 18:21:09 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:21:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:21:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:21:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:21:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:21:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:21:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:21:10 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 18:21:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:21:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:21:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:21:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:21:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:21:10 INFO - no previously-included directories found matching 'docs/_build' 18:21:10 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:21:10 INFO - Running setup.py install for pep8 18:21:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:21:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:21:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:21:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:21:10 INFO - no previously-included directories found matching 'docs/_build' 18:21:10 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:21:10 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:21:10 INFO - Cleaning up... 18:21:10 INFO - Return code: 0 18:21:10 INFO - Running post-action listener: _resource_record_post_action 18:21:10 INFO - Running post-action listener: _start_resource_monitoring 18:21:10 INFO - Starting resource monitoring. 18:21:10 INFO - ##### 18:21:10 INFO - ##### Running install step. 18:21:10 INFO - ##### 18:21:10 INFO - Running pre-action listener: _resource_record_pre_action 18:21:10 INFO - Running main action method: install 18:21:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:21:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:21:10 INFO - Reading from file tmpfile_stdout 18:21:10 INFO - Detecting whether we're running mozinstall >=1.0... 18:21:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:21:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:21:10 INFO - Reading from file tmpfile_stdout 18:21:10 INFO - Output received: 18:21:10 INFO - Usage: mozinstall [options] installer 18:21:10 INFO - Options: 18:21:10 INFO - -h, --help show this help message and exit 18:21:10 INFO - -d DEST, --destination=DEST 18:21:10 INFO - Directory to install application into. [default: 18:21:10 INFO - "/builds/slave/test"] 18:21:10 INFO - --app=APP Application being installed. [default: firefox] 18:21:10 INFO - mkdir: /builds/slave/test/build/application 18:21:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:21:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 18:21:25 INFO - Reading from file tmpfile_stdout 18:21:25 INFO - Output received: 18:21:25 INFO - /builds/slave/test/build/application/firefox/firefox 18:21:25 INFO - Running post-action listener: _resource_record_post_action 18:21:25 INFO - ##### 18:21:25 INFO - ##### Running run-tests step. 18:21:25 INFO - ##### 18:21:25 INFO - Running pre-action listener: _resource_record_pre_action 18:21:25 INFO - Running main action method: run_tests 18:21:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:21:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:21:25 INFO - Python 2.7.3 18:21:25 INFO - Return code: 0 18:21:25 INFO - grabbing minidump binary from tooltool 18:21:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:21:25 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf400b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf40fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf41440>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:21:25 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:21:25 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 18:21:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:21:25 INFO - Return code: 0 18:21:25 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:21:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:21:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:21:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:21:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:21:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:21:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:21:26 INFO - Return code: 0 18:21:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'B2G-Inbound-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/P0lPiNyARI6ZkYZyxsdEyg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--webServer', 'localhost'] in /builds/slave/test/build 18:21:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --debug --branchName B2G-Inbound-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/P0lPiNyARI6ZkYZyxsdEyg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-030 --webServer localhost 18:21:26 INFO - Using env: {'DISPLAY': ':0', 18:21:26 INFO - 'HOME': '/home/cltbld', 18:21:26 INFO - 'LANG': 'en_US.UTF-8', 18:21:26 INFO - 'LANGUAGE': 'en_US:en', 18:21:26 INFO - 'LOGNAME': 'cltbld', 18:21:26 INFO - 'MAIL': '/var/mail/cltbld', 18:21:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:21:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:21:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:26 INFO - 'MOZ_NO_REMOTE': '1', 18:21:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:21:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:21:26 INFO - 'NO_EM_RESTART': '1', 18:21:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:21:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:26 INFO - 'PWD': '/builds/slave/test', 18:21:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:21:26 INFO - 'SHELL': '/bin/bash', 18:21:26 INFO - 'SHLVL': '1', 18:21:26 INFO - 'TERM': 'linux', 18:21:26 INFO - 'TMOUT': '86400', 18:21:26 INFO - 'USER': 'cltbld', 18:21:26 INFO - 'XDG_SESSION_COOKIE': '23f5739b2d6aa5d9caf5c428000001ae-1449973169.107041-1994106989', 18:21:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:26 INFO - '_': '/tools/buildbot/bin/python'} 18:21:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'B2G-Inbound-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/P0lPiNyARI6ZkYZyxsdEyg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--webServer', 'localhost'] with output_timeout 3600 18:21:26 INFO - mozversion INFO | application_buildid: 20151212170331 18:21:26 INFO - mozversion INFO | application_changeset: f8168f43b47fb3e1c18b25f91b014006aa092e1b 18:21:26 INFO - mozversion INFO | application_display_name: Nightly 18:21:26 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:21:26 INFO - mozversion INFO | application_name: Firefox 18:21:26 INFO - mozversion INFO | application_remotingname: firefox 18:21:26 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/b2g-inbound 18:21:26 INFO - mozversion INFO | application_vendor: Mozilla 18:21:26 INFO - mozversion INFO | application_version: 45.0a1 18:21:26 INFO - mozversion INFO | platform_buildid: 20151212170331 18:21:26 INFO - mozversion INFO | platform_changeset: f8168f43b47fb3e1c18b25f91b014006aa092e1b 18:21:26 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/b2g-inbound 18:21:26 INFO - mozversion INFO | platform_version: 45.0a1 18:21:26 INFO - 2015-12-12 18:21:26,529 DEBUG : using testdate: 1449973286 18:21:26 INFO - 2015-12-12 18:21:26,529 DEBUG : actual date: 1449973286 18:21:26 INFO - 2015-12-12 18:21:26,537 INFO : starting webserver on 'localhost:60098' 18:21:26 INFO - 2015-12-12 18:21:26,538 INFO : Starting test suite talos-linux64-ix-030 18:21:26 INFO - 2015-12-12 18:21:26,538 INFO : Starting test dromaeo_css 18:21:26 INFO - 2015-12-12 18:21:26,538 DEBUG : operating with platform_type : linux_ 18:21:26 INFO - 2015-12-12 18:21:26,538 INFO : Initialising browser for dromaeo_css test... 18:21:26 INFO - 2015-12-12 18:21:26,545 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1VC_4Z/profile http://localhost:60098/getInfo.html 18:21:30 INFO - 2015-12-12 18:21:30,928 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:21:30 INFO - 2015-12-12 18:21:30,928 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:21:30 INFO - 2015-12-12 18:21:30,929 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:21:30 INFO - 2015-12-12 18:21:30,929 DEBUG : BROWSER_OUTPUT: __metrics 18:21:31 INFO - 2015-12-12 18:21:31,050 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 18:21:31 INFO - 2015-12-12 18:21:31,110 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:21:32 INFO - 2015-12-12 18:21:32,366 INFO : Browser initialized. 18:21:32 INFO - 2015-12-12 18:21:32,367 INFO : Running cycle 1/1 for dromaeo_css test... 18:21:32 INFO - 2015-12-12 18:21:32,367 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1VC_4Z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:21:44 INFO - 2015-12-12 18:21:44,428 DEBUG : BROWSER_OUTPUT: RSS: Main: 142753792 18:21:44 INFO - 2015-12-12 18:21:44,428 DEBUG : BROWSER_OUTPUT: 18:22:35 INFO - 2015-12-12 18:22:35,640 DEBUG : BROWSER_OUTPUT: 1449973355640 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 18:24:47 INFO - 2015-12-12 18:24:47,589 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60098/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60098/tests/dromaeo/cssquery-ext.html) 18:24:48 INFO - 2015-12-12 18:24:48,754 DEBUG : BROWSER_OUTPUT: RSS: Main: 242298880 18:24:48 INFO - 2015-12-12 18:24:48,754 DEBUG : BROWSER_OUTPUT: 18:27:51 INFO - 2015-12-12 18:27:51,250 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60098/tests/dromaeo/cssquery-ext.html (next: http://localhost:60098/tests/dromaeo/cssquery-jquery.html) 18:27:51 INFO - 2015-12-12 18:27:51,831 DEBUG : BROWSER_OUTPUT: RSS: Main: 221409280 18:27:51 INFO - 2015-12-12 18:27:51,831 DEBUG : BROWSER_OUTPUT: 18:30:53 INFO - 2015-12-12 18:30:53,950 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60098/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60098/tests/dromaeo/cssquery-mootools.html) 18:30:54 INFO - 2015-12-12 18:30:54,524 DEBUG : BROWSER_OUTPUT: RSS: Main: 212602880 18:30:54 INFO - 2015-12-12 18:30:54,525 DEBUG : BROWSER_OUTPUT: 18:33:56 INFO - 2015-12-12 18:33:56,486 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60098/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60098/tests/dromaeo/cssquery-prototype.html) 18:33:57 INFO - 2015-12-12 18:33:57,052 DEBUG : BROWSER_OUTPUT: RSS: Main: 204054528 18:33:57 INFO - 2015-12-12 18:33:57,052 DEBUG : BROWSER_OUTPUT: 18:36:59 INFO - 2015-12-12 18:36:59,725 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60098/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60098/tests/dromaeo/cssquery-yui.html) 18:37:00 INFO - 2015-12-12 18:37:00,311 DEBUG : BROWSER_OUTPUT: RSS: Main: 208003072 18:37:00 INFO - 2015-12-12 18:37:00,311 DEBUG : BROWSER_OUTPUT: 18:40:02 INFO - 2015-12-12 18:40:02,787 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60098/tests/dromaeo/cssquery-yui.html (next: http://localhost:60098/tests/dromaeo/cssquery-dojo.html) 18:40:03 INFO - 2015-12-12 18:40:03,037 DEBUG : BROWSER_OUTPUT: RSS: Main: 251662336 18:40:03 INFO - 2015-12-12 18:40:03,037 DEBUG : BROWSER_OUTPUT: 18:40:03 INFO - 2015-12-12 18:40:03,038 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:40:03 INFO - 2015-12-12 18:40:03,039 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:40:03 INFO - 2015-12-12 18:40:03,039 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:40:03 INFO - 2015-12-12 18:40:03,039 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:40:03 INFO - 2015-12-12 18:40:03,042 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2622.436176222112;2714.5656694928816;2725.3459169799253;2732.54912939365;2735.3024978630433;4509.233430316845;4657.3945387099675;4660.067986402716;4735.289706544016;4739.834105806298;4565.65757568182;4657.0453057123295;4670.696404733699;4675.672702910798;4808.855734327975;4282.9357559636555;4350.58669426404;4372.693911426201;4389.078293558357;4465.173942820572;4210.62104410603;4447.1995040892625;4451.065276291983;4497.887552811173;4509.526499717532;4238.173556156551;4281.5290318064835;4297.03316753731;4322.286822674255;4326.178026175016;4284.485861696585;4286.721363111419;4296.742195468282;4308.159908817782;4337.284348082562;4279.422277992462;4281.871543853689;4293.892652683677;4325.82696692132;4333.133373325348;4430.534793846632;4509.684322097454;4529.6376289896725;4536.387587675655;4539.387182730356;4995.975020124913;5046.167382381903;5063.443021267658;5073.7209453480045;5074.035933172722;5080.415752188509;5099.4900509949075;5106.336176297094;5108.64239503235;5147;4490.371348011281;4612.76936153191;4614.146382919171;4705.71765694056;4725.07860967115;5053.772580233898;5132.409772876167;5156.355455568054;5172.922406163911;5177.171652535576;4929.556339929423;4930.876728081755;4949.975250123726;4954.256861470808;4996.600271978233;4814.518548145227;5030.4718004608985;5036.471170527115;5042.394912610437;5043.87390315245;4843.975780121147;4848.296996935495;4874.805007799649;4935.802567897315;4981.327520784647;4983.053219888209;5019.52314530119;5026.220935754964;5027.0197311524635;5037.29477873098;3987.0829709167056;4195.685323600742;4205.642520385741;4205.663546916239;4248.617624413818;3590.2998915211283;3653.4337177737493;3678.6873115785456;3685.336639404933;3688.0226739913946;4346.7609281489185;4352.499462561846;4360.302351623725;4371.147626212858;4405.052913623586;5081.822136225214;5085.338905942203;5087.720175390315;5097.617678674114;5139.280500729901;4141.068259641616;4231.069164783743;4231.682623803227;4239.385289133058;4346.543612920661;4448.354988526646;4466.0844526871915;4476;4477.3731677565165;4515.841945531891;4785.377900872864;4787.4494433140635;4789.856304310807;4795.568398844121;4831.106245344622;4819.59033482158;4924.926126108039;4934.629902757236;4972.105021096238;5068.949310506848;4803.855884323476;4809.5430934061205;4830.2513110467935;4831.975840120777;4874.098291816025;5018;5021.774020169174;5024.648274620741;5030.522100400456;5073.11220536412;4773.9761301193275;4858.611311095175;4904.852854414374;4921.360223171108;5011.649184557192;4908.361912951294;4920.114379411741;4920.975395123145;4930.186519224431;4967.279744436964;4912.975435122801;4914.8771280718265;4917.877053073702;4926.926096108489;4970.62720295972;4676.719396836201;4681.625469962463;4756.619470442425;4798.0403919217;4807.375041244616;4941.703497790144;4963.900721985612;4971.701697898137;4983.327250821238;4989.027139707868;4842.854714358575;4853.708777473363;4858.708477491362;4861.878453038702;4896.2655601660035 18:40:03 INFO - 2015-12-12 18:40:03,043 DEBUG : BROWSER_OUTPUT: |1;ext.html;583.6848102024907;583.9883202335953;584.5060923519625;596.3738075021226;597.250450684391;26487.337816554595;27111.457770844598;35111.297774044535;35432.82283588585;35538.2892342153;39952.80023599878;40229;40421;40491.39262911059;40536;6921.515493915428;7847.254510821463;7980.840383192332;7992.880106798402;7993.1607181246;7125.786226413215;10172.084512393883;10456.58173673052;10463.372197668126;10493.527791249413;9979.75050623733;10089.243306751987;10092.142167915736;10094.444805535693;10098.9495052525;7234.60209688467;7461.477696561243;9938.55276512555;10082.798344033115;10247.180225581935;9695.806083878353;9827.164691001273;9828.459434731087;9829.852552211723;9842.507874606312;35699.28601427957;36469;36537.634623653954;36558.45162322567;36582.45126323107;2889.0754958413318;2957.8668959896854;3044.984775076133;3099.6435409927694;3565.9658698977264;27396.863015684994;27463;27605.171844844484;27620;28290;3364.5794275715534;3392.6098498672723;3397.711194548466;3465.7573969822124;3543.6810687038032;209341;210050;210061;210525;210683;50157.49842501601;51396;53304.667383314954;53562.464375356525;53897.730511347974;45746.31380529295;45890.31164532468;45915.311270330974;46053;46059.53940460619;12557.811632825515;12570.120091593684;12697.809532857014;12723.681907952376;12725.172863763826;8173.060098088796;8179.713710020121;8183.181681831769;8187.38594605407;8245.752627421187;17963.640727185644;18036.90981545084;18090.18594163266;18181.81818181828;18190.363337283397;7260.891086633705;7499.21258267885;7518.736844210536;7571.659275332623;7597.392208623297;831.8128421105324;884.5002573545926;887.316766090107;887.6050157679771;986.2800155886243;25634;25669.61495577568;25708.48583028329;25810.225693229233;25984.480310394065;978.1148061004802;1000.9299349045497;1029.8197815382277;1045.132539991805;1054.3463052907246;13552.254625995665;13730;13734.381952812148;13832.100913440594;13878.167309961435;253552;255030;256720;257218;259016;44593;44856.55143448589;44914;44914.55085449103;44937.55062449399;1068.7167900506372;1117.6311817100338;1118.8321751737305;1130.559081958037;1221.7190046289404;22565.43586410353;22808.97359618788;23566.764332356455;23599;23612.527749444573;33546.66453335436;35081;35099.29801404009;35123;35144;299041;300588;331128;338792;338896;25763;25766.355841103374;25850.612240816026;25854.61218081768;25869.611955820303;35128.82435587908;35185.64814351926;35720.82139589285;35868.820655896554;36086.63913360938;30264.69735302707;31112.377752445278;31340;32755;33015.33969320552;29975.55036674408;31268.530972035893;31466.685333146375;31508.684913150573;31682.841585791924;31871.362572747952;32206.35587288288;32289;32686.83656581702;32728.836355818068;25214.495710086063;25530.872345638152;27048.459030819668;27540.144379786445;27770.611469426043;21182.364529064154;21347.25284615031;21353.145874165482;21421.678674819577;21424.250151244632 18:40:03 INFO - 2015-12-12 18:40:03,043 DEBUG : BROWSER_OUTPUT: |2;jquery.html;336.80128724052804;337.5561137104707;337.7382528540379;337.78719406773735;339.5467051486266;12611;13007.47970081198;15046.623834404145;15209.543713688594;16895.155242237885;12176.93911530441;14538.273086345693;15222.619434514143;15641.217939103057;15663.60840978976;4867.245576935572;4956.157453232959;5010.949890501103;5118.564921981636;5120.513551212629;3510.052285882823;3633.3459977204093;5080.314157588734;5212.713300768456;5379.515843574078;5319.441458646845;5321.3348331458565;5331.6534425262425;5342.759575819078;5350.8127215547465;5182.455842136578;5307.628466007381;5316.787328506855;5320.654157479751;5322.627416080876;5077.213031980049;5085.79656813727;5124.308218390544;5129.692218466904;5133.204353325241;12127.878721212763;12159.209651372721;12166.878331216663;12168.57409990646;14502.347394367172;1466.9779953300708;1705.9744103838327;1832.8625353098437;1861.3020117455953;1909.1218039701753;9019.23336516397;9028.322875784277;9237.769055773591;10789.568417263301;12710.809337859939;1685.8482736553647;1687.5865412973778;1764.9117544122741;1785.0717626834103;1790.7313902914536;51972.22041669378;53919;54120.72939635316;55300.446995529936;55354.44645553533;9607.231421486264;9887.159591434665;9899.703008909744;9936.205103591696;9938;12232.877671223352;12234.510619575296;12244.265344079206;12246.75506489883;12275.877241227474;6502.447291980235;6608.504362172856;6623.172103487064;6679.398854103057;6680.465562754968;6130.080487926846;6158.507319414436;6199.907001394983;6288.717007734572;6297.779577714758;4186.267403204427;4191.11986482842;4199.853005144805;4212.283911735012;4212.557681443465;1611.7421212606162;1666.633340665076;1670.983290167107;1687.70465168595;1695.8643308535288;764.8661484240235;825.6078362777632;877.8200468903859;928.6099838068028;991.4101109839634;15468.690626187414;15639.68720625581;15969.121698306704;16207.756883646754;16241.350345986028;759.5784339691525;827.3091968206495;849.1847826086902;862.0732712334266;906.8458362078462;8510;8572.314214862796;8749.343799215085;8945.552722363856;9238.307126965505;492062;496853;498245;512038;524152;67998.66000669965;69544.6522767383;70417.64791176011;70750.2924970754;71395.57208855794;1088.3361149698678;1109.351029647649;1113.17068783755;1115.8660960684608;1118.3960661242838;14385.7842132366;14560.927195363955;14740.7151891457;14745.705085898437;15083.24583770829;33552.32895342031;33937.49093763546;34008.1497962543;34118.31763364768;34530.48204276988;42028.36957445702;42755;43293;43580.128397432505;46469.535304646524;8846.601902914514;8986.281097512314;9102.408343457653;9103.18071373553;9167.17495425415;10677.626283080366;10921.6723498292;10929.234953553176;10939.015488606065;10947.83578246311;11677.416129193676;13648.112872663245;14017.929910350384;14033.298335083;14148.363323650667;8769.298456123368;8934.642614295615;13338;13916;13968;7871.881921771293;8157.75526734199;9348.766280842761;9609;9772.071653192936;13150.27673477968;13725.107867988549;13829.239391833144;14172;14175.64560885986;14475.059121157092;14742.189179595225;14760.409583616534;14963.625909352353;15147.697046059238 18:40:03 INFO - 2015-12-12 18:40:03,044 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1246.5574720974048;1680.4706517447005;1702.412667629668;1733.9566510837228;1742.0766993493448;6007.429294217048;7063.470239732022;7261.201267860538;7263.709451621942;7275.090613673291;7169;7320.9633951830165;7329.596872172028;7334.963325183367;7346.8530629387515;1709.2564734340601;1742.2595396956306;2034.2269937423757;2058.650029494982;2062.102985201435;2099.7585277693106;2111.123883588309;2111.7360329958756;2112.5563631637374;2112.577484503098;1985.3249895035685;2014.879107253562;2017.5561376497146;2017.6872584749387;2049.9795002050014;2061.690746388039;2076.8961551922494;2088.3943656339643;2088.895555222233;2118.120979793384;2079.854410191287;2108.16727392679;2109.535902101535;2111.187192930717;2113.3871177358546;7260.636968151572;7360.447966402541;7363.926360736377;7365.2634736525915;7371.557706537625;4039.373897045933;4142.130152667944;4142.357934520154;4144.378343248507;4147.958520414818;2525.2802951158833;2601.531724289627;2605.674290713661;2609.4650596627644;2619.620155077503;777.747232149548;792.825578372757;800.3037357498955;852.0627309959058;852.211704173643;10002.549885255181;10004.699859004242;10005.04952029556;10014.64948726791;10018.3488073275;3797.9555622204116;3885.3977633466857;3894.4742459767567;3899.29812633723;3903.863364782219;8628.094050124771;8633.39566230358;8647.092055334226;8653.091425400367;8653.350998675125;4283.7429754214845;4287.721298115613;4295.935560966588;4302.182585308781;4305.138972205571;3924.136689928211;3928.4107383892647;3930.1550166713723;3934.291827471083;3939.290927632996;3007.789454738191;3066.708662677042;3077.39990701811;3125.9843700781353;3327;1058.2539309786582;1065.408698172506;1077.6120596585224;1091.1979694924225;1095.4960718069617;831.4470876866936;841.8526757817358;844.5566077809076;891.4473026723343;1031.4997226345217;6779.254282028973;6843.247242803288;6887.724491020404;6897.137857767779;6899.275576064541;1029.7734498410339;1041.6458404142627;1043.7181960870673;1056.1920131099714;1066.168388656849;5291.470852914755;5354;5357.624966252325;5360.2227676987395;5361.35663720356;8407.915920840713;8502.574871256413;8779;8819;8842.602082906285;8568.57157142153;8626.18051285127;8642.567871606394;8663.870041949376;8704.564771761387;6798.864022719518;6803.251642319341;6907.585544867224;6926.619035953071;6944.097267355211;6562.606243625398;6589.374009469093;6597.307282735435;6606.240282367589;6613.570117942319;7664.73173438927;7807.531548107132;7837.49056311338;7937.32532734724;7978.521288722463;7897.763067107996;7991.4006449515355;8041.8391632168195;8142.7557173284895;8189.713360032609;6460.095586617783;6488.643124628191;6504.544681872224;6515.250746164252;6515.478761698958;5841.970790146022;6167.907481387872;6342.460890824332;6495.253045899782;6513;6167;6247.843803904939;6307.30619631831;6370.74517019314;6442.549021568632;6124.785632502841;6215.9378406215355;6328.493720502257;6334.986402175629;6537.738490460519;6457.773977910939;6500.349965003462;6611.239707433514;6614.10709554204;6618.536702430783;5999.4600485954725;6025.608335458356;6033.547483938634;6034;6044.969775151272;6131.110988906495;6134.754609815557;6175.814725558241;6190.442860142609;6264.2796078451565 18:40:03 INFO - 2015-12-12 18:40:03,045 DEBUG : BROWSER_OUTPUT: |4;prototype.html;293.7855365583124;300.28981458849825;303.8040463900784;306.3276108940873;307.62623412553745;5300.204969254609;5347.973260133704;5355.732213389329;5417.3770016448125;5431;5279.868003299919;5312.681239125655;5314.787408503665;5315.813946511873;5319.627626066177;3967.5437324707596;4189.7695626740515;4199.6220340169375;4202.138561594881;4206.453161089062;4054.108096218827;4078.9184216315657;4162.562930892258;4168.583141685837;4181.665466762667;4110.815013324408;4114.567970363114;4117.526484454281;4119.917601647965;4135.028268356934;3927.764334139932;4015.9799201003807;4035.1727895781296;4121.608447197512;4124.113315637137;3688.944665830015;3695.7782533048103;3700.3154416432776;3700.8889733307774;3701.68535674468;5102.311187989648;5102.311187989648;5107.540321371094;5114.539691427789;5116.667416617908;4748.857534273978;4754.904901901942;4759.7620118994255;4762.666613337068;4763.333133361332;5459.181122831607;5594.992901277768;5596.300462442195;5603.635763675389;5621.690807005613;3984.183242435294;3985.60143985602;3988;3988.8603898863403;3989.6608788253034;5794.652320860761;5887.499562537189;5895.263092113486;5904.7342869570975;5909;4126.587341265849;4130.359794231899;4131.338985762263;4131.752094874317;4132.40080188371;4060.2082593894497;4181.560936101726;4190.350495673176;4194.475690538683;4198.328267477189;4739.95260047402;4740.312654665123;4741.336212930192;4745.217039188564;4745.572898439088;5604.887902242014;5610.85972850682;5615.241942337732;5616.859578510488;5621.213030175779;1468.3098943496539;1485.1608841004797;1485.2647939269914;1485.9851401486062;1486.8810495160578;1012.4026824173773;1015.6496008876926;1018.7300365403174;1035.5598870480017;1040.0535512683423;4905.632077594123;4925.285833554186;4931.580815630711;4933.112039832865;4933.383327084114;5827.825165245049;5833.620814647034;5834.504067154255;5836.066229403275;5836.241288632451;4918.877028074256;4922.704637721677;4929.901401971941;4931.284963680247;4935.407751069823;3077.215310095956;3124.109628755794;3145.7168854803176;3148.2916343822912;3150.054983504939;5514.035043867388;5574.832755017356;5575.358833734173;5581.525570326487;5582.7766889323975;6042.184305118841;6044.697765111727;6048.879022419615;6052.394760523913;6055.485283750842;5557.36090349615;5561.555075593943;5563.721813909288;5564.554835613222;5565.081761509306;5732.369439361751;5880.970595147168;5882.235309409921;5885.793997210077;5889.175515427929;5730.541556675539;5734.770609175586;5735.627184233012;5741.3397459291045;5743.712814359349;5556.611037227355;5557.722113894209;5557.944420555905;5559.861003474945;5563.638363506359;3915.2169566086327;3977.92044159121;3981.7610943343493;4017.2568074906235;4017.6183262590007;5857.736401862012;5857.941420585739;5859.619124756877;5863.706814659165;5865.266841644794;5631.774729010794;5637.46444087811;5640.351359593536;5642.605017648725;5648.604597678287;5510.779568817363;5636.0418728815375;5643.266375371176;5644;5646.181303711021;5505.807296744595;5506.91739623898;5514.172874068921;5514.972425137848;5520.585956053232;5458.754356054066;5577.888442231051;5658.575606829587;5684.459976302244;5687.289088863892;5431.131019036935;5443.401225865073;5447.12845944647;5447.863803404946;5450.563954883679 18:40:03 INFO - 2015-12-12 18:40:03,046 DEBUG : BROWSER_OUTPUT: |5;yui.html;689.5414549324697;691.6438034412279;694.482610455211;698.3749544157982;698.6890833579058;11762.529498820048;11992.940035299833;12016.63950081498;12038.277703337806;12041.939790301036;11603.07175425966;11643.767124657501;11644.24312419692;11671.649850504498;11697.59058432955;453.9841105561306;456.1151366349272;460.2842579788428;785.1245860865137;802.0936341933607;621.7512994802092;626.880892630401;785.4972817396871;800.3517151107592;803.7428023032631;846.4075147396816;847.817719190374;854.3506934729588;854.3848429131016;854.7008547008528;648.9188851393576;839.3285371702674;841.6549214821924;841.7180244618061;842.5197637346715;484.0899109673826;609.2567068176861;620.5780069552702;620.7765204526366;621.6549814852755;11262.943685281522;11363.715907102305;11373.488193031251;11374.71563210918;11387.658370248906;10683.679489615324;11635.709107272301;11777.352245626487;11822.054235661126;12081.577144799978;8970.461772293684;8991.955040224822;9050.818983620356;9145;9249.35254532183;3301.1747063234193;3336.4995250712345;3344.0469466202017;3345.782524135948;3346.2638219591895;37200;37343;37446;37692.43461348082;37695.24609507822;9077.863832042525;9292.814143717087;9325.90674093264;9373.90626093744;9455.243580513543;16679.66640667204;16688.916555417145;16704.74942875858;16713.665726685642;17049.659006819795;6328.905066424008;6346;6429.324920883333;6511.967440162769;6595.571287866273;3244.0916543367894;3345.347657206821;3372.3392621019398;3406.880759173417;3408.301298233856;2561.5133124706244;2578.059008461898;2587.8706064697067;2594.1439325023075;2604.2187343796786;451.4447229907233;455.97036192648034;458.0175523502069;462.5536357415111;462.64607575204997;504.26125725811863;572.4618858273293;613.6778191449436;621.6114928169894;621.8479894077545;7646.3882889368715;7732.381409487228;7851.175626559243;7897.24976127267;8027.638756255982;452.69669321553977;570.9209593867594;589.3899813692807;605.7274226598048;607.6809674920613;3540.238848647553;3564.625714300013;3635.4546817976984;3728.664420202195;3748.381517049711;47449.76275118603;47914.52085479101;48063;48086.27870581944;48244.27633585499;42530.57469425328;42843.7362725131;44232.778836106256;44296.77851610721;44399.55600443954;39377.21245575073;39918.600813991485;40167;40665.796671016455;40712.38931416031;7200.855982880418;7300.415966722543;7431.479796414199;7455.850882982419;7515;29541.85229073841;30052.24869378283;30193.396132077676;30374.240643984078;30785;35738.463923041694;35980.64019359773;38475.42286865755;38639.80680096582;39090.60909390869;9479.47862867549;9512.667056652985;9538.904610953801;9580.76048098803;9616.95191524038;10110.140638045848;10308.175345972155;10321.122704570196;10331.638392656221;10394.428306443218;9350.67272645454;9461.952690236505;9525.428474291564;9612.903870961482;9993.10062094415;9607.375520591138;9718.173955213886;9733.659321923698;9853;10092.293539452168;9778.315517913963;9882.258830587878;9883.703488895344;9889.50552472388;9906.554205060645;8203.9589802053;8240.175982401952;8307.933639784513;8417.681989868315;8514.148585141436;8954.552272386485;9083.409578377385;9274.397164184307;9395.154436100784;9485.905140948778 18:40:03 INFO - 2015-12-12 18:40:03,046 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:40:03 INFO - 2015-12-12 18:40:03,046 DEBUG : BROWSER_OUTPUT: __start_cc_report 18:40:03 INFO - 2015-12-12 18:40:03,046 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,951 18:40:03 INFO - 2015-12-12 18:40:03,046 DEBUG : BROWSER_OUTPUT: __end_cc_report 18:40:03 INFO - 2015-12-12 18:40:03,046 DEBUG : BROWSER_OUTPUT: __startTimestamp1449974403038__endTimestamp 18:40:03 INFO - 2015-12-12 18:40:03,046 DEBUG : BROWSER_OUTPUT: 18:40:03 INFO - 2015-12-12 18:40:03,046 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:40:03 INFO - 2015-12-12 18:40:03,046 DEBUG : BROWSER_OUTPUT: Number of tests: 6 18:40:03 INFO - 2015-12-12 18:40:03,046 DEBUG : BROWSER_OUTPUT: 18:40:03 INFO - 2015-12-12 18:40:03,046 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4635.63 Median:4798.04 stddev:486.08 (10.1%) stddev-sans-first:461.20 18:40:03 INFO - 2015-12-12 18:40:03,048 DEBUG : BROWSER_OUTPUT: Values: 2622.4 2714.6 2725.3 2732.5 2735.3 4509.2 4657.4 4660.1 4735.3 4739.8 4565.7 4657.0 4670.7 4675.7 4808.9 4282.9 4350.6 4372.7 4389.1 4465.2 4210.6 4447.2 4451.1 4497.9 4509.5 4238.2 4281.5 4297.0 4322.3 4326.2 4284.5 4286.7 4296.7 4308.2 4337.3 4279.4 4281.9 4293.9 4325.8 4333.1 4430.5 4509.7 4529.6 4536.4 4539.4 4996.0 5046.2 5063.4 5073.7 5074.0 5080.4 5099.5 5106.3 5108.6 5147.0 4490.4 4612.8 4614.1 4705.7 4725.1 5053.8 5132.4 5156.4 5172.9 5177.2 4929.6 4930.9 4950.0 4954.3 4996.6 4814.5 5030.5 5036.5 5042.4 5043.9 4844.0 4848.3 4874.8 4935.8 4981.3 4983.1 5019.5 5026.2 5027.0 5037.3 3987.1 4195.7 4205.6 4205.7 4248.6 3590.3 3653.4 3678.7 3685.3 3688.0 4346.8 4352.5 4360.3 4371.1 4405.1 5081.8 5085.3 5087.7 5097.6 5139.3 4141.1 4231.1 4231.7 4239.4 4346.5 4448.4 4466.1 4476.0 4477.4 4515.8 4785.4 4787.4 4789.9 4795.6 4831.1 4819.6 4924.9 4934.6 4972.1 5068.9 4803.9 4809.5 4830.3 4832.0 4874.1 5018.0 5021.8 5024.6 5030.5 5073.1 4774.0 4858.6 4904.9 4921.4 5011.6 4908.4 4920.1 4921.0 4930.2 4967.3 4913.0 4914.9 4917.9 4926.9 4970.6 4676.7 4681.6 4756.6 4798.0 4807.4 4941.7 4963.9 4971.7 4983.3 4989.0 4842.9 4853.7 4858.7 4861.9 4896.3 18:40:03 INFO - 2015-12-12 18:40:03,048 DEBUG : BROWSER_OUTPUT: 18:40:03 INFO - 2015-12-12 18:40:03,048 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:40911.16 Median:32038.86 stddev:69884.00 (218.1%) stddev-sans-first:70014.45 18:40:03 INFO - 2015-12-12 18:40:03,048 DEBUG : BROWSER_OUTPUT: Values: 583.7 584.0 584.5 596.4 597.3 26487.3 27111.5 35111.3 35432.8 35538.3 39952.8 40229.0 40421.0 40491.4 40536.0 6921.5 7847.3 7980.8 7992.9 7993.2 7125.8 10172.1 10456.6 10463.4 10493.5 9979.8 10089.2 10092.1 10094.4 10098.9 7234.6 7461.5 9938.6 10082.8 10247.2 9695.8 9827.2 9828.5 9829.9 9842.5 35699.3 36469.0 36537.6 36558.5 36582.5 2889.1 2957.9 3045.0 3099.6 3566.0 27396.9 27463.0 27605.2 27620.0 28290.0 3364.6 3392.6 3397.7 3465.8 3543.7 209341.0 210050.0 210061.0 210525.0 210683.0 50157.5 51396.0 53304.7 53562.5 53897.7 45746.3 45890.3 45915.3 46053.0 46059.5 12557.8 12570.1 12697.8 12723.7 12725.2 8173.1 8179.7 8183.2 8187.4 8245.8 17963.6 18036.9 18090.2 18181.8 18190.4 7260.9 7499.2 7518.7 7571.7 7597.4 831.8 884.5 887.3 887.6 986.3 25634.0 25669.6 25708.5 25810.2 25984.5 978.1 1000.9 1029.8 1045.1 1054.3 13552.3 13730.0 13734.4 13832.1 13878.2 253552.0 255030.0 256720.0 257218.0 259016.0 44593.0 44856.6 44914.0 44914.6 44937.6 1068.7 1117.6 1118.8 1130.6 1221.7 22565.4 22809.0 23566.8 23599.0 23612.5 33546.7 35081.0 35099.3 35123.0 35144.0 299041.0 300588.0 331128.0 338792.0 338896.0 25763.0 25766.4 25850.6 25854.6 25869.6 35128.8 35185.6 35720.8 35868.8 36086.6 30264.7 31112.4 31340.0 32755.0 33015.3 29975.6 31268.5 31466.7 31508.7 31682.8 31871.4 32206.4 32289.0 32686.8 32728.8 25214.5 25530.9 27048.5 27540.1 27770.6 21182.4 21347.3 21353.1 21421.7 21424.3 18:40:03 INFO - 2015-12-12 18:40:03,048 DEBUG : BROWSER_OUTPUT: 18:40:03 INFO - 2015-12-12 18:40:03,048 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:26484.62 Median:23120.33 stddev:82426.45 (356.5%) stddev-sans-first:82634.29 18:40:03 INFO - 2015-12-12 18:40:03,048 DEBUG : BROWSER_OUTPUT: Values: 336.8 337.6 337.7 337.8 339.5 12611.0 13007.5 15046.6 15209.5 16895.2 12176.9 14538.3 15222.6 15641.2 15663.6 4867.2 4956.2 5010.9 5118.6 5120.5 3510.1 3633.3 5080.3 5212.7 5379.5 5319.4 5321.3 5331.7 5342.8 5350.8 5182.5 5307.6 5316.8 5320.7 5322.6 5077.2 5085.8 5124.3 5129.7 5133.2 12127.9 12159.2 12166.9 12168.6 14502.3 1467.0 1706.0 1832.9 1861.3 1909.1 9019.2 9028.3 9237.8 10789.6 12710.8 1685.8 1687.6 1764.9 1785.1 1790.7 51972.2 53919.0 54120.7 55300.4 55354.4 9607.2 9887.2 9899.7 9936.2 9938.0 12232.9 12234.5 12244.3 12246.8 12275.9 6502.4 6608.5 6623.2 6679.4 6680.5 6130.1 6158.5 6199.9 6288.7 6297.8 4186.3 4191.1 4199.9 4212.3 4212.6 1611.7 1666.6 1671.0 1687.7 1695.9 764.9 825.6 877.8 928.6 991.4 15468.7 15639.7 15969.1 16207.8 16241.4 759.6 827.3 849.2 862.1 906.8 8510.0 8572.3 8749.3 8945.6 9238.3 492062.0 496853.0 498245.0 512038.0 524152.0 67998.7 69544.7 70417.6 70750.3 71395.6 1088.3 1109.4 1113.2 1115.9 1118.4 14385.8 14560.9 14740.7 14745.7 15083.2 33552.3 33937.5 34008.1 34118.3 34530.5 42028.4 42755.0 43293.0 43580.1 46469.5 8846.6 8986.3 9102.4 9103.2 9167.2 10677.6 10921.7 10929.2 10939.0 10947.8 11677.4 13648.1 14017.9 14033.3 14148.4 8769.3 8934.6 13338.0 13916.0 13968.0 7871.9 8157.8 9348.8 9609.0 9772.1 13150.3 13725.1 13829.2 14172.0 14175.6 14475.1 14742.2 14760.4 14963.6 15147.7 18:40:03 INFO - 2015-12-12 18:40:03,048 DEBUG : BROWSER_OUTPUT: 18:40:03 INFO - 2015-12-12 18:40:03,048 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5025.98 Median:6150.88 stddev:2657.91 (43.2%) stddev-sans-first:2650.18 18:40:03 INFO - 2015-12-12 18:40:03,050 DEBUG : BROWSER_OUTPUT: Values: 1246.6 1680.5 1702.4 1734.0 1742.1 6007.4 7063.5 7261.2 7263.7 7275.1 7169.0 7321.0 7329.6 7335.0 7346.9 1709.3 1742.3 2034.2 2058.7 2062.1 2099.8 2111.1 2111.7 2112.6 2112.6 1985.3 2014.9 2017.6 2017.7 2050.0 2061.7 2076.9 2088.4 2088.9 2118.1 2079.9 2108.2 2109.5 2111.2 2113.4 7260.6 7360.4 7363.9 7365.3 7371.6 4039.4 4142.1 4142.4 4144.4 4148.0 2525.3 2601.5 2605.7 2609.5 2619.6 777.7 792.8 800.3 852.1 852.2 10002.5 10004.7 10005.0 10014.6 10018.3 3798.0 3885.4 3894.5 3899.3 3903.9 8628.1 8633.4 8647.1 8653.1 8653.4 4283.7 4287.7 4295.9 4302.2 4305.1 3924.1 3928.4 3930.2 3934.3 3939.3 3007.8 3066.7 3077.4 3126.0 3327.0 1058.3 1065.4 1077.6 1091.2 1095.5 831.4 841.9 844.6 891.4 1031.5 6779.3 6843.2 6887.7 6897.1 6899.3 1029.8 1041.6 1043.7 1056.2 1066.2 5291.5 5354.0 5357.6 5360.2 5361.4 8407.9 8502.6 8779.0 8819.0 8842.6 8568.6 8626.2 8642.6 8663.9 8704.6 6798.9 6803.3 6907.6 6926.6 6944.1 6562.6 6589.4 6597.3 6606.2 6613.6 7664.7 7807.5 7837.5 7937.3 7978.5 7897.8 7991.4 8041.8 8142.8 8189.7 6460.1 6488.6 6504.5 6515.3 6515.5 5842.0 6167.9 6342.5 6495.3 6513.0 6167.0 6247.8 6307.3 6370.7 6442.5 6124.8 6215.9 6328.5 6335.0 6537.7 6457.8 6500.3 6611.2 6614.1 6618.5 5999.5 6025.6 6033.5 6034.0 6045.0 6131.1 6134.8 6175.8 6190.4 6264.3 18:40:03 INFO - 2015-12-12 18:40:03,050 DEBUG : BROWSER_OUTPUT: 18:40:03 INFO - 2015-12-12 18:40:03,050 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4677.89 Median:5290.04 stddev:1364.69 (25.8%) stddev-sans-first:1328.26 18:40:03 INFO - 2015-12-12 18:40:03,050 DEBUG : BROWSER_OUTPUT: Values: 293.8 300.3 303.8 306.3 307.6 5300.2 5348.0 5355.7 5417.4 5431.0 5279.9 5312.7 5314.8 5315.8 5319.6 3967.5 4189.8 4199.6 4202.1 4206.5 4054.1 4078.9 4162.6 4168.6 4181.7 4110.8 4114.6 4117.5 4119.9 4135.0 3927.8 4016.0 4035.2 4121.6 4124.1 3688.9 3695.8 3700.3 3700.9 3701.7 5102.3 5102.3 5107.5 5114.5 5116.7 4748.9 4754.9 4759.8 4762.7 4763.3 5459.2 5595.0 5596.3 5603.6 5621.7 3984.2 3985.6 3988.0 3988.9 3989.7 5794.7 5887.5 5895.3 5904.7 5909.0 4126.6 4130.4 4131.3 4131.8 4132.4 4060.2 4181.6 4190.4 4194.5 4198.3 4740.0 4740.3 4741.3 4745.2 4745.6 5604.9 5610.9 5615.2 5616.9 5621.2 1468.3 1485.2 1485.3 1486.0 1486.9 1012.4 1015.6 1018.7 1035.6 1040.1 4905.6 4925.3 4931.6 4933.1 4933.4 5827.8 5833.6 5834.5 5836.1 5836.2 4918.9 4922.7 4929.9 4931.3 4935.4 3077.2 3124.1 3145.7 3148.3 3150.1 5514.0 5574.8 5575.4 5581.5 5582.8 6042.2 6044.7 6048.9 6052.4 6055.5 5557.4 5561.6 5563.7 5564.6 5565.1 5732.4 5881.0 5882.2 5885.8 5889.2 5730.5 5734.8 5735.6 5741.3 5743.7 5556.6 5557.7 5557.9 5559.9 5563.6 3915.2 3977.9 3981.8 4017.3 4017.6 5857.7 5857.9 5859.6 5863.7 5865.3 5631.8 5637.5 5640.4 5642.6 5648.6 5510.8 5636.0 5643.3 5644.0 5646.2 5505.8 5506.9 5514.2 5515.0 5520.6 5458.8 5577.9 5658.6 5684.5 5687.3 5431.1 5443.4 5447.1 5447.9 5450.6 18:40:03 INFO - 2015-12-12 18:40:03,051 DEBUG : BROWSER_OUTPUT: 18:40:03 INFO - 2015-12-12 18:40:03,051 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:11829.82 Median:494.18 stddev:13314.89 (2694.4%) stddev-sans-first:13325.96 18:40:03 INFO - 2015-12-12 18:40:03,051 DEBUG : BROWSER_OUTPUT: Values: 689.5 691.6 694.5 698.4 698.7 11762.5 11992.9 12016.6 12038.3 12041.9 11603.1 11643.8 11644.2 11671.6 11697.6 454.0 456.1 460.3 785.1 802.1 621.8 626.9 785.5 800.4 803.7 846.4 847.8 854.4 854.4 854.7 648.9 839.3 841.7 841.7 842.5 484.1 609.3 620.6 620.8 621.7 11262.9 11363.7 11373.5 11374.7 11387.7 10683.7 11635.7 11777.4 11822.1 12081.6 8970.5 8992.0 9050.8 9145.0 9249.4 3301.2 3336.5 3344.0 3345.8 3346.3 37200.0 37343.0 37446.0 37692.4 37695.2 9077.9 9292.8 9325.9 9373.9 9455.2 16679.7 16688.9 16704.7 16713.7 17049.7 6328.9 6346.0 6429.3 6512.0 6595.6 3244.1 3345.3 3372.3 3406.9 3408.3 2561.5 2578.1 2587.9 2594.1 2604.2 451.4 456.0 458.0 462.6 462.6 504.3 572.5 613.7 621.6 621.8 7646.4 7732.4 7851.2 7897.2 8027.6 452.7 570.9 589.4 605.7 607.7 3540.2 3564.6 3635.5 3728.7 3748.4 47449.8 47914.5 48063.0 48086.3 48244.3 42530.6 42843.7 44232.8 44296.8 44399.6 39377.2 39918.6 40167.0 40665.8 40712.4 7200.9 7300.4 7431.5 7455.9 7515.0 29541.9 30052.2 30193.4 30374.2 30785.0 35738.5 35980.6 38475.4 38639.8 39090.6 9479.5 9512.7 9538.9 9580.8 9617.0 10110.1 10308.2 10321.1 10331.6 10394.4 9350.7 9462.0 9525.4 9612.9 9993.1 9607.4 9718.2 9733.7 9853.0 10092.3 9778.3 9882.3 9883.7 9889.5 9906.6 8204.0 8240.2 8307.9 8417.7 8514.1 8954.6 9083.4 9274.4 9395.2 9485.9 18:40:03 INFO - 2015-12-12 18:40:03,051 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:40:03 INFO - 2015-12-12 18:40:03,051 DEBUG : BROWSER_OUTPUT: 18:40:03 INFO - 2015-12-12 18:40:03,787 INFO : Browser exited with error code: 0 18:40:03 INFO - 2015-12-12 18:40:03,804 INFO : Completed test dromaeo_css (00:18:37) 18:40:03 INFO - 2015-12-12 18:40:03,804 INFO : Starting test kraken 18:40:03 INFO - 2015-12-12 18:40:03,804 DEBUG : operating with platform_type : linux_ 18:40:03 INFO - 2015-12-12 18:40:03,804 INFO : Initialising browser for kraken test... 18:40:03 INFO - 2015-12-12 18:40:03,811 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQJLf4T/profile http://localhost:60098/getInfo.html 18:40:08 INFO - 2015-12-12 18:40:08,432 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:40:08 INFO - 2015-12-12 18:40:08,432 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:40:08 INFO - 2015-12-12 18:40:08,432 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:40:08 INFO - 2015-12-12 18:40:08,432 DEBUG : BROWSER_OUTPUT: __metrics 18:40:08 INFO - 2015-12-12 18:40:08,509 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 18:40:08 INFO - 2015-12-12 18:40:08,577 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:40:09 INFO - 2015-12-12 18:40:09,430 INFO : Browser initialized. 18:40:09 INFO - 2015-12-12 18:40:09,431 INFO : Running cycle 1/1 for kraken test... 18:40:09 INFO - 2015-12-12 18:40:09,431 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQJLf4T/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:40:21 INFO - 2015-12-12 18:40:21,477 DEBUG : BROWSER_OUTPUT: RSS: Main: 143060992 18:40:21 INFO - 2015-12-12 18:40:21,478 DEBUG : BROWSER_OUTPUT: 18:40:24 INFO - 2015-12-12 18:40:24,078 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60098/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60098/tests/kraken/driver.html?testName=audio-beat-detection) 18:40:24 INFO - 2015-12-12 18:40:24,682 DEBUG : BROWSER_OUTPUT: RSS: Main: 179777536 18:40:24 INFO - 2015-12-12 18:40:24,682 DEBUG : BROWSER_OUTPUT: 18:40:27 INFO - 2015-12-12 18:40:27,444 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60098/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60098/tests/kraken/driver.html?testName=audio-dft) 18:40:28 INFO - 2015-12-12 18:40:28,017 DEBUG : BROWSER_OUTPUT: RSS: Main: 168402944 18:40:28 INFO - 2015-12-12 18:40:28,017 DEBUG : BROWSER_OUTPUT: 18:40:31 INFO - 2015-12-12 18:40:31,020 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60098/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60098/tests/kraken/driver.html?testName=audio-fft) 18:40:31 INFO - 2015-12-12 18:40:31,586 DEBUG : BROWSER_OUTPUT: RSS: Main: 153174016 18:40:31 INFO - 2015-12-12 18:40:31,586 DEBUG : BROWSER_OUTPUT: 18:40:33 INFO - 2015-12-12 18:40:33,645 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60098/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60098/tests/kraken/driver.html?testName=audio-oscillator) 18:40:34 INFO - 2015-12-12 18:40:34,230 DEBUG : BROWSER_OUTPUT: RSS: Main: 154640384 18:40:34 INFO - 2015-12-12 18:40:34,230 DEBUG : BROWSER_OUTPUT: 18:40:36 INFO - 2015-12-12 18:40:36,509 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60098/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60098/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:40:37 INFO - 2015-12-12 18:40:37,075 DEBUG : BROWSER_OUTPUT: RSS: Main: 157392896 18:40:37 INFO - 2015-12-12 18:40:37,075 DEBUG : BROWSER_OUTPUT: 18:40:40 INFO - 2015-12-12 18:40:40,058 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60098/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60098/tests/kraken/driver.html?testName=imaging-darkroom) 18:40:40 INFO - 2015-12-12 18:40:40,631 DEBUG : BROWSER_OUTPUT: RSS: Main: 157192192 18:40:40 INFO - 2015-12-12 18:40:40,631 DEBUG : BROWSER_OUTPUT: 18:40:44 INFO - 2015-12-12 18:40:44,011 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60098/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60098/tests/kraken/driver.html?testName=imaging-desaturate) 18:40:44 INFO - 2015-12-12 18:40:44,593 DEBUG : BROWSER_OUTPUT: RSS: Main: 159944704 18:40:44 INFO - 2015-12-12 18:40:44,593 DEBUG : BROWSER_OUTPUT: 18:40:47 INFO - 2015-12-12 18:40:47,431 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60098/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60098/tests/kraken/driver.html?testName=json-parse-financial) 18:40:48 INFO - 2015-12-12 18:40:48,017 DEBUG : BROWSER_OUTPUT: RSS: Main: 159154176 18:40:48 INFO - 2015-12-12 18:40:48,017 DEBUG : BROWSER_OUTPUT: 18:40:49 INFO - 2015-12-12 18:40:49,805 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60098/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60098/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:40:50 INFO - 2015-12-12 18:40:50,366 DEBUG : BROWSER_OUTPUT: RSS: Main: 165474304 18:40:50 INFO - 2015-12-12 18:40:50,366 DEBUG : BROWSER_OUTPUT: 18:40:52 INFO - 2015-12-12 18:40:52,244 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60098/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60098/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:40:52 INFO - 2015-12-12 18:40:52,846 DEBUG : BROWSER_OUTPUT: RSS: Main: 167346176 18:40:52 INFO - 2015-12-12 18:40:52,846 DEBUG : BROWSER_OUTPUT: 18:40:55 INFO - 2015-12-12 18:40:55,023 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60098/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60098/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:40:55 INFO - 2015-12-12 18:40:55,604 DEBUG : BROWSER_OUTPUT: RSS: Main: 179167232 18:40:55 INFO - 2015-12-12 18:40:55,604 DEBUG : BROWSER_OUTPUT: 18:40:58 INFO - 2015-12-12 18:40:58,071 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60098/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60098/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:40:58 INFO - 2015-12-12 18:40:58,653 DEBUG : BROWSER_OUTPUT: RSS: Main: 183062528 18:40:58 INFO - 2015-12-12 18:40:58,654 DEBUG : BROWSER_OUTPUT: 18:41:01 INFO - 2015-12-12 18:41:01,249 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60098/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60098/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:41:01 INFO - 2015-12-12 18:41:01,832 DEBUG : BROWSER_OUTPUT: RSS: Main: 181387264 18:41:01 INFO - 2015-12-12 18:41:01,833 DEBUG : BROWSER_OUTPUT: 18:41:03 INFO - 2015-12-12 18:41:03,583 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60098/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60098/tests/kraken/driver.html?testName=ai-astar) 18:41:03 INFO - 2015-12-12 18:41:03,833 DEBUG : BROWSER_OUTPUT: RSS: Main: 239747072 18:41:03 INFO - 2015-12-12 18:41:03,833 DEBUG : BROWSER_OUTPUT: 18:41:03 INFO - 2015-12-12 18:41:03,836 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:41:03 INFO - 2015-12-12 18:41:03,836 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:41:03 INFO - 2015-12-12 18:41:03,837 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:41:03 INFO - 2015-12-12 18:41:03,837 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:41:03 INFO - 2015-12-12 18:41:03,837 DEBUG : BROWSER_OUTPUT: |0;ai-astar;111;112;106;114;112;113;100;113;111;114 18:41:03 INFO - 2015-12-12 18:41:03,837 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;151;131;158;163;130;165;159;134;168;132 18:41:03 INFO - 2015-12-12 18:41:03,838 DEBUG : BROWSER_OUTPUT: |2;audio-dft;164;160;160;161;160;160;163;161;160;162 18:41:03 INFO - 2015-12-12 18:41:03,838 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;74;86;91;72;72;72;107;98;71 18:41:03 INFO - 2015-12-12 18:41:03,838 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;94;95;131;94;94;95;133;94;94;127 18:41:03 INFO - 2015-12-12 18:41:03,838 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;110;110;110;111;110;110;110;109;109 18:41:03 INFO - 2015-12-12 18:41:03,838 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;146;146;146;146;146;148;146;146;147 18:41:03 INFO - 2015-12-12 18:41:03,838 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;95;95;95;95;94;99;95;95 18:41:03 INFO - 2015-12-12 18:41:03,839 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;74;73;72;73;71;72;71;82;69;72 18:41:03 INFO - 2015-12-12 18:41:03,839 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;67;69;68;68;68;68;68;67;67;67 18:41:03 INFO - 2015-12-12 18:41:03,839 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;89;84;82;84;83;85;83;84;84;85 18:41:03 INFO - 2015-12-12 18:41:03,839 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;120;121;123;115;118;119;131;121;122;123 18:41:03 INFO - 2015-12-12 18:41:03,839 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;141;150;154;147;147;142;151;148;148;138 18:41:03 INFO - 2015-12-12 18:41:03,839 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;70;71;71;70;70;71;72;71;73;71 18:41:03 INFO - 2015-12-12 18:41:03,839 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:41:03 INFO - 2015-12-12 18:41:03,839 DEBUG : BROWSER_OUTPUT: __start_cc_report 18:41:03 INFO - 2015-12-12 18:41:03,840 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,1005 18:41:03 INFO - 2015-12-12 18:41:03,840 DEBUG : BROWSER_OUTPUT: __end_cc_report 18:41:03 INFO - 2015-12-12 18:41:03,840 DEBUG : BROWSER_OUTPUT: __startTimestamp1449974463835__endTimestamp 18:41:03 INFO - 2015-12-12 18:41:03,840 DEBUG : BROWSER_OUTPUT: 18:41:03 INFO - 2015-12-12 18:41:03,847 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:41:03 INFO - 2015-12-12 18:41:03,848 DEBUG : BROWSER_OUTPUT: Number of tests: 14 18:41:03 INFO - 2015-12-12 18:41:03,848 DEBUG : BROWSER_OUTPUT: 18:41:03 INFO - 2015-12-12 18:41:03,848 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:110.60 Median:112.50 stddev:4.38 (3.9%) stddev-sans-first:4.64 18:41:03 INFO - 2015-12-12 18:41:03,848 DEBUG : BROWSER_OUTPUT: Values: 111.0 112.0 106.0 114.0 112.0 113.0 100.0 113.0 111.0 114.0 18:41:03 INFO - 2015-12-12 18:41:03,848 DEBUG : BROWSER_OUTPUT: 18:41:03 INFO - 2015-12-12 18:41:03,848 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:149.10 Median:158.50 stddev:15.62 (9.9%) stddev-sans-first:16.56 18:41:03 INFO - 2015-12-12 18:41:03,848 DEBUG : BROWSER_OUTPUT: Values: 151.0 131.0 158.0 163.0 130.0 165.0 159.0 134.0 168.0 132.0 18:41:03 INFO - 2015-12-12 18:41:03,848 DEBUG : BROWSER_OUTPUT: 18:41:03 INFO - 2015-12-12 18:41:03,848 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:1.45 (0.9%) stddev-sans-first:1.09 18:41:03 INFO - 2015-12-12 18:41:03,848 DEBUG : BROWSER_OUTPUT: Values: 164.0 160.0 160.0 161.0 160.0 160.0 163.0 161.0 160.0 162.0 18:41:03 INFO - 2015-12-12 18:41:03,848 DEBUG : BROWSER_OUTPUT: 18:41:03 INFO - 2015-12-12 18:41:03,848 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:81.50 Median:73.00 stddev:13.17 (18.0%) stddev-sans-first:13.51 18:41:03 INFO - 2015-12-12 18:41:03,849 DEBUG : BROWSER_OUTPUT: Values: 72.0 74.0 86.0 91.0 72.0 72.0 72.0 107.0 98.0 71.0 18:41:03 INFO - 2015-12-12 18:41:03,849 DEBUG : BROWSER_OUTPUT: 18:41:03 INFO - 2015-12-12 18:41:03,849 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:105.10 Median:94.00 stddev:17.48 (18.6%) stddev-sans-first:18.07 18:41:03 INFO - 2015-12-12 18:41:03,849 DEBUG : BROWSER_OUTPUT: Values: 94.0 95.0 131.0 94.0 94.0 95.0 133.0 94.0 94.0 127.0 18:41:03 INFO - 2015-12-12 18:41:03,849 DEBUG : BROWSER_OUTPUT: 18:41:03 INFO - 2015-12-12 18:41:03,849 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.90 Median:110.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 18:41:03 INFO - 2015-12-12 18:41:03,849 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 110.0 110.0 111.0 110.0 110.0 110.0 109.0 109.0 18:41:03 INFO - 2015-12-12 18:41:03,849 DEBUG : BROWSER_OUTPUT: 18:41:03 INFO - 2015-12-12 18:41:03,849 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.00 stddev:0.70 (0.5%) stddev-sans-first:0.71 18:41:03 INFO - 2015-12-12 18:41:03,850 DEBUG : BROWSER_OUTPUT: Values: 147.0 146.0 146.0 146.0 146.0 146.0 148.0 146.0 146.0 147.0 18:41:03 INFO - 2015-12-12 18:41:03,850 DEBUG : BROWSER_OUTPUT: 18:41:03 INFO - 2015-12-12 18:41:03,850 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:1.34 (1.4%) stddev-sans-first:1.41 18:41:03 INFO - 2015-12-12 18:41:03,850 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 95.0 95.0 95.0 95.0 94.0 99.0 95.0 95.0 18:41:03 INFO - 2015-12-12 18:41:03,850 DEBUG : BROWSER_OUTPUT: 18:41:03 INFO - 2015-12-12 18:41:03,850 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:72.90 Median:72.50 stddev:3.48 (4.8%) stddev-sans-first:3.67 18:41:03 INFO - 2015-12-12 18:41:03,850 DEBUG : BROWSER_OUTPUT: Values: 74.0 73.0 72.0 73.0 71.0 72.0 71.0 82.0 69.0 72.0 18:41:03 INFO - 2015-12-12 18:41:03,850 DEBUG : BROWSER_OUTPUT: 18:41:03 INFO - 2015-12-12 18:41:03,850 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:67.70 Median:68.00 stddev:0.67 (1.0%) stddev-sans-first:0.67 18:41:03 INFO - 2015-12-12 18:41:03,850 DEBUG : BROWSER_OUTPUT: Values: 67.0 69.0 68.0 68.0 68.0 68.0 68.0 67.0 67.0 67.0 18:41:03 INFO - 2015-12-12 18:41:03,850 DEBUG : BROWSER_OUTPUT: 18:41:03 INFO - 2015-12-12 18:41:03,850 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:84.30 Median:84.00 stddev:1.89 (2.2%) stddev-sans-first:0.97 18:41:03 INFO - 2015-12-12 18:41:03,850 DEBUG : BROWSER_OUTPUT: Values: 89.0 84.0 82.0 84.0 83.0 85.0 83.0 84.0 84.0 85.0 18:41:03 INFO - 2015-12-12 18:41:03,850 DEBUG : BROWSER_OUTPUT: 18:41:03 INFO - 2015-12-12 18:41:03,850 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:121.30 Median:121.50 stddev:4.19 (3.4%) stddev-sans-first:4.42 18:41:03 INFO - 2015-12-12 18:41:03,850 DEBUG : BROWSER_OUTPUT: Values: 120.0 121.0 123.0 115.0 118.0 119.0 131.0 121.0 122.0 123.0 18:41:03 INFO - 2015-12-12 18:41:03,850 DEBUG : BROWSER_OUTPUT: 18:41:03 INFO - 2015-12-12 18:41:03,851 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:146.60 Median:148.00 stddev:4.90 (3.3%) stddev-sans-first:4.76 18:41:03 INFO - 2015-12-12 18:41:03,851 DEBUG : BROWSER_OUTPUT: Values: 141.0 150.0 154.0 147.0 147.0 142.0 151.0 148.0 148.0 138.0 18:41:03 INFO - 2015-12-12 18:41:03,851 DEBUG : BROWSER_OUTPUT: 18:41:03 INFO - 2015-12-12 18:41:03,851 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.00 Median:71.00 stddev:0.94 (1.3%) stddev-sans-first:0.93 18:41:03 INFO - 2015-12-12 18:41:03,851 DEBUG : BROWSER_OUTPUT: Values: 70.0 71.0 71.0 70.0 70.0 71.0 72.0 71.0 73.0 71.0 18:41:03 INFO - 2015-12-12 18:41:03,851 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:41:03 INFO - 2015-12-12 18:41:03,851 DEBUG : BROWSER_OUTPUT: 18:41:04 INFO - 2015-12-12 18:41:04,701 INFO : Browser exited with error code: 0 18:41:04 INFO - 2015-12-12 18:41:04,751 INFO : Completed test kraken (00:01:00) 18:41:04 INFO - 2015-12-12 18:41:04,751 INFO : Starting test v8_7 18:41:04 INFO - 2015-12-12 18:41:04,751 DEBUG : operating with platform_type : linux_ 18:41:04 INFO - 2015-12-12 18:41:04,751 INFO : Initialising browser for v8_7 test... 18:41:04 INFO - 2015-12-12 18:41:04,757 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQhbUXy/profile http://localhost:60098/getInfo.html 18:41:08 INFO - 2015-12-12 18:41:08,946 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:41:08 INFO - 2015-12-12 18:41:08,946 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:41:08 INFO - 2015-12-12 18:41:08,946 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:41:08 INFO - 2015-12-12 18:41:08,946 DEBUG : BROWSER_OUTPUT: __metrics 18:41:09 INFO - 2015-12-12 18:41:09,111 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 18:41:09 INFO - 2015-12-12 18:41:09,187 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:41:10 INFO - 2015-12-12 18:41:10,427 INFO : Browser initialized. 18:41:10 INFO - 2015-12-12 18:41:10,428 INFO : Running cycle 1/1 for v8_7 test... 18:41:10 INFO - 2015-12-12 18:41:10,428 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQhbUXy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:41:22 INFO - 2015-12-12 18:41:22,495 DEBUG : BROWSER_OUTPUT: RSS: Main: 143269888 18:41:22 INFO - 2015-12-12 18:41:22,495 DEBUG : BROWSER_OUTPUT: 18:41:43 INFO - 2015-12-12 18:41:43,863 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60098/tests/v8_7/run.html (next: http://localhost:60098/tests/v8_7/run.html) 18:41:44 INFO - 2015-12-12 18:41:44,113 DEBUG : BROWSER_OUTPUT: RSS: Main: 440389632 18:41:44 INFO - 2015-12-12 18:41:44,113 DEBUG : BROWSER_OUTPUT: 18:41:44 INFO - 2015-12-12 18:41:44,113 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:41:44 INFO - 2015-12-12 18:41:44,113 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:41:44 INFO - 2015-12-12 18:41:44,113 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:41:44 INFO - 2015-12-12 18:41:44,114 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:41:44 INFO - 2015-12-12 18:41:44,114 DEBUG : BROWSER_OUTPUT: |0;Richards;165.53550736633008 18:41:44 INFO - 2015-12-12 18:41:44,114 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;186.01190476190476 18:41:44 INFO - 2015-12-12 18:41:44,114 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.39643652561244 18:41:44 INFO - 2015-12-12 18:41:44,115 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5181.347150259067 18:41:44 INFO - 2015-12-12 18:41:44,115 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1970.4724409448818 18:41:44 INFO - 2015-12-12 18:41:44,115 DEBUG : BROWSER_OUTPUT: |5;Earley;783.6990595611285 18:41:44 INFO - 2015-12-12 18:41:44,115 DEBUG : BROWSER_OUTPUT: |6;Boyer;12108.43373493976 18:41:44 INFO - 2015-12-12 18:41:44,116 DEBUG : BROWSER_OUTPUT: |7;RegExp;26605.263157894737 18:41:44 INFO - 2015-12-12 18:41:44,116 DEBUG : BROWSER_OUTPUT: |8;Splay;619.9628022318661 18:41:44 INFO - 2015-12-12 18:41:44,116 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4830.917874396136 18:41:44 INFO - 2015-12-12 18:41:44,116 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:41:44 INFO - 2015-12-12 18:41:44,117 DEBUG : BROWSER_OUTPUT: __startTimestamp1449974504111__endTimestamp 18:41:44 INFO - 2015-12-12 18:41:44,117 DEBUG : BROWSER_OUTPUT: 18:41:44 INFO - 2015-12-12 18:41:44,117 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:41:44 INFO - 2015-12-12 18:41:44,117 DEBUG : BROWSER_OUTPUT: Number of tests: 10 18:41:44 INFO - 2015-12-12 18:41:44,117 DEBUG : BROWSER_OUTPUT: 18:41:44 INFO - 2015-12-12 18:41:44,117 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:165.54 Median:165.54 stddev:0.00 (0.0%) 18:41:44 INFO - 2015-12-12 18:41:44,117 DEBUG : BROWSER_OUTPUT: Values: 165.5 18:41:44 INFO - 2015-12-12 18:41:44,117 DEBUG : BROWSER_OUTPUT: 18:41:44 INFO - 2015-12-12 18:41:44,118 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:186.01 Median:186.01 stddev:0.00 (0.0%) 18:41:44 INFO - 2015-12-12 18:41:44,118 DEBUG : BROWSER_OUTPUT: Values: 186.0 18:41:44 INFO - 2015-12-12 18:41:44,118 DEBUG : BROWSER_OUTPUT: 18:41:44 INFO - 2015-12-12 18:41:44,118 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.40 Median:278.40 stddev:0.00 (0.0%) 18:41:44 INFO - 2015-12-12 18:41:44,118 DEBUG : BROWSER_OUTPUT: Values: 278.4 18:41:44 INFO - 2015-12-12 18:41:44,118 DEBUG : BROWSER_OUTPUT: 18:41:44 INFO - 2015-12-12 18:41:44,118 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5181.35 Median:5181.35 stddev:0.00 (0.0%) 18:41:44 INFO - 2015-12-12 18:41:44,118 DEBUG : BROWSER_OUTPUT: Values: 5181.3 18:41:44 INFO - 2015-12-12 18:41:44,118 DEBUG : BROWSER_OUTPUT: 18:41:44 INFO - 2015-12-12 18:41:44,119 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1970.47 Median:1970.47 stddev:0.00 (0.0%) 18:41:44 INFO - 2015-12-12 18:41:44,119 DEBUG : BROWSER_OUTPUT: Values: 1970.5 18:41:44 INFO - 2015-12-12 18:41:44,119 DEBUG : BROWSER_OUTPUT: 18:41:44 INFO - 2015-12-12 18:41:44,119 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:783.70 Median:783.70 stddev:0.00 (0.0%) 18:41:44 INFO - 2015-12-12 18:41:44,119 DEBUG : BROWSER_OUTPUT: Values: 783.7 18:41:44 INFO - 2015-12-12 18:41:44,119 DEBUG : BROWSER_OUTPUT: 18:41:44 INFO - 2015-12-12 18:41:44,120 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12108.43 Median:12108.43 stddev:0.00 (0.0%) 18:41:44 INFO - 2015-12-12 18:41:44,120 DEBUG : BROWSER_OUTPUT: Values: 12108.4 18:41:44 INFO - 2015-12-12 18:41:44,120 DEBUG : BROWSER_OUTPUT: 18:41:44 INFO - 2015-12-12 18:41:44,120 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26605.26 Median:26605.26 stddev:0.00 (0.0%) 18:41:44 INFO - 2015-12-12 18:41:44,120 DEBUG : BROWSER_OUTPUT: Values: 26605.3 18:41:44 INFO - 2015-12-12 18:41:44,120 DEBUG : BROWSER_OUTPUT: 18:41:44 INFO - 2015-12-12 18:41:44,120 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:619.96 Median:619.96 stddev:0.00 (0.0%) 18:41:44 INFO - 2015-12-12 18:41:44,120 DEBUG : BROWSER_OUTPUT: Values: 620.0 18:41:44 INFO - 2015-12-12 18:41:44,120 DEBUG : BROWSER_OUTPUT: 18:41:44 INFO - 2015-12-12 18:41:44,120 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 18:41:44 INFO - 2015-12-12 18:41:44,120 DEBUG : BROWSER_OUTPUT: Values: 4830.9 18:41:44 INFO - 2015-12-12 18:41:44,120 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:41:44 INFO - 2015-12-12 18:41:44,121 DEBUG : BROWSER_OUTPUT: 18:41:45 INFO - 2015-12-12 18:41:45,049 INFO : Browser exited with error code: 0 18:41:45 INFO - 2015-12-12 18:41:45,067 INFO : Completed test v8_7 (00:00:40) 18:41:45 INFO - 2015-12-12 18:41:45,171 INFO : Completed test suite (00:20:18) 18:41:45 INFO - 2015-12-12 18:41:45,172 DEBUG : Working with test: dromaeo_css 18:41:45 INFO - 2015-12-12 18:41:45,172 DEBUG : Generating results file: dromaeo_css 18:41:45 INFO - 2015-12-12 18:41:45,173 DEBUG : Working with test: kraken 18:41:45 INFO - 2015-12-12 18:41:45,173 DEBUG : Generating results file: kraken 18:41:45 INFO - 2015-12-12 18:41:45,173 INFO : javascript benchmark 18:41:45 INFO - 2015-12-12 18:41:45,173 DEBUG : Working with test: v8_7 18:41:45 INFO - 2015-12-12 18:41:45,173 DEBUG : Generating results file: v8_7 18:41:45 INFO - 2015-12-12 18:41:45,174 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:41:45 INFO - 2015-12-12 18:41:45,222 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,203,35]] 18:41:45 INFO - 2015-12-12 18:41:45,222 DEBUG : process_Request line: dromaeo_css 6234.27 graph.html#tests=[[72,203,35]] 18:41:45 INFO - 2015-12-12 18:41:45,222 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:41:45 INFO - 2015-12-12 18:41:45,256 DEBUG : process_Request line: kraken 1522.80 graph.html#tests=[[232,203,35]] 18:41:45 INFO - 2015-12-12 18:41:45,256 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:41:45 INFO - 2015-12-12 18:41:45,358 DEBUG : process_Request line: v8_7 19831.34 graph.html#tests=[[230,203,35]] 18:41:45 INFO - 2015-12-12 18:41:45,359 INFO : javascript benchmark 18:41:45 INFO - 2015-12-12 18:41:45,363 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2622.436176222112, 2714.5656694928816, 2725.3459169799253, 2732.54912939365, 2735.3024978630433, 4509.233430316845, 4657.3945387099675, 4660.067986402716, 4735.289706544016, 4739.834105806298, 4565.65757568182, 4657.0453057123295, 4670.696404733699, 4675.672702910798, 4808.855734327975, 4282.9357559636555, 4350.58669426404, 4372.693911426201, 4389.078293558357, 4465.173942820572, 4210.62104410603, 4447.1995040892625, 4451.065276291983, 4497.887552811173, 4509.526499717532, 4238.173556156551, 4281.5290318064835, 4297.03316753731, 4322.286822674255, 4326.178026175016, 4284.485861696585, 4286.721363111419, 4296.742195468282, 4308.159908817782, 4337.284348082562, 4279.422277992462, 4281.871543853689, 4293.892652683677, 4325.82696692132, 4333.133373325348, 4430.534793846632, 4509.684322097454, 4529.6376289896725, 4536.387587675655, 4539.387182730356, 4995.975020124913, 5046.167382381903, 5063.443021267658, 5073.7209453480045, 5074.035933172722, 5080.415752188509, 5099.4900509949075, 5106.336176297094, 5108.64239503235, 5147.0, 4490.371348011281, 4612.76936153191, 4614.146382919171, 4705.71765694056, 4725.07860967115, 5053.772580233898, 5132.409772876167, 5156.355455568054, 5172.922406163911, 5177.171652535576, 4929.556339929423, 4930.876728081755, 4949.975250123726, 4954.256861470808, 4996.600271978233, 4814.518548145227, 5030.4718004608985, 5036.471170527115, 5042.394912610437, 5043.87390315245, 4843.975780121147, 4848.296996935495, 4874.805007799649, 4935.802567897315, 4981.327520784647, 4983.053219888209, 5019.52314530119, 5026.220935754964, 5027.0197311524635, 5037.29477873098, 3987.0829709167056, 4195.685323600742, 4205.642520385741, 4205.663546916239, 4248.617624413818, 3590.2998915211283, 3653.4337177737493, 3678.6873115785456, 3685.336639404933, 3688.0226739913946, 4346.7609281489185, 4352.499462561846, 4360.302351623725, 4371.147626212858, 4405.052913623586, 5081.822136225214, 5085.338905942203, 5087.720175390315, 5097.617678674114, 5139.280500729901, 4141.068259641616, 4231.069164783743, 4231.682623803227, 4239.385289133058, 4346.543612920661, 4448.354988526646, 4466.0844526871915, 4476.0, 4477.3731677565165, 4515.841945531891, 4785.377900872864, 4787.4494433140635, 4789.856304310807, 4795.568398844121, 4831.106245344622, 4819.59033482158, 4924.926126108039, 4934.629902757236, 4972.105021096238, 5068.949310506848, 4803.855884323476, 4809.5430934061205, 4830.2513110467935, 4831.975840120777, 4874.098291816025, 5018.0, 5021.774020169174, 5024.648274620741, 5030.522100400456, 5073.11220536412, 4773.9761301193275, 4858.611311095175, 4904.852854414374, 4921.360223171108, 5011.649184557192, 4908.361912951294, 4920.114379411741, 4920.975395123145, 4930.186519224431, 4967.279744436964, 4912.975435122801, 4914.8771280718265, 4917.877053073702, 4926.926096108489, 4970.62720295972, 4676.719396836201, 4681.625469962463, 4756.619470442425, 4798.0403919217, 4807.375041244616, 4941.703497790144, 4963.900721985612, 4971.701697898137, 4983.327250821238, 4989.027139707868, 4842.854714358575, 4853.708777473363, 4858.708477491362, 4861.878453038702, 4896.2655601660035], "unit": "ms", "name": "dojo.html", "value": 4605.379625611382}, {"lowerIsBetter": false, "replicates": [583.6848102024907, 583.9883202335953, 584.5060923519625, 596.3738075021226, 597.250450684391, 26487.337816554595, 27111.457770844598, 35111.297774044535, 35432.82283588585, 35538.2892342153, 39952.80023599878, 40229.0, 40421.0, 40491.39262911059, 40536.0, 6921.515493915428, 7847.254510821463, 7980.840383192332, 7992.880106798402, 7993.1607181246, 7125.786226413215, 10172.084512393883, 10456.58173673052, 10463.372197668126, 10493.527791249413, 9979.75050623733, 10089.243306751987, 10092.142167915736, 10094.444805535693, 10098.9495052525, 7234.60209688467, 7461.477696561243, 9938.55276512555, 10082.798344033115, 10247.180225581935, 9695.806083878353, 9827.164691001273, 9828.459434731087, 9829.852552211723, 9842.507874606312, 35699.28601427957, 36469.0, 36537.634623653954, 36558.45162322567, 36582.45126323107, 2889.0754958413318, 2957.8668959896854, 3044.984775076133, 3099.6435409927694, 3565.9658698977264, 27396.863015684994, 27463.0, 27605.171844844484, 27620.0, 28290.0, 3364.5794275715534, 3392.6098498672723, 3397.711194548466, 3465.7573969822124, 3543.6810687038032, 209341.0, 210050.0, 210061.0, 210525.0, 210683.0, 50157.49842501601, 51396.0, 53304.667383314954, 53562.464375356525, 53897.730511347974, 45746.31380529295, 45890.31164532468, 45915.311270330974, 46053.0, 46059.53940460619, 12557.811632825515, 12570.120091593684, 12697.809532857014, 12723.681907952376, 12725.172863763826, 8173.060098088796, 8179.713710020121, 8183.181681831769, 8187.38594605407, 8245.752627421187, 17963.640727185644, 18036.90981545084, 18090.18594163266, 18181.81818181828, 18190.363337283397, 7260.891086633705, 7499.21258267885, 7518.736844210536, 7571.659275332623, 7597.392208623297, 831.8128421105324, 884.5002573545926, 887.316766090107, 887.6050157679771, 986.2800155886243, 25634.0, 25669.61495577568, 25708.48583028329, 25810.225693229233, 25984.480310394065, 978.1148061004802, 1000.9299349045497, 1029.8197815382277, 1045.132539991805, 1054.3463052907246, 13552.254625995665, 13730.0, 13734.381952812148, 13832.100913440594, 13878.167309961435, 253552.0, 255030.0, 256720.0, 257218.0, 259016.0, 44593.0, 44856.55143448589, 44914.0, 44914.55085449103, 44937.55062449399, 1068.7167900506372, 1117.6311817100338, 1118.8321751737305, 1130.559081958037, 1221.7190046289404, 22565.43586410353, 22808.97359618788, 23566.764332356455, 23599.0, 23612.527749444573, 33546.66453335436, 35081.0, 35099.29801404009, 35123.0, 35144.0, 299041.0, 300588.0, 331128.0, 338792.0, 338896.0, 25763.0, 25766.355841103374, 25850.612240816026, 25854.61218081768, 25869.611955820303, 35128.82435587908, 35185.64814351926, 35720.82139589285, 35868.820655896554, 36086.63913360938, 30264.69735302707, 31112.377752445278, 31340.0, 32755.0, 33015.33969320552, 29975.55036674408, 31268.530972035893, 31466.685333146375, 31508.684913150573, 31682.841585791924, 31871.362572747952, 32206.35587288288, 32289.0, 32686.83656581702, 32728.836355818068, 25214.495710086063, 25530.872345638152, 27048.459030819668, 27540.144379786445, 27770.611469426043, 21182.364529064154, 21347.25284615031, 21353.145874165482, 21421.678674819577, 21424.250151244632], "unit": "ms", "name": "ext.html", "value": 16526.421248132552}, {"lowerIsBetter": false, "replicates": [336.80128724052804, 337.5561137104707, 337.7382528540379, 337.78719406773735, 339.5467051486266, 12611.0, 13007.47970081198, 15046.623834404145, 15209.543713688594, 16895.155242237885, 12176.93911530441, 14538.273086345693, 15222.619434514143, 15641.217939103057, 15663.60840978976, 4867.245576935572, 4956.157453232959, 5010.949890501103, 5118.564921981636, 5120.513551212629, 3510.052285882823, 3633.3459977204093, 5080.314157588734, 5212.713300768456, 5379.515843574078, 5319.441458646845, 5321.3348331458565, 5331.6534425262425, 5342.759575819078, 5350.8127215547465, 5182.455842136578, 5307.628466007381, 5316.787328506855, 5320.654157479751, 5322.627416080876, 5077.213031980049, 5085.79656813727, 5124.308218390544, 5129.692218466904, 5133.204353325241, 12127.878721212763, 12159.209651372721, 12166.878331216663, 12168.57409990646, 14502.347394367172, 1466.9779953300708, 1705.9744103838327, 1832.8625353098437, 1861.3020117455953, 1909.1218039701753, 9019.23336516397, 9028.322875784277, 9237.769055773591, 10789.568417263301, 12710.809337859939, 1685.8482736553647, 1687.5865412973778, 1764.9117544122741, 1785.0717626834103, 1790.7313902914536, 51972.22041669378, 53919.0, 54120.72939635316, 55300.446995529936, 55354.44645553533, 9607.231421486264, 9887.159591434665, 9899.703008909744, 9936.205103591696, 9938.0, 12232.877671223352, 12234.510619575296, 12244.265344079206, 12246.75506489883, 12275.877241227474, 6502.447291980235, 6608.504362172856, 6623.172103487064, 6679.398854103057, 6680.465562754968, 6130.080487926846, 6158.507319414436, 6199.907001394983, 6288.717007734572, 6297.779577714758, 4186.267403204427, 4191.11986482842, 4199.853005144805, 4212.283911735012, 4212.557681443465, 1611.7421212606162, 1666.633340665076, 1670.983290167107, 1687.70465168595, 1695.8643308535288, 764.8661484240235, 825.6078362777632, 877.8200468903859, 928.6099838068028, 991.4101109839634, 15468.690626187414, 15639.68720625581, 15969.121698306704, 16207.756883646754, 16241.350345986028, 759.5784339691525, 827.3091968206495, 849.1847826086902, 862.0732712334266, 906.8458362078462, 8510.0, 8572.314214862796, 8749.343799215085, 8945.552722363856, 9238.307126965505, 492062.0, 496853.0, 498245.0, 512038.0, 524152.0, 67998.66000669965, 69544.6522767383, 70417.64791176011, 70750.2924970754, 71395.57208855794, 1088.3361149698678, 1109.351029647649, 1113.17068783755, 1115.8660960684608, 1118.3960661242838, 14385.7842132366, 14560.927195363955, 14740.7151891457, 14745.705085898437, 15083.24583770829, 33552.32895342031, 33937.49093763546, 34008.1497962543, 34118.31763364768, 34530.48204276988, 42028.36957445702, 42755.0, 43293.0, 43580.128397432505, 46469.535304646524, 8846.601902914514, 8986.281097512314, 9102.408343457653, 9103.18071373553, 9167.17495425415, 10677.626283080366, 10921.6723498292, 10929.234953553176, 10939.015488606065, 10947.83578246311, 11677.416129193676, 13648.112872663245, 14017.929910350384, 14033.298335083, 14148.363323650667, 8769.298456123368, 8934.642614295615, 13338.0, 13916.0, 13968.0, 7871.881921771293, 8157.75526734199, 9348.766280842761, 9609.0, 9772.071653192936, 13150.27673477968, 13725.107867988549, 13829.239391833144, 14172.0, 14175.64560885986, 14475.059121157092, 14742.189179595225, 14760.409583616534, 14963.625909352353, 15147.697046059238], "unit": "ms", "name": "jquery.html", "value": 8071.808382610784}, {"lowerIsBetter": false, "replicates": [1246.5574720974048, 1680.4706517447005, 1702.412667629668, 1733.9566510837228, 1742.0766993493448, 6007.429294217048, 7063.470239732022, 7261.201267860538, 7263.709451621942, 7275.090613673291, 7169.0, 7320.9633951830165, 7329.596872172028, 7334.963325183367, 7346.8530629387515, 1709.2564734340601, 1742.2595396956306, 2034.2269937423757, 2058.650029494982, 2062.102985201435, 2099.7585277693106, 2111.123883588309, 2111.7360329958756, 2112.5563631637374, 2112.577484503098, 1985.3249895035685, 2014.879107253562, 2017.5561376497146, 2017.6872584749387, 2049.9795002050014, 2061.690746388039, 2076.8961551922494, 2088.3943656339643, 2088.895555222233, 2118.120979793384, 2079.854410191287, 2108.16727392679, 2109.535902101535, 2111.187192930717, 2113.3871177358546, 7260.636968151572, 7360.447966402541, 7363.926360736377, 7365.2634736525915, 7371.557706537625, 4039.373897045933, 4142.130152667944, 4142.357934520154, 4144.378343248507, 4147.958520414818, 2525.2802951158833, 2601.531724289627, 2605.674290713661, 2609.4650596627644, 2619.620155077503, 777.747232149548, 792.825578372757, 800.3037357498955, 852.0627309959058, 852.211704173643, 10002.549885255181, 10004.699859004242, 10005.04952029556, 10014.64948726791, 10018.3488073275, 3797.9555622204116, 3885.3977633466857, 3894.4742459767567, 3899.29812633723, 3903.863364782219, 8628.094050124771, 8633.39566230358, 8647.092055334226, 8653.091425400367, 8653.350998675125, 4283.7429754214845, 4287.721298115613, 4295.935560966588, 4302.182585308781, 4305.138972205571, 3924.136689928211, 3928.4107383892647, 3930.1550166713723, 3934.291827471083, 3939.290927632996, 3007.789454738191, 3066.708662677042, 3077.39990701811, 3125.9843700781353, 3327.0, 1058.2539309786582, 1065.408698172506, 1077.6120596585224, 1091.1979694924225, 1095.4960718069617, 831.4470876866936, 841.8526757817358, 844.5566077809076, 891.4473026723343, 1031.4997226345217, 6779.254282028973, 6843.247242803288, 6887.724491020404, 6897.137857767779, 6899.275576064541, 1029.7734498410339, 1041.6458404142627, 1043.7181960870673, 1056.1920131099714, 1066.168388656849, 5291.470852914755, 5354.0, 5357.624966252325, 5360.2227676987395, 5361.35663720356, 8407.915920840713, 8502.574871256413, 8779.0, 8819.0, 8842.602082906285, 8568.57157142153, 8626.18051285127, 8642.567871606394, 8663.870041949376, 8704.564771761387, 6798.864022719518, 6803.251642319341, 6907.585544867224, 6926.619035953071, 6944.097267355211, 6562.606243625398, 6589.374009469093, 6597.307282735435, 6606.240282367589, 6613.570117942319, 7664.73173438927, 7807.531548107132, 7837.49056311338, 7937.32532734724, 7978.521288722463, 7897.763067107996, 7991.4006449515355, 8041.8391632168195, 8142.7557173284895, 8189.713360032609, 6460.095586617783, 6488.643124628191, 6504.544681872224, 6515.250746164252, 6515.478761698958, 5841.970790146022, 6167.907481387872, 6342.460890824332, 6495.253045899782, 6513.0, 6167.0, 6247.843803904939, 6307.30619631831, 6370.74517019314, 6442.549021568632, 6124.785632502841, 6215.9378406215355, 6328.493720502257, 6334.986402175629, 6537.738490460519, 6457.773977910939, 6500.349965003462, 6611.239707433514, 6614.10709554204, 6618.536702430783, 5999.4600485954725, 6025.608335458356, 6033.547483938634, 6034.0, 6044.969775151272, 6131.110988906495, 6134.754609815557, 6175.814725558241, 6190.442860142609, 6264.2796078451565], "unit": "ms", "name": "mootools.html", "value": 4089.812658522713}, {"lowerIsBetter": false, "replicates": [293.7855365583124, 300.28981458849825, 303.8040463900784, 306.3276108940873, 307.62623412553745, 5300.204969254609, 5347.973260133704, 5355.732213389329, 5417.3770016448125, 5431.0, 5279.868003299919, 5312.681239125655, 5314.787408503665, 5315.813946511873, 5319.627626066177, 3967.5437324707596, 4189.7695626740515, 4199.6220340169375, 4202.138561594881, 4206.453161089062, 4054.108096218827, 4078.9184216315657, 4162.562930892258, 4168.583141685837, 4181.665466762667, 4110.815013324408, 4114.567970363114, 4117.526484454281, 4119.917601647965, 4135.028268356934, 3927.764334139932, 4015.9799201003807, 4035.1727895781296, 4121.608447197512, 4124.113315637137, 3688.944665830015, 3695.7782533048103, 3700.3154416432776, 3700.8889733307774, 3701.68535674468, 5102.311187989648, 5102.311187989648, 5107.540321371094, 5114.539691427789, 5116.667416617908, 4748.857534273978, 4754.904901901942, 4759.7620118994255, 4762.666613337068, 4763.333133361332, 5459.181122831607, 5594.992901277768, 5596.300462442195, 5603.635763675389, 5621.690807005613, 3984.183242435294, 3985.60143985602, 3988.0, 3988.8603898863403, 3989.6608788253034, 5794.652320860761, 5887.499562537189, 5895.263092113486, 5904.7342869570975, 5909.0, 4126.587341265849, 4130.359794231899, 4131.338985762263, 4131.752094874317, 4132.40080188371, 4060.2082593894497, 4181.560936101726, 4190.350495673176, 4194.475690538683, 4198.328267477189, 4739.95260047402, 4740.312654665123, 4741.336212930192, 4745.217039188564, 4745.572898439088, 5604.887902242014, 5610.85972850682, 5615.241942337732, 5616.859578510488, 5621.213030175779, 1468.3098943496539, 1485.1608841004797, 1485.2647939269914, 1485.9851401486062, 1486.8810495160578, 1012.4026824173773, 1015.6496008876926, 1018.7300365403174, 1035.5598870480017, 1040.0535512683423, 4905.632077594123, 4925.285833554186, 4931.580815630711, 4933.112039832865, 4933.383327084114, 5827.825165245049, 5833.620814647034, 5834.504067154255, 5836.066229403275, 5836.241288632451, 4918.877028074256, 4922.704637721677, 4929.901401971941, 4931.284963680247, 4935.407751069823, 3077.215310095956, 3124.109628755794, 3145.7168854803176, 3148.2916343822912, 3150.054983504939, 5514.035043867388, 5574.832755017356, 5575.358833734173, 5581.525570326487, 5582.7766889323975, 6042.184305118841, 6044.697765111727, 6048.879022419615, 6052.394760523913, 6055.485283750842, 5557.36090349615, 5561.555075593943, 5563.721813909288, 5564.554835613222, 5565.081761509306, 5732.369439361751, 5880.970595147168, 5882.235309409921, 5885.793997210077, 5889.175515427929, 5730.541556675539, 5734.770609175586, 5735.627184233012, 5741.3397459291045, 5743.712814359349, 5556.611037227355, 5557.722113894209, 5557.944420555905, 5559.861003474945, 5563.638363506359, 3915.2169566086327, 3977.92044159121, 3981.7610943343493, 4017.2568074906235, 4017.6183262590007, 5857.736401862012, 5857.941420585739, 5859.619124756877, 5863.706814659165, 5865.266841644794, 5631.774729010794, 5637.46444087811, 5640.351359593536, 5642.605017648725, 5648.604597678287, 5510.779568817363, 5636.0418728815375, 5643.266375371176, 5644.0, 5646.181303711021, 5505.807296744595, 5506.91739623898, 5514.172874068921, 5514.972425137848, 5520.585956053232, 5458.754356054066, 5577.888442231051, 5658.575606829587, 5684.459976302244, 5687.289088863892, 5431.131019036935, 5443.401225865073, 5447.12845944647, 5447.863803404946, 5450.563954883679], "unit": "ms", "name": "prototype.html", "value": 4241.424571087243}, {"lowerIsBetter": false, "replicates": [689.5414549324697, 691.6438034412279, 694.482610455211, 698.3749544157982, 698.6890833579058, 11762.529498820048, 11992.940035299833, 12016.63950081498, 12038.277703337806, 12041.939790301036, 11603.07175425966, 11643.767124657501, 11644.24312419692, 11671.649850504498, 11697.59058432955, 453.9841105561306, 456.1151366349272, 460.2842579788428, 785.1245860865137, 802.0936341933607, 621.7512994802092, 626.880892630401, 785.4972817396871, 800.3517151107592, 803.7428023032631, 846.4075147396816, 847.817719190374, 854.3506934729588, 854.3848429131016, 854.7008547008528, 648.9188851393576, 839.3285371702674, 841.6549214821924, 841.7180244618061, 842.5197637346715, 484.0899109673826, 609.2567068176861, 620.5780069552702, 620.7765204526366, 621.6549814852755, 11262.943685281522, 11363.715907102305, 11373.488193031251, 11374.71563210918, 11387.658370248906, 10683.679489615324, 11635.709107272301, 11777.352245626487, 11822.054235661126, 12081.577144799978, 8970.461772293684, 8991.955040224822, 9050.818983620356, 9145.0, 9249.35254532183, 3301.1747063234193, 3336.4995250712345, 3344.0469466202017, 3345.782524135948, 3346.2638219591895, 37200.0, 37343.0, 37446.0, 37692.43461348082, 37695.24609507822, 9077.863832042525, 9292.814143717087, 9325.90674093264, 9373.90626093744, 9455.243580513543, 16679.66640667204, 16688.916555417145, 16704.74942875858, 16713.665726685642, 17049.659006819795, 6328.905066424008, 6346.0, 6429.324920883333, 6511.967440162769, 6595.571287866273, 3244.0916543367894, 3345.347657206821, 3372.3392621019398, 3406.880759173417, 3408.301298233856, 2561.5133124706244, 2578.059008461898, 2587.8706064697067, 2594.1439325023075, 2604.2187343796786, 451.4447229907233, 455.97036192648034, 458.0175523502069, 462.5536357415111, 462.64607575204997, 504.26125725811863, 572.4618858273293, 613.6778191449436, 621.6114928169894, 621.8479894077545, 7646.3882889368715, 7732.381409487228, 7851.175626559243, 7897.24976127267, 8027.638756255982, 452.69669321553977, 570.9209593867594, 589.3899813692807, 605.7274226598048, 607.6809674920613, 3540.238848647553, 3564.625714300013, 3635.4546817976984, 3728.664420202195, 3748.381517049711, 47449.76275118603, 47914.52085479101, 48063.0, 48086.27870581944, 48244.27633585499, 42530.57469425328, 42843.7362725131, 44232.778836106256, 44296.77851610721, 44399.55600443954, 39377.21245575073, 39918.600813991485, 40167.0, 40665.796671016455, 40712.38931416031, 7200.855982880418, 7300.415966722543, 7431.479796414199, 7455.850882982419, 7515.0, 29541.85229073841, 30052.24869378283, 30193.396132077676, 30374.240643984078, 30785.0, 35738.463923041694, 35980.64019359773, 38475.42286865755, 38639.80680096582, 39090.60909390869, 9479.47862867549, 9512.667056652985, 9538.904610953801, 9580.76048098803, 9616.95191524038, 10110.140638045848, 10308.175345972155, 10321.122704570196, 10331.638392656221, 10394.428306443218, 9350.67272645454, 9461.952690236505, 9525.428474291564, 9612.903870961482, 9993.10062094415, 9607.375520591138, 9718.173955213886, 9733.659321923698, 9853.0, 10092.293539452168, 9778.315517913963, 9882.258830587878, 9883.703488895344, 9889.50552472388, 9906.554205060645, 8203.9589802053, 8240.175982401952, 8307.933639784513, 8417.681989868315, 8514.148585141436, 8954.552272386485, 9083.409578377385, 9274.397164184307, 9395.154436100784, 9485.905140948778], "unit": "ms", "name": "yui.html", "value": 5508.591600605475}], "name": "dromaeo_css", "value": 6234.272419923077}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 112.0, 106.0, 114.0, 112.0, 113.0, 100.0, 113.0, 111.0, 114.0], "unit": "ms", "name": "ai-astar", "value": 110.6}, {"lowerIsBetter": true, "replicates": [151.0, 131.0, 158.0, 163.0, 130.0, 165.0, 159.0, 134.0, 168.0, 132.0], "unit": "ms", "name": "audio-beat-detection", "value": 149.1}, {"lowerIsBetter": true, "replicates": [164.0, 160.0, 160.0, 161.0, 160.0, 160.0, 163.0, 161.0, 160.0, 162.0], "unit": "ms", "name": "audio-dft", "value": 161.1}, {"lowerIsBetter": true, "replicates": [72.0, 74.0, 86.0, 91.0, 72.0, 72.0, 72.0, 107.0, 98.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 81.5}, {"lowerIsBetter": true, "replicates": [94.0, 95.0, 131.0, 94.0, 94.0, 95.0, 133.0, 94.0, 94.0, 127.0], "unit": "ms", "name": "audio-oscillator", "value": 105.1}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 110.0, 110.0, 111.0, 110.0, 110.0, 110.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.9}, {"lowerIsBetter": true, "replicates": [147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 148.0, 146.0, 146.0, 147.0], "unit": "ms", "name": "imaging-darkroom", "value": 146.4}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 99.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.3}, {"lowerIsBetter": true, "replicates": [74.0, 73.0, 72.0, 73.0, 71.0, 72.0, 71.0, 82.0, 69.0, 72.0], "unit": "ms", "name": "json-parse-financial", "value": 72.9}, {"lowerIsBetter": true, "replicates": [67.0, 69.0, 68.0, 68.0, 68.0, 68.0, 68.0, 67.0, 67.0, 67.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 67.7}, {"lowerIsBetter": true, "replicates": [89.0, 84.0, 82.0, 84.0, 83.0, 85.0, 83.0, 84.0, 84.0, 85.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 84.3}, {"lowerIsBetter": true, "replicates": [120.0, 121.0, 123.0, 115.0, 118.0, 119.0, 131.0, 121.0, 122.0, 123.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 121.3}, {"lowerIsBetter": true, "replicates": [141.0, 150.0, 154.0, 147.0, 147.0, 142.0, 151.0, 148.0, 148.0, 138.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 146.6}, {"lowerIsBetter": true, "replicates": [70.0, 71.0, 71.0, 70.0, 70.0, 71.0, 72.0, 71.0, 73.0, 71.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 71.0}], "name": "kraken", "value": 1522.7999999999997}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [165.53550736633008], "unit": "score", "name": "Richards", "value": 213.25938200000004}, {"lowerIsBetter": false, "replicates": [186.01190476190476], "unit": "score", "name": "DeltaBlue", "value": 355.4503679999999}, {"lowerIsBetter": false, "replicates": [278.39643652561244], "unit": "score", "name": "Encrypt", "value": 956.1221520000003}, {"lowerIsBetter": false, "replicates": [5181.347150259067], "unit": "score", "name": "Decrypt", "value": 51.372933}, {"lowerIsBetter": false, "replicates": [1970.4724409448818], "unit": "score", "name": "RayTrace", "value": 375.5388731268733}, {"lowerIsBetter": false, "replicates": [783.6990595611285], "unit": "score", "name": "Earley", "value": 850.4067880000003}, {"lowerIsBetter": false, "replicates": [12108.43373493976], "unit": "score", "name": "Boyer", "value": 55.0412228855721}, {"lowerIsBetter": false, "replicates": [26605.263157894737], "unit": "score", "name": "RegExp", "value": 34.24078140454997}, {"lowerIsBetter": false, "replicates": [619.9628022318661], "unit": "score", "name": "Splay", "value": 131.44498299999998}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7", "value": 19831.33770128034}]} 18:41:45 INFO - RETURN: dromaeo_css: 6234.27 18:41:45 INFO - RETURN: kraken: 1522.80 18:41:45 INFO - RETURN: v8_7: 19831.34 18:41:45 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,203,35]]", "result": "19831.34"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,203,35]]", "result": "1522.80"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,203,35]]", "result": "6234.27"}}} 18:41:45 INFO - Return code: 0 18:41:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:41:45 INFO - # TBPL SUCCESS # 18:41:45 INFO - Running post-action listener: _resource_record_post_action 18:41:45 INFO - Running post-run listener: _resource_record_post_run 18:41:46 INFO - Total resource usage - Wall time: 1234s; CPU: 13.0%; Read bytes: 4096; Write bytes: 515076096; Read time: 12; Write time: 522584 18:41:46 INFO - install - Wall time: 15s; CPU: 15.0%; Read bytes: 0; Write bytes: 240218112; Read time: 0; Write time: 335712 18:41:46 INFO - run-tests - Wall time: 1220s; CPU: 13.0%; Read bytes: 4096; Write bytes: 274857984; Read time: 12; Write time: 186872 18:41:46 INFO - Running post-run listener: _upload_blobber_files 18:41:46 INFO - Blob upload gear active. 18:41:46 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 18:41:46 INFO - Copying logs to upload dir... 18:41:46 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1269.745884 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2015-12-12 18:41:46.157135) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-12 18:41:46.161206) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=23f5739b2d6aa5d9caf5c428000001ae-1449973169.107041-1994106989 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/P0lPiNyARI6ZkYZyxsdEyg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010053 build_url: 'https://queue.taskcluster.net/v1/task/P0lPiNyARI6ZkYZyxsdEyg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.05 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-12 18:41:46.216918) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-12 18:41:46.217299) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=23f5739b2d6aa5d9caf5c428000001ae-1449973169.107041-1994106989 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004517 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-12 18:41:46.267657) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-12 18:41:46.267967) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-12 18:41:46.268363) ========= ========= Total master_lag: 0.27 =========