builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-046 starttime: 1450403544.24 results: success (0) buildid: 20151217170415 builduid: 040a8fa2e2354fac96173be5d901cdc3 revision: e1ce5b4fa814367b46379641a77f06eabd9348ed ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-17 17:52:24.243404) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-17 17:52:24.243931) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-17 17:52:24.244349) ========= 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=fce1c2174958ee92775f70ae000001a3-1450396466.764017-455142318 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.006312 basedir: '/builds/slave/test' ========= master_lag: 0.05 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-17 17:52:24.301418) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-17 17:52:24.301957) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-17 17:52:24.344553) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 17:52:24.344876) ========= 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=fce1c2174958ee92775f70ae000001a3-1450396466.764017-455142318 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004437 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 17:52:24.421096) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-17 17:52:24.421375) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-17 17:52:24.421761) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 17:52:24.422044) ========= 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=fce1c2174958ee92775f70ae000001a3-1450396466.764017-455142318 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-17 17:52:24-- 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% 40.3M=0s 2015-12-17 17:52:24 (40.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.225538 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 17:52:24.661782) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 17:52:24.662076) ========= 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=fce1c2174958ee92775f70ae000001a3-1450396466.764017-455142318 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.158943 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 17:52:24.834980) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-12-17 17:52:24.835301) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev e1ce5b4fa814367b46379641a77f06eabd9348ed --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev e1ce5b4fa814367b46379641a77f06eabd9348ed --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=fce1c2174958ee92775f70ae000001a3-1450396466.764017-455142318 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-17 17:52:24,948 truncating revision to first 12 chars 2015-12-17 17:52:24,948 Setting DEBUG logging. 2015-12-17 17:52:24,948 attempt 1/10 2015-12-17 17:52:24,948 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/e1ce5b4fa814?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-17 17:52:25,995 unpacking tar archive at: fx-team-e1ce5b4fa814/testing/mozharness/ program finished with exit code 0 elapsedTime=1.324194 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-12-17 17:52:26.171931) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-17 17:52:26.172237) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-17 17:52:26.180672) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-17 17:52:26.180935) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-17 17:52:26.181355) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2015-12-17 17:52:26.181698) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-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=fce1c2174958ee92775f70ae000001a3-1450396466.764017-455142318 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:52:26 INFO - MultiFileLogger online at 20151217 17:52:26 in /builds/slave/test 17:52:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 17:52:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:52:26 INFO - {'append_to_log': False, 17:52:26 INFO - 'base_work_dir': '/builds/slave/test', 17:52:26 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 17:52:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:52:26 INFO - 'branch': 'Fx-Team-Non-PGO', 17:52:26 INFO - 'buildbot_json_path': 'buildprops.json', 17:52:26 INFO - 'config_files': ('talos/linux_config.py',), 17:52:26 INFO - 'default_actions': ('clobber', 17:52:26 INFO - 'read-buildbot-config', 17:52:26 INFO - 'download-and-extract', 17:52:26 INFO - 'populate-webroot', 17:52:26 INFO - 'create-virtualenv', 17:52:26 INFO - 'install', 17:52:26 INFO - 'run-tests'), 17:52:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:52:26 INFO - 'download_minidump_stackwalk': True, 17:52:26 INFO - 'download_symbols': 'ondemand', 17:52:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:52:26 INFO - 'tooltool.py': '/tools/tooltool.py', 17:52:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:52:26 INFO - '/tools/misc-python/virtualenv.py')}, 17:52:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:52:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:52:26 INFO - 'installer_path': 'installer.exe', 17:52:26 INFO - 'log_level': 'info', 17:52:26 INFO - 'log_name': 'talos', 17:52:26 INFO - 'log_to_console': True, 17:52:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:52:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:52:26 INFO - 'opt_config_files': (), 17:52:26 INFO - 'pip_index': False, 17:52:26 INFO - 'sps_profile': False, 17:52:26 INFO - 'sps_profile_interval': 0, 17:52:26 INFO - 'suite': 'dromaeojs-e10s', 17:52:26 INFO - 'system_bits': '64', 17:52:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:52:26 INFO - 'title': 'talos-linux64-ix-046', 17:52:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:52:26 INFO - 'use_talos_json': True, 17:52:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:52:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:52:26 INFO - 'work_dir': 'build'} 17:52:26 INFO - ##### 17:52:26 INFO - ##### Running clobber step. 17:52:26 INFO - ##### 17:52:26 INFO - Running pre-action listener: _resource_record_pre_action 17:52:26 INFO - Running main action method: clobber 17:52:26 INFO - rmtree: /builds/slave/test/build 17:52:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:52:28 INFO - Running post-action listener: _resource_record_post_action 17:52:28 INFO - ##### 17:52:28 INFO - ##### Running read-buildbot-config step. 17:52:28 INFO - ##### 17:52:28 INFO - Running pre-action listener: _resource_record_pre_action 17:52:28 INFO - Running main action method: read_buildbot_config 17:52:28 INFO - Using buildbot properties: 17:52:28 INFO - { 17:52:28 INFO - "project": "", 17:52:28 INFO - "product": "firefox", 17:52:28 INFO - "script_repo_revision": "production", 17:52:28 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 17:52:28 INFO - "repository": "", 17:52:28 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs-e10s", 17:52:28 INFO - "buildid": "20151217170415", 17:52:28 INFO - "pgo_build": "False", 17:52:28 INFO - "basedir": "/builds/slave/test", 17:52:28 INFO - "buildnumber": 580, 17:52:28 INFO - "slavename": "talos-linux64-ix-046", 17:52:28 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 17:52:28 INFO - "platform": "ubuntu64_hw", 17:52:28 INFO - "scheduler": "tests-fx-team-linux64-talos", 17:52:28 INFO - "branch": "fx-team", 17:52:28 INFO - "repo_path": "integration/fx-team", 17:52:28 INFO - "revision": "e1ce5b4fa814367b46379641a77f06eabd9348ed", 17:52:28 INFO - "stage_platform": "linux64", 17:52:28 INFO - "builduid": "040a8fa2e2354fac96173be5d901cdc3", 17:52:28 INFO - "slavebuilddir": "test" 17:52:28 INFO - } 17:52:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/KTJF1JIjRdC93-LnnksKRQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 17:52:28 INFO - Running post-action listener: _resource_record_post_action 17:52:28 INFO - ##### 17:52:28 INFO - ##### Running download-and-extract step. 17:52:28 INFO - ##### 17:52:28 INFO - Running pre-action listener: _resource_record_pre_action 17:52:28 INFO - Running main action method: download_and_extract 17:52:28 INFO - mkdir: /builds/slave/test/build/tests 17:52:28 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')]} 17:52:28 INFO - https://queue.taskcluster.net/v1/task/KTJF1JIjRdC93-LnnksKRQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 17:52:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KTJF1JIjRdC93-LnnksKRQ/artifacts/public/build/test_packages.json 17:52:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KTJF1JIjRdC93-LnnksKRQ/artifacts/public/build/test_packages.json 17:52:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KTJF1JIjRdC93-LnnksKRQ/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 17:52:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KTJF1JIjRdC93-LnnksKRQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 17:52:30 INFO - Downloaded 1302 bytes. 17:52:30 INFO - Reading from file /builds/slave/test/build/test_packages.json 17:52:30 INFO - Using the following test package requirements: 17:52:30 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 17:52:30 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 17:52:30 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 17:52:30 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 17:52:30 INFO - u'jsshell-linux-x86_64.zip'], 17:52:30 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 17:52:30 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 17:52:30 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 17:52:30 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 17:52:30 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 17:52:30 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 17:52:30 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 17:52:30 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 17:52:30 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 17:52:30 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 17:52:30 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 17:52:30 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 17:52:30 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:52:30 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')]} 17:52:30 INFO - https://queue.taskcluster.net/v1/task/KTJF1JIjRdC93-LnnksKRQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:52:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KTJF1JIjRdC93-LnnksKRQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 17:52:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KTJF1JIjRdC93-LnnksKRQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 17:52:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KTJF1JIjRdC93-LnnksKRQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 17:52:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KTJF1JIjRdC93-LnnksKRQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:52:36 INFO - Downloaded 21870957 bytes. 17:52:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 17:52:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 17:52:36 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 17:52:37 INFO - Return code: 0 17:52:37 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 17:52: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')]} 17:52:37 INFO - https://queue.taskcluster.net/v1/task/KTJF1JIjRdC93-LnnksKRQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:52:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KTJF1JIjRdC93-LnnksKRQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 17:52:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KTJF1JIjRdC93-LnnksKRQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 17:52:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KTJF1JIjRdC93-LnnksKRQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 17:52:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KTJF1JIjRdC93-LnnksKRQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:52:39 INFO - Downloaded 21870957 bytes. 17:52:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 17:52:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 17:52:39 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 17:52:41 INFO - Return code: 0 17:52: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')]} 17:52:41 INFO - https://queue.taskcluster.net/v1/task/KTJF1JIjRdC93-LnnksKRQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 17:52:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KTJF1JIjRdC93-LnnksKRQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 17:52:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KTJF1JIjRdC93-LnnksKRQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 17:52:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KTJF1JIjRdC93-LnnksKRQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 17:52:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KTJF1JIjRdC93-LnnksKRQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 17:52:42 INFO - Downloaded 11132039 bytes. 17:52:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test/build/tests 17:52:42 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 17:52:42 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 17:52:42 INFO - Return code: 0 17:52:42 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')]} 17:52:42 INFO - https://queue.taskcluster.net/v1/task/KTJF1JIjRdC93-LnnksKRQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 17:52:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KTJF1JIjRdC93-LnnksKRQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 17:52:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KTJF1JIjRdC93-LnnksKRQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 17:52:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KTJF1JIjRdC93-LnnksKRQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 17:52:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KTJF1JIjRdC93-LnnksKRQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 17:52:44 INFO - Downloaded 53015239 bytes. 17:52:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KTJF1JIjRdC93-LnnksKRQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 17:52:44 INFO - mkdir: /builds/slave/test/properties 17:52:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:52:44 INFO - Writing to file /builds/slave/test/properties/build_url 17:52:44 INFO - Contents: 17:52:44 INFO - build_url:https://queue.taskcluster.net/v1/task/KTJF1JIjRdC93-LnnksKRQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 17:52:44 INFO - Running post-action listener: _resource_record_post_action 17:52:44 INFO - Running post-action listener: set_extra_try_arguments 17:52:44 INFO - ##### 17:52:44 INFO - ##### Running populate-webroot step. 17:52:44 INFO - ##### 17:52:44 INFO - Running pre-action listener: _resource_record_pre_action 17:52:44 INFO - Running main action method: populate_webroot 17:52:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:52:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:52:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:52:44 INFO - '--tppagecycles', 17:52:44 INFO - '7'], 17:52:44 INFO - 'tests': ['tsvgm']}}, 17:52:44 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 17:52:44 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 17:52:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:52:44 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 17:52:44 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 17:52:44 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 17:52:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:52:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:52:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:52:44 INFO - 'talos_options': ['--e10s'], 17:52:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:52:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:52:44 INFO - 'tests': ['damp', 'tps']}, 17:52:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:52:44 INFO - 'talos_options': ['--e10s'], 17:52:44 INFO - 'tests': ['damp', 'tps']}, 17:52:44 INFO - 'g3': {'tests': ['dromaeo_dom']}, 17:52:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:52:44 INFO - 'other': {'tests': ['a11yr', 17:52:44 INFO - 'ts_paint', 17:52:44 INFO - 'tpaint', 17:52:44 INFO - 'sessionrestore', 17:52:44 INFO - 'sessionrestore_no_auto_restore']}, 17:52:44 INFO - 'other-e10s': {'talos_options': ['--e10s'], 17:52:44 INFO - 'tests': ['a11yr', 17:52:44 INFO - 'ts_paint', 17:52:44 INFO - 'tpaint', 17:52:44 INFO - 'sessionrestore', 17:52:44 INFO - 'sessionrestore_no_auto_restore']}, 17:52:44 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 17:52:44 INFO - 'tests': ['a11yr', 17:52:44 INFO - 'ts_paint', 17:52:44 INFO - 'tpaint', 17:52:44 INFO - 'sessionrestore', 17:52:44 INFO - 'sessionrestore_no_auto_restore']}, 17:52:44 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 17:52:44 INFO - 'tests': ['a11yr', 17:52:44 INFO - 'ts_paint', 17:52:44 INFO - 'tpaint', 17:52:44 INFO - 'sessionrestore', 17:52:44 INFO - 'sessionrestore_no_auto_restore']}, 17:52:44 INFO - 'other_l64': {'tests': ['a11yr', 17:52:44 INFO - 'ts_paint', 17:52:44 INFO - 'tpaint', 17:52:44 INFO - 'sessionrestore', 17:52:44 INFO - 'sessionrestore_no_auto_restore']}, 17:52:44 INFO - 'other_nol64': {'tests': ['a11yr', 17:52:44 INFO - 'ts_paint', 17:52:44 INFO - 'tpaint', 17:52:44 INFO - 'sessionrestore', 17:52:44 INFO - 'sessionrestore_no_auto_restore']}, 17:52:44 INFO - 'svgr': {'tests': ['tsvgx', 17:52:44 INFO - 'tsvgr_opacity', 17:52:44 INFO - 'tart', 17:52:44 INFO - 'tscrollx', 17:52:44 INFO - 'cart']}, 17:52:44 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 17:52:44 INFO - 'tests': ['tsvgx', 17:52:44 INFO - 'tsvgr_opacity', 17:52:44 INFO - 'tart', 17:52:44 INFO - 'tscrollx', 17:52:44 INFO - 'cart']}, 17:52:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:52:44 INFO - 'tests': ['tp5o']}, 17:52:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:52:44 INFO - 'talos_options': ['--e10s'], 17:52:44 INFO - 'tests': ['tp5o']}, 17:52:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:52:44 INFO - 'talos_options': ['--xperf_path', 17:52:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:52:44 INFO - 'tests': ['tp5n']}, 17:52:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:52:44 INFO - 'talos_options': ['--e10s', 17:52:44 INFO - '--xperf_path', 17:52:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:52:44 INFO - 'tests': ['tp5n']}}, 17:52:44 INFO - 'talos.zip': {'path': '', 17:52:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:52:44 INFO - Running post-action listener: _resource_record_post_action 17:52:44 INFO - ##### 17:52:44 INFO - ##### Running create-virtualenv step. 17:52:44 INFO - ##### 17:52:44 INFO - Running pre-action listener: _resource_record_pre_action 17:52:44 INFO - Running main action method: create_virtualenv 17:52:44 INFO - Creating virtualenv /builds/slave/test/build/venv 17:52:44 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 17:52:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:52:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:52:44 INFO - Using real prefix '/usr' 17:52:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:52:44 INFO - Installing distribute.............................................................................................................................................................................................done. 17:52:47 INFO - Installing pip.................done. 17:52:47 INFO - Return code: 0 17:52:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:52:47 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')]} 17:52:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:52:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:52:47 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')]} 17:52:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:52:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:52:47 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 0x7ffc8bbb8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15d58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16a3240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17320b0>, '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 0x1732fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1733440>, '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': 'fce1c2174958ee92775f70ae000001a3-1450396466.764017-455142318', '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 17:52:47 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 17:52:47 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 17:52:47 INFO - Using env: {'DISPLAY': ':0', 17:52:47 INFO - 'HOME': '/home/cltbld', 17:52:47 INFO - 'LANG': 'en_US.UTF-8', 17:52:47 INFO - 'LANGUAGE': 'en_US:en', 17:52:47 INFO - 'LOGNAME': 'cltbld', 17:52:47 INFO - 'MAIL': '/var/mail/cltbld', 17:52:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:52:47 INFO - 'MOZ_NO_REMOTE': '1', 17:52:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:52:47 INFO - 'NO_EM_RESTART': '1', 17:52:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:52:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:52:47 INFO - 'PWD': '/builds/slave/test', 17:52:47 INFO - 'SHELL': '/bin/bash', 17:52:47 INFO - 'SHLVL': '1', 17:52:47 INFO - 'TERM': 'linux', 17:52:47 INFO - 'TMOUT': '86400', 17:52:47 INFO - 'USER': 'cltbld', 17:52:47 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1450396466.764017-455142318', 17:52:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:52:47 INFO - '_': '/tools/buildbot/bin/python'} 17:52:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:52:47 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 17:52:47 INFO - Cleaning up... 17:52:47 INFO - Return code: 0 17:52:47 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 17:52:47 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')]} 17:52:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:52:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:52:47 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')]} 17:52:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:52:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:52:47 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 0x7ffc8bbb8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15d58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16a3240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17320b0>, '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 0x1732fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1733440>, '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': 'fce1c2174958ee92775f70ae000001a3-1450396466.764017-455142318', '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 17:52:47 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 17:52:47 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 17:52:47 INFO - Using env: {'DISPLAY': ':0', 17:52:47 INFO - 'HOME': '/home/cltbld', 17:52:47 INFO - 'LANG': 'en_US.UTF-8', 17:52:47 INFO - 'LANGUAGE': 'en_US:en', 17:52:47 INFO - 'LOGNAME': 'cltbld', 17:52:47 INFO - 'MAIL': '/var/mail/cltbld', 17:52:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:52:47 INFO - 'MOZ_NO_REMOTE': '1', 17:52:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:52:47 INFO - 'NO_EM_RESTART': '1', 17:52:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:52:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:52:47 INFO - 'PWD': '/builds/slave/test', 17:52:47 INFO - 'SHELL': '/bin/bash', 17:52:47 INFO - 'SHLVL': '1', 17:52:47 INFO - 'TERM': 'linux', 17:52:47 INFO - 'TMOUT': '86400', 17:52:47 INFO - 'USER': 'cltbld', 17:52:47 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1450396466.764017-455142318', 17:52:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:52:47 INFO - '_': '/tools/buildbot/bin/python'} 17:52:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:52:47 INFO - Downloading/unpacking psutil>=0.7.1 17:52:47 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 17:52:47 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 17:52:47 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 17:52:47 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 17:52:47 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 17:52:47 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 17:52:49 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 17:52:49 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 17:52:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:52:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:52:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:52:49 INFO - Installing collected packages: psutil 17:52:49 INFO - Running setup.py install for psutil 17:52:49 INFO - building 'psutil._psutil_linux' extension 17:52:49 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 17:52:50 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 17:52:50 INFO - building 'psutil._psutil_posix' extension 17:52:50 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 17:52:51 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 17:52:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:52:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:52:51 INFO - Successfully installed psutil 17:52:51 INFO - Cleaning up... 17:52:51 INFO - Return code: 0 17:52:51 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 17:52:51 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')]} 17:52:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:52:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:52:51 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')]} 17:52:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:52:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:52:51 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 0x7ffc8bbb8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15d58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16a3240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17320b0>, '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 0x1732fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1733440>, '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': 'fce1c2174958ee92775f70ae000001a3-1450396466.764017-455142318', '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 17:52:51 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 17:52:51 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 17:52:51 INFO - Using env: {'DISPLAY': ':0', 17:52:51 INFO - 'HOME': '/home/cltbld', 17:52:51 INFO - 'LANG': 'en_US.UTF-8', 17:52:51 INFO - 'LANGUAGE': 'en_US:en', 17:52:51 INFO - 'LOGNAME': 'cltbld', 17:52:51 INFO - 'MAIL': '/var/mail/cltbld', 17:52:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:52:51 INFO - 'MOZ_NO_REMOTE': '1', 17:52:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:52:51 INFO - 'NO_EM_RESTART': '1', 17:52:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:52:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:52:51 INFO - 'PWD': '/builds/slave/test', 17:52:51 INFO - 'SHELL': '/bin/bash', 17:52:51 INFO - 'SHLVL': '1', 17:52:51 INFO - 'TERM': 'linux', 17:52:51 INFO - 'TMOUT': '86400', 17:52:51 INFO - 'USER': 'cltbld', 17:52:51 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1450396466.764017-455142318', 17:52:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:52:51 INFO - '_': '/tools/buildbot/bin/python'} 17:52:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:52:51 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 17:52:51 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 17:52:51 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 17:52:51 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 17:52:51 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 17:52:51 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 17:52:51 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 17:52:53 INFO - Downloading mozsystemmonitor-0.0.tar.gz 17:52:53 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 17:52:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:52:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 17:52:53 INFO - Installing collected packages: mozsystemmonitor 17:52:53 INFO - Running setup.py install for mozsystemmonitor 17:52:53 INFO - Successfully installed mozsystemmonitor 17:52:53 INFO - Cleaning up... 17:52:53 INFO - Return code: 0 17:52:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:52: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')]} 17:52:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:52:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:52: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')]} 17:52:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:52:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:52: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', '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 0x7ffc8bbb8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15d58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16a3240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17320b0>, '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 0x1732fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1733440>, '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': 'fce1c2174958ee92775f70ae000001a3-1450396466.764017-455142318', '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 17:52: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:52: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 blobuploader==1.2.4 17:52:53 INFO - Using env: {'DISPLAY': ':0', 17:52:53 INFO - 'HOME': '/home/cltbld', 17:52:53 INFO - 'LANG': 'en_US.UTF-8', 17:52:53 INFO - 'LANGUAGE': 'en_US:en', 17:52:53 INFO - 'LOGNAME': 'cltbld', 17:52:53 INFO - 'MAIL': '/var/mail/cltbld', 17:52:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:52:53 INFO - 'MOZ_NO_REMOTE': '1', 17:52:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:52:53 INFO - 'NO_EM_RESTART': '1', 17:52:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:52:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:52:53 INFO - 'PWD': '/builds/slave/test', 17:52:53 INFO - 'SHELL': '/bin/bash', 17:52:53 INFO - 'SHLVL': '1', 17:52:53 INFO - 'TERM': 'linux', 17:52:53 INFO - 'TMOUT': '86400', 17:52:53 INFO - 'USER': 'cltbld', 17:52:53 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1450396466.764017-455142318', 17:52:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:52:53 INFO - '_': '/tools/buildbot/bin/python'} 17:52:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:52:53 INFO - Downloading/unpacking blobuploader==1.2.4 17:52: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 17:52: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 17:52: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 17:52: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 17:52: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 17:52: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 17:52:55 INFO - Downloading blobuploader-1.2.4.tar.gz 17:52:55 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 17:52:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:52:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:52: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 17:52: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 17:52: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 17:52: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 17:52: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 17:52: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 17:52:55 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 17:52:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:52:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:52: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 17:52: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 17:52: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 17:52: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 17:52: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 17:52: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 17:52:55 INFO - Downloading docopt-0.6.1.tar.gz 17:52:55 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 17:52:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:52:55 INFO - Installing collected packages: blobuploader, requests, docopt 17:52:55 INFO - Running setup.py install for blobuploader 17:52:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:52:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:52:55 INFO - Running setup.py install for requests 17:52:56 INFO - Running setup.py install for docopt 17:52:56 INFO - Successfully installed blobuploader requests docopt 17:52:56 INFO - Cleaning up... 17:52:56 INFO - Return code: 0 17:52:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:52:56 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')]} 17:52:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:52:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:52:56 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')]} 17:52:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:52:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:52:56 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 0x7ffc8bbb8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15d58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16a3240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17320b0>, '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 0x1732fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1733440>, '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': 'fce1c2174958ee92775f70ae000001a3-1450396466.764017-455142318', '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 17:52:56 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 17:52:56 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 17:52:56 INFO - Using env: {'DISPLAY': ':0', 17:52:56 INFO - 'HOME': '/home/cltbld', 17:52:56 INFO - 'LANG': 'en_US.UTF-8', 17:52:56 INFO - 'LANGUAGE': 'en_US:en', 17:52:56 INFO - 'LOGNAME': 'cltbld', 17:52:56 INFO - 'MAIL': '/var/mail/cltbld', 17:52:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:52:56 INFO - 'MOZ_NO_REMOTE': '1', 17:52:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:52:56 INFO - 'NO_EM_RESTART': '1', 17:52:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:52:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:52:56 INFO - 'PWD': '/builds/slave/test', 17:52:56 INFO - 'SHELL': '/bin/bash', 17:52:56 INFO - 'SHLVL': '1', 17:52:56 INFO - 'TERM': 'linux', 17:52:56 INFO - 'TMOUT': '86400', 17:52:56 INFO - 'USER': 'cltbld', 17:52:56 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1450396466.764017-455142318', 17:52:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:52:56 INFO - '_': '/tools/buildbot/bin/python'} 17:52:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:52:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:52:56 INFO - Running setup.py (path:/tmp/pip-yd7LnM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:52:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:52:56 INFO - Running setup.py (path:/tmp/pip-5MyCGO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:52:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:52:56 INFO - Running setup.py (path:/tmp/pip-2JGbia-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:52:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:52:56 INFO - Running setup.py (path:/tmp/pip-xn5KRY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:52:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:52:56 INFO - Running setup.py (path:/tmp/pip-E1dMYv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:52:57 INFO - Running setup.py (path:/tmp/pip-PCMDIt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:52:57 INFO - Running setup.py (path:/tmp/pip-A2D6jt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:52:57 INFO - Running setup.py (path:/tmp/pip-dEEX02-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:52:57 INFO - Running setup.py (path:/tmp/pip-oNC6in-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:52:57 INFO - Running setup.py (path:/tmp/pip-VulnVW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:52:57 INFO - Running setup.py (path:/tmp/pip-qgoALp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:52:57 INFO - Running setup.py (path:/tmp/pip-pqSgZN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:52:57 INFO - Running setup.py (path:/tmp/pip-0fChex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:52:57 INFO - Running setup.py (path:/tmp/pip-1NmevW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:52:57 INFO - Running setup.py (path:/tmp/pip-CJSwQs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:52:57 INFO - Running setup.py (path:/tmp/pip-MiLxUH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:52:57 INFO - Running setup.py (path:/tmp/pip-ednz4H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:52:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:52:57 INFO - Running setup.py install for manifestparser 17:52:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:52:58 INFO - Running setup.py install for mozcrash 17:52:58 INFO - Running setup.py install for mozdebug 17:52:58 INFO - Running setup.py install for mozdevice 17:52:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:52:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:52:58 INFO - Running setup.py install for mozfile 17:52:58 INFO - Running setup.py install for mozhttpd 17:52:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:52:58 INFO - Running setup.py install for mozinfo 17:52:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:52:58 INFO - Running setup.py install for mozInstall 17:52:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:52:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:52:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:52:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:52:58 INFO - Running setup.py install for mozleak 17:52:58 INFO - Running setup.py install for mozlog 17:52:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:52:58 INFO - Running setup.py install for moznetwork 17:52:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:52:59 INFO - Running setup.py install for mozprocess 17:52:59 INFO - Running setup.py install for mozprofile 17:52:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:52:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:52:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:52:59 INFO - Running setup.py install for mozrunner 17:52:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:52:59 INFO - Running setup.py install for mozscreenshot 17:52:59 INFO - Running setup.py install for moztest 17:52:59 INFO - Running setup.py install for mozversion 17:52:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:52:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:52:59 INFO - Cleaning up... 17:52:59 INFO - Return code: 0 17:52:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:52:59 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')]} 17:52:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:52:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:52:59 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')]} 17:52:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:52:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:52:59 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 0x7ffc8bbb8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15d58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16a3240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17320b0>, '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 0x1732fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1733440>, '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': 'fce1c2174958ee92775f70ae000001a3-1450396466.764017-455142318', '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 17:52:59 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 17:52:59 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 17:52:59 INFO - Using env: {'DISPLAY': ':0', 17:52:59 INFO - 'HOME': '/home/cltbld', 17:52:59 INFO - 'LANG': 'en_US.UTF-8', 17:52:59 INFO - 'LANGUAGE': 'en_US:en', 17:52:59 INFO - 'LOGNAME': 'cltbld', 17:52:59 INFO - 'MAIL': '/var/mail/cltbld', 17:52:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:52:59 INFO - 'MOZ_NO_REMOTE': '1', 17:52:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:52:59 INFO - 'NO_EM_RESTART': '1', 17:52:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:52:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:52:59 INFO - 'PWD': '/builds/slave/test', 17:52:59 INFO - 'SHELL': '/bin/bash', 17:52:59 INFO - 'SHLVL': '1', 17:52:59 INFO - 'TERM': 'linux', 17:52:59 INFO - 'TMOUT': '86400', 17:52:59 INFO - 'USER': 'cltbld', 17:52:59 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1450396466.764017-455142318', 17:52:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:52:59 INFO - '_': '/tools/buildbot/bin/python'} 17:52:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:52:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:52:59 INFO - Running setup.py (path:/tmp/pip-PfT_SG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:52:59 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)) 17:52:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:52:59 INFO - Running setup.py (path:/tmp/pip-5fN9wV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:53:00 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)) 17:53:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:53:00 INFO - Running setup.py (path:/tmp/pip-yJ8a7F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:53:00 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)) 17:53:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:53:00 INFO - Running setup.py (path:/tmp/pip-NdHkAJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:53:00 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)) 17:53:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:53:00 INFO - Running setup.py (path:/tmp/pip-CIUJu6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:53:00 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)) 17:53:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:53:00 INFO - Running setup.py (path:/tmp/pip-YqPqvi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:53:00 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)) 17:53:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:53:00 INFO - Running setup.py (path:/tmp/pip-F5SvlD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:53:00 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)) 17:53:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:53:00 INFO - Running setup.py (path:/tmp/pip-pEmJeV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:53:00 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)) 17:53:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:53:00 INFO - Running setup.py (path:/tmp/pip-AoYy7E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:53:00 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)) 17:53:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:53:00 INFO - Running setup.py (path:/tmp/pip-vaTIf0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:53:00 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)) 17:53:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:53:00 INFO - Running setup.py (path:/tmp/pip-wpZT20-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:53:00 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)) 17:53:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:53:00 INFO - Running setup.py (path:/tmp/pip-H6FkrI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:53:00 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)) 17:53:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:53:00 INFO - Running setup.py (path:/tmp/pip-ExuKIu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:53:00 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)) 17:53:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:53:00 INFO - Running setup.py (path:/tmp/pip-Nlj8kJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:53:00 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)) 17:53:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:53:00 INFO - Running setup.py (path:/tmp/pip-BfowHI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:53:00 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)) 17:53:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:53:00 INFO - Running setup.py (path:/tmp/pip-fxLHD0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:53:01 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)) 17:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:53:01 INFO - Running setup.py (path:/tmp/pip-adWLmO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:53:01 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)) 17:53:01 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)) 17:53:01 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)) 17:53:01 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)) 17:53:01 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)) 17:53:01 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)) 17:53:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:53:01 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 17:53:01 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 17:53:01 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 17:53:01 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 17:53:01 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 17:53:01 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 17:53:02 INFO - Downloading blessings-1.5.1.tar.gz 17:53:02 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 17:53:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:53:02 INFO - Installing collected packages: blessings 17:53:02 INFO - Running setup.py install for blessings 17:53:03 INFO - Successfully installed blessings 17:53:03 INFO - Cleaning up... 17:53:03 INFO - Return code: 0 17:53:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:53:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:53:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:53:03 INFO - Reading from file tmpfile_stdout 17:53:03 INFO - Current package versions: 17:53:03 INFO - argparse == 1.2.1 17:53:03 INFO - blessings == 1.5.1 17:53:03 INFO - blobuploader == 1.2.4 17:53:03 INFO - docopt == 0.6.1 17:53:03 INFO - manifestparser == 1.1 17:53:03 INFO - mozInstall == 1.12 17:53:03 INFO - mozcrash == 0.16 17:53:03 INFO - mozdebug == 0.1 17:53:03 INFO - mozdevice == 0.47 17:53:03 INFO - mozfile == 1.2 17:53:03 INFO - mozhttpd == 0.7 17:53:03 INFO - mozinfo == 0.9 17:53:03 INFO - mozleak == 0.1 17:53:03 INFO - mozlog == 3.1 17:53:03 INFO - moznetwork == 0.27 17:53:03 INFO - mozprocess == 0.22 17:53:03 INFO - mozprofile == 0.27 17:53:03 INFO - mozrunner == 6.11 17:53:03 INFO - mozscreenshot == 0.1 17:53:03 INFO - mozsystemmonitor == 0.0 17:53:03 INFO - moztest == 0.7 17:53:03 INFO - mozversion == 1.4 17:53:03 INFO - psutil == 3.1.1 17:53:03 INFO - requests == 1.2.3 17:53:03 INFO - wsgiref == 0.1.2 17:53:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:53:03 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')]} 17:53:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:53:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:53:03 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')]} 17:53:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:53:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:53:03 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 0x7ffc8bbb8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15d58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16a3240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17320b0>, '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 0x1732fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1733440>, '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': 'fce1c2174958ee92775f70ae000001a3-1450396466.764017-455142318', '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 17:53:03 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 17:53:03 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 17:53:03 INFO - Using env: {'DISPLAY': ':0', 17:53:03 INFO - 'HOME': '/home/cltbld', 17:53:03 INFO - 'LANG': 'en_US.UTF-8', 17:53:03 INFO - 'LANGUAGE': 'en_US:en', 17:53:03 INFO - 'LOGNAME': 'cltbld', 17:53:03 INFO - 'MAIL': '/var/mail/cltbld', 17:53:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:53:03 INFO - 'MOZ_NO_REMOTE': '1', 17:53:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:53:03 INFO - 'NO_EM_RESTART': '1', 17:53:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:53:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:53:03 INFO - 'PWD': '/builds/slave/test', 17:53:03 INFO - 'SHELL': '/bin/bash', 17:53:03 INFO - 'SHLVL': '1', 17:53:03 INFO - 'TERM': 'linux', 17:53:03 INFO - 'TMOUT': '86400', 17:53:03 INFO - 'USER': 'cltbld', 17:53:03 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1450396466.764017-455142318', 17:53:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:53:03 INFO - '_': '/tools/buildbot/bin/python'} 17:53:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:53:03 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)) 17:53:03 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)) 17:53:03 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)) 17:53:03 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)) 17:53:03 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)) 17:53:03 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)) 17:53:03 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)) 17:53:03 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)) 17:53:03 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)) 17:53:03 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)) 17:53:03 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)) 17:53:03 INFO - Cleaning up... 17:53:03 INFO - Return code: 0 17:53:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:53:03 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')]} 17:53:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:53:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:53:03 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')]} 17:53:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:53:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:53:03 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 0x7ffc8bbb8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15d58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16a3240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17320b0>, '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 0x1732fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1733440>, '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': 'fce1c2174958ee92775f70ae000001a3-1450396466.764017-455142318', '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 17:53:03 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 17:53:03 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 17:53:03 INFO - Using env: {'DISPLAY': ':0', 17:53:03 INFO - 'HOME': '/home/cltbld', 17:53:03 INFO - 'LANG': 'en_US.UTF-8', 17:53:03 INFO - 'LANGUAGE': 'en_US:en', 17:53:03 INFO - 'LOGNAME': 'cltbld', 17:53:03 INFO - 'MAIL': '/var/mail/cltbld', 17:53:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:53:03 INFO - 'MOZ_NO_REMOTE': '1', 17:53:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:53:03 INFO - 'NO_EM_RESTART': '1', 17:53:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:53:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:53:03 INFO - 'PWD': '/builds/slave/test', 17:53:03 INFO - 'SHELL': '/bin/bash', 17:53:03 INFO - 'SHLVL': '1', 17:53:03 INFO - 'TERM': 'linux', 17:53:03 INFO - 'TMOUT': '86400', 17:53:03 INFO - 'USER': 'cltbld', 17:53:03 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1450396466.764017-455142318', 17:53:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:53:03 INFO - '_': '/tools/buildbot/bin/python'} 17:53:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:53:03 INFO - Downloading/unpacking jsonschema 17:53:03 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 17:53:03 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 17:53:03 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 17:53:03 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 17:53:03 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 17:53:03 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 17:53:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:53:05 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 17:53:05 INFO - Downloading/unpacking functools32 (from jsonschema) 17:53: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 17:53: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 17:53: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 17:53: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 17:53: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 17:53: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 17:53:05 INFO - Downloading functools32-3.2.3-2.tar.gz 17:53:05 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 17:53:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:53:05 INFO - warning: no files found matching '*.txt' 17:53:05 INFO - no previously-included directories found matching 'build' 17:53:05 INFO - no previously-included directories found matching 'dist' 17:53:05 INFO - no previously-included directories found matching '.git*' 17:53:05 INFO - Installing collected packages: jsonschema, functools32 17:53:05 INFO - Running setup.py install for functools32 17:53:05 INFO - warning: no files found matching '*.txt' 17:53:05 INFO - no previously-included directories found matching 'build' 17:53:05 INFO - no previously-included directories found matching 'dist' 17:53:05 INFO - no previously-included directories found matching '.git*' 17:53:05 INFO - Successfully installed jsonschema functools32 17:53:05 INFO - Cleaning up... 17:53:05 INFO - Return code: 0 17:53:05 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 17:53: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')]} 17:53:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:53:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:53: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')]} 17:53:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:53:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:53: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc8bbb8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15d58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16a3240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17320b0>, '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 0x1732fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1733440>, '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': 'fce1c2174958ee92775f70ae000001a3-1450396466.764017-455142318', '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 17:53: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', 'flake8'] in /builds/slave/test/build 17:53: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 flake8 17:53:05 INFO - Using env: {'DISPLAY': ':0', 17:53:05 INFO - 'HOME': '/home/cltbld', 17:53:05 INFO - 'LANG': 'en_US.UTF-8', 17:53:05 INFO - 'LANGUAGE': 'en_US:en', 17:53:05 INFO - 'LOGNAME': 'cltbld', 17:53:05 INFO - 'MAIL': '/var/mail/cltbld', 17:53:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:53:05 INFO - 'MOZ_NO_REMOTE': '1', 17:53:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:53:05 INFO - 'NO_EM_RESTART': '1', 17:53:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:53:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:53:05 INFO - 'PWD': '/builds/slave/test', 17:53:05 INFO - 'SHELL': '/bin/bash', 17:53:05 INFO - 'SHLVL': '1', 17:53:05 INFO - 'TERM': 'linux', 17:53:05 INFO - 'TMOUT': '86400', 17:53:05 INFO - 'USER': 'cltbld', 17:53:05 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1450396466.764017-455142318', 17:53:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:53:05 INFO - '_': '/tools/buildbot/bin/python'} 17:53:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:53:06 INFO - Downloading/unpacking flake8 17:53:06 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 17:53:06 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 17:53:06 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 17:53:06 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 17:53:06 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 17:53:06 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 17:53:07 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 17:53:07 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 17:53:07 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 17:53: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 17:53: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 17:53: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 17:53: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 17:53: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 17:53: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 17:53:07 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 17:53:07 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 17:53:07 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 17:53: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 17:53: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 17:53: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 17:53: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 17:53: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 17:53: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 17:53:08 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 17:53:08 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 17:53:08 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 17:53:08 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 17:53:08 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 17:53:08 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 17:53:08 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 17:53:08 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 17:53:08 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 17:53:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 17:53:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:53:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:53:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:53:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:53:08 INFO - no previously-included directories found matching 'docs/_build' 17:53:08 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 17:53:08 INFO - Running setup.py install for pep8 17:53:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:53:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:53:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:53:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:53:08 INFO - no previously-included directories found matching 'docs/_build' 17:53:08 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 17:53:08 INFO - Successfully installed flake8 mccabe pyflakes pep8 17:53:08 INFO - Cleaning up... 17:53:08 INFO - Return code: 0 17:53:08 INFO - Running post-action listener: _resource_record_post_action 17:53:08 INFO - Running post-action listener: _start_resource_monitoring 17:53:08 INFO - Starting resource monitoring. 17:53:08 INFO - ##### 17:53:08 INFO - ##### Running install step. 17:53:08 INFO - ##### 17:53:08 INFO - Running pre-action listener: _resource_record_pre_action 17:53:08 INFO - Running main action method: install 17:53:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:53:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:53:08 INFO - Reading from file tmpfile_stdout 17:53:08 INFO - Detecting whether we're running mozinstall >=1.0... 17:53:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:53:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:53:09 INFO - Reading from file tmpfile_stdout 17:53:09 INFO - Output received: 17:53:09 INFO - Usage: mozinstall [options] installer 17:53:09 INFO - Options: 17:53:09 INFO - -h, --help show this help message and exit 17:53:09 INFO - -d DEST, --destination=DEST 17:53:09 INFO - Directory to install application into. [default: 17:53:09 INFO - "/builds/slave/test"] 17:53:09 INFO - --app=APP Application being installed. [default: firefox] 17:53:09 INFO - mkdir: /builds/slave/test/build/application 17:53:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 17:53:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 17:53:24 INFO - Reading from file tmpfile_stdout 17:53:24 INFO - Output received: 17:53:24 INFO - /builds/slave/test/build/application/firefox/firefox 17:53:24 INFO - Running post-action listener: _resource_record_post_action 17:53:24 INFO - ##### 17:53:24 INFO - ##### Running run-tests step. 17:53:24 INFO - ##### 17:53:24 INFO - Running pre-action listener: _resource_record_pre_action 17:53:24 INFO - Running main action method: run_tests 17:53:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:53:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:53:24 INFO - Python 2.7.3 17:53:24 INFO - Return code: 0 17:53:24 INFO - grabbing minidump binary from tooltool 17:53:24 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')]} 17:53:24 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 0x17320b0>, '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 0x1732fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1733440>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:53:24 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 17:53:24 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 17:53:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:53:24 INFO - Return code: 0 17:53:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:53:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:53:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:53:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:53:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:53:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 17:53:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 17:53:25 INFO - Return code: 0 17:53:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KTJF1JIjRdC93-LnnksKRQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--webServer', 'localhost'] in /builds/slave/test/build 17:53:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --debug --branchName Fx-Team-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KTJF1JIjRdC93-LnnksKRQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-046 --webServer localhost 17:53:25 INFO - Using env: {'DISPLAY': ':0', 17:53:25 INFO - 'HOME': '/home/cltbld', 17:53:25 INFO - 'LANG': 'en_US.UTF-8', 17:53:25 INFO - 'LANGUAGE': 'en_US:en', 17:53:25 INFO - 'LOGNAME': 'cltbld', 17:53:25 INFO - 'MAIL': '/var/mail/cltbld', 17:53:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:53:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:53:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:53:25 INFO - 'MOZ_NO_REMOTE': '1', 17:53:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:53:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:53:25 INFO - 'NO_EM_RESTART': '1', 17:53:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:53:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:53:25 INFO - 'PWD': '/builds/slave/test', 17:53:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:53:25 INFO - 'SHELL': '/bin/bash', 17:53:25 INFO - 'SHLVL': '1', 17:53:25 INFO - 'TERM': 'linux', 17:53:25 INFO - 'TMOUT': '86400', 17:53:25 INFO - 'USER': 'cltbld', 17:53:25 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1450396466.764017-455142318', 17:53:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:53:25 INFO - '_': '/tools/buildbot/bin/python'} 17:53:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KTJF1JIjRdC93-LnnksKRQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--webServer', 'localhost'] with output_timeout 3600 17:53:25 INFO - mozversion INFO | application_buildid: 20151217170415 17:53:25 INFO - mozversion INFO | application_changeset: e1ce5b4fa814367b46379641a77f06eabd9348ed 17:53:25 INFO - mozversion INFO | application_display_name: Nightly 17:53:25 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:53:25 INFO - mozversion INFO | application_name: Firefox 17:53:25 INFO - mozversion INFO | application_remotingname: firefox 17:53:25 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 17:53:25 INFO - mozversion INFO | application_vendor: Mozilla 17:53:25 INFO - mozversion INFO | application_version: 46.0a1 17:53:25 INFO - mozversion INFO | platform_buildid: 20151217170415 17:53:25 INFO - mozversion INFO | platform_changeset: e1ce5b4fa814367b46379641a77f06eabd9348ed 17:53:25 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 17:53:25 INFO - mozversion INFO | platform_version: 46.0a1 17:53:25 INFO - 2015-12-17 17:53:25,707 DEBUG : using testdate: 1450403605 17:53:25 INFO - 2015-12-17 17:53:25,707 DEBUG : actual date: 1450403605 17:53:25 INFO - 2015-12-17 17:53:25,716 INFO : starting webserver on 'localhost:47594' 17:53:25 INFO - 2015-12-17 17:53:25,716 INFO : Starting test suite talos-linux64-ix-046.e 17:53:25 INFO - 2015-12-17 17:53:25,717 INFO : Starting test dromaeo_css 17:53:25 INFO - 2015-12-17 17:53:25,717 DEBUG : operating with platform_type : linux_ 17:53:25 INFO - 2015-12-17 17:53:25,717 INFO : Initialising browser for dromaeo_css test... 17:53:25 INFO - 2015-12-17 17:53:25,735 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp57masA/profile http://localhost:47594/getInfo.html 17:53:32 INFO - 2015-12-17 17:53:32,539 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 17:53:32 INFO - 2015-12-17 17:53:32,539 DEBUG : BROWSER_OUTPUT: colorDepth:24 17:53:32 INFO - 2015-12-17 17:53:32,540 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 17:53:32 INFO - 2015-12-17 17:53:32,540 DEBUG : BROWSER_OUTPUT: __metrics 17:53:32 INFO - 2015-12-17 17:53:32,697 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] 17:53:33 INFO - 2015-12-17 17:53:33,609 INFO : Browser initialized. 17:53:33 INFO - 2015-12-17 17:53:33,610 INFO : Running cycle 1/1 for dromaeo_css test... 17:53:33 INFO - 2015-12-17 17:53:33,610 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp57masA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 17:53:45 INFO - 2015-12-17 17:53:45,634 DEBUG : BROWSER_OUTPUT: RSS: Main: 151068672 17:53:45 INFO - 2015-12-17 17:53:45,634 DEBUG : BROWSER_OUTPUT: 17:54:35 INFO - 2015-12-17 17:54:35,257 DEBUG : BROWSER_OUTPUT: 1450403675255 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] 17:56:47 INFO - 2015-12-17 17:56:47,232 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47594/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47594/tests/dromaeo/cssquery-ext.html) 17:56:48 INFO - 2015-12-17 17:56:48,377 DEBUG : BROWSER_OUTPUT: RSS: Main: 155164672 17:56:48 INFO - 2015-12-17 17:56:48,378 DEBUG : BROWSER_OUTPUT: 17:59:50 INFO - 2015-12-17 17:59:50,414 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47594/tests/dromaeo/cssquery-ext.html (next: http://localhost:47594/tests/dromaeo/cssquery-jquery.html) 17:59:51 INFO - 2015-12-17 17:59:51,007 DEBUG : BROWSER_OUTPUT: RSS: Main: 152084480 17:59:51 INFO - 2015-12-17 17:59:51,008 DEBUG : BROWSER_OUTPUT: 18:02:52 INFO - 2015-12-17 18:02:52,777 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47594/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47594/tests/dromaeo/cssquery-mootools.html) 18:02:53 INFO - 2015-12-17 18:02:53,366 DEBUG : BROWSER_OUTPUT: RSS: Main: 153509888 18:02:53 INFO - 2015-12-17 18:02:53,367 DEBUG : BROWSER_OUTPUT: 18:05:55 INFO - 2015-12-17 18:05:55,145 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47594/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47594/tests/dromaeo/cssquery-prototype.html) 18:05:55 INFO - 2015-12-17 18:05:55,751 DEBUG : BROWSER_OUTPUT: RSS: Main: 152092672 18:05:55 INFO - 2015-12-17 18:05:55,751 DEBUG : BROWSER_OUTPUT: 18:08:58 INFO - 2015-12-17 18:08:58,304 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47594/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47594/tests/dromaeo/cssquery-yui.html) 18:08:58 INFO - 2015-12-17 18:08:58,909 DEBUG : BROWSER_OUTPUT: RSS: Main: 151982080 18:08:58 INFO - 2015-12-17 18:08:58,910 DEBUG : BROWSER_OUTPUT: 18:12:01 INFO - 2015-12-17 18:12:01,308 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47594/tests/dromaeo/cssquery-yui.html (next: http://localhost:47594/tests/dromaeo/cssquery-dojo.html) 18:12:01 INFO - 2015-12-17 18:12:01,559 DEBUG : BROWSER_OUTPUT: RSS: Main: 155525120 18:12:01 INFO - 2015-12-17 18:12:01,559 DEBUG : BROWSER_OUTPUT: 18:12:01 INFO - 2015-12-17 18:12:01,560 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:12:01 INFO - 2015-12-17 18:12:01,560 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:12:01 INFO - 2015-12-17 18:12:01,561 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:12:01 INFO - 2015-12-17 18:12:01,561 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:12:01 INFO - 2015-12-17 18:12:01,562 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2652.0850306644206;2686.5835795451712;2732.125719769673;2736.9863150684246;2747.2994386431455;4564.452265728118;4654.604358629521;4661;4697.2954056891485;4700.177468942934;4657.0453057123295;4687.132880417125;4721.315409265654;4827.8793030174265;4835.202191638376;4267.338562522814;4401.515833258339;4534.569215924482;4540.2054640437955;4621.838235661752;4222.873313800591;4492.7528985905765;4568.543145685421;4570.657200709943;4590.770461476929;4327.675424343187;4391.297392417214;4402.691811573191;4436.179306828247;4451.866444006652;4452.0205554777895;4470.619997300233;4471.642268618535;4487.596116349511;4495.078508905699;4203.159368126387;4321.567843215685;4434.2905135178535;4441.888952776174;4480.551944805526;4587.816487340502;4611.5619016193405;4720.740359280238;4736.194846876039;4749.453812811536;5049.3183420238165;5054.620903432257;5055.671381360236;5056.570191533724;5065.974670126662;4944.777485013147;5057.873553161164;5070.61970352225;5084.694918304914;5126.333576635014;4707.882302942454;4727.57451829337;4733.83431579893;4734.053189362107;4739.4075740532435;5290.259363689087;5296.232046353334;5296.443873393238;5307.495787900143;5308.999999999923;5049.974750126225;5092.134337162691;5092.821751238689;5098.694078355311;5134.409542902614;4948.38145231846;5062.265971434122;5062.645614807002;5168.328117344797;5186.844394668166;5075.543201111844;5128.897422051538;5131.512506311894;5162.251473536317;5162.845114646567;5057.140286151363;5092.9745351273;5115.130427827278;5115.974420127875;5126.87182820425;4081.8163182656854;4155;4174.20690068886;4211.957880421157;4260.978695106504;3734.8506059757847;3761.981190094032;3772.3775577029483;3793.962060379416;3809.1619843634357;4256.808443619982;4459.353393757887;4473.4408198975125;4477.552244775497;4481.641468682497;4980.800767969313;5036.823711170092;5122.769475373617;5128.58971282304;5191.195364718475;4404;4406.184855801687;4411.1618792429335;4417.514073451917;4470.776461176928;4336.82652693895;4463.888402789891;4545.159210519745;4560.156371071363;4607.424071991001;4762.59517940972;4793.880152996203;4814.735189564608;4839.854804355734;4886.047220791911;5177.508136727005;5181;5186.170212765939;5209.505097015777;5215.530602245816;4672.08894265615;4785.258284965836;4816.4220293564995;4902.63230257725;4956.10790057793;5057.115004874206;5070.8225212117395;5082.567981721595;5092.312537807348;5094.1849304111165;5114.769835357343;5128.384593848762;5128.948710512847;5141.794328226829;5158;4773.880652983703;4836.613070954245;4877.878053048702;4926.655134140719;4976.029674213493;4964.627652926118;4986.376702912136;5001.449840517613;5033.622478314214;5045.268436076822;4890.7554622268035;4915.361003069578;4939.53074457926;4965.106280869334;5002.074616196015;4739.526047395233;4843.782029808596;4878.121938051185;4992.076465853684;5019.648624596243;4957.058158949788;4961.925571116364;4991.376077990251;5002.774875130555;5042.495750425075 18:12:01 INFO - 2015-12-17 18:12:01,566 DEBUG : BROWSER_OUTPUT: |1;ext.html;557.361820715281;564.2918137737139;564.6781334639255;569.5443645083934;573.5870173475097;34711.99999999997;35679;35710.107247318796;35715.46426803597;35919;39693.20613587726;40104.59895401045;40159;40823.59176408235;40913.18173636525;7877.6848926042885;7961.562114083723;7978.481398709066;8054.033515978091;8062.879056814152;7875;7928.127905930343;7970.800729981739;7985.04179498461;7985.361171106298;7906.327962123227;8006.839863202733;8016.038075430927;8024.558649274288;8030.799230019238;7881.211878812131;7900.249476299799;7947.403944704112;7962.442629016;7970.641321140533;7878.527288362717;7958.6418611162635;8005.9599702001115;8024.037115546172;8031.759047228593;35659.82170089133;36211.45682814733;36367;36479.635203648155;36588.634113659056;2758.7930905182193;2863.7708983281286;2885.2354126156583;3046.573479712842;3485.9128521787156;28188.577171342447;28488.85755571209;28653.28366790847;28688;28699;3088.8764449421997;3089.057837359627;3102.5966624338917;3122.8244654400796;3166.509191075387;212386;212735;213201;213368;214886;51871.48128518742;55083;55488;55503.44496555063;55640.72179639076;44567.77716111398;65322.673386633716;66355;66372;66473.33526664769;14230.288485575888;14286.214258215898;14339.211343376215;14388;14515.491957781427;10323;10341.4312212829;10352.068313851789;10361.481925903825;10366;18913.81086189148;19253.22987080529;19300.1314940828;19316.034198290028;19334.613307733765;7278.344948954619;7500.887486687704;7542.886856697154;7549.169591344948;7596.316331530189;816.2049448912672;895.5566994337714;902.7878448564582;938.4275591888942;1064.3135177810268;26008.479830403285;26743;26877;26890.462190756076;26912.192634221006;971.3152227679469;982.8525721141743;1034.4879284754095;1084.6258040975852;1153.3022521374614;12834.614961551168;13046.152000120152;13202;13321.267330296927;13395.397207125703;260865;265208;265487;265692;265757;45654.77172614182;45942;46197.30704039442;46365.768171158925;46540.301895471595;1207.4023358437455;1224.253205544617;1226.337777600104;1230.846144231971;1232.0082333721261;25583.488330233664;25845.449273043676;25924.870375648003;26019.21942341733;26104.216873493824;40300;40974.18051638891;41049.384259235536;41287;41410.37884431676;242044;242259;243198;243263;243307;23984.400389989692;24541.75458245395;28997.855010724812;29251.268718282212;29438.11685649434;36692.81653591822;36781.63218367782;36811.815940920125;36999.526974879234;37543.43684844784;31513.684863151077;31729.68270317267;32001;32674.83662581672;32891.51988160586;31770.841145794122;32035.35929281448;32261.193470163435;32314;32322.515162273055;32132.357352852345;32524.186895327806;33260.66739332672;33827.66172338343;33846.15384615404;26939;28817.85591072031;29605.703942960296;30069;30072.398552029274;22756;23853.165139220044;23946.88026559856;23982.88008559946;25224.369390764645 18:12:01 INFO - 2015-12-17 18:12:01,566 DEBUG : BROWSER_OUTPUT: |2;jquery.html;321.23385725045756;325.7215081105655;327.10536682174256;329.92081900343925;337.45164108323974;13235.404406801705;15036.398544058238;15515.844841551581;15996.200189990483;16603.256658050912;15318.00432971856;15385.23073846306;15393.769093463607;15451.768223476629;15695.764563531557;4274.187904298174;4458.933116003246;4459.576340247688;4462.754548499831;4470.441194850644;3743.4759133721163;4392.604665580097;4414.536473670267;4445.622122119607;4837.226043832988;4785.449673287581;4795.184818580832;4800.591949684281;4953.678010929296;4959.578435832958;4823.782929768169;4916.311716359713;4923.82766603169;4925.802967881298;4943.950560494403;4934.481879402647;5002.674826136289;5015.899682006339;5039.823606173766;5049.621278404097;16731.749023764653;18310.816891831044;18390.908045459822;18816.529586760305;18874;1824.2155872974615;1828.9176987035482;1844.732513785507;1882.115405759291;1905.1236431241646;8881.689140880038;8959.820803583892;9098.090190980914;10362;12673.112882098258;1706.6074802795301;1769.5576105973507;1774.7426623139702;1789.337944960373;1833.9449816505394;56414.92896510421;60176;60303.396966030654;60364.094538582394;60917.69541152266;9534.809303813885;9714.757131071638;9796.510174491246;9810.509474526389;9904.702858914245;12517.93741031289;12541.247525148337;12542.686432839251;12553.99568034555;12575.937120314522;6994.370506654323;7272.563646181246;7306.744263950843;7307.26927307265;7315.561066336037;6902.516823822284;6954.478414118961;6968.059311992816;6990;7153.105861767279;4024.2353952748886;4077.245709543744;4082.775447350424;4085.20338533989;4169.478140478764;1697.9660406791795;1759.4897479730992;1770.8583313334902;1780.7506949027147;1792.7759030121235;361.8643008871673;368.3186105704479;376.8643288416223;403.294235088595;406.4533202842172;9281.535923203948;9398.107179817905;9649.710508684751;9658.613655453702;9760.707178784647;391.04779861038463;392.47800425434673;409.14488718578315;412.420549128475;417.04496702551427;5155.200943853709;5305.973470132702;5310.123829568078;5314.229436731653;5414.133738601805;449958;453466;455102;455886;459885;69051;70486.64756676287;72695.63652181705;73285;73839;445.3208856493808;451.4853067503049;452.1747810246248;452.57457989489774;454.8908262017158;9228;9643;9692.17616502577;9758.658446954323;9833.803323933338;34361.656383435846;34428.65571344355;35155.82422087975;35191.47212791861;35321.470177947864;43321;46295;48009;50063.49936500588;50083.74958125186;8683.435576687496;8858.867116993379;8919.241864441596;9028.864567031369;9344.532773361441;16010.199490025685;16385.180740962667;16469.91765041167;16564.917175414044;16659.500214993568;13586.45654173822;13669.658258543615;13707.520236791665;13732.107413018122;14182.787258190929;13312.933435332761;13431.261280629658;13568.79646805279;14156.929215353857;14183.007189496637;13625.250611216477;13742.106763060368;13937.30313484342;14290.642733931734;14311.141331520143;9102.180803727697;9135.63457461694;9217.493037882981;9234.9538252311;9236.953815230881;13170.275634839792;13649.181049137083;13831.861681383058;13927.791083133558;14131.293435327987 18:12:01 INFO - 2015-12-17 18:12:01,567 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1461.400825661479;1669.0820048973067;1678.3622223555046;1682.4080587944743;1745.720684690449;6102.328743838174;6305.148804911336;7086.681099350529;7283.344498995089;7285.198628150899;6692.564983276084;6834.077399551059;6849.897251541219;6897.448204143669;6915.481338899577;2046.8362530997485;2086.843486738493;2088.0290664840845;2089.3836318286067;2091.2576035507327;2071.337172104927;2073.129285700002;2080.2823026055985;2113.778053304404;2125.3517677108475;2012.2856385982984;2020.6261841559285;2037.7656569494475;2040.1125510402949;2042.79572042796;2046.5906818636258;2056.5372791122027;2074.6161960037443;2080.855529458799;2084.6351888419467;1997.3508609701905;2009.6884982827685;2033.9288124915704;2036.694495825623;2041.642712525317;7195.496315257934;7294.525855819408;7309.049823522962;7316.085489313836;7333.963330183368;4112.609302116294;4162.105147393325;4163.854265100737;4170.4995400551825;4193.119444916571;2558.1174494799266;2606.1660268714018;2627.671541057368;2657.7475139861876;2658.6809582849987;847.7753395350237;847.92792612628;856.0027567883409;864.0754392799707;871.2594294849391;9452.716418507456;9497.192738617157;9512.762180945463;9527.857082143773;9532.237421006303;3633.0554055945645;3652.1052342176336;3664.5419322584676;3669.7064234861145;3691.8707845225827;8205.71280005197;8232.588370581567;8236.094029656733;8243.711470098518;8254.504729716236;4081.714280000431;4090.836366545305;4094.815733292009;4098.057446787256;4101.036256479725;3196.4406228909847;3270.8691652334114;3623.673869351718;3746.756460830037;3749.681277091423;3282.885099021523;3312.685294896981;3372.9831350843087;3465.8786942457396;3530.0821786335737;1116.1461481966303;1129.4917287220783;1145.5589598004829;1150.6950658075616;1150.8043632582314;857.2670366836389;866.5623859950684;1176.5940750441084;1188.8929996300374;1198.3888217009214;6551.377619126175;6600.306967768315;6606.174228221473;6645.335466453316;6669.699863506154;407.6596042304709;1084.8318510630866;1102.0687519046394;1133.3369978562632;1143.091242462228;5453.291072160594;5538.612297139242;5539.196816461671;5542.39033706292;5560.082586373284;8345.499270043816;8383.11977242393;8663.740087797376;8726.738197854074;8746.781330466662;8567.614457349435;8623.094575069654;8643.567821608895;8645.740627781177;8655.6537738491;6853.657317134123;6861.828454288683;6866.487722437658;6908.378245957888;6953.339432753982;6619.238787539494;6624.735010599522;6647.235567909753;6658.667066646745;6661.733530658913;7721;7752.844943100994;7797.727079552188;7810.648520816463;7968.880466793119;7885.89597456368;7956.283934445928;8120.066192387951;8135.267825895698;8250.46371985819;5203.699075231192;5207.557357624644;5727.198192253171;5754.625949313281;5828.6502809831545;6869.828254293682;6895.000224967252;6952.5480843745;6958.165020197406;6969.30306969299;6445.12990746262;6555.704993275219;6692.096566963398;6725.024871393718;6746.9662651688395;6093.725782339894;6476.287608363171;6505.0567667688865;6532.477401807939;6601.207855057424;6569.9343006569325;6594.670266486561;6636.900446493403;6645.3022432643875;6647.401733843977;5977.7310021050025;6013.128096425906;6041.456268935817;6082.817515474543;6112.816615501542;6026.246719160105;6065.4541091302;6090.908636370369;6091.360407157184;6101.908471373022 18:12:01 INFO - 2015-12-17 18:12:01,568 DEBUG : BROWSER_OUTPUT: |4;prototype.html;294.6375957572186;304.9512078067509;307.0665177859308;310.448953108233;312.1727422330823;5414.2420061191415;5517.227588137659;5546.91679624805;5573.916391254134;5680.375158732536;5502.422245664207;5517.227588137654;5540.695261760601;5541.778328866841;5561.193626924093;4253.744775313491;4253.8723838284905;4257.638100761439;4266.802596662721;4270.551592082841;4251.59609837065;4260.744355338674;4264.957350426503;4268.850590229343;4282.550332215119;4085.2850751118585;4198.769067701275;4223.13425747721;4224.176285624305;4229.6404805591565;4176.60322269384;4198.706090573661;4200.810963506634;4201.873943781691;4210.221109094797;3642.8907132786057;3645.6718895299286;3646.6717995380545;3653.4337177737493;3655.4334078217653;5317.8404647860625;5328.973355133238;5329.680219186861;5333.786648534054;5338.813141540028;4631.490536041033;4647.3958385410015;4839.637027222972;4843.29772183035;4849.9757501212625;5677.233573467571;5720.1991721159075;5735.397783232742;5751.194832723422;5762.97118514409;3882.3594106972314;3888.2029184017492;3892.9026774330587;3893.9610603894166;3897.7856217908;5996.970015149896;6069.089636554552;6087.634741915499;6088.512918966472;6098.298695650013;3905.9804700976315;3950.4074388841905;3959.9604003960167;4067.5525692173837;4082.6937979651643;4133.256013917464;4137.54487006429;4138.151678905817;4140.420341152241;4144.979275103605;4777.402824646919;4779.354787103766;4781.497942716034;4792.472827988919;4800.975995120072;5697.971510142505;5726.713664316851;5734.111212762028;5743.368229494752;5762.827115186551;1429.1282317786142;1444.8482909294582;1459.88320934325;1462.627030107316;1468.2585294426246;954.7994921066515;961.8845738511284;970.5875003123645;980.887197972228;982.258394911837;5063.594912406998;5064.442911279756;5073.720945347967;5074.568661663726;5081.4918508148885;6004.099385092271;6013.037913933749;6013.579049466583;6013.939860601338;6025.879482410415;5054.848354549369;5055;5055.443901170868;5065.240213967934;5074.517920797518;3185.761067919915;3236.336551007038;3259.1363288728508;3260.3153337798853;3264.3308121834966;5657.094864821609;5658.519025882846;5661.716914154275;5666.688332141689;5687.601867869293;6127.632342059402;6153.476954458921;6170.321264660884;6185.381461853779;6190.783322583688;5418.078926582491;5512.779488820483;5611.831645050655;5672.602917795797;5685.886282274331;6062.302835174011;6074.301455332693;6078.908816367846;6079.604825686316;6093.177421048279;5882.7058647066615;5914.408559144051;5916.585838991229;5931.406859314034;5931.673757943355;5616.38219795814;5712.6286791358425;5713.914291285717;5722.055860782924;5731.971340143439;4133.937990930199;4136.565660605714;4140.296149654626;4141.958580414157;4147.502299724052;6012.789552365821;6041.577089603686;6055.818325450244;6056.333803281724;6057.42454466825;5797.304323481209;5798.739056742373;5808.809194115281;5812.244408226903;5820.883582328414;5685.71571421419;5813.157092221689;5813.79651712188;5825.155352473952;5832.883342333214;5691.402402747817;5691.971540142273;5696.003199440164;5708.286464191976;5714.514266287412;5854.677992710272;5857.062869940789;5857.179994800646;5864.530837532901;5879.059350503899;5616.859578510406;5720.685362305113;5722.484976352148;5723.112917497794;5724.427557244242 18:12:01 INFO - 2015-12-17 18:12:01,568 DEBUG : BROWSER_OUTPUT: |5;yui.html;633.7021599848069;736.374082030274;738.697134174988;743.3421422041492;746.2276543777192;10919.945400272998;11120.388378639173;11149.108071354292;11175.608853690102;11193.16051296152;11124.221304508688;11132.61035863745;11141.997220250176;11143.387113708743;11147.386893720843;612.5099920063954;627.0625415004574;772.0619447371442;779.0456690554068;784.047382430347;786.5791801386231;790.1505881177757;795.0935933036315;795.4908858330673;799.3684988858795;828.8673812190051;834.6077343648506;836.0385556609887;837.4347315561971;839.6515446089866;815.4577206157898;821.2116368286452;823.2056065896438;824.9505029698178;826.2068414322258;616.2881871438451;618.7710547097557;618.9969050154765;619.0497586205198;619.065211530592;10602.098821600173;10662.840057399146;10712.625058122929;10717.303375280582;10723;10419.322744021614;11049.834252486218;11063.668089957315;11086;11112.722181945435;9249.953750231205;9273.536323183815;9525.714228573153;9530.237580993504;9531.428114313163;3173.6350319713297;3230.7415406767404;3236.5112048457736;3237.821919794434;3246.4481038223557;37136.62863371359;37274.25451490982;37658.81170594157;37672.43491347632;37707.81146094252;8699.086595907338;8893;9170.633174673072;9275.443473391619;9293.210077143518;16694.58263543421;16708.582285442717;16752.49742507727;16769.496915092568;16807.747883781754;5779.971100144472;5883.588148829541;5920.822375328747;5977.880442391128;6013.218281623361;3379.4930760385646;3430.5368775215525;3501.509788629594;3520.6303338149637;3554.235839294564;2492.4641202141624;2523.507915956371;2524.2301098164867;2529.7730600658665;2535.4802265535523;433.5361163555029;437.1366551061628;439.0757455556072;440.1483130143183;445.72365133616717;462.4635422909476;480.61550759392344;515.6931625682713;518.9273501709764;570.9086546152596;6851.588904665736;6912.308769123048;7006.124234470692;7167.605781681953;7344.853102937911;491.7664109547936;493.6199126672442;507.0138580461042;532.6564365983919;534.767376191358;3330.9500357494658;3515.54297941266;3530.417481115587;3553.1650062235353;3577.5170352002147;47740.5225947743;47750.28374574384;48237.517624824;48354.75822620935;48495;43937;44023.55976440194;44119;44530.55469445329;44719.776401117786;35141.12147196341;35549.64450355515;35724.464133038025;36005.819970899975;40576.59423405728;7002.684879180347;7180.425565954815;7198.89201661965;7289.708411663474;7345.77962661121;30312.242193944447;30387.848060759556;30541.02966614326;30819.229519262197;30897.227569310948;33966.66033339635;34607.653923461454;34863.47704784481;34890.47664284987;35283;9740.172085372822;9784;9873.654422095193;9918.752031199278;9995.850062248926;10214;10640.255182137176;10781.191410644076;11089.667309980714;11411.543538258376;9627.229821614394;9705.466199359103;9836.114749672564;9889.25830562725;10399.94800025995;9943.651972180938;10027.04743049409;10091.495425228562;10228.64199753005;10253.846192306972;9940.353876997971;10188.490575471049;10373.896261037293;10390.740231494274;10683.198760092868;8185;8226.753197404087;8287.709930152416;8573.828523429373;8835.779105522157;8057.234562716532;8399.580020998803;8482.745517634481;8553.700620478254;8695.478271303742 18:12:01 INFO - 2015-12-17 18:12:01,568 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:12:01 INFO - 2015-12-17 18:12:01,569 DEBUG : BROWSER_OUTPUT: __start_cc_report 18:12:01 INFO - 2015-12-17 18:12:01,569 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,332 18:12:01 INFO - 2015-12-17 18:12:01,569 DEBUG : BROWSER_OUTPUT: __end_cc_report 18:12:01 INFO - 2015-12-17 18:12:01,569 DEBUG : BROWSER_OUTPUT: __startTimestamp1450404721559__endTimestamp 18:12:01 INFO - 2015-12-17 18:12:01,569 DEBUG : BROWSER_OUTPUT: 18:12:01 INFO - 2015-12-17 18:12:01,569 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:12:01 INFO - 2015-12-17 18:12:01,569 DEBUG : BROWSER_OUTPUT: Number of tests: 6 18:12:01 INFO - 2015-12-17 18:12:01,569 DEBUG : BROWSER_OUTPUT: 18:12:01 INFO - 2015-12-17 18:12:01,569 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4719.04 Median:4835.20 stddev:496.15 (10.3%) stddev-sans-first:470.43 18:12:01 INFO - 2015-12-17 18:12:01,571 DEBUG : BROWSER_OUTPUT: Values: 2652.1 2686.6 2732.1 2737.0 2747.3 4564.5 4654.6 4661.0 4697.3 4700.2 4657.0 4687.1 4721.3 4827.9 4835.2 4267.3 4401.5 4534.6 4540.2 4621.8 4222.9 4492.8 4568.5 4570.7 4590.8 4327.7 4391.3 4402.7 4436.2 4451.9 4452.0 4470.6 4471.6 4487.6 4495.1 4203.2 4321.6 4434.3 4441.9 4480.6 4587.8 4611.6 4720.7 4736.2 4749.5 5049.3 5054.6 5055.7 5056.6 5066.0 4944.8 5057.9 5070.6 5084.7 5126.3 4707.9 4727.6 4733.8 4734.1 4739.4 5290.3 5296.2 5296.4 5307.5 5309.0 5050.0 5092.1 5092.8 5098.7 5134.4 4948.4 5062.3 5062.6 5168.3 5186.8 5075.5 5128.9 5131.5 5162.3 5162.8 5057.1 5093.0 5115.1 5116.0 5126.9 4081.8 4155.0 4174.2 4212.0 4261.0 3734.9 3762.0 3772.4 3794.0 3809.2 4256.8 4459.4 4473.4 4477.6 4481.6 4980.8 5036.8 5122.8 5128.6 5191.2 4404.0 4406.2 4411.2 4417.5 4470.8 4336.8 4463.9 4545.2 4560.2 4607.4 4762.6 4793.9 4814.7 4839.9 4886.0 5177.5 5181.0 5186.2 5209.5 5215.5 4672.1 4785.3 4816.4 4902.6 4956.1 5057.1 5070.8 5082.6 5092.3 5094.2 5114.8 5128.4 5128.9 5141.8 5158.0 4773.9 4836.6 4877.9 4926.7 4976.0 4964.6 4986.4 5001.4 5033.6 5045.3 4890.8 4915.4 4939.5 4965.1 5002.1 4739.5 4843.8 4878.1 4992.1 5019.6 4957.1 4961.9 4991.4 5002.8 5042.5 18:12:01 INFO - 2015-12-17 18:12:01,571 DEBUG : BROWSER_OUTPUT: 18:12:01 INFO - 2015-12-17 18:12:01,571 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:40185.27 Median:32318.26 stddev:62848.34 (194.5%) stddev-sans-first:62954.21 18:12:01 INFO - 2015-12-17 18:12:01,571 DEBUG : BROWSER_OUTPUT: Values: 557.4 564.3 564.7 569.5 573.6 34712.0 35679.0 35710.1 35715.5 35919.0 39693.2 40104.6 40159.0 40823.6 40913.2 7877.7 7961.6 7978.5 8054.0 8062.9 7875.0 7928.1 7970.8 7985.0 7985.4 7906.3 8006.8 8016.0 8024.6 8030.8 7881.2 7900.2 7947.4 7962.4 7970.6 7878.5 7958.6 8006.0 8024.0 8031.8 35659.8 36211.5 36367.0 36479.6 36588.6 2758.8 2863.8 2885.2 3046.6 3485.9 28188.6 28488.9 28653.3 28688.0 28699.0 3088.9 3089.1 3102.6 3122.8 3166.5 212386.0 212735.0 213201.0 213368.0 214886.0 51871.5 55083.0 55488.0 55503.4 55640.7 44567.8 65322.7 66355.0 66372.0 66473.3 14230.3 14286.2 14339.2 14388.0 14515.5 10323.0 10341.4 10352.1 10361.5 10366.0 18913.8 19253.2 19300.1 19316.0 19334.6 7278.3 7500.9 7542.9 7549.2 7596.3 816.2 895.6 902.8 938.4 1064.3 26008.5 26743.0 26877.0 26890.5 26912.2 971.3 982.9 1034.5 1084.6 1153.3 12834.6 13046.2 13202.0 13321.3 13395.4 260865.0 265208.0 265487.0 265692.0 265757.0 45654.8 45942.0 46197.3 46365.8 46540.3 1207.4 1224.3 1226.3 1230.8 1232.0 25583.5 25845.4 25924.9 26019.2 26104.2 40300.0 40974.2 41049.4 41287.0 41410.4 242044.0 242259.0 243198.0 243263.0 243307.0 23984.4 24541.8 28997.9 29251.3 29438.1 36692.8 36781.6 36811.8 36999.5 37543.4 31513.7 31729.7 32001.0 32674.8 32891.5 31770.8 32035.4 32261.2 32314.0 32322.5 32132.4 32524.2 33260.7 33827.7 33846.2 26939.0 28817.9 29605.7 30069.0 30072.4 22756.0 23853.2 23946.9 23982.9 25224.4 18:12:01 INFO - 2015-12-17 18:12:01,571 DEBUG : BROWSER_OUTPUT: 18:12:01 INFO - 2015-12-17 18:12:01,571 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:25366.23 Median:4403.57 stddev:74512.75 (1692.1%) stddev-sans-first:74698.05 18:12:01 INFO - 2015-12-17 18:12:01,571 DEBUG : BROWSER_OUTPUT: Values: 321.2 325.7 327.1 329.9 337.5 13235.4 15036.4 15515.8 15996.2 16603.3 15318.0 15385.2 15393.8 15451.8 15695.8 4274.2 4458.9 4459.6 4462.8 4470.4 3743.5 4392.6 4414.5 4445.6 4837.2 4785.4 4795.2 4800.6 4953.7 4959.6 4823.8 4916.3 4923.8 4925.8 4944.0 4934.5 5002.7 5015.9 5039.8 5049.6 16731.7 18310.8 18390.9 18816.5 18874.0 1824.2 1828.9 1844.7 1882.1 1905.1 8881.7 8959.8 9098.1 10362.0 12673.1 1706.6 1769.6 1774.7 1789.3 1833.9 56414.9 60176.0 60303.4 60364.1 60917.7 9534.8 9714.8 9796.5 9810.5 9904.7 12517.9 12541.2 12542.7 12554.0 12575.9 6994.4 7272.6 7306.7 7307.3 7315.6 6902.5 6954.5 6968.1 6990.0 7153.1 4024.2 4077.2 4082.8 4085.2 4169.5 1698.0 1759.5 1770.9 1780.8 1792.8 361.9 368.3 376.9 403.3 406.5 9281.5 9398.1 9649.7 9658.6 9760.7 391.0 392.5 409.1 412.4 417.0 5155.2 5306.0 5310.1 5314.2 5414.1 449958.0 453466.0 455102.0 455886.0 459885.0 69051.0 70486.6 72695.6 73285.0 73839.0 445.3 451.5 452.2 452.6 454.9 9228.0 9643.0 9692.2 9758.7 9833.8 34361.7 34428.7 35155.8 35191.5 35321.5 43321.0 46295.0 48009.0 50063.5 50083.7 8683.4 8858.9 8919.2 9028.9 9344.5 16010.2 16385.2 16469.9 16564.9 16659.5 13586.5 13669.7 13707.5 13732.1 14182.8 13312.9 13431.3 13568.8 14156.9 14183.0 13625.3 13742.1 13937.3 14290.6 14311.1 9102.2 9135.6 9217.5 9235.0 9237.0 13170.3 13649.2 13831.9 13927.8 14131.3 18:12:01 INFO - 2015-12-17 18:12:01,571 DEBUG : BROWSER_OUTPUT: 18:12:01 INFO - 2015-12-17 18:12:01,572 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:4976.09 Median:6092.54 stddev:2596.52 (42.6%) stddev-sans-first:2590.37 18:12:01 INFO - 2015-12-17 18:12:01,573 DEBUG : BROWSER_OUTPUT: Values: 1461.4 1669.1 1678.4 1682.4 1745.7 6102.3 6305.1 7086.7 7283.3 7285.2 6692.6 6834.1 6849.9 6897.4 6915.5 2046.8 2086.8 2088.0 2089.4 2091.3 2071.3 2073.1 2080.3 2113.8 2125.4 2012.3 2020.6 2037.8 2040.1 2042.8 2046.6 2056.5 2074.6 2080.9 2084.6 1997.4 2009.7 2033.9 2036.7 2041.6 7195.5 7294.5 7309.0 7316.1 7334.0 4112.6 4162.1 4163.9 4170.5 4193.1 2558.1 2606.2 2627.7 2657.7 2658.7 847.8 847.9 856.0 864.1 871.3 9452.7 9497.2 9512.8 9527.9 9532.2 3633.1 3652.1 3664.5 3669.7 3691.9 8205.7 8232.6 8236.1 8243.7 8254.5 4081.7 4090.8 4094.8 4098.1 4101.0 3196.4 3270.9 3623.7 3746.8 3749.7 3282.9 3312.7 3373.0 3465.9 3530.1 1116.1 1129.5 1145.6 1150.7 1150.8 857.3 866.6 1176.6 1188.9 1198.4 6551.4 6600.3 6606.2 6645.3 6669.7 407.7 1084.8 1102.1 1133.3 1143.1 5453.3 5538.6 5539.2 5542.4 5560.1 8345.5 8383.1 8663.7 8726.7 8746.8 8567.6 8623.1 8643.6 8645.7 8655.7 6853.7 6861.8 6866.5 6908.4 6953.3 6619.2 6624.7 6647.2 6658.7 6661.7 7721.0 7752.8 7797.7 7810.6 7968.9 7885.9 7956.3 8120.1 8135.3 8250.5 5203.7 5207.6 5727.2 5754.6 5828.7 6869.8 6895.0 6952.5 6958.2 6969.3 6445.1 6555.7 6692.1 6725.0 6747.0 6093.7 6476.3 6505.1 6532.5 6601.2 6569.9 6594.7 6636.9 6645.3 6647.4 5977.7 6013.1 6041.5 6082.8 6112.8 6026.2 6065.5 6090.9 6091.4 6101.9 18:12:01 INFO - 2015-12-17 18:12:01,573 DEBUG : BROWSER_OUTPUT: 18:12:01 INFO - 2015-12-17 18:12:01,573 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4787.46 Median:5528.96 stddev:1428.70 (25.8%) stddev-sans-first:1392.34 18:12:01 INFO - 2015-12-17 18:12:01,573 DEBUG : BROWSER_OUTPUT: Values: 294.6 305.0 307.1 310.4 312.2 5414.2 5517.2 5546.9 5573.9 5680.4 5502.4 5517.2 5540.7 5541.8 5561.2 4253.7 4253.9 4257.6 4266.8 4270.6 4251.6 4260.7 4265.0 4268.9 4282.6 4085.3 4198.8 4223.1 4224.2 4229.6 4176.6 4198.7 4200.8 4201.9 4210.2 3642.9 3645.7 3646.7 3653.4 3655.4 5317.8 5329.0 5329.7 5333.8 5338.8 4631.5 4647.4 4839.6 4843.3 4850.0 5677.2 5720.2 5735.4 5751.2 5763.0 3882.4 3888.2 3892.9 3894.0 3897.8 5997.0 6069.1 6087.6 6088.5 6098.3 3906.0 3950.4 3960.0 4067.6 4082.7 4133.3 4137.5 4138.2 4140.4 4145.0 4777.4 4779.4 4781.5 4792.5 4801.0 5698.0 5726.7 5734.1 5743.4 5762.8 1429.1 1444.8 1459.9 1462.6 1468.3 954.8 961.9 970.6 980.9 982.3 5063.6 5064.4 5073.7 5074.6 5081.5 6004.1 6013.0 6013.6 6013.9 6025.9 5054.8 5055.0 5055.4 5065.2 5074.5 3185.8 3236.3 3259.1 3260.3 3264.3 5657.1 5658.5 5661.7 5666.7 5687.6 6127.6 6153.5 6170.3 6185.4 6190.8 5418.1 5512.8 5611.8 5672.6 5685.9 6062.3 6074.3 6078.9 6079.6 6093.2 5882.7 5914.4 5916.6 5931.4 5931.7 5616.4 5712.6 5713.9 5722.1 5732.0 4133.9 4136.6 4140.3 4142.0 4147.5 6012.8 6041.6 6055.8 6056.3 6057.4 5797.3 5798.7 5808.8 5812.2 5820.9 5685.7 5813.2 5813.8 5825.2 5832.9 5691.4 5692.0 5696.0 5708.3 5714.5 5854.7 5857.1 5857.2 5864.5 5879.1 5616.9 5720.7 5722.5 5723.1 5724.4 18:12:01 INFO - 2015-12-17 18:12:01,573 DEBUG : BROWSER_OUTPUT: 18:12:01 INFO - 2015-12-17 18:12:01,573 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:11600.82 Median:47745.40 stddev:13052.65 (27.3%) stddev-sans-first:13063.28 18:12:01 INFO - 2015-12-17 18:12:01,574 DEBUG : BROWSER_OUTPUT: Values: 633.7 736.4 738.7 743.3 746.2 10919.9 11120.4 11149.1 11175.6 11193.2 11124.2 11132.6 11142.0 11143.4 11147.4 612.5 627.1 772.1 779.0 784.0 786.6 790.2 795.1 795.5 799.4 828.9 834.6 836.0 837.4 839.7 815.5 821.2 823.2 825.0 826.2 616.3 618.8 619.0 619.0 619.1 10602.1 10662.8 10712.6 10717.3 10723.0 10419.3 11049.8 11063.7 11086.0 11112.7 9250.0 9273.5 9525.7 9530.2 9531.4 3173.6 3230.7 3236.5 3237.8 3246.4 37136.6 37274.3 37658.8 37672.4 37707.8 8699.1 8893.0 9170.6 9275.4 9293.2 16694.6 16708.6 16752.5 16769.5 16807.7 5780.0 5883.6 5920.8 5977.9 6013.2 3379.5 3430.5 3501.5 3520.6 3554.2 2492.5 2523.5 2524.2 2529.8 2535.5 433.5 437.1 439.1 440.1 445.7 462.5 480.6 515.7 518.9 570.9 6851.6 6912.3 7006.1 7167.6 7344.9 491.8 493.6 507.0 532.7 534.8 3331.0 3515.5 3530.4 3553.2 3577.5 47740.5 47750.3 48237.5 48354.8 48495.0 43937.0 44023.6 44119.0 44530.6 44719.8 35141.1 35549.6 35724.5 36005.8 40576.6 7002.7 7180.4 7198.9 7289.7 7345.8 30312.2 30387.8 30541.0 30819.2 30897.2 33966.7 34607.7 34863.5 34890.5 35283.0 9740.2 9784.0 9873.7 9918.8 9995.9 10214.0 10640.3 10781.2 11089.7 11411.5 9627.2 9705.5 9836.1 9889.3 10399.9 9943.7 10027.0 10091.5 10228.6 10253.8 9940.4 10188.5 10373.9 10390.7 10683.2 8185.0 8226.8 8287.7 8573.8 8835.8 8057.2 8399.6 8482.7 8553.7 8695.5 18:12:01 INFO - 2015-12-17 18:12:01,574 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:12:01 INFO - 2015-12-17 18:12:01,574 DEBUG : BROWSER_OUTPUT: 18:12:02 INFO - 2015-12-17 18:12:02,262 INFO : Browser exited with error code: 0 18:12:02 INFO - 2015-12-17 18:12:02,281 INFO : Completed test dromaeo_css (00:18:36) 18:12:02 INFO - 2015-12-17 18:12:02,281 INFO : Starting test kraken 18:12:02 INFO - 2015-12-17 18:12:02,281 DEBUG : operating with platform_type : linux_ 18:12:02 INFO - 2015-12-17 18:12:02,281 INFO : Initialising browser for kraken test... 18:12:02 INFO - 2015-12-17 18:12:02,287 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXCXFmW/profile http://localhost:47594/getInfo.html 18:12:07 INFO - 2015-12-17 18:12:07,071 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:12:07 INFO - 2015-12-17 18:12:07,072 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:12:07 INFO - 2015-12-17 18:12:07,072 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:12:07 INFO - 2015-12-17 18:12:07,072 DEBUG : BROWSER_OUTPUT: __metrics 18:12:07 INFO - 2015-12-17 18:12:07,194 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:12:08 INFO - 2015-12-17 18:12:08,145 INFO : Browser initialized. 18:12:08 INFO - 2015-12-17 18:12:08,146 INFO : Running cycle 1/1 for kraken test... 18:12:08 INFO - 2015-12-17 18:12:08,146 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXCXFmW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:12:20 INFO - 2015-12-17 18:12:20,249 DEBUG : BROWSER_OUTPUT: RSS: Main: 150921216 18:12:20 INFO - 2015-12-17 18:12:20,250 DEBUG : BROWSER_OUTPUT: 18:12:22 INFO - 2015-12-17 18:12:22,898 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47594/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47594/tests/kraken/driver.html?testName=audio-beat-detection) 18:12:23 INFO - 2015-12-17 18:12:23,520 DEBUG : BROWSER_OUTPUT: RSS: Main: 151638016 18:12:23 INFO - 2015-12-17 18:12:23,520 DEBUG : BROWSER_OUTPUT: 18:12:26 INFO - 2015-12-17 18:12:26,237 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47594/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47594/tests/kraken/driver.html?testName=audio-dft) 18:12:26 INFO - 2015-12-17 18:12:26,838 DEBUG : BROWSER_OUTPUT: RSS: Main: 150462464 18:12:26 INFO - 2015-12-17 18:12:26,839 DEBUG : BROWSER_OUTPUT: 18:12:29 INFO - 2015-12-17 18:12:29,738 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47594/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47594/tests/kraken/driver.html?testName=audio-fft) 18:12:30 INFO - 2015-12-17 18:12:30,324 DEBUG : BROWSER_OUTPUT: RSS: Main: 145219584 18:12:30 INFO - 2015-12-17 18:12:30,324 DEBUG : BROWSER_OUTPUT: 18:12:32 INFO - 2015-12-17 18:12:32,437 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47594/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47594/tests/kraken/driver.html?testName=audio-oscillator) 18:12:33 INFO - 2015-12-17 18:12:33,059 DEBUG : BROWSER_OUTPUT: RSS: Main: 146132992 18:12:33 INFO - 2015-12-17 18:12:33,059 DEBUG : BROWSER_OUTPUT: 18:12:35 INFO - 2015-12-17 18:12:35,314 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47594/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47594/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:12:35 INFO - 2015-12-17 18:12:35,895 DEBUG : BROWSER_OUTPUT: RSS: Main: 146853888 18:12:35 INFO - 2015-12-17 18:12:35,896 DEBUG : BROWSER_OUTPUT: 18:12:38 INFO - 2015-12-17 18:12:38,934 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47594/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47594/tests/kraken/driver.html?testName=imaging-darkroom) 18:12:39 INFO - 2015-12-17 18:12:39,523 DEBUG : BROWSER_OUTPUT: RSS: Main: 147714048 18:12:39 INFO - 2015-12-17 18:12:39,523 DEBUG : BROWSER_OUTPUT: 18:12:42 INFO - 2015-12-17 18:12:42,985 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47594/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47594/tests/kraken/driver.html?testName=imaging-desaturate) 18:12:43 INFO - 2015-12-17 18:12:43,578 DEBUG : BROWSER_OUTPUT: RSS: Main: 146276352 18:12:43 INFO - 2015-12-17 18:12:43,578 DEBUG : BROWSER_OUTPUT: 18:12:46 INFO - 2015-12-17 18:12:46,469 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47594/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47594/tests/kraken/driver.html?testName=json-parse-financial) 18:12:47 INFO - 2015-12-17 18:12:47,056 DEBUG : BROWSER_OUTPUT: RSS: Main: 147243008 18:12:47 INFO - 2015-12-17 18:12:47,056 DEBUG : BROWSER_OUTPUT: 18:12:48 INFO - 2015-12-17 18:12:48,819 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47594/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47594/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:12:49 INFO - 2015-12-17 18:12:49,403 DEBUG : BROWSER_OUTPUT: RSS: Main: 148926464 18:12:49 INFO - 2015-12-17 18:12:49,403 DEBUG : BROWSER_OUTPUT: 18:12:51 INFO - 2015-12-17 18:12:51,285 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47594/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47594/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:12:51 INFO - 2015-12-17 18:12:51,907 DEBUG : BROWSER_OUTPUT: RSS: Main: 147648512 18:12:51 INFO - 2015-12-17 18:12:51,908 DEBUG : BROWSER_OUTPUT: 18:12:54 INFO - 2015-12-17 18:12:54,075 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47594/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47594/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:12:54 INFO - 2015-12-17 18:12:54,669 DEBUG : BROWSER_OUTPUT: RSS: Main: 147927040 18:12:54 INFO - 2015-12-17 18:12:54,669 DEBUG : BROWSER_OUTPUT: 18:12:57 INFO - 2015-12-17 18:12:57,244 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47594/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47594/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:12:57 INFO - 2015-12-17 18:12:57,846 DEBUG : BROWSER_OUTPUT: RSS: Main: 148287488 18:12:57 INFO - 2015-12-17 18:12:57,846 DEBUG : BROWSER_OUTPUT: 18:13:00 INFO - 2015-12-17 18:13:00,453 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47594/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47594/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:13:01 INFO - 2015-12-17 18:13:01,054 DEBUG : BROWSER_OUTPUT: RSS: Main: 148430848 18:13:01 INFO - 2015-12-17 18:13:01,055 DEBUG : BROWSER_OUTPUT: 18:13:02 INFO - 2015-12-17 18:13:02,832 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47594/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47594/tests/kraken/driver.html?testName=ai-astar) 18:13:03 INFO - 2015-12-17 18:13:03,082 DEBUG : BROWSER_OUTPUT: RSS: Main: 149598208 18:13:03 INFO - 2015-12-17 18:13:03,083 DEBUG : BROWSER_OUTPUT: 18:13:03 INFO - 2015-12-17 18:13:03,086 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:13:03 INFO - 2015-12-17 18:13:03,086 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:13:03 INFO - 2015-12-17 18:13:03,086 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:13:03 INFO - 2015-12-17 18:13:03,086 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:13:03 INFO - 2015-12-17 18:13:03,087 DEBUG : BROWSER_OUTPUT: |0;ai-astar;116;109;112;115;113;123;113;113;113;114 18:13:03 INFO - 2015-12-17 18:13:03,087 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;161;133;153;171;132;160;152;142;168;135 18:13:03 INFO - 2015-12-17 18:13:03,087 DEBUG : BROWSER_OUTPUT: |2;audio-dft;163;162;162;162;163;163;165;162;163;162 18:13:03 INFO - 2015-12-17 18:13:03,087 DEBUG : BROWSER_OUTPUT: |3;audio-fft;73;72;73;76;75;73;140;73;71;71 18:13:03 INFO - 2015-12-17 18:13:03,087 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;94;94;145;94;94;95;128;94;95;126 18:13:03 INFO - 2015-12-17 18:13:03,087 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;111;110;110;110;110;110;110;110;111 18:13:03 INFO - 2015-12-17 18:13:03,087 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;148;147;148;148;148;148;147;148;149 18:13:03 INFO - 2015-12-17 18:13:03,088 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;97;97;95;96;97;96;97;96;96 18:13:03 INFO - 2015-12-17 18:13:03,088 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;72;74;71;73;73;73;74;72;71;74 18:13:03 INFO - 2015-12-17 18:13:03,088 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;68;69;67;68;68;68;67;68;69;68 18:13:03 INFO - 2015-12-17 18:13:03,088 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;82;82;85;84;85;84;83;83;83;84 18:13:03 INFO - 2015-12-17 18:13:03,088 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;130;118;122;125;129;133;127;120;141;131 18:13:03 INFO - 2015-12-17 18:13:03,088 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;137;152;159;148;158;136;147;144;153;142 18:13:03 INFO - 2015-12-17 18:13:03,088 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;71;70;70;72;69;70;70;70;70;76 18:13:03 INFO - 2015-12-17 18:13:03,088 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:13:03 INFO - 2015-12-17 18:13:03,089 DEBUG : BROWSER_OUTPUT: __start_cc_report 18:13:03 INFO - 2015-12-17 18:13:03,089 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,762 18:13:03 INFO - 2015-12-17 18:13:03,089 DEBUG : BROWSER_OUTPUT: __end_cc_report 18:13:03 INFO - 2015-12-17 18:13:03,089 DEBUG : BROWSER_OUTPUT: __startTimestamp1450404783084__endTimestamp 18:13:03 INFO - 2015-12-17 18:13:03,089 DEBUG : BROWSER_OUTPUT: 18:13:03 INFO - 2015-12-17 18:13:03,092 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:13:03 INFO - 2015-12-17 18:13:03,093 DEBUG : BROWSER_OUTPUT: Number of tests: 14 18:13:03 INFO - 2015-12-17 18:13:03,093 DEBUG : BROWSER_OUTPUT: 18:13:03 INFO - 2015-12-17 18:13:03,093 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:114.10 Median:113.50 stddev:3.63 (3.2%) stddev-sans-first:3.79 18:13:03 INFO - 2015-12-17 18:13:03,093 DEBUG : BROWSER_OUTPUT: Values: 116.0 109.0 112.0 115.0 113.0 123.0 113.0 113.0 113.0 114.0 18:13:03 INFO - 2015-12-17 18:13:03,093 DEBUG : BROWSER_OUTPUT: 18:13:03 INFO - 2015-12-17 18:13:03,093 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:150.70 Median:156.50 stddev:14.51 (9.3%) stddev-sans-first:14.91 18:13:03 INFO - 2015-12-17 18:13:03,093 DEBUG : BROWSER_OUTPUT: Values: 161.0 133.0 153.0 171.0 132.0 160.0 152.0 142.0 168.0 135.0 18:13:03 INFO - 2015-12-17 18:13:03,093 DEBUG : BROWSER_OUTPUT: 18:13:03 INFO - 2015-12-17 18:13:03,093 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:162.70 Median:163.00 stddev:0.95 (0.6%) stddev-sans-first:1.00 18:13:03 INFO - 2015-12-17 18:13:03,093 DEBUG : BROWSER_OUTPUT: Values: 163.0 162.0 162.0 162.0 163.0 163.0 165.0 162.0 163.0 162.0 18:13:03 INFO - 2015-12-17 18:13:03,093 DEBUG : BROWSER_OUTPUT: 18:13:03 INFO - 2015-12-17 18:13:03,093 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:79.70 Median:73.00 stddev:21.24 (29.1%) stddev-sans-first:22.39 18:13:03 INFO - 2015-12-17 18:13:03,093 DEBUG : BROWSER_OUTPUT: Values: 73.0 72.0 73.0 76.0 75.0 73.0 140.0 73.0 71.0 71.0 18:13:03 INFO - 2015-12-17 18:13:03,093 DEBUG : BROWSER_OUTPUT: 18:13:03 INFO - 2015-12-17 18:13:03,093 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:105.90 Median:94.00 stddev:19.34 (20.6%) stddev-sans-first:20.03 18:13:03 INFO - 2015-12-17 18:13:03,094 DEBUG : BROWSER_OUTPUT: Values: 94.0 94.0 145.0 94.0 94.0 95.0 128.0 94.0 95.0 126.0 18:13:03 INFO - 2015-12-17 18:13:03,094 DEBUG : BROWSER_OUTPUT: 18:13:03 INFO - 2015-12-17 18:13:03,094 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:110.20 Median:110.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 18:13:03 INFO - 2015-12-17 18:13:03,094 DEBUG : BROWSER_OUTPUT: Values: 110.0 111.0 110.0 110.0 110.0 110.0 110.0 110.0 110.0 111.0 18:13:03 INFO - 2015-12-17 18:13:03,094 DEBUG : BROWSER_OUTPUT: 18:13:03 INFO - 2015-12-17 18:13:03,094 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:147.80 Median:148.00 stddev:0.63 (0.4%) stddev-sans-first:0.60 18:13:03 INFO - 2015-12-17 18:13:03,094 DEBUG : BROWSER_OUTPUT: Values: 147.0 148.0 147.0 148.0 148.0 148.0 148.0 147.0 148.0 149.0 18:13:03 INFO - 2015-12-17 18:13:03,094 DEBUG : BROWSER_OUTPUT: 18:13:03 INFO - 2015-12-17 18:13:03,094 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.20 Median:96.50 stddev:0.79 (0.8%) stddev-sans-first:0.71 18:13:03 INFO - 2015-12-17 18:13:03,094 DEBUG : BROWSER_OUTPUT: Values: 95.0 97.0 97.0 95.0 96.0 97.0 96.0 97.0 96.0 96.0 18:13:03 INFO - 2015-12-17 18:13:03,094 DEBUG : BROWSER_OUTPUT: 18:13:03 INFO - 2015-12-17 18:13:03,094 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:72.70 Median:73.00 stddev:1.16 (1.6%) stddev-sans-first:1.20 18:13:03 INFO - 2015-12-17 18:13:03,094 DEBUG : BROWSER_OUTPUT: Values: 72.0 74.0 71.0 73.0 73.0 73.0 74.0 72.0 71.0 74.0 18:13:03 INFO - 2015-12-17 18:13:03,095 DEBUG : BROWSER_OUTPUT: 18:13:03 INFO - 2015-12-17 18:13:03,095 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:68.00 Median:68.00 stddev:0.67 (1.0%) stddev-sans-first:0.71 18:13:03 INFO - 2015-12-17 18:13:03,095 DEBUG : BROWSER_OUTPUT: Values: 68.0 69.0 67.0 68.0 68.0 68.0 67.0 68.0 69.0 68.0 18:13:03 INFO - 2015-12-17 18:13:03,095 DEBUG : BROWSER_OUTPUT: 18:13:03 INFO - 2015-12-17 18:13:03,095 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:83.50 Median:84.00 stddev:1.08 (1.3%) stddev-sans-first:1.00 18:13:03 INFO - 2015-12-17 18:13:03,095 DEBUG : BROWSER_OUTPUT: Values: 82.0 82.0 85.0 84.0 85.0 84.0 83.0 83.0 83.0 84.0 18:13:03 INFO - 2015-12-17 18:13:03,095 DEBUG : BROWSER_OUTPUT: 18:13:03 INFO - 2015-12-17 18:13:03,095 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:127.60 Median:129.50 stddev:6.80 (5.3%) stddev-sans-first:7.16 18:13:03 INFO - 2015-12-17 18:13:03,095 DEBUG : BROWSER_OUTPUT: Values: 130.0 118.0 122.0 125.0 129.0 133.0 127.0 120.0 141.0 131.0 18:13:03 INFO - 2015-12-17 18:13:03,095 DEBUG : BROWSER_OUTPUT: 18:13:03 INFO - 2015-12-17 18:13:03,096 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:147.60 Median:150.00 stddev:8.02 (5.3%) stddev-sans-first:7.53 18:13:03 INFO - 2015-12-17 18:13:03,096 DEBUG : BROWSER_OUTPUT: Values: 137.0 152.0 159.0 148.0 158.0 136.0 147.0 144.0 153.0 142.0 18:13:03 INFO - 2015-12-17 18:13:03,096 DEBUG : BROWSER_OUTPUT: 18:13:03 INFO - 2015-12-17 18:13:03,096 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.80 Median:70.00 stddev:1.99 (2.8%) stddev-sans-first:2.11 18:13:03 INFO - 2015-12-17 18:13:03,096 DEBUG : BROWSER_OUTPUT: Values: 71.0 70.0 70.0 72.0 69.0 70.0 70.0 70.0 70.0 76.0 18:13:03 INFO - 2015-12-17 18:13:03,096 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:13:03 INFO - 2015-12-17 18:13:03,096 DEBUG : BROWSER_OUTPUT: 18:13:04 INFO - 2015-12-17 18:13:04,014 INFO : Browser exited with error code: 0 18:13:04 INFO - 2015-12-17 18:13:04,062 INFO : Completed test kraken (00:01:01) 18:13:04 INFO - 2015-12-17 18:13:04,062 INFO : Starting test v8_7 18:13:04 INFO - 2015-12-17 18:13:04,062 DEBUG : operating with platform_type : linux_ 18:13:04 INFO - 2015-12-17 18:13:04,062 INFO : Initialising browser for v8_7 test... 18:13:04 INFO - 2015-12-17 18:13:04,068 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpi4pKCn/profile http://localhost:47594/getInfo.html 18:13:08 INFO - 2015-12-17 18:13:08,715 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:13:08 INFO - 2015-12-17 18:13:08,715 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:13:08 INFO - 2015-12-17 18:13:08,715 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:13:08 INFO - 2015-12-17 18:13:08,715 DEBUG : BROWSER_OUTPUT: __metrics 18:13:08 INFO - 2015-12-17 18:13:08,856 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:13:09 INFO - 2015-12-17 18:13:09,749 INFO : Browser initialized. 18:13:09 INFO - 2015-12-17 18:13:09,750 INFO : Running cycle 1/1 for v8_7 test... 18:13:09 INFO - 2015-12-17 18:13:09,750 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpi4pKCn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:13:21 INFO - 2015-12-17 18:13:21,840 DEBUG : BROWSER_OUTPUT: RSS: Main: 150802432 18:13:21 INFO - 2015-12-17 18:13:21,841 DEBUG : BROWSER_OUTPUT: 18:13:43 INFO - 2015-12-17 18:13:43,270 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47594/tests/v8_7/run.html (next: http://localhost:47594/tests/v8_7/run.html) 18:13:43 INFO - 2015-12-17 18:13:43,529 DEBUG : BROWSER_OUTPUT: RSS: Main: 144891904 18:13:43 INFO - 2015-12-17 18:13:43,529 DEBUG : BROWSER_OUTPUT: 18:13:43 INFO - 2015-12-17 18:13:43,529 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:13:43 INFO - 2015-12-17 18:13:43,529 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:13:43 INFO - 2015-12-17 18:13:43,529 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:13:43 INFO - 2015-12-17 18:13:43,529 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:13:43 INFO - 2015-12-17 18:13:43,529 DEBUG : BROWSER_OUTPUT: |0;Richards;166.11295681063123 18:13:43 INFO - 2015-12-17 18:13:43,529 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;191.46084625694044 18:13:43 INFO - 2015-12-17 18:13:43,530 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.08676307007784 18:13:43 INFO - 2015-12-17 18:13:43,530 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5138.461538461538 18:13:43 INFO - 2015-12-17 18:13:43,530 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1069.51871657754 18:13:43 INFO - 2015-12-17 18:13:43,530 DEBUG : BROWSER_OUTPUT: |5;Earley;700.770847932726 18:13:43 INFO - 2015-12-17 18:13:43,530 DEBUG : BROWSER_OUTPUT: |6;Boyer;12500 18:13:43 INFO - 2015-12-17 18:13:43,530 DEBUG : BROWSER_OUTPUT: |7;RegExp;26179.48717948718 18:13:43 INFO - 2015-12-17 18:13:43,530 DEBUG : BROWSER_OUTPUT: |8;Splay;657.030223390276 18:13:43 INFO - 2015-12-17 18:13:43,530 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4840.579710144928 18:13:43 INFO - 2015-12-17 18:13:43,530 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:13:43 INFO - 2015-12-17 18:13:43,530 DEBUG : BROWSER_OUTPUT: __startTimestamp1450404823522__endTimestamp 18:13:43 INFO - 2015-12-17 18:13:43,531 DEBUG : BROWSER_OUTPUT: 18:13:43 INFO - 2015-12-17 18:13:43,531 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:13:43 INFO - 2015-12-17 18:13:43,531 DEBUG : BROWSER_OUTPUT: Number of tests: 10 18:13:43 INFO - 2015-12-17 18:13:43,531 DEBUG : BROWSER_OUTPUT: 18:13:43 INFO - 2015-12-17 18:13:43,531 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:166.11 Median:166.11 stddev:0.00 (0.0%) 18:13:43 INFO - 2015-12-17 18:13:43,531 DEBUG : BROWSER_OUTPUT: Values: 166.1 18:13:43 INFO - 2015-12-17 18:13:43,531 DEBUG : BROWSER_OUTPUT: 18:13:43 INFO - 2015-12-17 18:13:43,531 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:191.46 Median:191.46 stddev:0.00 (0.0%) 18:13:43 INFO - 2015-12-17 18:13:43,531 DEBUG : BROWSER_OUTPUT: Values: 191.5 18:13:43 INFO - 2015-12-17 18:13:43,531 DEBUG : BROWSER_OUTPUT: 18:13:43 INFO - 2015-12-17 18:13:43,531 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.09 Median:278.09 stddev:0.00 (0.0%) 18:13:43 INFO - 2015-12-17 18:13:43,531 DEBUG : BROWSER_OUTPUT: Values: 278.1 18:13:43 INFO - 2015-12-17 18:13:43,531 DEBUG : BROWSER_OUTPUT: 18:13:43 INFO - 2015-12-17 18:13:43,531 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5138.46 Median:5138.46 stddev:0.00 (0.0%) 18:13:43 INFO - 2015-12-17 18:13:43,532 DEBUG : BROWSER_OUTPUT: Values: 5138.5 18:13:43 INFO - 2015-12-17 18:13:43,532 DEBUG : BROWSER_OUTPUT: 18:13:43 INFO - 2015-12-17 18:13:43,532 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1069.52 Median:1069.52 stddev:0.00 (0.0%) 18:13:43 INFO - 2015-12-17 18:13:43,532 DEBUG : BROWSER_OUTPUT: Values: 1069.5 18:13:43 INFO - 2015-12-17 18:13:43,532 DEBUG : BROWSER_OUTPUT: 18:13:43 INFO - 2015-12-17 18:13:43,532 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:700.77 Median:700.77 stddev:0.00 (0.0%) 18:13:43 INFO - 2015-12-17 18:13:43,532 DEBUG : BROWSER_OUTPUT: Values: 700.8 18:13:43 INFO - 2015-12-17 18:13:43,532 DEBUG : BROWSER_OUTPUT: 18:13:43 INFO - 2015-12-17 18:13:43,533 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12500.00 Median:12500.00 stddev:0.00 (0.0%) 18:13:43 INFO - 2015-12-17 18:13:43,533 DEBUG : BROWSER_OUTPUT: Values: 12500.0 18:13:43 INFO - 2015-12-17 18:13:43,533 DEBUG : BROWSER_OUTPUT: 18:13:43 INFO - 2015-12-17 18:13:43,533 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26179.49 Median:26179.49 stddev:0.00 (0.0%) 18:13:43 INFO - 2015-12-17 18:13:43,533 DEBUG : BROWSER_OUTPUT: Values: 26179.5 18:13:43 INFO - 2015-12-17 18:13:43,533 DEBUG : BROWSER_OUTPUT: 18:13:43 INFO - 2015-12-17 18:13:43,534 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:657.03 Median:657.03 stddev:0.00 (0.0%) 18:13:43 INFO - 2015-12-17 18:13:43,534 DEBUG : BROWSER_OUTPUT: Values: 657.0 18:13:43 INFO - 2015-12-17 18:13:43,534 DEBUG : BROWSER_OUTPUT: 18:13:43 INFO - 2015-12-17 18:13:43,534 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4840.58 Median:4840.58 stddev:0.00 (0.0%) 18:13:43 INFO - 2015-12-17 18:13:43,534 DEBUG : BROWSER_OUTPUT: Values: 4840.6 18:13:43 INFO - 2015-12-17 18:13:43,534 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:13:43 INFO - 2015-12-17 18:13:43,534 DEBUG : BROWSER_OUTPUT: 18:13:44 INFO - 2015-12-17 18:13:44,401 INFO : Browser exited with error code: 0 18:13:44 INFO - 2015-12-17 18:13:44,420 INFO : Completed test v8_7 (00:00:40) 18:13:44 INFO - 2015-12-17 18:13:44,534 INFO : Completed test suite (00:20:18) 18:13:44 INFO - 2015-12-17 18:13:44,534 DEBUG : Working with test: dromaeo_css 18:13:44 INFO - 2015-12-17 18:13:44,534 DEBUG : Generating results file: dromaeo_css 18:13:44 INFO - 2015-12-17 18:13:44,536 DEBUG : Working with test: kraken 18:13:44 INFO - 2015-12-17 18:13:44,536 DEBUG : Generating results file: kraken 18:13:44 INFO - 2015-12-17 18:13:44,536 INFO : javascript benchmark 18:13:44 INFO - 2015-12-17 18:13:44,536 DEBUG : Working with test: v8_7 18:13:44 INFO - 2015-12-17 18:13:44,536 DEBUG : Generating results file: v8_7 18:13:44 INFO - 2015-12-17 18:13:44,537 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:13:44 INFO - 2015-12-17 18:13:44,665 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,132,43]] 18:13:44 INFO - 2015-12-17 18:13:44,665 DEBUG : process_Request line: dromaeo_css 6191.57 graph.html#tests=[[72,132,43]] 18:13:44 INFO - 2015-12-17 18:13:44,666 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:13:44 INFO - 2015-12-17 18:13:44,739 DEBUG : process_Request line: kraken 1537.50 graph.html#tests=[[232,132,43]] 18:13:44 INFO - 2015-12-17 18:13:44,739 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:13:44 INFO - 2015-12-17 18:13:44,778 DEBUG : process_Request line: v8_7 21113.40 graph.html#tests=[[230,132,43]] 18:13:44 INFO - 2015-12-17 18:13:44,779 INFO : javascript benchmark 18:13:44 INFO - 2015-12-17 18:13:44,783 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2652.0850306644206, 2686.5835795451712, 2732.125719769673, 2736.9863150684246, 2747.2994386431455, 4564.452265728118, 4654.604358629521, 4661.0, 4697.2954056891485, 4700.177468942934, 4657.0453057123295, 4687.132880417125, 4721.315409265654, 4827.8793030174265, 4835.202191638376, 4267.338562522814, 4401.515833258339, 4534.569215924482, 4540.2054640437955, 4621.838235661752, 4222.873313800591, 4492.7528985905765, 4568.543145685421, 4570.657200709943, 4590.770461476929, 4327.675424343187, 4391.297392417214, 4402.691811573191, 4436.179306828247, 4451.866444006652, 4452.0205554777895, 4470.619997300233, 4471.642268618535, 4487.596116349511, 4495.078508905699, 4203.159368126387, 4321.567843215685, 4434.2905135178535, 4441.888952776174, 4480.551944805526, 4587.816487340502, 4611.5619016193405, 4720.740359280238, 4736.194846876039, 4749.453812811536, 5049.3183420238165, 5054.620903432257, 5055.671381360236, 5056.570191533724, 5065.974670126662, 4944.777485013147, 5057.873553161164, 5070.61970352225, 5084.694918304914, 5126.333576635014, 4707.882302942454, 4727.57451829337, 4733.83431579893, 4734.053189362107, 4739.4075740532435, 5290.259363689087, 5296.232046353334, 5296.443873393238, 5307.495787900143, 5308.999999999923, 5049.974750126225, 5092.134337162691, 5092.821751238689, 5098.694078355311, 5134.409542902614, 4948.38145231846, 5062.265971434122, 5062.645614807002, 5168.328117344797, 5186.844394668166, 5075.543201111844, 5128.897422051538, 5131.512506311894, 5162.251473536317, 5162.845114646567, 5057.140286151363, 5092.9745351273, 5115.130427827278, 5115.974420127875, 5126.87182820425, 4081.8163182656854, 4155.0, 4174.20690068886, 4211.957880421157, 4260.978695106504, 3734.8506059757847, 3761.981190094032, 3772.3775577029483, 3793.962060379416, 3809.1619843634357, 4256.808443619982, 4459.353393757887, 4473.4408198975125, 4477.552244775497, 4481.641468682497, 4980.800767969313, 5036.823711170092, 5122.769475373617, 5128.58971282304, 5191.195364718475, 4404.0, 4406.184855801687, 4411.1618792429335, 4417.514073451917, 4470.776461176928, 4336.82652693895, 4463.888402789891, 4545.159210519745, 4560.156371071363, 4607.424071991001, 4762.59517940972, 4793.880152996203, 4814.735189564608, 4839.854804355734, 4886.047220791911, 5177.508136727005, 5181.0, 5186.170212765939, 5209.505097015777, 5215.530602245816, 4672.08894265615, 4785.258284965836, 4816.4220293564995, 4902.63230257725, 4956.10790057793, 5057.115004874206, 5070.8225212117395, 5082.567981721595, 5092.312537807348, 5094.1849304111165, 5114.769835357343, 5128.384593848762, 5128.948710512847, 5141.794328226829, 5158.0, 4773.880652983703, 4836.613070954245, 4877.878053048702, 4926.655134140719, 4976.029674213493, 4964.627652926118, 4986.376702912136, 5001.449840517613, 5033.622478314214, 5045.268436076822, 4890.7554622268035, 4915.361003069578, 4939.53074457926, 4965.106280869334, 5002.074616196015, 4739.526047395233, 4843.782029808596, 4878.121938051185, 4992.076465853684, 5019.648624596243, 4957.058158949788, 4961.925571116364, 4991.376077990251, 5002.774875130555, 5042.495750425075], "unit": "score", "name": "dojo.html", "value": 4687.925098894998}, {"lowerIsBetter": false, "replicates": [557.361820715281, 564.2918137737139, 564.6781334639255, 569.5443645083934, 573.5870173475097, 34711.99999999997, 35679.0, 35710.107247318796, 35715.46426803597, 35919.0, 39693.20613587726, 40104.59895401045, 40159.0, 40823.59176408235, 40913.18173636525, 7877.6848926042885, 7961.562114083723, 7978.481398709066, 8054.033515978091, 8062.879056814152, 7875.0, 7928.127905930343, 7970.800729981739, 7985.04179498461, 7985.361171106298, 7906.327962123227, 8006.839863202733, 8016.038075430927, 8024.558649274288, 8030.799230019238, 7881.211878812131, 7900.249476299799, 7947.403944704112, 7962.442629016, 7970.641321140533, 7878.527288362717, 7958.6418611162635, 8005.9599702001115, 8024.037115546172, 8031.759047228593, 35659.82170089133, 36211.45682814733, 36367.0, 36479.635203648155, 36588.634113659056, 2758.7930905182193, 2863.7708983281286, 2885.2354126156583, 3046.573479712842, 3485.9128521787156, 28188.577171342447, 28488.85755571209, 28653.28366790847, 28688.0, 28699.0, 3088.8764449421997, 3089.057837359627, 3102.5966624338917, 3122.8244654400796, 3166.509191075387, 212386.0, 212735.0, 213201.0, 213368.0, 214886.0, 51871.48128518742, 55083.0, 55488.0, 55503.44496555063, 55640.72179639076, 44567.77716111398, 65322.673386633716, 66355.0, 66372.0, 66473.33526664769, 14230.288485575888, 14286.214258215898, 14339.211343376215, 14388.0, 14515.491957781427, 10323.0, 10341.4312212829, 10352.068313851789, 10361.481925903825, 10366.0, 18913.81086189148, 19253.22987080529, 19300.1314940828, 19316.034198290028, 19334.613307733765, 7278.344948954619, 7500.887486687704, 7542.886856697154, 7549.169591344948, 7596.316331530189, 816.2049448912672, 895.5566994337714, 902.7878448564582, 938.4275591888942, 1064.3135177810268, 26008.479830403285, 26743.0, 26877.0, 26890.462190756076, 26912.192634221006, 971.3152227679469, 982.8525721141743, 1034.4879284754095, 1084.6258040975852, 1153.3022521374614, 12834.614961551168, 13046.152000120152, 13202.0, 13321.267330296927, 13395.397207125703, 260865.0, 265208.0, 265487.0, 265692.0, 265757.0, 45654.77172614182, 45942.0, 46197.30704039442, 46365.768171158925, 46540.301895471595, 1207.4023358437455, 1224.253205544617, 1226.337777600104, 1230.846144231971, 1232.0082333721261, 25583.488330233664, 25845.449273043676, 25924.870375648003, 26019.21942341733, 26104.216873493824, 40300.0, 40974.18051638891, 41049.384259235536, 41287.0, 41410.37884431676, 242044.0, 242259.0, 243198.0, 243263.0, 243307.0, 23984.400389989692, 24541.75458245395, 28997.855010724812, 29251.268718282212, 29438.11685649434, 36692.81653591822, 36781.63218367782, 36811.815940920125, 36999.526974879234, 37543.43684844784, 31513.684863151077, 31729.68270317267, 32001.0, 32674.83662581672, 32891.51988160586, 31770.841145794122, 32035.35929281448, 32261.193470163435, 32314.0, 32322.515162273055, 32132.357352852345, 32524.186895327806, 33260.66739332672, 33827.66172338343, 33846.15384615404, 26939.0, 28817.85591072031, 29605.703942960296, 30069.0, 30072.398552029274, 22756.0, 23853.165139220044, 23946.88026559856, 23982.88008559946, 25224.369390764645], "unit": "score", "name": "ext.html", "value": 16777.581033671446}, {"lowerIsBetter": false, "replicates": [321.23385725045756, 325.7215081105655, 327.10536682174256, 329.92081900343925, 337.45164108323974, 13235.404406801705, 15036.398544058238, 15515.844841551581, 15996.200189990483, 16603.256658050912, 15318.00432971856, 15385.23073846306, 15393.769093463607, 15451.768223476629, 15695.764563531557, 4274.187904298174, 4458.933116003246, 4459.576340247688, 4462.754548499831, 4470.441194850644, 3743.4759133721163, 4392.604665580097, 4414.536473670267, 4445.622122119607, 4837.226043832988, 4785.449673287581, 4795.184818580832, 4800.591949684281, 4953.678010929296, 4959.578435832958, 4823.782929768169, 4916.311716359713, 4923.82766603169, 4925.802967881298, 4943.950560494403, 4934.481879402647, 5002.674826136289, 5015.899682006339, 5039.823606173766, 5049.621278404097, 16731.749023764653, 18310.816891831044, 18390.908045459822, 18816.529586760305, 18874.0, 1824.2155872974615, 1828.9176987035482, 1844.732513785507, 1882.115405759291, 1905.1236431241646, 8881.689140880038, 8959.820803583892, 9098.090190980914, 10362.0, 12673.112882098258, 1706.6074802795301, 1769.5576105973507, 1774.7426623139702, 1789.337944960373, 1833.9449816505394, 56414.92896510421, 60176.0, 60303.396966030654, 60364.094538582394, 60917.69541152266, 9534.809303813885, 9714.757131071638, 9796.510174491246, 9810.509474526389, 9904.702858914245, 12517.93741031289, 12541.247525148337, 12542.686432839251, 12553.99568034555, 12575.937120314522, 6994.370506654323, 7272.563646181246, 7306.744263950843, 7307.26927307265, 7315.561066336037, 6902.516823822284, 6954.478414118961, 6968.059311992816, 6990.0, 7153.105861767279, 4024.2353952748886, 4077.245709543744, 4082.775447350424, 4085.20338533989, 4169.478140478764, 1697.9660406791795, 1759.4897479730992, 1770.8583313334902, 1780.7506949027147, 1792.7759030121235, 361.8643008871673, 368.3186105704479, 376.8643288416223, 403.294235088595, 406.4533202842172, 9281.535923203948, 9398.107179817905, 9649.710508684751, 9658.613655453702, 9760.707178784647, 391.04779861038463, 392.47800425434673, 409.14488718578315, 412.420549128475, 417.04496702551427, 5155.200943853709, 5305.973470132702, 5310.123829568078, 5314.229436731653, 5414.133738601805, 449958.0, 453466.0, 455102.0, 455886.0, 459885.0, 69051.0, 70486.64756676287, 72695.63652181705, 73285.0, 73839.0, 445.3208856493808, 451.4853067503049, 452.1747810246248, 452.57457989489774, 454.8908262017158, 9228.0, 9643.0, 9692.17616502577, 9758.658446954323, 9833.803323933338, 34361.656383435846, 34428.65571344355, 35155.82422087975, 35191.47212791861, 35321.470177947864, 43321.0, 46295.0, 48009.0, 50063.49936500588, 50083.74958125186, 8683.435576687496, 8858.867116993379, 8919.241864441596, 9028.864567031369, 9344.532773361441, 16010.199490025685, 16385.180740962667, 16469.91765041167, 16564.917175414044, 16659.500214993568, 13586.45654173822, 13669.658258543615, 13707.520236791665, 13732.107413018122, 14182.787258190929, 13312.933435332761, 13431.261280629658, 13568.79646805279, 14156.929215353857, 14183.007189496637, 13625.250611216477, 13742.106763060368, 13937.30313484342, 14290.642733931734, 14311.141331520143, 9102.180803727697, 9135.63457461694, 9217.493037882981, 9234.9538252311, 9236.953815230881, 13170.275634839792, 13649.181049137083, 13831.861681383058, 13927.791083133558, 14131.293435327987], "unit": "score", "name": "jquery.html", "value": 7459.943653487093}, {"lowerIsBetter": false, "replicates": [1461.400825661479, 1669.0820048973067, 1678.3622223555046, 1682.4080587944743, 1745.720684690449, 6102.328743838174, 6305.148804911336, 7086.681099350529, 7283.344498995089, 7285.198628150899, 6692.564983276084, 6834.077399551059, 6849.897251541219, 6897.448204143669, 6915.481338899577, 2046.8362530997485, 2086.843486738493, 2088.0290664840845, 2089.3836318286067, 2091.2576035507327, 2071.337172104927, 2073.129285700002, 2080.2823026055985, 2113.778053304404, 2125.3517677108475, 2012.2856385982984, 2020.6261841559285, 2037.7656569494475, 2040.1125510402949, 2042.79572042796, 2046.5906818636258, 2056.5372791122027, 2074.6161960037443, 2080.855529458799, 2084.6351888419467, 1997.3508609701905, 2009.6884982827685, 2033.9288124915704, 2036.694495825623, 2041.642712525317, 7195.496315257934, 7294.525855819408, 7309.049823522962, 7316.085489313836, 7333.963330183368, 4112.609302116294, 4162.105147393325, 4163.854265100737, 4170.4995400551825, 4193.119444916571, 2558.1174494799266, 2606.1660268714018, 2627.671541057368, 2657.7475139861876, 2658.6809582849987, 847.7753395350237, 847.92792612628, 856.0027567883409, 864.0754392799707, 871.2594294849391, 9452.716418507456, 9497.192738617157, 9512.762180945463, 9527.857082143773, 9532.237421006303, 3633.0554055945645, 3652.1052342176336, 3664.5419322584676, 3669.7064234861145, 3691.8707845225827, 8205.71280005197, 8232.588370581567, 8236.094029656733, 8243.711470098518, 8254.504729716236, 4081.714280000431, 4090.836366545305, 4094.815733292009, 4098.057446787256, 4101.036256479725, 3196.4406228909847, 3270.8691652334114, 3623.673869351718, 3746.756460830037, 3749.681277091423, 3282.885099021523, 3312.685294896981, 3372.9831350843087, 3465.8786942457396, 3530.0821786335737, 1116.1461481966303, 1129.4917287220783, 1145.5589598004829, 1150.6950658075616, 1150.8043632582314, 857.2670366836389, 866.5623859950684, 1176.5940750441084, 1188.8929996300374, 1198.3888217009214, 6551.377619126175, 6600.306967768315, 6606.174228221473, 6645.335466453316, 6669.699863506154, 407.6596042304709, 1084.8318510630866, 1102.0687519046394, 1133.3369978562632, 1143.091242462228, 5453.291072160594, 5538.612297139242, 5539.196816461671, 5542.39033706292, 5560.082586373284, 8345.499270043816, 8383.11977242393, 8663.740087797376, 8726.738197854074, 8746.781330466662, 8567.614457349435, 8623.094575069654, 8643.567821608895, 8645.740627781177, 8655.6537738491, 6853.657317134123, 6861.828454288683, 6866.487722437658, 6908.378245957888, 6953.339432753982, 6619.238787539494, 6624.735010599522, 6647.235567909753, 6658.667066646745, 6661.733530658913, 7721.0, 7752.844943100994, 7797.727079552188, 7810.648520816463, 7968.880466793119, 7885.89597456368, 7956.283934445928, 8120.066192387951, 8135.267825895698, 8250.46371985819, 5203.699075231192, 5207.557357624644, 5727.198192253171, 5754.625949313281, 5828.6502809831545, 6869.828254293682, 6895.000224967252, 6952.5480843745, 6958.165020197406, 6969.30306969299, 6445.12990746262, 6555.704993275219, 6692.096566963398, 6725.024871393718, 6746.9662651688395, 6093.725782339894, 6476.287608363171, 6505.0567667688865, 6532.477401807939, 6601.207855057424, 6569.9343006569325, 6594.670266486561, 6636.900446493403, 6645.3022432643875, 6647.401733843977, 5977.7310021050025, 6013.128096425906, 6041.456268935817, 6082.817515474543, 6112.816615501542, 6026.246719160105, 6065.4541091302, 6090.908636370369, 6091.360407157184, 6101.908471373022], "unit": "score", "name": "mootools.html", "value": 4083.385554062638}, {"lowerIsBetter": false, "replicates": [294.6375957572186, 304.9512078067509, 307.0665177859308, 310.448953108233, 312.1727422330823, 5414.2420061191415, 5517.227588137659, 5546.91679624805, 5573.916391254134, 5680.375158732536, 5502.422245664207, 5517.227588137654, 5540.695261760601, 5541.778328866841, 5561.193626924093, 4253.744775313491, 4253.8723838284905, 4257.638100761439, 4266.802596662721, 4270.551592082841, 4251.59609837065, 4260.744355338674, 4264.957350426503, 4268.850590229343, 4282.550332215119, 4085.2850751118585, 4198.769067701275, 4223.13425747721, 4224.176285624305, 4229.6404805591565, 4176.60322269384, 4198.706090573661, 4200.810963506634, 4201.873943781691, 4210.221109094797, 3642.8907132786057, 3645.6718895299286, 3646.6717995380545, 3653.4337177737493, 3655.4334078217653, 5317.8404647860625, 5328.973355133238, 5329.680219186861, 5333.786648534054, 5338.813141540028, 4631.490536041033, 4647.3958385410015, 4839.637027222972, 4843.29772183035, 4849.9757501212625, 5677.233573467571, 5720.1991721159075, 5735.397783232742, 5751.194832723422, 5762.97118514409, 3882.3594106972314, 3888.2029184017492, 3892.9026774330587, 3893.9610603894166, 3897.7856217908, 5996.970015149896, 6069.089636554552, 6087.634741915499, 6088.512918966472, 6098.298695650013, 3905.9804700976315, 3950.4074388841905, 3959.9604003960167, 4067.5525692173837, 4082.6937979651643, 4133.256013917464, 4137.54487006429, 4138.151678905817, 4140.420341152241, 4144.979275103605, 4777.402824646919, 4779.354787103766, 4781.497942716034, 4792.472827988919, 4800.975995120072, 5697.971510142505, 5726.713664316851, 5734.111212762028, 5743.368229494752, 5762.827115186551, 1429.1282317786142, 1444.8482909294582, 1459.88320934325, 1462.627030107316, 1468.2585294426246, 954.7994921066515, 961.8845738511284, 970.5875003123645, 980.887197972228, 982.258394911837, 5063.594912406998, 5064.442911279756, 5073.720945347967, 5074.568661663726, 5081.4918508148885, 6004.099385092271, 6013.037913933749, 6013.579049466583, 6013.939860601338, 6025.879482410415, 5054.848354549369, 5055.0, 5055.443901170868, 5065.240213967934, 5074.517920797518, 3185.761067919915, 3236.336551007038, 3259.1363288728508, 3260.3153337798853, 3264.3308121834966, 5657.094864821609, 5658.519025882846, 5661.716914154275, 5666.688332141689, 5687.601867869293, 6127.632342059402, 6153.476954458921, 6170.321264660884, 6185.381461853779, 6190.783322583688, 5418.078926582491, 5512.779488820483, 5611.831645050655, 5672.602917795797, 5685.886282274331, 6062.302835174011, 6074.301455332693, 6078.908816367846, 6079.604825686316, 6093.177421048279, 5882.7058647066615, 5914.408559144051, 5916.585838991229, 5931.406859314034, 5931.673757943355, 5616.38219795814, 5712.6286791358425, 5713.914291285717, 5722.055860782924, 5731.971340143439, 4133.937990930199, 4136.565660605714, 4140.296149654626, 4141.958580414157, 4147.502299724052, 6012.789552365821, 6041.577089603686, 6055.818325450244, 6056.333803281724, 6057.42454466825, 5797.304323481209, 5798.739056742373, 5808.809194115281, 5812.244408226903, 5820.883582328414, 5685.71571421419, 5813.157092221689, 5813.79651712188, 5825.155352473952, 5832.883342333214, 5691.402402747817, 5691.971540142273, 5696.003199440164, 5708.286464191976, 5714.514266287412, 5854.677992710272, 5857.062869940789, 5857.179994800646, 5864.530837532901, 5879.059350503899, 5616.859578510406, 5720.685362305113, 5722.484976352148, 5723.112917497794, 5724.427557244242], "unit": "score", "name": "prototype.html", "value": 4323.05045693688}, {"lowerIsBetter": false, "replicates": [633.7021599848069, 736.374082030274, 738.697134174988, 743.3421422041492, 746.2276543777192, 10919.945400272998, 11120.388378639173, 11149.108071354292, 11175.608853690102, 11193.16051296152, 11124.221304508688, 11132.61035863745, 11141.997220250176, 11143.387113708743, 11147.386893720843, 612.5099920063954, 627.0625415004574, 772.0619447371442, 779.0456690554068, 784.047382430347, 786.5791801386231, 790.1505881177757, 795.0935933036315, 795.4908858330673, 799.3684988858795, 828.8673812190051, 834.6077343648506, 836.0385556609887, 837.4347315561971, 839.6515446089866, 815.4577206157898, 821.2116368286452, 823.2056065896438, 824.9505029698178, 826.2068414322258, 616.2881871438451, 618.7710547097557, 618.9969050154765, 619.0497586205198, 619.065211530592, 10602.098821600173, 10662.840057399146, 10712.625058122929, 10717.303375280582, 10723.0, 10419.322744021614, 11049.834252486218, 11063.668089957315, 11086.0, 11112.722181945435, 9249.953750231205, 9273.536323183815, 9525.714228573153, 9530.237580993504, 9531.428114313163, 3173.6350319713297, 3230.7415406767404, 3236.5112048457736, 3237.821919794434, 3246.4481038223557, 37136.62863371359, 37274.25451490982, 37658.81170594157, 37672.43491347632, 37707.81146094252, 8699.086595907338, 8893.0, 9170.633174673072, 9275.443473391619, 9293.210077143518, 16694.58263543421, 16708.582285442717, 16752.49742507727, 16769.496915092568, 16807.747883781754, 5779.971100144472, 5883.588148829541, 5920.822375328747, 5977.880442391128, 6013.218281623361, 3379.4930760385646, 3430.5368775215525, 3501.509788629594, 3520.6303338149637, 3554.235839294564, 2492.4641202141624, 2523.507915956371, 2524.2301098164867, 2529.7730600658665, 2535.4802265535523, 433.5361163555029, 437.1366551061628, 439.0757455556072, 440.1483130143183, 445.72365133616717, 462.4635422909476, 480.61550759392344, 515.6931625682713, 518.9273501709764, 570.9086546152596, 6851.588904665736, 6912.308769123048, 7006.124234470692, 7167.605781681953, 7344.853102937911, 491.7664109547936, 493.6199126672442, 507.0138580461042, 532.6564365983919, 534.767376191358, 3330.9500357494658, 3515.54297941266, 3530.417481115587, 3553.1650062235353, 3577.5170352002147, 47740.5225947743, 47750.28374574384, 48237.517624824, 48354.75822620935, 48495.0, 43937.0, 44023.55976440194, 44119.0, 44530.55469445329, 44719.776401117786, 35141.12147196341, 35549.64450355515, 35724.464133038025, 36005.819970899975, 40576.59423405728, 7002.684879180347, 7180.425565954815, 7198.89201661965, 7289.708411663474, 7345.77962661121, 30312.242193944447, 30387.848060759556, 30541.02966614326, 30819.229519262197, 30897.227569310948, 33966.66033339635, 34607.653923461454, 34863.47704784481, 34890.47664284987, 35283.0, 9740.172085372822, 9784.0, 9873.654422095193, 9918.752031199278, 9995.850062248926, 10214.0, 10640.255182137176, 10781.191410644076, 11089.667309980714, 11411.543538258376, 9627.229821614394, 9705.466199359103, 9836.114749672564, 9889.25830562725, 10399.94800025995, 9943.651972180938, 10027.04743049409, 10091.495425228562, 10228.64199753005, 10253.846192306972, 9940.353876997971, 10188.490575471049, 10373.896261037293, 10390.740231494274, 10683.198760092868, 8185.0, 8226.753197404087, 8287.709930152416, 8573.828523429373, 8835.779105522157, 8057.234562716532, 8399.580020998803, 8482.745517634481, 8553.700620478254, 8695.478271303742], "unit": "score", "name": "yui.html", "value": 5438.838162850126}], "name": "dromaeo_css", "value": 6191.570480618729}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [116.0, 109.0, 112.0, 115.0, 113.0, 123.0, 113.0, 113.0, 113.0, 114.0], "unit": "score", "name": "ai-astar", "value": 114.1}, {"lowerIsBetter": true, "replicates": [161.0, 133.0, 153.0, 171.0, 132.0, 160.0, 152.0, 142.0, 168.0, 135.0], "unit": "score", "name": "audio-beat-detection", "value": 150.7}, {"lowerIsBetter": true, "replicates": [163.0, 162.0, 162.0, 162.0, 163.0, 163.0, 165.0, 162.0, 163.0, 162.0], "unit": "score", "name": "audio-dft", "value": 162.7}, {"lowerIsBetter": true, "replicates": [73.0, 72.0, 73.0, 76.0, 75.0, 73.0, 140.0, 73.0, 71.0, 71.0], "unit": "score", "name": "audio-fft", "value": 79.7}, {"lowerIsBetter": true, "replicates": [94.0, 94.0, 145.0, 94.0, 94.0, 95.0, 128.0, 94.0, 95.0, 126.0], "unit": "score", "name": "audio-oscillator", "value": 105.9}, {"lowerIsBetter": true, "replicates": [110.0, 111.0, 110.0, 110.0, 110.0, 110.0, 110.0, 110.0, 110.0, 111.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 110.2}, {"lowerIsBetter": true, "replicates": [147.0, 148.0, 147.0, 148.0, 148.0, 148.0, 148.0, 147.0, 148.0, 149.0], "unit": "score", "name": "imaging-darkroom", "value": 147.8}, {"lowerIsBetter": true, "replicates": [95.0, 97.0, 97.0, 95.0, 96.0, 97.0, 96.0, 97.0, 96.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 96.2}, {"lowerIsBetter": true, "replicates": [72.0, 74.0, 71.0, 73.0, 73.0, 73.0, 74.0, 72.0, 71.0, 74.0], "unit": "score", "name": "json-parse-financial", "value": 72.7}, {"lowerIsBetter": true, "replicates": [68.0, 69.0, 67.0, 68.0, 68.0, 68.0, 67.0, 68.0, 69.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 68.0}, {"lowerIsBetter": true, "replicates": [82.0, 82.0, 85.0, 84.0, 85.0, 84.0, 83.0, 83.0, 83.0, 84.0], "unit": "score", "name": "stanford-crypto-aes", "value": 83.5}, {"lowerIsBetter": true, "replicates": [130.0, 118.0, 122.0, 125.0, 129.0, 133.0, 127.0, 120.0, 141.0, 131.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 127.6}, {"lowerIsBetter": true, "replicates": [137.0, 152.0, 159.0, 148.0, 158.0, 136.0, 147.0, 144.0, 153.0, 142.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 147.6}, {"lowerIsBetter": true, "replicates": [71.0, 70.0, 70.0, 72.0, 69.0, 70.0, 70.0, 70.0, 70.0, 76.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 70.8}], "name": "kraken", "value": 1537.4999999999998}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.11295681063123], "unit": "score", "name": "Richards", "value": 212.51804000000004}, {"lowerIsBetter": false, "replicates": [191.46084625694044], "unit": "score", "name": "DeltaBlue", "value": 345.33431399999995}, {"lowerIsBetter": false, "replicates": [278.08676307007784], "unit": "score", "name": "Encrypt", "value": 957.1868759999999}, {"lowerIsBetter": false, "replicates": [5138.461538461538], "unit": "score", "name": "Decrypt", "value": 51.80169161676644}, {"lowerIsBetter": false, "replicates": [1069.51871657754], "unit": "score", "name": "RayTrace", "value": 691.889715}, {"lowerIsBetter": false, "replicates": [700.770847932726], "unit": "score", "name": "Earley", "value": 951.0427009999997}, {"lowerIsBetter": false, "replicates": [12500.0], "unit": "score", "name": "Boyer", "value": 53.31704}, {"lowerIsBetter": false, "replicates": [26179.48717948718], "unit": "score", "name": "RegExp", "value": 34.79766405484819}, {"lowerIsBetter": false, "replicates": [657.030223390276], "unit": "score", "name": "Splay", "value": 124.02930199999997}, {"lowerIsBetter": false, "replicates": [4840.579710144928], "unit": "score", "name": "NavierStokes", "value": 306.5748502994012}], "name": "v8_7", "value": 21113.396551484668}]} 18:13:44 INFO - RETURN: dromaeo_css: 6191.57 18:13:44 INFO - RETURN: kraken: 1537.50 18:13:44 INFO - RETURN: v8_7: 21113.40 18:13:44 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,132,43]]", "result": "21113.40"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,132,43]]", "result": "1537.50"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,132,43]]", "result": "6191.57"}}} 18:13:44 INFO - Return code: 0 18:13:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:13:44 INFO - # TBPL SUCCESS # 18:13:44 INFO - Running post-action listener: _resource_record_post_action 18:13:44 INFO - Running post-run listener: _resource_record_post_run 18:13:45 INFO - Total resource usage - Wall time: 1236s; CPU: 13.0%; Read bytes: 10571776; Write bytes: 447565824; Read time: 2900; Write time: 358032 18:13:45 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 8192; Write bytes: 84783104; Read time: 36; Write time: 54784 18:13:46 INFO - run-tests - Wall time: 1221s; CPU: 13.0%; Read bytes: 8802304; Write bytes: 362782720; Read time: 2816; Write time: 303248 18:13:46 INFO - Running post-run listener: _upload_blobber_files 18:13:46 INFO - Blob upload gear active. 18:13:46 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 18:13:46 INFO - Copying logs to upload dir... 18:13:46 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1279.777881 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2015-12-17 18:13:45.981757) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-17 18:13:45.984857) ========= 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=fce1c2174958ee92775f70ae000001a3-1450396466.764017-455142318 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/KTJF1JIjRdC93-LnnksKRQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009967 build_url: 'https://queue.taskcluster.net/v1/task/KTJF1JIjRdC93-LnnksKRQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-17 18:13:46.035586) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 18:13:46.035934) ========= 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=fce1c2174958ee92775f70ae000001a3-1450396466.764017-455142318 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004388 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 18:13:46.085481) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-17 18:13:46.085858) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-17 18:13:46.086185) ========= ========= Total master_lag: 0.27 =========