builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-016 starttime: 1450472084.38 results: success (0) buildid: 20151218113634 builduid: 95962a22bf624adb9b7f6d362b3c41ee revision: f696bbc40e1047a95af58c0280fdd02faf370565 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-18 12:54:44.376867) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-18 12:54:44.377366) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-18 12:54:44.377683) ========= 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=d6a7e047ef025f707024e163000001c3-1450472083.740019-905888863 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.006323 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-18 12:54:44.400602) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-18 12:54:44.400904) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-18 12:54:44.414606) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 12:54:44.414961) ========= 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=d6a7e047ef025f707024e163000001c3-1450472083.740019-905888863 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004554 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 12:54:44.464030) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-18 12:54:44.464336) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-18 12:54:44.464728) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 12:54:44.465032) ========= 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=d6a7e047ef025f707024e163000001c3-1450472083.740019-905888863 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-18 12:54:44-- 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% 219M=0s 2015-12-18 12:54:44 (219 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.222019 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 12:54:44.699689) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 12:54:44.700044) ========= 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=d6a7e047ef025f707024e163000001c3-1450472083.740019-905888863 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016725 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 12:54:44.749833) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-12-18 12:54:44.750189) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev f696bbc40e1047a95af58c0280fdd02faf370565 --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 f696bbc40e1047a95af58c0280fdd02faf370565 --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=d6a7e047ef025f707024e163000001c3-1450472083.740019-905888863 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-18 12:54:44,824 truncating revision to first 12 chars 2015-12-18 12:54:44,824 Setting DEBUG logging. 2015-12-18 12:54:44,824 attempt 1/10 2015-12-18 12:54:44,824 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/f696bbc40e10?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-18 12:54:45,574 unpacking tar archive at: fx-team-f696bbc40e10/testing/mozharness/ program finished with exit code 0 elapsedTime=1.368037 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-12-18 12:54:46.131158) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-18 12:54:46.131476) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-18 12:54:46.139645) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-18 12:54:46.139897) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-18 12:54:46.140297) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2015-12-18 12:54:46.140659) ========= /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=d6a7e047ef025f707024e163000001c3-1450472083.740019-905888863 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:54:46 INFO - MultiFileLogger online at 20151218 12:54:46 in /builds/slave/test 12:54:46 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 12:54:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:54:46 INFO - {'append_to_log': False, 12:54:46 INFO - 'base_work_dir': '/builds/slave/test', 12:54:46 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 12:54:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:54:46 INFO - 'branch': 'Fx-Team-Non-PGO', 12:54:46 INFO - 'buildbot_json_path': 'buildprops.json', 12:54:46 INFO - 'config_files': ('talos/linux_config.py',), 12:54:46 INFO - 'default_actions': ('clobber', 12:54:46 INFO - 'read-buildbot-config', 12:54:46 INFO - 'download-and-extract', 12:54:46 INFO - 'populate-webroot', 12:54:46 INFO - 'create-virtualenv', 12:54:46 INFO - 'install', 12:54:46 INFO - 'run-tests'), 12:54:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:54:46 INFO - 'download_minidump_stackwalk': True, 12:54:46 INFO - 'download_symbols': 'ondemand', 12:54:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:54:46 INFO - 'tooltool.py': '/tools/tooltool.py', 12:54:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:54:46 INFO - '/tools/misc-python/virtualenv.py')}, 12:54:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:54:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:54:46 INFO - 'installer_path': 'installer.exe', 12:54:46 INFO - 'log_level': 'info', 12:54:46 INFO - 'log_name': 'talos', 12:54:46 INFO - 'log_to_console': True, 12:54:46 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:54:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:54:46 INFO - 'opt_config_files': (), 12:54:46 INFO - 'pip_index': False, 12:54:46 INFO - 'sps_profile': False, 12:54:46 INFO - 'sps_profile_interval': 0, 12:54:46 INFO - 'suite': 'dromaeojs-e10s', 12:54:46 INFO - 'system_bits': '64', 12:54:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:54:46 INFO - 'title': 'talos-linux64-ix-016', 12:54:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:54:46 INFO - 'use_talos_json': True, 12:54:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:54:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:54:46 INFO - 'work_dir': 'build'} 12:54:46 INFO - ##### 12:54:46 INFO - ##### Running clobber step. 12:54:46 INFO - ##### 12:54:46 INFO - Running pre-action listener: _resource_record_pre_action 12:54:46 INFO - Running main action method: clobber 12:54:46 INFO - rmtree: /builds/slave/test/build 12:54:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:54:47 INFO - Running post-action listener: _resource_record_post_action 12:54:47 INFO - ##### 12:54:47 INFO - ##### Running read-buildbot-config step. 12:54:47 INFO - ##### 12:54:47 INFO - Running pre-action listener: _resource_record_pre_action 12:54:47 INFO - Running main action method: read_buildbot_config 12:54:47 INFO - Using buildbot properties: 12:54:47 INFO - { 12:54:47 INFO - "project": "", 12:54:47 INFO - "product": "firefox", 12:54:47 INFO - "script_repo_revision": "production", 12:54:47 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 12:54:47 INFO - "repository": "", 12:54:47 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs-e10s", 12:54:47 INFO - "buildid": "20151218113634", 12:54:47 INFO - "pgo_build": "False", 12:54:47 INFO - "basedir": "/builds/slave/test", 12:54:47 INFO - "buildnumber": 829, 12:54:47 INFO - "slavename": "talos-linux64-ix-016", 12:54:47 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 12:54:47 INFO - "platform": "ubuntu64_hw", 12:54:47 INFO - "scheduler": "tests-fx-team-linux64-talos", 12:54:47 INFO - "branch": "fx-team", 12:54:47 INFO - "repo_path": "integration/fx-team", 12:54:47 INFO - "revision": "f696bbc40e1047a95af58c0280fdd02faf370565", 12:54:47 INFO - "stage_platform": "linux64", 12:54:47 INFO - "builduid": "95962a22bf624adb9b7f6d362b3c41ee", 12:54:47 INFO - "slavebuilddir": "test" 12:54:47 INFO - } 12:54:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/dZqfn1r_TIiKsZVfqrCifw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 12:54:47 INFO - Running post-action listener: _resource_record_post_action 12:54:47 INFO - ##### 12:54:47 INFO - ##### Running download-and-extract step. 12:54:47 INFO - ##### 12:54:47 INFO - Running pre-action listener: _resource_record_pre_action 12:54:47 INFO - Running main action method: download_and_extract 12:54:47 INFO - mkdir: /builds/slave/test/build/tests 12:54: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')]} 12:54:47 INFO - https://queue.taskcluster.net/v1/task/dZqfn1r_TIiKsZVfqrCifw/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 12:54:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dZqfn1r_TIiKsZVfqrCifw/artifacts/public/build/test_packages.json 12:54:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dZqfn1r_TIiKsZVfqrCifw/artifacts/public/build/test_packages.json 12:54:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dZqfn1r_TIiKsZVfqrCifw/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 12:54:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dZqfn1r_TIiKsZVfqrCifw/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 12:54:48 INFO - Downloaded 1302 bytes. 12:54:48 INFO - Reading from file /builds/slave/test/build/test_packages.json 12:54:48 INFO - Using the following test package requirements: 12:54:48 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 12:54:48 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 12:54:48 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 12:54:48 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 12:54:48 INFO - u'jsshell-linux-x86_64.zip'], 12:54:48 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 12:54:48 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 12:54:48 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 12:54:48 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 12:54:48 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 12:54:48 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 12:54:48 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 12:54:48 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 12:54:48 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 12:54:48 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 12:54:48 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 12:54:48 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 12:54:48 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:54:48 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')]} 12:54:48 INFO - https://queue.taskcluster.net/v1/task/dZqfn1r_TIiKsZVfqrCifw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:54:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dZqfn1r_TIiKsZVfqrCifw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 12:54:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dZqfn1r_TIiKsZVfqrCifw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 12:54:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dZqfn1r_TIiKsZVfqrCifw/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 12:54:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dZqfn1r_TIiKsZVfqrCifw/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 12:54:50 INFO - Downloaded 21872076 bytes. 12:54:50 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 12:54:50 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 12:54:50 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 12:54:51 INFO - Return code: 0 12:54:51 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 12:54: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')]} 12:54:51 INFO - https://queue.taskcluster.net/v1/task/dZqfn1r_TIiKsZVfqrCifw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:54:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dZqfn1r_TIiKsZVfqrCifw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 12:54:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dZqfn1r_TIiKsZVfqrCifw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 12:54:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dZqfn1r_TIiKsZVfqrCifw/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 12:54:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dZqfn1r_TIiKsZVfqrCifw/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 12:54:55 INFO - Downloaded 21872076 bytes. 12:54:55 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 12:54:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 12:54:55 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 12:54:56 INFO - Return code: 0 12:54: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')]} 12:54:56 INFO - https://queue.taskcluster.net/v1/task/dZqfn1r_TIiKsZVfqrCifw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 12:54:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dZqfn1r_TIiKsZVfqrCifw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 12:54:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dZqfn1r_TIiKsZVfqrCifw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 12:54:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dZqfn1r_TIiKsZVfqrCifw/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 12:54:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dZqfn1r_TIiKsZVfqrCifw/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 12:54:59 INFO - Downloaded 11132039 bytes. 12:54:59 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 12:54:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 12:54:59 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 12:54:59 INFO - Return code: 0 12:54: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')]} 12:54:59 INFO - https://queue.taskcluster.net/v1/task/dZqfn1r_TIiKsZVfqrCifw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 12:54:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dZqfn1r_TIiKsZVfqrCifw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 12:54:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dZqfn1r_TIiKsZVfqrCifw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 12:54:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dZqfn1r_TIiKsZVfqrCifw/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 12:54:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dZqfn1r_TIiKsZVfqrCifw/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 12:55:02 INFO - Downloaded 53019425 bytes. 12:55:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dZqfn1r_TIiKsZVfqrCifw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 12:55:02 INFO - mkdir: /builds/slave/test/properties 12:55:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:55:02 INFO - Writing to file /builds/slave/test/properties/build_url 12:55:02 INFO - Contents: 12:55:02 INFO - build_url:https://queue.taskcluster.net/v1/task/dZqfn1r_TIiKsZVfqrCifw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 12:55:02 INFO - Running post-action listener: _resource_record_post_action 12:55:02 INFO - Running post-action listener: set_extra_try_arguments 12:55:02 INFO - ##### 12:55:02 INFO - ##### Running populate-webroot step. 12:55:02 INFO - ##### 12:55:02 INFO - Running pre-action listener: _resource_record_pre_action 12:55:02 INFO - Running main action method: populate_webroot 12:55:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:55:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:55:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:55:02 INFO - '--tppagecycles', 12:55:02 INFO - '7'], 12:55:02 INFO - 'tests': ['tsvgm']}}, 12:55:02 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 12:55:02 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 12:55:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:55:02 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 12:55:02 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 12:55:02 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 12:55:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:55:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:55:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:55:02 INFO - 'talos_options': ['--e10s'], 12:55:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:55:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:55:02 INFO - 'tests': ['damp', 'tps']}, 12:55:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:55:02 INFO - 'talos_options': ['--e10s'], 12:55:02 INFO - 'tests': ['damp', 'tps']}, 12:55:02 INFO - 'g3': {'tests': ['dromaeo_dom']}, 12:55:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:55:02 INFO - 'other': {'tests': ['a11yr', 12:55:02 INFO - 'ts_paint', 12:55:02 INFO - 'tpaint', 12:55:02 INFO - 'sessionrestore', 12:55:02 INFO - 'sessionrestore_no_auto_restore']}, 12:55:02 INFO - 'other-e10s': {'talos_options': ['--e10s'], 12:55:02 INFO - 'tests': ['a11yr', 12:55:02 INFO - 'ts_paint', 12:55:02 INFO - 'tpaint', 12:55:02 INFO - 'sessionrestore', 12:55:02 INFO - 'sessionrestore_no_auto_restore']}, 12:55:02 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 12:55:02 INFO - 'tests': ['a11yr', 12:55:02 INFO - 'ts_paint', 12:55:02 INFO - 'tpaint', 12:55:02 INFO - 'sessionrestore', 12:55:02 INFO - 'sessionrestore_no_auto_restore']}, 12:55:02 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 12:55:02 INFO - 'tests': ['a11yr', 12:55:02 INFO - 'ts_paint', 12:55:02 INFO - 'tpaint', 12:55:02 INFO - 'sessionrestore', 12:55:02 INFO - 'sessionrestore_no_auto_restore']}, 12:55:02 INFO - 'other_l64': {'tests': ['a11yr', 12:55:02 INFO - 'ts_paint', 12:55:02 INFO - 'tpaint', 12:55:02 INFO - 'sessionrestore', 12:55:02 INFO - 'sessionrestore_no_auto_restore']}, 12:55:02 INFO - 'other_nol64': {'tests': ['a11yr', 12:55:02 INFO - 'ts_paint', 12:55:02 INFO - 'tpaint', 12:55:02 INFO - 'sessionrestore', 12:55:02 INFO - 'sessionrestore_no_auto_restore']}, 12:55:02 INFO - 'svgr': {'tests': ['tsvgx', 12:55:02 INFO - 'tsvgr_opacity', 12:55:02 INFO - 'tart', 12:55:02 INFO - 'tscrollx', 12:55:02 INFO - 'cart']}, 12:55:02 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 12:55:02 INFO - 'tests': ['tsvgx', 12:55:02 INFO - 'tsvgr_opacity', 12:55:02 INFO - 'tart', 12:55:02 INFO - 'tscrollx', 12:55:02 INFO - 'cart']}, 12:55:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:55:02 INFO - 'tests': ['tp5o']}, 12:55:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:55:02 INFO - 'talos_options': ['--e10s'], 12:55:02 INFO - 'tests': ['tp5o']}, 12:55:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:55:02 INFO - 'talos_options': ['--xperf_path', 12:55:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:55:02 INFO - 'tests': ['tp5n']}, 12:55:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:55:02 INFO - 'talos_options': ['--e10s', 12:55:02 INFO - '--xperf_path', 12:55:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:55:02 INFO - 'tests': ['tp5n']}}, 12:55:02 INFO - 'talos.zip': {'path': '', 12:55:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:55:02 INFO - Running post-action listener: _resource_record_post_action 12:55:02 INFO - ##### 12:55:02 INFO - ##### Running create-virtualenv step. 12:55:02 INFO - ##### 12:55:02 INFO - Running pre-action listener: _resource_record_pre_action 12:55:02 INFO - Running main action method: create_virtualenv 12:55:02 INFO - Creating virtualenv /builds/slave/test/build/venv 12:55:02 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 12:55:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:55:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:55:03 INFO - Using real prefix '/usr' 12:55:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:55:03 INFO - Installing distribute.............................................................................................................................................................................................done. 12:55:05 INFO - Installing pip.................done. 12:55:05 INFO - Return code: 0 12:55:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:55: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')]} 12:55:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:55:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55: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')]} 12:55:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:55:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4808faf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18aa240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19390b0>, '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 0x1939fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x193a440>, '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': 'd6a7e047ef025f707024e163000001c3-1450472083.740019-905888863', '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 12:55: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', 'pip>=1.5'] in /builds/slave/test/build 12:55: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 pip>=1.5 12:55:05 INFO - Using env: {'DISPLAY': ':0', 12:55:05 INFO - 'HOME': '/home/cltbld', 12:55:05 INFO - 'LANG': 'en_US.UTF-8', 12:55:05 INFO - 'LANGUAGE': 'en_US:en', 12:55:05 INFO - 'LOGNAME': 'cltbld', 12:55:05 INFO - 'MAIL': '/var/mail/cltbld', 12:55:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:55:05 INFO - 'MOZ_NO_REMOTE': '1', 12:55:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:55:05 INFO - 'NO_EM_RESTART': '1', 12:55:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:55:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:55:05 INFO - 'PWD': '/builds/slave/test', 12:55:05 INFO - 'SHELL': '/bin/bash', 12:55:05 INFO - 'SHLVL': '1', 12:55:05 INFO - 'TERM': 'linux', 12:55:05 INFO - 'TMOUT': '86400', 12:55:05 INFO - 'USER': 'cltbld', 12:55:05 INFO - 'XDG_SESSION_COOKIE': 'd6a7e047ef025f707024e163000001c3-1450472083.740019-905888863', 12:55:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:55:05 INFO - '_': '/tools/buildbot/bin/python'} 12:55:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:55:05 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 12:55:05 INFO - Cleaning up... 12:55:05 INFO - Return code: 0 12:55:05 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 12:55: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')]} 12:55:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:55:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55: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')]} 12:55:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:55:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55: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', '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 0x7fe4808faf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18aa240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19390b0>, '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 0x1939fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x193a440>, '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': 'd6a7e047ef025f707024e163000001c3-1450472083.740019-905888863', '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 12:55: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', 'psutil>=0.7.1'] in /builds/slave/test/build 12:55: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 psutil>=0.7.1 12:55:05 INFO - Using env: {'DISPLAY': ':0', 12:55:05 INFO - 'HOME': '/home/cltbld', 12:55:05 INFO - 'LANG': 'en_US.UTF-8', 12:55:05 INFO - 'LANGUAGE': 'en_US:en', 12:55:05 INFO - 'LOGNAME': 'cltbld', 12:55:05 INFO - 'MAIL': '/var/mail/cltbld', 12:55:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:55:05 INFO - 'MOZ_NO_REMOTE': '1', 12:55:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:55:05 INFO - 'NO_EM_RESTART': '1', 12:55:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:55:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:55:05 INFO - 'PWD': '/builds/slave/test', 12:55:05 INFO - 'SHELL': '/bin/bash', 12:55:05 INFO - 'SHLVL': '1', 12:55:05 INFO - 'TERM': 'linux', 12:55:05 INFO - 'TMOUT': '86400', 12:55:05 INFO - 'USER': 'cltbld', 12:55:05 INFO - 'XDG_SESSION_COOKIE': 'd6a7e047ef025f707024e163000001c3-1450472083.740019-905888863', 12:55:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:55:05 INFO - '_': '/tools/buildbot/bin/python'} 12:55:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:55:05 INFO - Downloading/unpacking psutil>=0.7.1 12:55: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 12:55: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 12:55: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 12:55: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 12:55: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 12:55: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 12:55:07 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 12:55:07 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 12:55:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:55:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:55:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:55:07 INFO - Installing collected packages: psutil 12:55:07 INFO - Running setup.py install for psutil 12:55:07 INFO - building 'psutil._psutil_linux' extension 12:55:07 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 12:55:07 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 12:55:07 INFO - building 'psutil._psutil_posix' extension 12:55:07 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 12:55:08 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 12:55:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:55:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:55:08 INFO - Successfully installed psutil 12:55:08 INFO - Cleaning up... 12:55:08 INFO - Return code: 0 12:55:08 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 12:55:08 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')]} 12:55:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:55:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:08 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')]} 12:55:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:55:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:08 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 0x7fe4808faf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18aa240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19390b0>, '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 0x1939fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x193a440>, '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': 'd6a7e047ef025f707024e163000001c3-1450472083.740019-905888863', '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 12:55:08 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 12:55:08 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 12:55:08 INFO - Using env: {'DISPLAY': ':0', 12:55:08 INFO - 'HOME': '/home/cltbld', 12:55:08 INFO - 'LANG': 'en_US.UTF-8', 12:55:08 INFO - 'LANGUAGE': 'en_US:en', 12:55:08 INFO - 'LOGNAME': 'cltbld', 12:55:08 INFO - 'MAIL': '/var/mail/cltbld', 12:55:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:55:08 INFO - 'MOZ_NO_REMOTE': '1', 12:55:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:55:08 INFO - 'NO_EM_RESTART': '1', 12:55:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:55:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:55:08 INFO - 'PWD': '/builds/slave/test', 12:55:08 INFO - 'SHELL': '/bin/bash', 12:55:08 INFO - 'SHLVL': '1', 12:55:08 INFO - 'TERM': 'linux', 12:55:08 INFO - 'TMOUT': '86400', 12:55:08 INFO - 'USER': 'cltbld', 12:55:08 INFO - 'XDG_SESSION_COOKIE': 'd6a7e047ef025f707024e163000001c3-1450472083.740019-905888863', 12:55:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:55:08 INFO - '_': '/tools/buildbot/bin/python'} 12:55:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:55:08 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 12:55: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 12:55: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 12:55: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 12:55: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 12:55: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 12:55: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 12:55:10 INFO - Downloading mozsystemmonitor-0.0.tar.gz 12:55:10 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 12:55:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:55:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 12:55:10 INFO - Installing collected packages: mozsystemmonitor 12:55:10 INFO - Running setup.py install for mozsystemmonitor 12:55:10 INFO - Successfully installed mozsystemmonitor 12:55:10 INFO - Cleaning up... 12:55:10 INFO - Return code: 0 12:55:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:55:10 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')]} 12:55:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:55:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:10 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')]} 12:55:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:55:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:10 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 0x7fe4808faf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18aa240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19390b0>, '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 0x1939fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x193a440>, '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': 'd6a7e047ef025f707024e163000001c3-1450472083.740019-905888863', '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 12:55:10 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 12:55:10 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 12:55:10 INFO - Using env: {'DISPLAY': ':0', 12:55:10 INFO - 'HOME': '/home/cltbld', 12:55:10 INFO - 'LANG': 'en_US.UTF-8', 12:55:10 INFO - 'LANGUAGE': 'en_US:en', 12:55:10 INFO - 'LOGNAME': 'cltbld', 12:55:10 INFO - 'MAIL': '/var/mail/cltbld', 12:55:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:55:10 INFO - 'MOZ_NO_REMOTE': '1', 12:55:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:55:10 INFO - 'NO_EM_RESTART': '1', 12:55:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:55:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:55:10 INFO - 'PWD': '/builds/slave/test', 12:55:10 INFO - 'SHELL': '/bin/bash', 12:55:10 INFO - 'SHLVL': '1', 12:55:10 INFO - 'TERM': 'linux', 12:55:10 INFO - 'TMOUT': '86400', 12:55:10 INFO - 'USER': 'cltbld', 12:55:10 INFO - 'XDG_SESSION_COOKIE': 'd6a7e047ef025f707024e163000001c3-1450472083.740019-905888863', 12:55:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:55:10 INFO - '_': '/tools/buildbot/bin/python'} 12:55:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:55:10 INFO - Downloading/unpacking blobuploader==1.2.4 12:55:10 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 12:55:10 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 12:55:10 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 12:55:10 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 12:55:10 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 12:55:10 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 12:55:12 INFO - Downloading blobuploader-1.2.4.tar.gz 12:55:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 12:55:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:55:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:55:12 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 12:55:12 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 12:55:12 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 12:55:12 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 12:55:12 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 12:55:12 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 12:55:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 12:55:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:55:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:55:12 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 12:55:12 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 12:55:12 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 12:55:12 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 12:55:12 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 12:55:12 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 12:55:12 INFO - Downloading docopt-0.6.1.tar.gz 12:55:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 12:55:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:55:12 INFO - Installing collected packages: blobuploader, requests, docopt 12:55:12 INFO - Running setup.py install for blobuploader 12:55:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:55:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:55:13 INFO - Running setup.py install for requests 12:55:13 INFO - Running setup.py install for docopt 12:55:13 INFO - Successfully installed blobuploader requests docopt 12:55:13 INFO - Cleaning up... 12:55:13 INFO - Return code: 0 12:55:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:55:13 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')]} 12:55:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:55:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:13 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')]} 12:55:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:55:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:13 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 0x7fe4808faf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18aa240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19390b0>, '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 0x1939fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x193a440>, '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': 'd6a7e047ef025f707024e163000001c3-1450472083.740019-905888863', '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 12:55:13 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 12:55:13 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 12:55:13 INFO - Using env: {'DISPLAY': ':0', 12:55:13 INFO - 'HOME': '/home/cltbld', 12:55:13 INFO - 'LANG': 'en_US.UTF-8', 12:55:13 INFO - 'LANGUAGE': 'en_US:en', 12:55:13 INFO - 'LOGNAME': 'cltbld', 12:55:13 INFO - 'MAIL': '/var/mail/cltbld', 12:55:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:55:13 INFO - 'MOZ_NO_REMOTE': '1', 12:55:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:55:13 INFO - 'NO_EM_RESTART': '1', 12:55:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:55:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:55:13 INFO - 'PWD': '/builds/slave/test', 12:55:13 INFO - 'SHELL': '/bin/bash', 12:55:13 INFO - 'SHLVL': '1', 12:55:13 INFO - 'TERM': 'linux', 12:55:13 INFO - 'TMOUT': '86400', 12:55:13 INFO - 'USER': 'cltbld', 12:55:13 INFO - 'XDG_SESSION_COOKIE': 'd6a7e047ef025f707024e163000001c3-1450472083.740019-905888863', 12:55:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:55:13 INFO - '_': '/tools/buildbot/bin/python'} 12:55:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:55:13 INFO - Running setup.py (path:/tmp/pip-roJ0fX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:55:13 INFO - Running setup.py (path:/tmp/pip-9Ktny0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:55:13 INFO - Running setup.py (path:/tmp/pip-04zkPG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:55:13 INFO - Running setup.py (path:/tmp/pip-uu3x4g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:55:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:55:14 INFO - Running setup.py (path:/tmp/pip-YJaLZr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:55:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:55:14 INFO - Running setup.py (path:/tmp/pip-X7wGR4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:55:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:55:14 INFO - Running setup.py (path:/tmp/pip-KYSe__-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:55:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:55:14 INFO - Running setup.py (path:/tmp/pip-FB4wOe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:55:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:55:14 INFO - Running setup.py (path:/tmp/pip-6oAIik-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:55:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:55:14 INFO - Running setup.py (path:/tmp/pip-s7Ue8b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:55:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:55:14 INFO - Running setup.py (path:/tmp/pip-6kzLce-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:55:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:55:14 INFO - Running setup.py (path:/tmp/pip-lNA8cd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:55:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:55:14 INFO - Running setup.py (path:/tmp/pip-4NESMs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:55:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:55:14 INFO - Running setup.py (path:/tmp/pip-Hsw5_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:55:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:55:14 INFO - Running setup.py (path:/tmp/pip-R2u4B4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:55:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:55:14 INFO - Running setup.py (path:/tmp/pip-0qw0Yw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:55:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:55:14 INFO - Running setup.py (path:/tmp/pip-0cwOPS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:55:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:55:14 INFO - Running setup.py install for manifestparser 12:55:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:55:15 INFO - Running setup.py install for mozcrash 12:55:15 INFO - Running setup.py install for mozdebug 12:55:15 INFO - Running setup.py install for mozdevice 12:55:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:55:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:55:15 INFO - Running setup.py install for mozfile 12:55:15 INFO - Running setup.py install for mozhttpd 12:55:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:55:15 INFO - Running setup.py install for mozinfo 12:55:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:55:15 INFO - Running setup.py install for mozInstall 12:55:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:55:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:55:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:55:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:55:15 INFO - Running setup.py install for mozleak 12:55:15 INFO - Running setup.py install for mozlog 12:55:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:55:16 INFO - Running setup.py install for moznetwork 12:55:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:55:16 INFO - Running setup.py install for mozprocess 12:55:16 INFO - Running setup.py install for mozprofile 12:55:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:55:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:55:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:55:16 INFO - Running setup.py install for mozrunner 12:55:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:55:16 INFO - Running setup.py install for mozscreenshot 12:55:16 INFO - Running setup.py install for moztest 12:55:16 INFO - Running setup.py install for mozversion 12:55:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:55:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:55:16 INFO - Cleaning up... 12:55:16 INFO - Return code: 0 12:55:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:55:16 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')]} 12:55:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:55:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:16 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')]} 12:55:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:55:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:16 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 0x7fe4808faf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18aa240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19390b0>, '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 0x1939fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x193a440>, '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': 'd6a7e047ef025f707024e163000001c3-1450472083.740019-905888863', '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 12:55:16 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 12:55:16 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 12:55:16 INFO - Using env: {'DISPLAY': ':0', 12:55:16 INFO - 'HOME': '/home/cltbld', 12:55:16 INFO - 'LANG': 'en_US.UTF-8', 12:55:16 INFO - 'LANGUAGE': 'en_US:en', 12:55:16 INFO - 'LOGNAME': 'cltbld', 12:55:16 INFO - 'MAIL': '/var/mail/cltbld', 12:55:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:55:16 INFO - 'MOZ_NO_REMOTE': '1', 12:55:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:55:16 INFO - 'NO_EM_RESTART': '1', 12:55:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:55:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:55:16 INFO - 'PWD': '/builds/slave/test', 12:55:16 INFO - 'SHELL': '/bin/bash', 12:55:16 INFO - 'SHLVL': '1', 12:55:16 INFO - 'TERM': 'linux', 12:55:16 INFO - 'TMOUT': '86400', 12:55:16 INFO - 'USER': 'cltbld', 12:55:16 INFO - 'XDG_SESSION_COOKIE': 'd6a7e047ef025f707024e163000001c3-1450472083.740019-905888863', 12:55:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:55:16 INFO - '_': '/tools/buildbot/bin/python'} 12:55:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:55:16 INFO - Running setup.py (path:/tmp/pip-4kkRty-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:55:17 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)) 12:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:55:17 INFO - Running setup.py (path:/tmp/pip-PnzysP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:55:17 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)) 12:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:55:17 INFO - Running setup.py (path:/tmp/pip-0j04N_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:55:17 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)) 12:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:55:17 INFO - Running setup.py (path:/tmp/pip-Ch74YB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:55:17 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)) 12:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:55:17 INFO - Running setup.py (path:/tmp/pip-SsmqZX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:55:17 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)) 12:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:55:17 INFO - Running setup.py (path:/tmp/pip-T1QfzF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:55:17 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)) 12:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:55:17 INFO - Running setup.py (path:/tmp/pip-uaXLiS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:55:17 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)) 12:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:55:17 INFO - Running setup.py (path:/tmp/pip-DyAUTL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:55:17 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)) 12:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:55:17 INFO - Running setup.py (path:/tmp/pip-yiciic-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:55:17 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)) 12:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:55:17 INFO - Running setup.py (path:/tmp/pip-IYE8dn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:55:17 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)) 12:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:55:17 INFO - Running setup.py (path:/tmp/pip-Bp8OFQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:55:17 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)) 12:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:55:17 INFO - Running setup.py (path:/tmp/pip-W77XuK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:55:17 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)) 12:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:55:17 INFO - Running setup.py (path:/tmp/pip-du8wqs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:55:17 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)) 12:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:55:17 INFO - Running setup.py (path:/tmp/pip-iWBxCn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:55:17 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)) 12:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:55:17 INFO - Running setup.py (path:/tmp/pip-7Tf5qY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:55:18 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)) 12:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:55:18 INFO - Running setup.py (path:/tmp/pip-duEItw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:55:18 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)) 12:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:55:18 INFO - Running setup.py (path:/tmp/pip-TYR0tq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:55:18 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)) 12:55:18 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)) 12:55:18 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)) 12:55:18 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)) 12:55:18 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)) 12:55:18 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)) 12:55:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:55:18 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 12:55:18 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 12:55:18 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 12:55:18 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 12:55:18 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 12:55:18 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 12:55:19 INFO - Downloading blessings-1.5.1.tar.gz 12:55:19 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 12:55:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:55:19 INFO - Installing collected packages: blessings 12:55:19 INFO - Running setup.py install for blessings 12:55:20 INFO - Successfully installed blessings 12:55:20 INFO - Cleaning up... 12:55:20 INFO - Return code: 0 12:55:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:55:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:55:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:55:20 INFO - Reading from file tmpfile_stdout 12:55:20 INFO - Current package versions: 12:55:20 INFO - argparse == 1.2.1 12:55:20 INFO - blessings == 1.5.1 12:55:20 INFO - blobuploader == 1.2.4 12:55:20 INFO - docopt == 0.6.1 12:55:20 INFO - manifestparser == 1.1 12:55:20 INFO - mozInstall == 1.12 12:55:20 INFO - mozcrash == 0.16 12:55:20 INFO - mozdebug == 0.1 12:55:20 INFO - mozdevice == 0.47 12:55:20 INFO - mozfile == 1.2 12:55:20 INFO - mozhttpd == 0.7 12:55:20 INFO - mozinfo == 0.9 12:55:20 INFO - mozleak == 0.1 12:55:20 INFO - mozlog == 3.1 12:55:20 INFO - moznetwork == 0.27 12:55:20 INFO - mozprocess == 0.22 12:55:20 INFO - mozprofile == 0.27 12:55:20 INFO - mozrunner == 6.11 12:55:20 INFO - mozscreenshot == 0.1 12:55:20 INFO - mozsystemmonitor == 0.0 12:55:20 INFO - moztest == 0.7 12:55:20 INFO - mozversion == 1.4 12:55:20 INFO - psutil == 3.1.1 12:55:20 INFO - requests == 1.2.3 12:55:20 INFO - wsgiref == 0.1.2 12:55:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:55:20 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')]} 12:55:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:55:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:20 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')]} 12:55:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:55:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:20 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 0x7fe4808faf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18aa240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19390b0>, '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 0x1939fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x193a440>, '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': 'd6a7e047ef025f707024e163000001c3-1450472083.740019-905888863', '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 12:55:20 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 12:55:20 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 12:55:20 INFO - Using env: {'DISPLAY': ':0', 12:55:20 INFO - 'HOME': '/home/cltbld', 12:55:20 INFO - 'LANG': 'en_US.UTF-8', 12:55:20 INFO - 'LANGUAGE': 'en_US:en', 12:55:20 INFO - 'LOGNAME': 'cltbld', 12:55:20 INFO - 'MAIL': '/var/mail/cltbld', 12:55:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:55:20 INFO - 'MOZ_NO_REMOTE': '1', 12:55:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:55:20 INFO - 'NO_EM_RESTART': '1', 12:55:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:55:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:55:20 INFO - 'PWD': '/builds/slave/test', 12:55:20 INFO - 'SHELL': '/bin/bash', 12:55:20 INFO - 'SHLVL': '1', 12:55:20 INFO - 'TERM': 'linux', 12:55:20 INFO - 'TMOUT': '86400', 12:55:20 INFO - 'USER': 'cltbld', 12:55:20 INFO - 'XDG_SESSION_COOKIE': 'd6a7e047ef025f707024e163000001c3-1450472083.740019-905888863', 12:55:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:55:20 INFO - '_': '/tools/buildbot/bin/python'} 12:55:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:55:20 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)) 12:55:20 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)) 12:55:20 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)) 12:55:20 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)) 12:55:20 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)) 12:55:20 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)) 12:55:20 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)) 12:55:20 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)) 12:55:20 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)) 12:55:20 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)) 12:55:20 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)) 12:55:20 INFO - Cleaning up... 12:55:20 INFO - Return code: 0 12:55:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:55:20 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')]} 12:55:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:55:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:20 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')]} 12:55:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:55:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:20 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 0x7fe4808faf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18aa240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19390b0>, '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 0x1939fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x193a440>, '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': 'd6a7e047ef025f707024e163000001c3-1450472083.740019-905888863', '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 12:55:20 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 12:55:20 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 12:55:20 INFO - Using env: {'DISPLAY': ':0', 12:55:20 INFO - 'HOME': '/home/cltbld', 12:55:20 INFO - 'LANG': 'en_US.UTF-8', 12:55:20 INFO - 'LANGUAGE': 'en_US:en', 12:55:20 INFO - 'LOGNAME': 'cltbld', 12:55:20 INFO - 'MAIL': '/var/mail/cltbld', 12:55:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:55:20 INFO - 'MOZ_NO_REMOTE': '1', 12:55:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:55:20 INFO - 'NO_EM_RESTART': '1', 12:55:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:55:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:55:20 INFO - 'PWD': '/builds/slave/test', 12:55:20 INFO - 'SHELL': '/bin/bash', 12:55:20 INFO - 'SHLVL': '1', 12:55:20 INFO - 'TERM': 'linux', 12:55:20 INFO - 'TMOUT': '86400', 12:55:20 INFO - 'USER': 'cltbld', 12:55:20 INFO - 'XDG_SESSION_COOKIE': 'd6a7e047ef025f707024e163000001c3-1450472083.740019-905888863', 12:55:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:55:20 INFO - '_': '/tools/buildbot/bin/python'} 12:55:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:55:20 INFO - Downloading/unpacking jsonschema 12:55:20 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 12:55:20 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 12:55:20 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 12:55:20 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 12:55:20 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 12:55:20 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 12:55:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:55:22 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 12:55:22 INFO - Downloading/unpacking functools32 (from jsonschema) 12:55:22 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 12:55:22 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 12:55:22 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 12:55:22 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 12:55:22 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 12:55:22 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 12:55:22 INFO - Downloading functools32-3.2.3-2.tar.gz 12:55:22 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 12:55:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:55:22 INFO - warning: no files found matching '*.txt' 12:55:22 INFO - no previously-included directories found matching 'build' 12:55:22 INFO - no previously-included directories found matching 'dist' 12:55:22 INFO - no previously-included directories found matching '.git*' 12:55:22 INFO - Installing collected packages: jsonschema, functools32 12:55:22 INFO - Running setup.py install for functools32 12:55:22 INFO - warning: no files found matching '*.txt' 12:55:22 INFO - no previously-included directories found matching 'build' 12:55:22 INFO - no previously-included directories found matching 'dist' 12:55:22 INFO - no previously-included directories found matching '.git*' 12:55:22 INFO - Successfully installed jsonschema functools32 12:55:22 INFO - Cleaning up... 12:55:22 INFO - Return code: 0 12:55:22 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 12:55:22 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')]} 12:55:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:55:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:22 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')]} 12:55:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:55:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:55:22 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 0x7fe4808faf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18aa240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19390b0>, '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 0x1939fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x193a440>, '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': 'd6a7e047ef025f707024e163000001c3-1450472083.740019-905888863', '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 12:55:22 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 12:55:22 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 12:55:22 INFO - Using env: {'DISPLAY': ':0', 12:55:22 INFO - 'HOME': '/home/cltbld', 12:55:22 INFO - 'LANG': 'en_US.UTF-8', 12:55:22 INFO - 'LANGUAGE': 'en_US:en', 12:55:22 INFO - 'LOGNAME': 'cltbld', 12:55:22 INFO - 'MAIL': '/var/mail/cltbld', 12:55:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:55:22 INFO - 'MOZ_NO_REMOTE': '1', 12:55:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:55:22 INFO - 'NO_EM_RESTART': '1', 12:55:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:55:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:55:22 INFO - 'PWD': '/builds/slave/test', 12:55:22 INFO - 'SHELL': '/bin/bash', 12:55:22 INFO - 'SHLVL': '1', 12:55:22 INFO - 'TERM': 'linux', 12:55:22 INFO - 'TMOUT': '86400', 12:55:22 INFO - 'USER': 'cltbld', 12:55:22 INFO - 'XDG_SESSION_COOKIE': 'd6a7e047ef025f707024e163000001c3-1450472083.740019-905888863', 12:55:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:55:22 INFO - '_': '/tools/buildbot/bin/python'} 12:55:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:55:23 INFO - Downloading/unpacking flake8 12:55:23 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 12:55:23 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 12:55:23 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 12:55:23 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 12:55:23 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 12:55:23 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 12:55:24 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 12:55:24 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 12:55:24 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 12:55:24 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 12:55:24 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 12:55:24 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 12:55:24 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 12:55:24 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 12:55:24 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 12:55:25 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 12:55:25 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 12:55:25 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 12:55:25 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 12:55:25 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 12:55:25 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 12:55:25 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 12:55:25 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 12:55:25 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 12:55:25 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 12:55:25 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 12:55:25 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 12:55:25 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 12:55:25 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 12:55:25 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 12:55:25 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 12:55:25 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 12:55:25 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 12:55:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 12:55:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:55:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:55:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:55:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:55:25 INFO - no previously-included directories found matching 'docs/_build' 12:55:25 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 12:55:25 INFO - Running setup.py install for pep8 12:55:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:55:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:55:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:55:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:55:25 INFO - no previously-included directories found matching 'docs/_build' 12:55:25 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 12:55:25 INFO - Successfully installed flake8 mccabe pyflakes pep8 12:55:25 INFO - Cleaning up... 12:55:25 INFO - Return code: 0 12:55:25 INFO - Running post-action listener: _resource_record_post_action 12:55:25 INFO - Running post-action listener: _start_resource_monitoring 12:55:25 INFO - Starting resource monitoring. 12:55:25 INFO - ##### 12:55:25 INFO - ##### Running install step. 12:55:25 INFO - ##### 12:55:25 INFO - Running pre-action listener: _resource_record_pre_action 12:55:25 INFO - Running main action method: install 12:55:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:55:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:55:25 INFO - Reading from file tmpfile_stdout 12:55:25 INFO - Detecting whether we're running mozinstall >=1.0... 12:55:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:55:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:55:26 INFO - Reading from file tmpfile_stdout 12:55:26 INFO - Output received: 12:55:26 INFO - Usage: mozinstall [options] installer 12:55:26 INFO - Options: 12:55:26 INFO - -h, --help show this help message and exit 12:55:26 INFO - -d DEST, --destination=DEST 12:55:26 INFO - Directory to install application into. [default: 12:55:26 INFO - "/builds/slave/test"] 12:55:26 INFO - --app=APP Application being installed. [default: firefox] 12:55:26 INFO - mkdir: /builds/slave/test/build/application 12:55:26 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'] 12:55:26 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 12:55:41 INFO - Reading from file tmpfile_stdout 12:55:41 INFO - Output received: 12:55:41 INFO - /builds/slave/test/build/application/firefox/firefox 12:55:41 INFO - Running post-action listener: _resource_record_post_action 12:55:41 INFO - ##### 12:55:41 INFO - ##### Running run-tests step. 12:55:41 INFO - ##### 12:55:41 INFO - Running pre-action listener: _resource_record_pre_action 12:55:41 INFO - Running main action method: run_tests 12:55:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:55:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:55:41 INFO - Python 2.7.3 12:55:41 INFO - Return code: 0 12:55:41 INFO - grabbing minidump binary from tooltool 12:55: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')]} 12:55:41 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 0x19390b0>, '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 0x1939fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x193a440>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:55:41 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 12:55:41 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 12:55:41 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:55:41 INFO - Return code: 0 12:55:41 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:55:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:55:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:55:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:55:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:55:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 12:55:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 12:55:42 INFO - Return code: 0 12:55:42 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/dZqfn1r_TIiKsZVfqrCifw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--webServer', 'localhost'] in /builds/slave/test/build 12:55:42 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/dZqfn1r_TIiKsZVfqrCifw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-016 --webServer localhost 12:55:42 INFO - Using env: {'DISPLAY': ':0', 12:55:42 INFO - 'HOME': '/home/cltbld', 12:55:42 INFO - 'LANG': 'en_US.UTF-8', 12:55:42 INFO - 'LANGUAGE': 'en_US:en', 12:55:42 INFO - 'LOGNAME': 'cltbld', 12:55:42 INFO - 'MAIL': '/var/mail/cltbld', 12:55:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:55:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:55:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:55:42 INFO - 'MOZ_NO_REMOTE': '1', 12:55:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:55:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:55:42 INFO - 'NO_EM_RESTART': '1', 12:55:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:55:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:55:42 INFO - 'PWD': '/builds/slave/test', 12:55:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:55:42 INFO - 'SHELL': '/bin/bash', 12:55:42 INFO - 'SHLVL': '1', 12:55:42 INFO - 'TERM': 'linux', 12:55:42 INFO - 'TMOUT': '86400', 12:55:42 INFO - 'USER': 'cltbld', 12:55:42 INFO - 'XDG_SESSION_COOKIE': 'd6a7e047ef025f707024e163000001c3-1450472083.740019-905888863', 12:55:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:55:42 INFO - '_': '/tools/buildbot/bin/python'} 12:55:42 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/dZqfn1r_TIiKsZVfqrCifw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--webServer', 'localhost'] with output_timeout 3600 12:55:42 INFO - mozversion INFO | application_buildid: 20151218113634 12:55:42 INFO - mozversion INFO | application_changeset: f696bbc40e1047a95af58c0280fdd02faf370565 12:55:42 INFO - mozversion INFO | application_display_name: Nightly 12:55:42 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:55:42 INFO - mozversion INFO | application_name: Firefox 12:55:42 INFO - mozversion INFO | application_remotingname: firefox 12:55:42 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 12:55:42 INFO - mozversion INFO | application_vendor: Mozilla 12:55:42 INFO - mozversion INFO | application_version: 46.0a1 12:55:42 INFO - mozversion INFO | platform_buildid: 20151218113634 12:55:42 INFO - mozversion INFO | platform_changeset: f696bbc40e1047a95af58c0280fdd02faf370565 12:55:42 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 12:55:42 INFO - mozversion INFO | platform_version: 46.0a1 12:55:42 INFO - 2015-12-18 12:55:42,624 DEBUG : using testdate: 1450472142 12:55:42 INFO - 2015-12-18 12:55:42,624 DEBUG : actual date: 1450472142 12:55:42 INFO - 2015-12-18 12:55:42,699 INFO : starting webserver on 'localhost:57549' 12:55:42 INFO - 2015-12-18 12:55:42,700 INFO : Starting test suite talos-linux64-ix-016.e 12:55:42 INFO - 2015-12-18 12:55:42,700 INFO : Starting test dromaeo_css 12:55:42 INFO - 2015-12-18 12:55:42,700 DEBUG : operating with platform_type : linux_ 12:55:42 INFO - 2015-12-18 12:55:42,701 INFO : Initialising browser for dromaeo_css test... 12:55:42 INFO - 2015-12-18 12:55:42,717 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpts3kYz/profile http://localhost:57549/getInfo.html 12:55:47 INFO - 2015-12-18 12:55:47,457 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 12:55:47 INFO - 2015-12-18 12:55:47,457 DEBUG : BROWSER_OUTPUT: colorDepth:24 12:55:47 INFO - 2015-12-18 12:55:47,457 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 12:55:47 INFO - 2015-12-18 12:55:47,457 DEBUG : BROWSER_OUTPUT: __metrics 12:55:47 INFO - 2015-12-18 12:55:47,574 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] 12:55:48 INFO - 2015-12-18 12:55:48,486 INFO : Browser initialized. 12:55:48 INFO - 2015-12-18 12:55:48,486 INFO : Running cycle 1/1 for dromaeo_css test... 12:55:48 INFO - 2015-12-18 12:55:48,487 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpts3kYz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 12:56:00 INFO - 2015-12-18 12:56:00,485 DEBUG : BROWSER_OUTPUT: RSS: Main: 150888448 12:56:00 INFO - 2015-12-18 12:56:00,485 DEBUG : BROWSER_OUTPUT: 12:56:50 INFO - 2015-12-18 12:56:50,117 DEBUG : BROWSER_OUTPUT: 1450472210117 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] 12:59:01 INFO - 2015-12-18 12:59:01,720 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57549/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57549/tests/dromaeo/cssquery-ext.html) 12:59:02 INFO - 2015-12-18 12:59:02,784 DEBUG : BROWSER_OUTPUT: RSS: Main: 154189824 12:59:02 INFO - 2015-12-18 12:59:02,784 DEBUG : BROWSER_OUTPUT: 13:02:04 INFO - 2015-12-18 13:02:04,979 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57549/tests/dromaeo/cssquery-ext.html (next: http://localhost:57549/tests/dromaeo/cssquery-jquery.html) 13:02:05 INFO - 2015-12-18 13:02:05,573 DEBUG : BROWSER_OUTPUT: RSS: Main: 153530368 13:02:05 INFO - 2015-12-18 13:02:05,573 DEBUG : BROWSER_OUTPUT: 13:05:07 INFO - 2015-12-18 13:05:07,393 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57549/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57549/tests/dromaeo/cssquery-mootools.html) 13:05:07 INFO - 2015-12-18 13:05:07,997 DEBUG : BROWSER_OUTPUT: RSS: Main: 154787840 13:05:07 INFO - 2015-12-18 13:05:07,997 DEBUG : BROWSER_OUTPUT: 13:08:09 INFO - 2015-12-18 13:08:09,795 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57549/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57549/tests/dromaeo/cssquery-prototype.html) 13:08:10 INFO - 2015-12-18 13:08:10,397 DEBUG : BROWSER_OUTPUT: RSS: Main: 152555520 13:08:10 INFO - 2015-12-18 13:08:10,397 DEBUG : BROWSER_OUTPUT: 13:11:13 INFO - 2015-12-18 13:11:13,003 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57549/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57549/tests/dromaeo/cssquery-yui.html) 13:11:13 INFO - 2015-12-18 13:11:13,604 DEBUG : BROWSER_OUTPUT: RSS: Main: 151920640 13:11:13 INFO - 2015-12-18 13:11:13,604 DEBUG : BROWSER_OUTPUT: 13:14:15 INFO - 2015-12-18 13:14:15,900 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57549/tests/dromaeo/cssquery-yui.html (next: http://localhost:57549/tests/dromaeo/cssquery-dojo.html) 13:14:16 INFO - 2015-12-18 13:14:16,150 DEBUG : BROWSER_OUTPUT: RSS: Main: 154947584 13:14:16 INFO - 2015-12-18 13:14:16,150 DEBUG : BROWSER_OUTPUT: 13:14:16 INFO - 2015-12-18 13:14:16,150 DEBUG : BROWSER_OUTPUT: __start_tp_report 13:14:16 INFO - 2015-12-18 13:14:16,151 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 13:14:16 INFO - 2015-12-18 13:14:16,151 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 13:14:16 INFO - 2015-12-18 13:14:16,151 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 13:14:16 INFO - 2015-12-18 13:14:16,152 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2669.7330266973304;2747.4642444723263;2776.5557510798276;2784.1925841505977;2788.609594656748;4697.718136911788;4807.519248075195;4844.733539655317;4852.369192005043;4916.2379831126145;4771.284307353899;4797.496262892399;4817.084753896757;4904.117258893398;4920.778564964577;4504.414426124599;4517.954820451786;4539.137563862855;4601.378813860119;4645.001324715186;135.2523225936358;4231.301835197189;4327.913441731163;4416.514183439819;4426.800793964263;4406.405135306741;4446.688731788776;4485.215087359699;4506.887327816831;4516.277395616702;4483.6861419700635;4492.707973981714;4492.955070449287;4497.415336006331;4505.684602077856;4267.573242675739;4342.544032876565;4357.7385356878685;4358.5205627380965;4464.933026004613;4794.137055330039;4798.448178459487;4824.34871292378;4829.2997515360075;4878.634102442295;5235.528802407802;5238.580913526908;5259.631825772198;5260.947390526084;5262.684238945637;4984.675996060245;5128.640995130305;5157.896842063138;5273.077211488013;5283.656562323436;4771.952280477186;4883.682560633583;4929.186684197068;4939.629527785394;4979.6265280104135;5433.701146436984;5436.130219164994;5438.184272359099;5440.972795135999;5444;4766.785494652749;4904.950950490521;4931.679440836334;5023.748812559357;5052.9242061369105;5216.321878155892;5281.102212623863;5283.418823929364;5289.788408463618;5339.839804805862;4851.878703032382;5210.7915683373;5223.52988231061;5229.869253268699;5231.032259032015;5024;5081.949180508222;5150.871228219249;5161.045206636784;5171.456997015334;4318.114786468766;4319.416878721395;4357.760323182255;4363.585459381354;4390.780460976938;3681.1349332906743;3792.3173828711097;3833.5783063862955;3843.1737176507186;3887.319719049155;4385.714928529699;4476.261416866246;4489.079738653568;4536.364908912755;4546.727196368229;5209.062368773657;5215.322008138917;5225.869353266123;5226.425093239741;5243.161094224982;4322.589354011364;4390.05613793036;4399.5820397062225;4444.644428445717;4512.954870451254;4627.838025669085;4631.861044168681;4633.2818413146015;4650.279206722963;4686.718796872198;4931.4000779431935;4937.407511098691;4951.801927922843;4952.059108769322;4999.825006124768;4991.226359914219;5108.259302401206;5133.614978876674;5248.2390053442805;5263.631545791911;4892.608591312757;4931.802727890988;4964.67729597575;4992.600591952708;5037.798488060436;5229.869253268699;5238.76425560843;5248.08158572256;5250.868728281824;5258.50044245796;4914.754262286943;5029.572486338702;5101.69389836611;5174.197999310113;5204.843854684365;5040.344755181803;5094.694318340911;5100.795968161232;5116.283720279238;5136.717480538607;4875.731834749123;5082;5086.54221120101;5090.338256026693;5090.923636145535;4842.249451335048;4871.95128048715;4908.484609115992;4936.7284799336385;4967.428745694291;5127.615428842777;5141.665791723526;5157.174852023658;5190.870228244324;5217.9478205217465;4695.506971768052;4827.324174615486;5000.77496512665;5026.095302845377;5053.444121146597 13:14:16 INFO - 2015-12-18 13:14:16,154 DEBUG : BROWSER_OUTPUT: |1;ext.html;566.0603398358726;571.0292502745334;575.9107338362555;577.7717801468417;581.1718301420475;34823.65176348239;35575;35698.28603427933;35765.99999999997;35825.64174358256;39740;40312.798436007855;40430.59569404305;40543.79728101363;40546.39180412296;7767.456278060524;7893.329067029309;7893.763187104396;7922;7929.960350198241;7934.563599002053;8020.558869262189;8046.557439340834;8068.314193293577;8076.919230807675;7997.480163789364;8003.759887203364;8064.919350806475;8082.0705618854;8101.837963240761;7876.724314648988;7988.9600551997155;8007.159248278934;8017.158198389144;8017.278444939925;7837.804054898616;7994.920050799476;8012.1186669466315;8037.799055023613;8038.155993620644;36986.26027479462;37462.62537374619;37532;37608.24783504315;37662.62337376618;2402.267308470916;2788.888444462219;2957.7781666374894;2975.925601859949;3202.343519578481;27033.45933081347;31128;32037.359252814815;32622.510662340082;33229;3108.751299896003;3120.1419609607315;3136.5138403547257;3155.1165673611426;3170.6987836155527;177282;178743;178801;179146;180052.19686818964;53051.73474132682;53145.46854531483;53164;53575.19637205445;54295;66875.66562167158;67874;68107.3189268101;68127;68316.31683683199;13469.461221551224;13600.455981760817;13674.794878076837;13702.246376449391;13760.518381856788;10652.733681657866;10738.409387483607;10752;10847.891521084846;10848.132149428027;19402.902985485263;19861.801381985995;19885.403437896886;19912;19928.60142797165;7386.482946193715;7523.285287897641;7567.962160189164;7672.65473053714;7743.380529557622;831.4595512916652;859.2867919626694;887.5251740318898;998.2463240253708;999.9950000250097;27929.44141117166;28143.859280703877;28150.71849281481;28346.149615511567;28392.71607283942;989.0505115089451;1028.4138041316528;1055.350959160111;1070.352436775755;1100.5157730598512;14792.260386980608;14930;15448.382064717285;16489.752653710202;16774.412895548598;304153;310575;311296;313165;313511;49510;49989.25016124761;50159.74920125376;50164;50380.244296335586;1205.801042827941;1242.4843689868603;1245.7882160032816;1245.9252444853337;1251.055453132879;28187.859060704566;28523.42953140967;29156.854215728785;29167.708322917348;29312.26719332034;43614.345784813886;45115.77442112769;45124.0975180488;45128.097438051715;45175.77412112919;255128;255402;257075;257827;258359;39692.801535992134;43008;43476.565234347254;43947.78026109849;44487.77756111199;39189.80405097956;39441.60558394494;39615;40132.59867401289;40380.3942940862;33203.50194747129;33615.495767563014;33656;34142.82928585341;37632.81183594174;32941.835290823394;33146.50280245847;33228;33568;33779;33907.321853562295;34429.827850861584;34483;34562;38806;28318.575221371284;28639.713602863703;30154.54768178523;30214.093577192718;30550.694493054783;23988.88005559961;24170;25377.74622253754;26436.603450947867;28029.299267518476 13:14:16 INFO - 2015-12-18 13:14:16,157 DEBUG : BROWSER_OUTPUT: |2;jquery.html;318.69246177438765;320.4424301715016;323.38395356845217;323.44529132537696;327.21958129860275;14656.853431465683;15225.771613425808;15306.081635101888;15817.683646327096;16500.504984850475;10210.23423243256;10433.113185379232;13446.798298025538;14847.77728334073;14877.107373557594;4543.136804007245;4661.720296782187;4668.229742092551;4856.684315519497;5087.796488140489;4101.753894766323;5158.767855446514;5171.663841850286;5192.532672059514;5194.584433245332;4886.560209581137;5028.522290382408;5052.9747351263195;5092.388913330406;5346.6791992480385;4967.50324967504;4972.353594032771;4996.175631020896;4996.900061998776;5007.874803129915;5073.518015788531;5092.719900405476;5093.083245015896;5097.184450487941;5099.056674515226;11697.3566453845;11845.348505832177;14818.333175007043;19265.903670481697;19420.320288789964;1762.691528982423;1812.501562070428;1843.3456123076382;1880.9059547022594;1917.5302050997595;8848.46909185451;9072.047435019294;12350;12390.876091239063;12639.43122559487;1755.0873545756263;1773.1134432783608;1780.97328540072;1799.9910000449913;1812.918418671163;54232.45767542314;57456;57531.71234143844;57535.42464575385;57675.423245767844;9607.471589062528;9765.902340976641;9766.316357855025;9808.362456440309;9883.456409897524;12521.248725076342;12521.248725076342;12524.81212781809;12560.057995650362;12578.30819304947;7156.284371562802;7159.176694680074;7247.347738703542;7341.522801017917;7431.591262480615;6865.759698410531;6875.174979002451;6901.275366086589;6945.201301850251;6968.512204145763;4106.240345536085;4110.301248787713;4165.854195103157;4180.832766689298;4222.556631553704;1712.7430885367046;1773.7782777152856;1775.1301862087741;1784.1525275494037;1800.9909950450426;352.43434287967955;375.8853549667378;387.7440889013238;388.6773977598643;391.2546598729425;9551.617935282511;9793.559289831976;10118.443485608363;10261.28171028021;10286.897131028743;371.9330520506335;378.91663833957034;385.9942100868489;386.92648396804515;391.6396914838356;5330.92003619946;5493.121100623881;5524;5573.665580065209;5601.187827765033;504354;508583;510496;515431;516027;69515.3048469519;73065.26934730585;73165.6341718288;73203.26796732072;73581.6320918392;434.28993595471;438.27465544523864;438.74991254984354;439.45288116295416;440.79282737113505;9715.805683886425;9737.22102231834;9851.605935762489;9911.603535858485;9946.055124763136;32501.674983249864;33569.49645755364;34241.48637770485;34382.14044648904;34619.4807077889;44278.33582496201;44668;44684.776576116914;44722.77638611786;44914.55085449234;8713.302935764998;8878.911210887809;8947.865782013405;8949.865752013855;8998.505082220541;10559.577616895545;10651.041406273473;10675.199360048182;14590.781138283146;16442.671146576762;10109.949450252701;11476.254043487146;13266.53567125146;13309.66725831862;13392;13364.799528006894;13419.261940593362;13709.520166794115;13739.587812365646;13905.582832515041;13376.866231337563;14071.577852664437;14090.22503762303;14159.716805663624;14463;8989.640414383352;9031.142041506046;9071.455712657262;9139.131782480654;9180.6786762463;9554.856677149988;9648.372855764354;10810.621628242974;13640.590782276548;13947.16317020982 13:14:16 INFO - 2015-12-18 13:14:16,158 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1493.2832240524542;1612.2583611538691;1691.9323227070913;1701.5150682055616;1726.0592976827636;6063.818085457432;6117.38826117388;6751.966240168798;7388.852222955545;7431.033965584473;7030.472714546417;7137.60743159126;7186.568805871651;7229.927700722991;7235.67439465224;2076.9376918692465;2105.5157313817754;2122.384508492535;2125.479257581887;2128.9148434062618;2096.308218287961;2111.0711287033732;2130.5419334843004;2147.3450597567735;2158.3632828315685;2049.2008116834445;2055.784142665021;2056.0953180600486;2057.1360028787944;2071.4407110080347;2068.369147410039;2081.885496297703;2084.155916853677;2092.853500254983;2094.9476263093393;2060.2789023841688;2089.6552068908763;2094.98952505238;2096.821770149539;2103.24283258027;7104.467164962597;7136.571805691675;7186.640667966581;7205.027321311661;7221.169565499929;4186.476690413699;4277.422547956017;4277.572242775728;4294.312909934427;4307;2742.081402730088;2760.585912113179;2820.111664825594;2821.1113499247876;2841.857907104657;850.7022542110212;1730.9567260818455;1731.9047452390114;1740.5648587853036;1753.6142048749255;9543.618255269781;9746.756331091708;9794.608215671364;9820;9844.458554779485;3849.40334248192;3859.6333348331864;3903.2193561287854;3908.4723562318723;4014.558398576154;8508.532030738368;8524.786880327918;8574.185452382013;8606;8652.221300082896;4303.397524346594;4338.674599404994;4341.587549182823;4353.434053573079;4361.672874534423;3300.66993300668;3379.442392005341;3811.2568049230135;3912.237113762789;3944.36890097583;3485.6688614581576;3502.2470168913296;3512.3677738007405;3520.8239588020497;3558.181618227822;1092.6066616018386;1107.0977153619774;1107.4739498738195;1112.6495154026456;1130.9660710178707;969.7333233360741;1050.9106725928382;1133.252053644607;1163.2729544034978;1166.7724793665323;6531.8367040823405;6584.76953306632;6590;6598.274189839114;6603.70283337251;1090.841827934945;1146.335125627151;1151.4875880233383;1160.6750109969223;1170.631251155883;5358.678479291177;5420.295361602966;5421.213923981;5423.105187644073;5437.048516509595;8416.621252043673;8567.150964699791;8621.69824056155;8674.609642565974;8679.913200868037;8568.871466928002;8582.570871456526;8595.871061934076;8616.78458038554;8677.783055423664;6382.936170638327;6730.259671436138;6831.248562658104;6858.348456896587;6874.793756187322;6806.65966701653;6887.827804304932;6892.724291028303;6911.516193866381;6929.099217101744;7743.5353878767455;7764;7892.960535197287;7899.1705870885025;7935;7932.801679958047;8058.194180581895;8087.757367278991;8112.1076681566165;8245.13426090252;5358.142697168434;5491;6092.847678807888;6233.0338797486465;6314.273858506147;6659.733610655519;6690.4647628190605;6692.732290708512;6747.426468750211;6771.559848609824;6294.905576416449;6398.93601064002;6528.902066469102;6532.477401807939;6591.571547849375;6188.288346840171;6211.751529938751;6219.968900155471;6359.982402815527;6372.553921225655;6307.432331090224;6338.683065846782;6366.427021568081;6372.87254254903;6378.9362106380195;5911.763529458945;5916.911246331395;5977.312609049841;5979.1928089707335;6038.5169186465855;5982.790602328898;6030.6080104793045;6033.5173186145885;6038.033914573647;6039.154518367344 13:14:16 INFO - 2015-12-18 13:14:16,160 DEBUG : BROWSER_OUTPUT: |4;prototype.html;297.81833081820093;301.4603859092225;306.3918122526784;307.1645125259294;307.64159753886725;5616.42740032791;5637.21079048933;5722.399148089453;5744.942550574493;5745.454181852718;5596.4123767004485;5598.776048958047;5604.831855044345;5609.719514024293;5635.408282130379;4090.3046482097966;4326.610605045553;4330.956690433087;4335.154644844257;4336.609705126538;4178.519470260913;4232.15356928614;4342.913141737164;4358.738475691469;4362.389265502832;4255.914881702364;4288.9785551072355;4291.742495450268;4298.075913678558;4306.7631280279575;4269.935950960738;4292.892677683068;4297.613214810666;4313.439252897135;4313.805878735465;3773.9622603773887;3779.829907654162;3787.0721673190246;3789.2800367930004;3794.7533410328515;5242.1088414969545;5431.402545719968;5469.07025805614;5485.9725701371635;5488.17677348397;4884.780184891689;4903.65674402792;4908.165611845959;4910.165271903749;4916.58209052231;5913.201717768089;5920.79277225295;5926.4073592640825;5936.990711579042;5937.317208521033;4009.218202450523;4011.0774521860135;4013.6588389986887;4023.396490526415;4030.7984600770137;5995.880082398327;6223.719932602999;6227.283862355842;6238.500919926395;6242.625442473421;4108.67130629549;4110.547839737626;4146.730462519927;4147.274227010322;4154.356074808409;4204.516480604709;4212.68404869636;4214;4215.304474761691;4219.451471308772;4847.103285892122;4851.878703032382;4854.17479028566;4855.223164293696;4863.294822250825;5684.772609095672;5825.563082768809;5871.442212989759;5912.231409916683;5917.408259174134;1457.9781303280458;1474.3070756744312;1474.8746356559814;1476.1659662290772;1476.6234610174338;958.0706714486937;971.3249291742171;976.5800705696619;981.1611072533025;991.6826615482976;5145.125328694131;5146.099432599281;5150.4334523202415;5154.407243167008;5175;6034.24571928509;6140;6212.627242365473;6215.036669316263;6239;5169.612279079084;5180.844574662766;5200.063988482109;5204.42751297357;5209.895802084013;3289.095498737818;3340.1983523954564;3341.8830340937952;3342.398368293682;3345.9665403346144;5678.176664383641;5739.971300143556;5747.482726554546;5802.100674395476;5811.099279611667;6280.654563999024;6303.590266632746;6361.332060133619;6362.331955144736;6388.233411990498;5698.458646428666;5736.19693242938;5777.942220577825;5795.594308398456;5807.419258074159;6045.788397406246;6159.4456498915315;6169.951108311688;6170.10533472653;6217.347178546188;6039.63762174271;6043.8186854394435;6046.244219472566;6067.484263837623;6068.575199735976;5787.045137552257;5789.9710501447225;5797.739101740347;5800.4489573490455;5820.5925585210325;4089.26393249218;4125.752454852598;4127.587241275968;4130.710850240454;4145.3781932710335;5991.970040149771;6157.568970172224;6158.538109641705;6191.566590338633;6200.720967556561;5993.160957465871;5995.700214989321;5998.400159983967;6000.579959402975;6013.699315034319;5986.880262394815;5990.610610310316;6001.519878409629;6002.519798416203;6010.9699451502465;5642.520385767256;5785.4214578541805;5821.534277257724;5821.922944255326;5866.50134738535;5968.4926781224085;5969.701514924323;5971.28344598651;6012.158297838394;6021.909671354845;5718.399568045462;5851.882962340645;5854.765809367577;5862.413758624103;5873.970630146993 13:14:16 INFO - 2015-12-18 13:14:16,163 DEBUG : BROWSER_OUTPUT: |5;yui.html;691.0843132848975;728.8979542863997;760.8059944714097;800.2237829305575;802.9397795165364;10840.6747797566;11171.050460710829;11202.831957520644;11213.607523736671;11215.214934954556;11628.476718547665;11732.35472049037;11733.53065877366;11741.589044383449;11787;758.8254701418678;770.8458308338327;771.1709911844789;773.8529679360931;774.2528460036064;620.888240116781;623.498084042348;767.6507189228915;778.5678948183755;783.4985609210111;819.1071731812324;822.9958850205741;826.8842362069315;831.0442990560873;831.8253166834942;815.3966065111774;817.4073796497552;817.497239197892;818.2758258940821;819.7008092046419;613.5306490534745;613.8526753579154;614.2659521871366;614.3303798859221;614.8800983808159;11330.263532870336;11378.544858205662;11408.771824563462;11410.030147437477;11438.256513326607;11088.33469991795;11088.390138542378;11161.776764464747;11219.719507012309;11412.20114591979;9474.90525094754;9515.952420237854;9566.426014439086;9652.420854748738;9787.951060244653;3215.742740580748;3301.323228738103;3312.9503057453917;3324.268660894599;3324.7173990210863;38752.61247387518;38897.22205555901;38988.61011389906;39032;39223.215535689415;9044.909550904538;9129.08709129095;9247.26021918245;9443.386179898285;9566.808663826685;16844.15779211059;16876.15619219034;16992.320307187572;16998.660026799644;17008.659826803396;6164.7842325518395;6402.167718196698;6481.611103333815;6491.545591808528;6740.359665831738;3551.5738111426795;3570.285942811396;3575.5173051638217;3583.552055993001;3592.7305452091196;2622.2002289301563;2665.506881226979;2670.893164273446;2675.1573254424793;2680.1557509384484;464.4241140985222;466.1609103613482;466.8062753957138;475.18742949556645;478.4904077157816;559.4377650461261;562.1202817590458;579.7304253522133;586.8356860079184;590.7046476761619;7245.818854528574;7246.93232446433;7526.811829704301;7600.087989441303;7616.619169041525;465.49726295601346;501.8143286983839;574.167457187072;586.560079940045;594.6342999055549;3734.215814678948;3864.1885204107452;3881.7088718346236;3928.5482169550296;3952.486176796998;46840;47314.5268547317;47329.05341893143;47406.5259347409;49448.50551494439;43022.56977430248;43601.78199109047;43610.563894360654;43678;43970.560294396644;39452.80273598614;39558.80220598879;39914.600853991666;40600;40876.38685419721;7889.171636978264;8011;8152.877706834283;8216;8234.258916697527;29752.404951901273;30133.246668832577;30604.693953060185;30728.231794205323;30850.382992340477;35835.28329433344;36245.45631815472;36379.27241455209;38202.94376449822;39855.80072099621;9734.659286924923;10007.449590272605;10032.64885728996;10200.540975656262;10216.693499195037;11161.330320180816;11164.776704466027;11203.439828008404;11210.831837522606;11278;10146.086852183338;10302.948485257526;10765.46172691378;10981.61564345244;11067.83398249043;9984.500774961369;10078.546465409221;10144.137748291478;10559.78880422372;10641.787164256826;10468.528916198637;10473.842892356774;10532.473376331305;10671.89328106709;10683;9268.582913768762;9276.814463710823;9280.118388753328;9477.857832132386;9628.277879158952;8799.384043116921;8921.330900182642;9081.863772043556;9085.13691199335;9116.225120864801 13:14:16 INFO - 2015-12-18 13:14:16,163 DEBUG : BROWSER_OUTPUT: __end_tp_report 13:14:16 INFO - 2015-12-18 13:14:16,163 DEBUG : BROWSER_OUTPUT: __start_cc_report 13:14:16 INFO - 2015-12-18 13:14:16,163 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,328 13:14:16 INFO - 2015-12-18 13:14:16,163 DEBUG : BROWSER_OUTPUT: __end_cc_report 13:14:16 INFO - 2015-12-18 13:14:16,164 DEBUG : BROWSER_OUTPUT: __startTimestamp1450473256148__endTimestamp 13:14:16 INFO - 2015-12-18 13:14:16,164 DEBUG : BROWSER_OUTPUT: 13:14:16 INFO - 2015-12-18 13:14:16,164 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 13:14:16 INFO - 2015-12-18 13:14:16,164 DEBUG : BROWSER_OUTPUT: Number of tests: 6 13:14:16 INFO - 2015-12-18 13:14:16,164 DEBUG : BROWSER_OUTPUT: 13:14:16 INFO - 2015-12-18 13:14:16,164 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4767.94 Median:4920.78 stddev:627.13 (12.7%) stddev-sans-first:607.07 13:14:16 INFO - 2015-12-18 13:14:16,164 DEBUG : BROWSER_OUTPUT: Values: 2669.7 2747.5 2776.6 2784.2 2788.6 4697.7 4807.5 4844.7 4852.4 4916.2 4771.3 4797.5 4817.1 4904.1 4920.8 4504.4 4518.0 4539.1 4601.4 4645.0 135.3 4231.3 4327.9 4416.5 4426.8 4406.4 4446.7 4485.2 4506.9 4516.3 4483.7 4492.7 4493.0 4497.4 4505.7 4267.6 4342.5 4357.7 4358.5 4464.9 4794.1 4798.4 4824.3 4829.3 4878.6 5235.5 5238.6 5259.6 5260.9 5262.7 4984.7 5128.6 5157.9 5273.1 5283.7 4772.0 4883.7 4929.2 4939.6 4979.6 5433.7 5436.1 5438.2 5441.0 5444.0 4766.8 4905.0 4931.7 5023.7 5052.9 5216.3 5281.1 5283.4 5289.8 5339.8 4851.9 5210.8 5223.5 5229.9 5231.0 5024.0 5081.9 5150.9 5161.0 5171.5 4318.1 4319.4 4357.8 4363.6 4390.8 3681.1 3792.3 3833.6 3843.2 3887.3 4385.7 4476.3 4489.1 4536.4 4546.7 5209.1 5215.3 5225.9 5226.4 5243.2 4322.6 4390.1 4399.6 4444.6 4513.0 4627.8 4631.9 4633.3 4650.3 4686.7 4931.4 4937.4 4951.8 4952.1 4999.8 4991.2 5108.3 5133.6 5248.2 5263.6 4892.6 4931.8 4964.7 4992.6 5037.8 5229.9 5238.8 5248.1 5250.9 5258.5 4914.8 5029.6 5101.7 5174.2 5204.8 5040.3 5094.7 5100.8 5116.3 5136.7 4875.7 5082.0 5086.5 5090.3 5090.9 4842.2 4872.0 4908.5 4936.7 4967.4 5127.6 5141.7 5157.2 5190.9 5217.9 4695.5 4827.3 5000.8 5026.1 5053.4 13:14:16 INFO - 2015-12-18 13:14:16,164 DEBUG : BROWSER_OUTPUT: 13:14:16 INFO - 2015-12-18 13:14:16,164 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:42275.21 Median:34522.50 stddev:66585.54 (192.9%) stddev-sans-first:66698.68 13:14:16 INFO - 2015-12-18 13:14:16,165 DEBUG : BROWSER_OUTPUT: Values: 566.1 571.0 575.9 577.8 581.2 34823.7 35575.0 35698.3 35766.0 35825.6 39740.0 40312.8 40430.6 40543.8 40546.4 7767.5 7893.3 7893.8 7922.0 7930.0 7934.6 8020.6 8046.6 8068.3 8076.9 7997.5 8003.8 8064.9 8082.1 8101.8 7876.7 7989.0 8007.2 8017.2 8017.3 7837.8 7994.9 8012.1 8037.8 8038.2 36986.3 37462.6 37532.0 37608.2 37662.6 2402.3 2788.9 2957.8 2975.9 3202.3 27033.5 31128.0 32037.4 32622.5 33229.0 3108.8 3120.1 3136.5 3155.1 3170.7 177282.0 178743.0 178801.0 179146.0 180052.2 53051.7 53145.5 53164.0 53575.2 54295.0 66875.7 67874.0 68107.3 68127.0 68316.3 13469.5 13600.5 13674.8 13702.2 13760.5 10652.7 10738.4 10752.0 10847.9 10848.1 19402.9 19861.8 19885.4 19912.0 19928.6 7386.5 7523.3 7568.0 7672.7 7743.4 831.5 859.3 887.5 998.2 1000.0 27929.4 28143.9 28150.7 28346.1 28392.7 989.1 1028.4 1055.4 1070.4 1100.5 14792.3 14930.0 15448.4 16489.8 16774.4 304153.0 310575.0 311296.0 313165.0 313511.0 49510.0 49989.3 50159.7 50164.0 50380.2 1205.8 1242.5 1245.8 1245.9 1251.1 28187.9 28523.4 29156.9 29167.7 29312.3 43614.3 45115.8 45124.1 45128.1 45175.8 255128.0 255402.0 257075.0 257827.0 258359.0 39692.8 43008.0 43476.6 43947.8 44487.8 39189.8 39441.6 39615.0 40132.6 40380.4 33203.5 33615.5 33656.0 34142.8 37632.8 32941.8 33146.5 33228.0 33568.0 33779.0 33907.3 34429.8 34483.0 34562.0 38806.0 28318.6 28639.7 30154.5 30214.1 30550.7 23988.9 24170.0 25377.7 26436.6 28029.3 13:14:16 INFO - 2015-12-18 13:14:16,165 DEBUG : BROWSER_OUTPUT: 13:14:16 INFO - 2015-12-18 13:14:16,165 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:26485.20 Median:436.28 stddev:83568.51 (19154.7%) stddev-sans-first:83779.84 13:14:16 INFO - 2015-12-18 13:14:16,165 DEBUG : BROWSER_OUTPUT: Values: 318.7 320.4 323.4 323.4 327.2 14656.9 15225.8 15306.1 15817.7 16500.5 10210.2 10433.1 13446.8 14847.8 14877.1 4543.1 4661.7 4668.2 4856.7 5087.8 4101.8 5158.8 5171.7 5192.5 5194.6 4886.6 5028.5 5053.0 5092.4 5346.7 4967.5 4972.4 4996.2 4996.9 5007.9 5073.5 5092.7 5093.1 5097.2 5099.1 11697.4 11845.3 14818.3 19265.9 19420.3 1762.7 1812.5 1843.3 1880.9 1917.5 8848.5 9072.0 12350.0 12390.9 12639.4 1755.1 1773.1 1781.0 1800.0 1812.9 54232.5 57456.0 57531.7 57535.4 57675.4 9607.5 9765.9 9766.3 9808.4 9883.5 12521.2 12521.2 12524.8 12560.1 12578.3 7156.3 7159.2 7247.3 7341.5 7431.6 6865.8 6875.2 6901.3 6945.2 6968.5 4106.2 4110.3 4165.9 4180.8 4222.6 1712.7 1773.8 1775.1 1784.2 1801.0 352.4 375.9 387.7 388.7 391.3 9551.6 9793.6 10118.4 10261.3 10286.9 371.9 378.9 386.0 386.9 391.6 5330.9 5493.1 5524.0 5573.7 5601.2 504354.0 508583.0 510496.0 515431.0 516027.0 69515.3 73065.3 73165.6 73203.3 73581.6 434.3 438.3 438.7 439.5 440.8 9715.8 9737.2 9851.6 9911.6 9946.1 32501.7 33569.5 34241.5 34382.1 34619.5 44278.3 44668.0 44684.8 44722.8 44914.6 8713.3 8878.9 8947.9 8949.9 8998.5 10559.6 10651.0 10675.2 14590.8 16442.7 10109.9 11476.3 13266.5 13309.7 13392.0 13364.8 13419.3 13709.5 13739.6 13905.6 13376.9 14071.6 14090.2 14159.7 14463.0 8989.6 9031.1 9071.5 9139.1 9180.7 9554.9 9648.4 10810.6 13640.6 13947.2 13:14:16 INFO - 2015-12-18 13:14:16,165 DEBUG : BROWSER_OUTPUT: 13:14:16 INFO - 2015-12-18 13:14:16,165 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5038.27 Median:6032.06 stddev:2557.79 (42.4%) stddev-sans-first:2551.09 13:14:16 INFO - 2015-12-18 13:14:16,165 DEBUG : BROWSER_OUTPUT: Values: 1493.3 1612.3 1691.9 1701.5 1726.1 6063.8 6117.4 6752.0 7388.9 7431.0 7030.5 7137.6 7186.6 7229.9 7235.7 2076.9 2105.5 2122.4 2125.5 2128.9 2096.3 2111.1 2130.5 2147.3 2158.4 2049.2 2055.8 2056.1 2057.1 2071.4 2068.4 2081.9 2084.2 2092.9 2094.9 2060.3 2089.7 2095.0 2096.8 2103.2 7104.5 7136.6 7186.6 7205.0 7221.2 4186.5 4277.4 4277.6 4294.3 4307.0 2742.1 2760.6 2820.1 2821.1 2841.9 850.7 1731.0 1731.9 1740.6 1753.6 9543.6 9746.8 9794.6 9820.0 9844.5 3849.4 3859.6 3903.2 3908.5 4014.6 8508.5 8524.8 8574.2 8606.0 8652.2 4303.4 4338.7 4341.6 4353.4 4361.7 3300.7 3379.4 3811.3 3912.2 3944.4 3485.7 3502.2 3512.4 3520.8 3558.2 1092.6 1107.1 1107.5 1112.6 1131.0 969.7 1050.9 1133.3 1163.3 1166.8 6531.8 6584.8 6590.0 6598.3 6603.7 1090.8 1146.3 1151.5 1160.7 1170.6 5358.7 5420.3 5421.2 5423.1 5437.0 8416.6 8567.2 8621.7 8674.6 8679.9 8568.9 8582.6 8595.9 8616.8 8677.8 6382.9 6730.3 6831.2 6858.3 6874.8 6806.7 6887.8 6892.7 6911.5 6929.1 7743.5 7764.0 7893.0 7899.2 7935.0 7932.8 8058.2 8087.8 8112.1 8245.1 5358.1 5491.0 6092.8 6233.0 6314.3 6659.7 6690.5 6692.7 6747.4 6771.6 6294.9 6398.9 6528.9 6532.5 6591.6 6188.3 6211.8 6220.0 6360.0 6372.6 6307.4 6338.7 6366.4 6372.9 6378.9 5911.8 5916.9 5977.3 5979.2 6038.5 5982.8 6030.6 6033.5 6038.0 6039.2 13:14:16 INFO - 2015-12-18 13:14:16,165 DEBUG : BROWSER_OUTPUT: 13:14:16 INFO - 2015-12-18 13:14:16,165 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4891.30 Median:5636.31 stddev:1464.55 (26.0%) stddev-sans-first:1427.50 13:14:16 INFO - 2015-12-18 13:14:16,167 DEBUG : BROWSER_OUTPUT: Values: 297.8 301.5 306.4 307.2 307.6 5616.4 5637.2 5722.4 5744.9 5745.5 5596.4 5598.8 5604.8 5609.7 5635.4 4090.3 4326.6 4331.0 4335.2 4336.6 4178.5 4232.2 4342.9 4358.7 4362.4 4255.9 4289.0 4291.7 4298.1 4306.8 4269.9 4292.9 4297.6 4313.4 4313.8 3774.0 3779.8 3787.1 3789.3 3794.8 5242.1 5431.4 5469.1 5486.0 5488.2 4884.8 4903.7 4908.2 4910.2 4916.6 5913.2 5920.8 5926.4 5937.0 5937.3 4009.2 4011.1 4013.7 4023.4 4030.8 5995.9 6223.7 6227.3 6238.5 6242.6 4108.7 4110.5 4146.7 4147.3 4154.4 4204.5 4212.7 4214.0 4215.3 4219.5 4847.1 4851.9 4854.2 4855.2 4863.3 5684.8 5825.6 5871.4 5912.2 5917.4 1458.0 1474.3 1474.9 1476.2 1476.6 958.1 971.3 976.6 981.2 991.7 5145.1 5146.1 5150.4 5154.4 5175.0 6034.2 6140.0 6212.6 6215.0 6239.0 5169.6 5180.8 5200.1 5204.4 5209.9 3289.1 3340.2 3341.9 3342.4 3346.0 5678.2 5740.0 5747.5 5802.1 5811.1 6280.7 6303.6 6361.3 6362.3 6388.2 5698.5 5736.2 5777.9 5795.6 5807.4 6045.8 6159.4 6170.0 6170.1 6217.3 6039.6 6043.8 6046.2 6067.5 6068.6 5787.0 5790.0 5797.7 5800.4 5820.6 4089.3 4125.8 4127.6 4130.7 4145.4 5992.0 6157.6 6158.5 6191.6 6200.7 5993.2 5995.7 5998.4 6000.6 6013.7 5986.9 5990.6 6001.5 6002.5 6011.0 5642.5 5785.4 5821.5 5821.9 5866.5 5968.5 5969.7 5971.3 6012.2 6021.9 5718.4 5851.9 5854.8 5862.4 5874.0 13:14:16 INFO - 2015-12-18 13:14:16,167 DEBUG : BROWSER_OUTPUT: 13:14:16 INFO - 2015-12-18 13:14:16,167 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:11997.80 Median:43644.28 stddev:13325.01 (30.5%) stddev-sans-first:13335.33 13:14:16 INFO - 2015-12-18 13:14:16,167 DEBUG : BROWSER_OUTPUT: Values: 691.1 728.9 760.8 800.2 802.9 10840.7 11171.1 11202.8 11213.6 11215.2 11628.5 11732.4 11733.5 11741.6 11787.0 758.8 770.8 771.2 773.9 774.3 620.9 623.5 767.7 778.6 783.5 819.1 823.0 826.9 831.0 831.8 815.4 817.4 817.5 818.3 819.7 613.5 613.9 614.3 614.3 614.9 11330.3 11378.5 11408.8 11410.0 11438.3 11088.3 11088.4 11161.8 11219.7 11412.2 9474.9 9516.0 9566.4 9652.4 9788.0 3215.7 3301.3 3313.0 3324.3 3324.7 38752.6 38897.2 38988.6 39032.0 39223.2 9044.9 9129.1 9247.3 9443.4 9566.8 16844.2 16876.2 16992.3 16998.7 17008.7 6164.8 6402.2 6481.6 6491.5 6740.4 3551.6 3570.3 3575.5 3583.6 3592.7 2622.2 2665.5 2670.9 2675.2 2680.2 464.4 466.2 466.8 475.2 478.5 559.4 562.1 579.7 586.8 590.7 7245.8 7246.9 7526.8 7600.1 7616.6 465.5 501.8 574.2 586.6 594.6 3734.2 3864.2 3881.7 3928.5 3952.5 46840.0 47314.5 47329.1 47406.5 49448.5 43022.6 43601.8 43610.6 43678.0 43970.6 39452.8 39558.8 39914.6 40600.0 40876.4 7889.2 8011.0 8152.9 8216.0 8234.3 29752.4 30133.2 30604.7 30728.2 30850.4 35835.3 36245.5 36379.3 38202.9 39855.8 9734.7 10007.4 10032.6 10200.5 10216.7 11161.3 11164.8 11203.4 11210.8 11278.0 10146.1 10302.9 10765.5 10981.6 11067.8 9984.5 10078.5 10144.1 10559.8 10641.8 10468.5 10473.8 10532.5 10671.9 10683.0 9268.6 9276.8 9280.1 9477.9 9628.3 8799.4 8921.3 9081.9 9085.1 9116.2 13:14:16 INFO - 2015-12-18 13:14:16,167 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 13:14:16 INFO - 2015-12-18 13:14:16,167 DEBUG : BROWSER_OUTPUT: 13:14:16 INFO - 2015-12-18 13:14:16,866 INFO : Browser exited with error code: 0 13:14:16 INFO - 2015-12-18 13:14:16,887 INFO : Completed test dromaeo_css (00:18:34) 13:14:16 INFO - 2015-12-18 13:14:16,887 INFO : Starting test kraken 13:14:16 INFO - 2015-12-18 13:14:16,887 DEBUG : operating with platform_type : linux_ 13:14:16 INFO - 2015-12-18 13:14:16,887 INFO : Initialising browser for kraken test... 13:14:16 INFO - 2015-12-18 13:14:16,893 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpv3V_aq/profile http://localhost:57549/getInfo.html 13:14:21 INFO - 2015-12-18 13:14:21,438 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 13:14:21 INFO - 2015-12-18 13:14:21,439 DEBUG : BROWSER_OUTPUT: colorDepth:24 13:14:21 INFO - 2015-12-18 13:14:21,439 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 13:14:21 INFO - 2015-12-18 13:14:21,439 DEBUG : BROWSER_OUTPUT: __metrics 13:14:21 INFO - 2015-12-18 13:14:21,555 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] 13:14:22 INFO - 2015-12-18 13:14:22,510 INFO : Browser initialized. 13:14:22 INFO - 2015-12-18 13:14:22,510 INFO : Running cycle 1/1 for kraken test... 13:14:22 INFO - 2015-12-18 13:14:22,511 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpv3V_aq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 13:14:34 INFO - 2015-12-18 13:14:34,499 DEBUG : BROWSER_OUTPUT: RSS: Main: 151240704 13:14:34 INFO - 2015-12-18 13:14:34,499 DEBUG : BROWSER_OUTPUT: 13:14:37 INFO - 2015-12-18 13:14:37,067 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57549/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57549/tests/kraken/driver.html?testName=audio-beat-detection) 13:14:37 INFO - 2015-12-18 13:14:37,680 DEBUG : BROWSER_OUTPUT: RSS: Main: 151805952 13:14:37 INFO - 2015-12-18 13:14:37,681 DEBUG : BROWSER_OUTPUT: 13:14:40 INFO - 2015-12-18 13:14:40,418 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57549/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57549/tests/kraken/driver.html?testName=audio-dft) 13:14:41 INFO - 2015-12-18 13:14:41,019 DEBUG : BROWSER_OUTPUT: RSS: Main: 152272896 13:14:41 INFO - 2015-12-18 13:14:41,019 DEBUG : BROWSER_OUTPUT: 13:14:43 INFO - 2015-12-18 13:14:43,890 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57549/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57549/tests/kraken/driver.html?testName=audio-fft) 13:14:44 INFO - 2015-12-18 13:14:44,492 DEBUG : BROWSER_OUTPUT: RSS: Main: 146436096 13:14:44 INFO - 2015-12-18 13:14:44,492 DEBUG : BROWSER_OUTPUT: 13:14:46 INFO - 2015-12-18 13:14:46,626 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57549/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57549/tests/kraken/driver.html?testName=audio-oscillator) 13:14:47 INFO - 2015-12-18 13:14:47,207 DEBUG : BROWSER_OUTPUT: RSS: Main: 146391040 13:14:47 INFO - 2015-12-18 13:14:47,207 DEBUG : BROWSER_OUTPUT: 13:14:49 INFO - 2015-12-18 13:14:49,466 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57549/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57549/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:14:50 INFO - 2015-12-18 13:14:50,047 DEBUG : BROWSER_OUTPUT: RSS: Main: 147148800 13:14:50 INFO - 2015-12-18 13:14:50,047 DEBUG : BROWSER_OUTPUT: 13:14:53 INFO - 2015-12-18 13:14:53,092 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57549/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57549/tests/kraken/driver.html?testName=imaging-darkroom) 13:14:53 INFO - 2015-12-18 13:14:53,693 DEBUG : BROWSER_OUTPUT: RSS: Main: 147374080 13:14:53 INFO - 2015-12-18 13:14:53,694 DEBUG : BROWSER_OUTPUT: 13:14:57 INFO - 2015-12-18 13:14:57,148 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57549/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57549/tests/kraken/driver.html?testName=imaging-desaturate) 13:14:57 INFO - 2015-12-18 13:14:57,749 DEBUG : BROWSER_OUTPUT: RSS: Main: 148430848 13:14:57 INFO - 2015-12-18 13:14:57,750 DEBUG : BROWSER_OUTPUT: 13:15:00 INFO - 2015-12-18 13:15:00,649 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57549/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57549/tests/kraken/driver.html?testName=json-parse-financial) 13:15:01 INFO - 2015-12-18 13:15:01,251 DEBUG : BROWSER_OUTPUT: RSS: Main: 148746240 13:15:01 INFO - 2015-12-18 13:15:01,251 DEBUG : BROWSER_OUTPUT: 13:15:03 INFO - 2015-12-18 13:15:03,053 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57549/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57549/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:15:03 INFO - 2015-12-18 13:15:03,640 DEBUG : BROWSER_OUTPUT: RSS: Main: 148340736 13:15:03 INFO - 2015-12-18 13:15:03,640 DEBUG : BROWSER_OUTPUT: 13:15:05 INFO - 2015-12-18 13:15:05,533 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57549/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57549/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:15:06 INFO - 2015-12-18 13:15:06,140 DEBUG : BROWSER_OUTPUT: RSS: Main: 149405696 13:15:06 INFO - 2015-12-18 13:15:06,141 DEBUG : BROWSER_OUTPUT: 13:15:08 INFO - 2015-12-18 13:15:08,333 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57549/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57549/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:15:08 INFO - 2015-12-18 13:15:08,916 DEBUG : BROWSER_OUTPUT: RSS: Main: 148926464 13:15:08 INFO - 2015-12-18 13:15:08,916 DEBUG : BROWSER_OUTPUT: 13:15:11 INFO - 2015-12-18 13:15:11,492 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57549/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57549/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:15:12 INFO - 2015-12-18 13:15:12,105 DEBUG : BROWSER_OUTPUT: RSS: Main: 149200896 13:15:12 INFO - 2015-12-18 13:15:12,105 DEBUG : BROWSER_OUTPUT: 13:15:14 INFO - 2015-12-18 13:15:14,713 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57549/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57549/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:15:15 INFO - 2015-12-18 13:15:15,306 DEBUG : BROWSER_OUTPUT: RSS: Main: 148209664 13:15:15 INFO - 2015-12-18 13:15:15,306 DEBUG : BROWSER_OUTPUT: 13:15:17 INFO - 2015-12-18 13:15:17,081 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57549/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57549/tests/kraken/driver.html?testName=ai-astar) 13:15:17 INFO - 2015-12-18 13:15:17,331 DEBUG : BROWSER_OUTPUT: RSS: Main: 148844544 13:15:17 INFO - 2015-12-18 13:15:17,331 DEBUG : BROWSER_OUTPUT: 13:15:17 INFO - 2015-12-18 13:15:17,335 DEBUG : BROWSER_OUTPUT: __start_tp_report 13:15:17 INFO - 2015-12-18 13:15:17,335 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 13:15:17 INFO - 2015-12-18 13:15:17,335 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 13:15:17 INFO - 2015-12-18 13:15:17,335 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 13:15:17 INFO - 2015-12-18 13:15:17,335 DEBUG : BROWSER_OUTPUT: |0;ai-astar;114;108;112;111;112;125;113;113;112;111 13:15:17 INFO - 2015-12-18 13:15:17,336 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;152;132;152;169;131;160;157;129;167;140 13:15:17 INFO - 2015-12-18 13:15:17,336 DEBUG : BROWSER_OUTPUT: |2;audio-dft;162;164;163;160;160;164;172;162;166;162 13:15:17 INFO - 2015-12-18 13:15:17,336 DEBUG : BROWSER_OUTPUT: |3;audio-fft;73;72;74;72;73;133;73;79;72;74 13:15:17 INFO - 2015-12-18 13:15:17,336 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;101;94;129;94;96;95;139;95;94;124 13:15:17 INFO - 2015-12-18 13:15:17,336 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;111;110;110;111;110;110;109;110;111;110 13:15:17 INFO - 2015-12-18 13:15:17,336 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;148;148;147;149;149;148;148;149;147;148 13:15:17 INFO - 2015-12-18 13:15:17,336 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;97;96;97;96;96;96;97;99;96;96 13:15:17 INFO - 2015-12-18 13:15:17,336 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;75;74;75;73;76;75;78;77;75;76 13:15:17 INFO - 2015-12-18 13:15:17,337 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;68;69;68;69;67;68;67;68;69;69 13:15:17 INFO - 2015-12-18 13:15:17,337 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;83;92;84;83;83;83;84;84;84;100 13:15:17 INFO - 2015-12-18 13:15:17,337 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;129;128;122;125;129;125;131;128;119;124 13:15:17 INFO - 2015-12-18 13:15:17,337 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;134;157;148;151;146;134;146;152;152;146 13:15:17 INFO - 2015-12-18 13:15:17,337 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;70;69;68;69;72;68;69;71;70;75 13:15:17 INFO - 2015-12-18 13:15:17,337 DEBUG : BROWSER_OUTPUT: __end_tp_report 13:15:17 INFO - 2015-12-18 13:15:17,337 DEBUG : BROWSER_OUTPUT: __start_cc_report 13:15:17 INFO - 2015-12-18 13:15:17,338 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,804 13:15:17 INFO - 2015-12-18 13:15:17,338 DEBUG : BROWSER_OUTPUT: __end_cc_report 13:15:17 INFO - 2015-12-18 13:15:17,338 DEBUG : BROWSER_OUTPUT: __startTimestamp1450473317333__endTimestamp 13:15:17 INFO - 2015-12-18 13:15:17,338 DEBUG : BROWSER_OUTPUT: 13:15:17 INFO - 2015-12-18 13:15:17,341 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 13:15:17 INFO - 2015-12-18 13:15:17,341 DEBUG : BROWSER_OUTPUT: Number of tests: 14 13:15:17 INFO - 2015-12-18 13:15:17,341 DEBUG : BROWSER_OUTPUT: 13:15:17 INFO - 2015-12-18 13:15:17,341 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:113.10 Median:112.50 stddev:4.48 (4.0%) stddev-sans-first:4.74 13:15:17 INFO - 2015-12-18 13:15:17,341 DEBUG : BROWSER_OUTPUT: Values: 114.0 108.0 112.0 111.0 112.0 125.0 113.0 113.0 112.0 111.0 13:15:17 INFO - 2015-12-18 13:15:17,341 DEBUG : BROWSER_OUTPUT: 13:15:17 INFO - 2015-12-18 13:15:17,341 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:148.90 Median:154.50 stddev:14.98 (9.7%) stddev-sans-first:15.85 13:15:17 INFO - 2015-12-18 13:15:17,342 DEBUG : BROWSER_OUTPUT: Values: 152.0 132.0 152.0 169.0 131.0 160.0 157.0 129.0 167.0 140.0 13:15:17 INFO - 2015-12-18 13:15:17,342 DEBUG : BROWSER_OUTPUT: 13:15:17 INFO - 2015-12-18 13:15:17,342 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:163.50 Median:163.50 stddev:3.50 (2.1%) stddev-sans-first:3.67 13:15:17 INFO - 2015-12-18 13:15:17,342 DEBUG : BROWSER_OUTPUT: Values: 162.0 164.0 163.0 160.0 160.0 164.0 172.0 162.0 166.0 162.0 13:15:17 INFO - 2015-12-18 13:15:17,342 DEBUG : BROWSER_OUTPUT: 13:15:17 INFO - 2015-12-18 13:15:17,342 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:79.50 Median:73.00 stddev:18.91 (25.9%) stddev-sans-first:19.91 13:15:17 INFO - 2015-12-18 13:15:17,342 DEBUG : BROWSER_OUTPUT: Values: 73.0 72.0 74.0 72.0 73.0 133.0 73.0 79.0 72.0 74.0 13:15:17 INFO - 2015-12-18 13:15:17,342 DEBUG : BROWSER_OUTPUT: 13:15:17 INFO - 2015-12-18 13:15:17,342 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.10 Median:94.00 stddev:17.45 (18.6%) stddev-sans-first:18.41 13:15:17 INFO - 2015-12-18 13:15:17,342 DEBUG : BROWSER_OUTPUT: Values: 101.0 94.0 129.0 94.0 96.0 95.0 139.0 95.0 94.0 124.0 13:15:17 INFO - 2015-12-18 13:15:17,343 DEBUG : BROWSER_OUTPUT: 13:15:17 INFO - 2015-12-18 13:15:17,343 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:110.20 Median:110.00 stddev:0.63 (0.6%) stddev-sans-first:0.60 13:15:17 INFO - 2015-12-18 13:15:17,343 DEBUG : BROWSER_OUTPUT: Values: 111.0 110.0 110.0 111.0 110.0 110.0 109.0 110.0 111.0 110.0 13:15:17 INFO - 2015-12-18 13:15:17,343 DEBUG : BROWSER_OUTPUT: 13:15:17 INFO - 2015-12-18 13:15:17,343 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:148.10 Median:148.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 13:15:17 INFO - 2015-12-18 13:15:17,343 DEBUG : BROWSER_OUTPUT: Values: 148.0 148.0 147.0 149.0 149.0 148.0 148.0 149.0 147.0 148.0 13:15:17 INFO - 2015-12-18 13:15:17,343 DEBUG : BROWSER_OUTPUT: 13:15:17 INFO - 2015-12-18 13:15:17,343 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.60 Median:96.50 stddev:0.97 (1.0%) stddev-sans-first:1.01 13:15:17 INFO - 2015-12-18 13:15:17,343 DEBUG : BROWSER_OUTPUT: Values: 97.0 96.0 97.0 96.0 96.0 96.0 97.0 99.0 96.0 96.0 13:15:17 INFO - 2015-12-18 13:15:17,343 DEBUG : BROWSER_OUTPUT: 13:15:17 INFO - 2015-12-18 13:15:17,343 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:75.40 Median:75.50 stddev:1.43 (1.9%) stddev-sans-first:1.51 13:15:17 INFO - 2015-12-18 13:15:17,343 DEBUG : BROWSER_OUTPUT: Values: 75.0 74.0 75.0 73.0 76.0 75.0 78.0 77.0 75.0 76.0 13:15:17 INFO - 2015-12-18 13:15:17,343 DEBUG : BROWSER_OUTPUT: 13:15:17 INFO - 2015-12-18 13:15:17,343 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:68.20 Median:68.50 stddev:0.79 (1.2%) stddev-sans-first:0.83 13:15:17 INFO - 2015-12-18 13:15:17,343 DEBUG : BROWSER_OUTPUT: Values: 68.0 69.0 68.0 69.0 67.0 68.0 67.0 68.0 69.0 69.0 13:15:17 INFO - 2015-12-18 13:15:17,344 DEBUG : BROWSER_OUTPUT: 13:15:17 INFO - 2015-12-18 13:15:17,344 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:86.00 Median:84.00 stddev:5.62 (6.7%) stddev-sans-first:5.85 13:15:17 INFO - 2015-12-18 13:15:17,344 DEBUG : BROWSER_OUTPUT: Values: 83.0 92.0 84.0 83.0 83.0 83.0 84.0 84.0 84.0 100.0 13:15:17 INFO - 2015-12-18 13:15:17,344 DEBUG : BROWSER_OUTPUT: 13:15:17 INFO - 2015-12-18 13:15:17,344 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:126.00 Median:128.00 stddev:3.68 (2.9%) stddev-sans-first:3.74 13:15:17 INFO - 2015-12-18 13:15:17,344 DEBUG : BROWSER_OUTPUT: Values: 129.0 128.0 122.0 125.0 129.0 125.0 131.0 128.0 119.0 124.0 13:15:17 INFO - 2015-12-18 13:15:17,344 DEBUG : BROWSER_OUTPUT: 13:15:17 INFO - 2015-12-18 13:15:17,344 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:146.60 Median:149.50 stddev:7.50 (5.0%) stddev-sans-first:6.42 13:15:17 INFO - 2015-12-18 13:15:17,345 DEBUG : BROWSER_OUTPUT: Values: 134.0 157.0 148.0 151.0 146.0 134.0 146.0 152.0 152.0 146.0 13:15:17 INFO - 2015-12-18 13:15:17,345 DEBUG : BROWSER_OUTPUT: 13:15:17 INFO - 2015-12-18 13:15:17,345 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.10 Median:70.00 stddev:2.13 (3.0%) stddev-sans-first:2.26 13:15:17 INFO - 2015-12-18 13:15:17,345 DEBUG : BROWSER_OUTPUT: Values: 70.0 69.0 68.0 69.0 72.0 68.0 69.0 71.0 70.0 75.0 13:15:17 INFO - 2015-12-18 13:15:17,345 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 13:15:17 INFO - 2015-12-18 13:15:17,345 DEBUG : BROWSER_OUTPUT: 13:15:18 INFO - 2015-12-18 13:15:18,266 INFO : Browser exited with error code: 0 13:15:18 INFO - 2015-12-18 13:15:18,314 INFO : Completed test kraken (00:01:01) 13:15:18 INFO - 2015-12-18 13:15:18,314 INFO : Starting test v8_7 13:15:18 INFO - 2015-12-18 13:15:18,315 DEBUG : operating with platform_type : linux_ 13:15:18 INFO - 2015-12-18 13:15:18,315 INFO : Initialising browser for v8_7 test... 13:15:18 INFO - 2015-12-18 13:15:18,321 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpikoWxC/profile http://localhost:57549/getInfo.html 13:15:22 INFO - 2015-12-18 13:15:22,890 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 13:15:22 INFO - 2015-12-18 13:15:22,890 DEBUG : BROWSER_OUTPUT: colorDepth:24 13:15:22 INFO - 2015-12-18 13:15:22,890 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 13:15:22 INFO - 2015-12-18 13:15:22,890 DEBUG : BROWSER_OUTPUT: __metrics 13:15:22 INFO - 2015-12-18 13:15:22,999 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] 13:15:23 INFO - 2015-12-18 13:15:23,946 INFO : Browser initialized. 13:15:23 INFO - 2015-12-18 13:15:23,946 INFO : Running cycle 1/1 for v8_7 test... 13:15:23 INFO - 2015-12-18 13:15:23,946 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpikoWxC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 13:15:35 INFO - 2015-12-18 13:15:35,903 DEBUG : BROWSER_OUTPUT: RSS: Main: 151252992 13:15:35 INFO - 2015-12-18 13:15:35,903 DEBUG : BROWSER_OUTPUT: 13:15:57 INFO - 2015-12-18 13:15:57,361 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57549/tests/v8_7/run.html (next: http://localhost:57549/tests/v8_7/run.html) 13:15:57 INFO - 2015-12-18 13:15:57,607 DEBUG : BROWSER_OUTPUT: RSS: Main: 145514496 13:15:57 INFO - 2015-12-18 13:15:57,607 DEBUG : BROWSER_OUTPUT: 13:15:57 INFO - 2015-12-18 13:15:57,607 DEBUG : BROWSER_OUTPUT: __start_tp_report 13:15:57 INFO - 2015-12-18 13:15:57,607 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 13:15:57 INFO - 2015-12-18 13:15:57,607 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 13:15:57 INFO - 2015-12-18 13:15:57,607 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 13:15:57 INFO - 2015-12-18 13:15:57,608 DEBUG : BROWSER_OUTPUT: |0;Richards;167.7008217340265 13:15:57 INFO - 2015-12-18 13:15:57,608 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;186.46280067126608 13:15:57 INFO - 2015-12-18 13:15:57,608 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.00945232137894 13:15:57 INFO - 2015-12-18 13:15:57,608 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5251.308900523561 13:15:57 INFO - 2015-12-18 13:15:57,608 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1055.9662090813094 13:15:57 INFO - 2015-12-18 13:15:57,608 DEBUG : BROWSER_OUTPUT: |5;Earley;719.626168224299 13:15:57 INFO - 2015-12-18 13:15:57,608 DEBUG : BROWSER_OUTPUT: |6;Boyer;12207.317073170732 13:15:57 INFO - 2015-12-18 13:15:57,608 DEBUG : BROWSER_OUTPUT: |7;RegExp;26179.48717948718 13:15:57 INFO - 2015-12-18 13:15:57,609 DEBUG : BROWSER_OUTPUT: |8;Splay;595.2522255192879 13:15:57 INFO - 2015-12-18 13:15:57,609 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4826.923076923077 13:15:57 INFO - 2015-12-18 13:15:57,609 DEBUG : BROWSER_OUTPUT: __end_tp_report 13:15:57 INFO - 2015-12-18 13:15:57,609 DEBUG : BROWSER_OUTPUT: __startTimestamp1450473357606__endTimestamp 13:15:57 INFO - 2015-12-18 13:15:57,610 DEBUG : BROWSER_OUTPUT: 13:15:57 INFO - 2015-12-18 13:15:57,610 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 13:15:57 INFO - 2015-12-18 13:15:57,610 DEBUG : BROWSER_OUTPUT: Number of tests: 10 13:15:57 INFO - 2015-12-18 13:15:57,610 DEBUG : BROWSER_OUTPUT: 13:15:57 INFO - 2015-12-18 13:15:57,610 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:167.70 Median:167.70 stddev:0.00 (0.0%) 13:15:57 INFO - 2015-12-18 13:15:57,610 DEBUG : BROWSER_OUTPUT: Values: 167.7 13:15:57 INFO - 2015-12-18 13:15:57,610 DEBUG : BROWSER_OUTPUT: 13:15:57 INFO - 2015-12-18 13:15:57,610 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:186.46 Median:186.46 stddev:0.00 (0.0%) 13:15:57 INFO - 2015-12-18 13:15:57,610 DEBUG : BROWSER_OUTPUT: Values: 186.5 13:15:57 INFO - 2015-12-18 13:15:57,610 DEBUG : BROWSER_OUTPUT: 13:15:57 INFO - 2015-12-18 13:15:57,610 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.01 Median:278.01 stddev:0.00 (0.0%) 13:15:57 INFO - 2015-12-18 13:15:57,610 DEBUG : BROWSER_OUTPUT: Values: 278.0 13:15:57 INFO - 2015-12-18 13:15:57,611 DEBUG : BROWSER_OUTPUT: 13:15:57 INFO - 2015-12-18 13:15:57,612 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5251.31 Median:5251.31 stddev:0.00 (0.0%) 13:15:57 INFO - 2015-12-18 13:15:57,612 DEBUG : BROWSER_OUTPUT: Values: 5251.3 13:15:57 INFO - 2015-12-18 13:15:57,612 DEBUG : BROWSER_OUTPUT: 13:15:57 INFO - 2015-12-18 13:15:57,612 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1055.97 Median:1055.97 stddev:0.00 (0.0%) 13:15:57 INFO - 2015-12-18 13:15:57,612 DEBUG : BROWSER_OUTPUT: Values: 1056.0 13:15:57 INFO - 2015-12-18 13:15:57,612 DEBUG : BROWSER_OUTPUT: 13:15:57 INFO - 2015-12-18 13:15:57,612 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:719.63 Median:719.63 stddev:0.00 (0.0%) 13:15:57 INFO - 2015-12-18 13:15:57,612 DEBUG : BROWSER_OUTPUT: Values: 719.6 13:15:57 INFO - 2015-12-18 13:15:57,612 DEBUG : BROWSER_OUTPUT: 13:15:57 INFO - 2015-12-18 13:15:57,612 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12207.32 Median:12207.32 stddev:0.00 (0.0%) 13:15:57 INFO - 2015-12-18 13:15:57,613 DEBUG : BROWSER_OUTPUT: Values: 12207.3 13:15:57 INFO - 2015-12-18 13:15:57,613 DEBUG : BROWSER_OUTPUT: 13:15:57 INFO - 2015-12-18 13:15:57,613 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26179.49 Median:26179.49 stddev:0.00 (0.0%) 13:15:57 INFO - 2015-12-18 13:15:57,613 DEBUG : BROWSER_OUTPUT: Values: 26179.5 13:15:57 INFO - 2015-12-18 13:15:57,613 DEBUG : BROWSER_OUTPUT: 13:15:57 INFO - 2015-12-18 13:15:57,613 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:595.25 Median:595.25 stddev:0.00 (0.0%) 13:15:57 INFO - 2015-12-18 13:15:57,613 DEBUG : BROWSER_OUTPUT: Values: 595.3 13:15:57 INFO - 2015-12-18 13:15:57,614 DEBUG : BROWSER_OUTPUT: 13:15:57 INFO - 2015-12-18 13:15:57,614 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4826.92 Median:4826.92 stddev:0.00 (0.0%) 13:15:57 INFO - 2015-12-18 13:15:57,614 DEBUG : BROWSER_OUTPUT: Values: 4826.9 13:15:57 INFO - 2015-12-18 13:15:57,614 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 13:15:57 INFO - 2015-12-18 13:15:57,614 DEBUG : BROWSER_OUTPUT: 13:15:58 INFO - 2015-12-18 13:15:58,526 INFO : Browser exited with error code: 0 13:15:58 INFO - 2015-12-18 13:15:58,538 INFO : Completed test v8_7 (00:00:40) 13:15:58 INFO - 2015-12-18 13:15:58,625 INFO : Completed test suite (00:20:15) 13:15:58 INFO - 2015-12-18 13:15:58,625 DEBUG : Working with test: dromaeo_css 13:15:58 INFO - 2015-12-18 13:15:58,626 DEBUG : Generating results file: dromaeo_css 13:15:58 INFO - 2015-12-18 13:15:58,627 DEBUG : Working with test: kraken 13:15:58 INFO - 2015-12-18 13:15:58,627 DEBUG : Generating results file: kraken 13:15:58 INFO - 2015-12-18 13:15:58,627 INFO : javascript benchmark 13:15:58 INFO - 2015-12-18 13:15:58,627 DEBUG : Working with test: v8_7 13:15:58 INFO - 2015-12-18 13:15:58,627 DEBUG : Generating results file: v8_7 13:15:58 INFO - 2015-12-18 13:15:58,628 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 13:15:58 INFO - 2015-12-18 13:15:58,686 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,132,43]] 13:15:58 INFO - 2015-12-18 13:15:58,686 DEBUG : process_Request line: dromaeo_css 6324.64 graph.html#tests=[[72,132,43]] 13:15:58 INFO - 2015-12-18 13:15:58,686 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 13:15:58 INFO - 2015-12-18 13:15:58,717 DEBUG : process_Request line: kraken 1538.30 graph.html#tests=[[232,132,43]] 13:15:58 INFO - 2015-12-18 13:15:58,717 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 13:15:58 INFO - 2015-12-18 13:15:58,747 DEBUG : process_Request line: v8_7 21339.80 graph.html#tests=[[230,132,43]] 13:15:58 INFO - 2015-12-18 13:15:58,748 INFO : javascript benchmark 13:15:58 INFO - 2015-12-18 13:15:58,750 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2669.7330266973304, 2747.4642444723263, 2776.5557510798276, 2784.1925841505977, 2788.609594656748, 4697.718136911788, 4807.519248075195, 4844.733539655317, 4852.369192005043, 4916.2379831126145, 4771.284307353899, 4797.496262892399, 4817.084753896757, 4904.117258893398, 4920.778564964577, 4504.414426124599, 4517.954820451786, 4539.137563862855, 4601.378813860119, 4645.001324715186, 135.2523225936358, 4231.301835197189, 4327.913441731163, 4416.514183439819, 4426.800793964263, 4406.405135306741, 4446.688731788776, 4485.215087359699, 4506.887327816831, 4516.277395616702, 4483.6861419700635, 4492.707973981714, 4492.955070449287, 4497.415336006331, 4505.684602077856, 4267.573242675739, 4342.544032876565, 4357.7385356878685, 4358.5205627380965, 4464.933026004613, 4794.137055330039, 4798.448178459487, 4824.34871292378, 4829.2997515360075, 4878.634102442295, 5235.528802407802, 5238.580913526908, 5259.631825772198, 5260.947390526084, 5262.684238945637, 4984.675996060245, 5128.640995130305, 5157.896842063138, 5273.077211488013, 5283.656562323436, 4771.952280477186, 4883.682560633583, 4929.186684197068, 4939.629527785394, 4979.6265280104135, 5433.701146436984, 5436.130219164994, 5438.184272359099, 5440.972795135999, 5444.0, 4766.785494652749, 4904.950950490521, 4931.679440836334, 5023.748812559357, 5052.9242061369105, 5216.321878155892, 5281.102212623863, 5283.418823929364, 5289.788408463618, 5339.839804805862, 4851.878703032382, 5210.7915683373, 5223.52988231061, 5229.869253268699, 5231.032259032015, 5024.0, 5081.949180508222, 5150.871228219249, 5161.045206636784, 5171.456997015334, 4318.114786468766, 4319.416878721395, 4357.760323182255, 4363.585459381354, 4390.780460976938, 3681.1349332906743, 3792.3173828711097, 3833.5783063862955, 3843.1737176507186, 3887.319719049155, 4385.714928529699, 4476.261416866246, 4489.079738653568, 4536.364908912755, 4546.727196368229, 5209.062368773657, 5215.322008138917, 5225.869353266123, 5226.425093239741, 5243.161094224982, 4322.589354011364, 4390.05613793036, 4399.5820397062225, 4444.644428445717, 4512.954870451254, 4627.838025669085, 4631.861044168681, 4633.2818413146015, 4650.279206722963, 4686.718796872198, 4931.4000779431935, 4937.407511098691, 4951.801927922843, 4952.059108769322, 4999.825006124768, 4991.226359914219, 5108.259302401206, 5133.614978876674, 5248.2390053442805, 5263.631545791911, 4892.608591312757, 4931.802727890988, 4964.67729597575, 4992.600591952708, 5037.798488060436, 5229.869253268699, 5238.76425560843, 5248.08158572256, 5250.868728281824, 5258.50044245796, 4914.754262286943, 5029.572486338702, 5101.69389836611, 5174.197999310113, 5204.843854684365, 5040.344755181803, 5094.694318340911, 5100.795968161232, 5116.283720279238, 5136.717480538607, 4875.731834749123, 5082.0, 5086.54221120101, 5090.338256026693, 5090.923636145535, 4842.249451335048, 4871.95128048715, 4908.484609115992, 4936.7284799336385, 4967.428745694291, 5127.615428842777, 5141.665791723526, 5157.174852023658, 5190.870228244324, 5217.9478205217465, 4695.506971768052, 4827.324174615486, 5000.77496512665, 5026.095302845377, 5053.444121146597], "unit": "score", "name": "dojo.html", "value": 4729.990074016531}, {"lowerIsBetter": false, "replicates": [566.0603398358726, 571.0292502745334, 575.9107338362555, 577.7717801468417, 581.1718301420475, 34823.65176348239, 35575.0, 35698.28603427933, 35765.99999999997, 35825.64174358256, 39740.0, 40312.798436007855, 40430.59569404305, 40543.79728101363, 40546.39180412296, 7767.456278060524, 7893.329067029309, 7893.763187104396, 7922.0, 7929.960350198241, 7934.563599002053, 8020.558869262189, 8046.557439340834, 8068.314193293577, 8076.919230807675, 7997.480163789364, 8003.759887203364, 8064.919350806475, 8082.0705618854, 8101.837963240761, 7876.724314648988, 7988.9600551997155, 8007.159248278934, 8017.158198389144, 8017.278444939925, 7837.804054898616, 7994.920050799476, 8012.1186669466315, 8037.799055023613, 8038.155993620644, 36986.26027479462, 37462.62537374619, 37532.0, 37608.24783504315, 37662.62337376618, 2402.267308470916, 2788.888444462219, 2957.7781666374894, 2975.925601859949, 3202.343519578481, 27033.45933081347, 31128.0, 32037.359252814815, 32622.510662340082, 33229.0, 3108.751299896003, 3120.1419609607315, 3136.5138403547257, 3155.1165673611426, 3170.6987836155527, 177282.0, 178743.0, 178801.0, 179146.0, 180052.19686818964, 53051.73474132682, 53145.46854531483, 53164.0, 53575.19637205445, 54295.0, 66875.66562167158, 67874.0, 68107.3189268101, 68127.0, 68316.31683683199, 13469.461221551224, 13600.455981760817, 13674.794878076837, 13702.246376449391, 13760.518381856788, 10652.733681657866, 10738.409387483607, 10752.0, 10847.891521084846, 10848.132149428027, 19402.902985485263, 19861.801381985995, 19885.403437896886, 19912.0, 19928.60142797165, 7386.482946193715, 7523.285287897641, 7567.962160189164, 7672.65473053714, 7743.380529557622, 831.4595512916652, 859.2867919626694, 887.5251740318898, 998.2463240253708, 999.9950000250097, 27929.44141117166, 28143.859280703877, 28150.71849281481, 28346.149615511567, 28392.71607283942, 989.0505115089451, 1028.4138041316528, 1055.350959160111, 1070.352436775755, 1100.5157730598512, 14792.260386980608, 14930.0, 15448.382064717285, 16489.752653710202, 16774.412895548598, 304153.0, 310575.0, 311296.0, 313165.0, 313511.0, 49510.0, 49989.25016124761, 50159.74920125376, 50164.0, 50380.244296335586, 1205.801042827941, 1242.4843689868603, 1245.7882160032816, 1245.9252444853337, 1251.055453132879, 28187.859060704566, 28523.42953140967, 29156.854215728785, 29167.708322917348, 29312.26719332034, 43614.345784813886, 45115.77442112769, 45124.0975180488, 45128.097438051715, 45175.77412112919, 255128.0, 255402.0, 257075.0, 257827.0, 258359.0, 39692.801535992134, 43008.0, 43476.565234347254, 43947.78026109849, 44487.77756111199, 39189.80405097956, 39441.60558394494, 39615.0, 40132.59867401289, 40380.3942940862, 33203.50194747129, 33615.495767563014, 33656.0, 34142.82928585341, 37632.81183594174, 32941.835290823394, 33146.50280245847, 33228.0, 33568.0, 33779.0, 33907.321853562295, 34429.827850861584, 34483.0, 34562.0, 38806.0, 28318.575221371284, 28639.713602863703, 30154.54768178523, 30214.093577192718, 30550.694493054783, 23988.88005559961, 24170.0, 25377.74622253754, 26436.603450947867, 28029.299267518476], "unit": "score", "name": "ext.html", "value": 17509.137870923518}, {"lowerIsBetter": false, "replicates": [318.69246177438765, 320.4424301715016, 323.38395356845217, 323.44529132537696, 327.21958129860275, 14656.853431465683, 15225.771613425808, 15306.081635101888, 15817.683646327096, 16500.504984850475, 10210.23423243256, 10433.113185379232, 13446.798298025538, 14847.77728334073, 14877.107373557594, 4543.136804007245, 4661.720296782187, 4668.229742092551, 4856.684315519497, 5087.796488140489, 4101.753894766323, 5158.767855446514, 5171.663841850286, 5192.532672059514, 5194.584433245332, 4886.560209581137, 5028.522290382408, 5052.9747351263195, 5092.388913330406, 5346.6791992480385, 4967.50324967504, 4972.353594032771, 4996.175631020896, 4996.900061998776, 5007.874803129915, 5073.518015788531, 5092.719900405476, 5093.083245015896, 5097.184450487941, 5099.056674515226, 11697.3566453845, 11845.348505832177, 14818.333175007043, 19265.903670481697, 19420.320288789964, 1762.691528982423, 1812.501562070428, 1843.3456123076382, 1880.9059547022594, 1917.5302050997595, 8848.46909185451, 9072.047435019294, 12350.0, 12390.876091239063, 12639.43122559487, 1755.0873545756263, 1773.1134432783608, 1780.97328540072, 1799.9910000449913, 1812.918418671163, 54232.45767542314, 57456.0, 57531.71234143844, 57535.42464575385, 57675.423245767844, 9607.471589062528, 9765.902340976641, 9766.316357855025, 9808.362456440309, 9883.456409897524, 12521.248725076342, 12521.248725076342, 12524.81212781809, 12560.057995650362, 12578.30819304947, 7156.284371562802, 7159.176694680074, 7247.347738703542, 7341.522801017917, 7431.591262480615, 6865.759698410531, 6875.174979002451, 6901.275366086589, 6945.201301850251, 6968.512204145763, 4106.240345536085, 4110.301248787713, 4165.854195103157, 4180.832766689298, 4222.556631553704, 1712.7430885367046, 1773.7782777152856, 1775.1301862087741, 1784.1525275494037, 1800.9909950450426, 352.43434287967955, 375.8853549667378, 387.7440889013238, 388.6773977598643, 391.2546598729425, 9551.617935282511, 9793.559289831976, 10118.443485608363, 10261.28171028021, 10286.897131028743, 371.9330520506335, 378.91663833957034, 385.9942100868489, 386.92648396804515, 391.6396914838356, 5330.92003619946, 5493.121100623881, 5524.0, 5573.665580065209, 5601.187827765033, 504354.0, 508583.0, 510496.0, 515431.0, 516027.0, 69515.3048469519, 73065.26934730585, 73165.6341718288, 73203.26796732072, 73581.6320918392, 434.28993595471, 438.27465544523864, 438.74991254984354, 439.45288116295416, 440.79282737113505, 9715.805683886425, 9737.22102231834, 9851.605935762489, 9911.603535858485, 9946.055124763136, 32501.674983249864, 33569.49645755364, 34241.48637770485, 34382.14044648904, 34619.4807077889, 44278.33582496201, 44668.0, 44684.776576116914, 44722.77638611786, 44914.55085449234, 8713.302935764998, 8878.911210887809, 8947.865782013405, 8949.865752013855, 8998.505082220541, 10559.577616895545, 10651.041406273473, 10675.199360048182, 14590.781138283146, 16442.671146576762, 10109.949450252701, 11476.254043487146, 13266.53567125146, 13309.66725831862, 13392.0, 13364.799528006894, 13419.261940593362, 13709.520166794115, 13739.587812365646, 13905.582832515041, 13376.866231337563, 14071.577852664437, 14090.22503762303, 14159.716805663624, 14463.0, 8989.640414383352, 9031.142041506046, 9071.455712657262, 9139.131782480654, 9180.6786762463, 9554.856677149988, 9648.372855764354, 10810.621628242974, 13640.590782276548, 13947.16317020982], "unit": "score", "name": "jquery.html", "value": 7346.890187644768}, {"lowerIsBetter": false, "replicates": [1493.2832240524542, 1612.2583611538691, 1691.9323227070913, 1701.5150682055616, 1726.0592976827636, 6063.818085457432, 6117.38826117388, 6751.966240168798, 7388.852222955545, 7431.033965584473, 7030.472714546417, 7137.60743159126, 7186.568805871651, 7229.927700722991, 7235.67439465224, 2076.9376918692465, 2105.5157313817754, 2122.384508492535, 2125.479257581887, 2128.9148434062618, 2096.308218287961, 2111.0711287033732, 2130.5419334843004, 2147.3450597567735, 2158.3632828315685, 2049.2008116834445, 2055.784142665021, 2056.0953180600486, 2057.1360028787944, 2071.4407110080347, 2068.369147410039, 2081.885496297703, 2084.155916853677, 2092.853500254983, 2094.9476263093393, 2060.2789023841688, 2089.6552068908763, 2094.98952505238, 2096.821770149539, 2103.24283258027, 7104.467164962597, 7136.571805691675, 7186.640667966581, 7205.027321311661, 7221.169565499929, 4186.476690413699, 4277.422547956017, 4277.572242775728, 4294.312909934427, 4307.0, 2742.081402730088, 2760.585912113179, 2820.111664825594, 2821.1113499247876, 2841.857907104657, 850.7022542110212, 1730.9567260818455, 1731.9047452390114, 1740.5648587853036, 1753.6142048749255, 9543.618255269781, 9746.756331091708, 9794.608215671364, 9820.0, 9844.458554779485, 3849.40334248192, 3859.6333348331864, 3903.2193561287854, 3908.4723562318723, 4014.558398576154, 8508.532030738368, 8524.786880327918, 8574.185452382013, 8606.0, 8652.221300082896, 4303.397524346594, 4338.674599404994, 4341.587549182823, 4353.434053573079, 4361.672874534423, 3300.66993300668, 3379.442392005341, 3811.2568049230135, 3912.237113762789, 3944.36890097583, 3485.6688614581576, 3502.2470168913296, 3512.3677738007405, 3520.8239588020497, 3558.181618227822, 1092.6066616018386, 1107.0977153619774, 1107.4739498738195, 1112.6495154026456, 1130.9660710178707, 969.7333233360741, 1050.9106725928382, 1133.252053644607, 1163.2729544034978, 1166.7724793665323, 6531.8367040823405, 6584.76953306632, 6590.0, 6598.274189839114, 6603.70283337251, 1090.841827934945, 1146.335125627151, 1151.4875880233383, 1160.6750109969223, 1170.631251155883, 5358.678479291177, 5420.295361602966, 5421.213923981, 5423.105187644073, 5437.048516509595, 8416.621252043673, 8567.150964699791, 8621.69824056155, 8674.609642565974, 8679.913200868037, 8568.871466928002, 8582.570871456526, 8595.871061934076, 8616.78458038554, 8677.783055423664, 6382.936170638327, 6730.259671436138, 6831.248562658104, 6858.348456896587, 6874.793756187322, 6806.65966701653, 6887.827804304932, 6892.724291028303, 6911.516193866381, 6929.099217101744, 7743.5353878767455, 7764.0, 7892.960535197287, 7899.1705870885025, 7935.0, 7932.801679958047, 8058.194180581895, 8087.757367278991, 8112.1076681566165, 8245.13426090252, 5358.142697168434, 5491.0, 6092.847678807888, 6233.0338797486465, 6314.273858506147, 6659.733610655519, 6690.4647628190605, 6692.732290708512, 6747.426468750211, 6771.559848609824, 6294.905576416449, 6398.93601064002, 6528.902066469102, 6532.477401807939, 6591.571547849375, 6188.288346840171, 6211.751529938751, 6219.968900155471, 6359.982402815527, 6372.553921225655, 6307.432331090224, 6338.683065846782, 6366.427021568081, 6372.87254254903, 6378.9362106380195, 5911.763529458945, 5916.911246331395, 5977.312609049841, 5979.1928089707335, 6038.5169186465855, 5982.790602328898, 6030.6080104793045, 6033.5173186145885, 6038.033914573647, 6039.154518367344], "unit": "score", "name": "mootools.html", "value": 4215.004921262}, {"lowerIsBetter": false, "replicates": [297.81833081820093, 301.4603859092225, 306.3918122526784, 307.1645125259294, 307.64159753886725, 5616.42740032791, 5637.21079048933, 5722.399148089453, 5744.942550574493, 5745.454181852718, 5596.4123767004485, 5598.776048958047, 5604.831855044345, 5609.719514024293, 5635.408282130379, 4090.3046482097966, 4326.610605045553, 4330.956690433087, 4335.154644844257, 4336.609705126538, 4178.519470260913, 4232.15356928614, 4342.913141737164, 4358.738475691469, 4362.389265502832, 4255.914881702364, 4288.9785551072355, 4291.742495450268, 4298.075913678558, 4306.7631280279575, 4269.935950960738, 4292.892677683068, 4297.613214810666, 4313.439252897135, 4313.805878735465, 3773.9622603773887, 3779.829907654162, 3787.0721673190246, 3789.2800367930004, 3794.7533410328515, 5242.1088414969545, 5431.402545719968, 5469.07025805614, 5485.9725701371635, 5488.17677348397, 4884.780184891689, 4903.65674402792, 4908.165611845959, 4910.165271903749, 4916.58209052231, 5913.201717768089, 5920.79277225295, 5926.4073592640825, 5936.990711579042, 5937.317208521033, 4009.218202450523, 4011.0774521860135, 4013.6588389986887, 4023.396490526415, 4030.7984600770137, 5995.880082398327, 6223.719932602999, 6227.283862355842, 6238.500919926395, 6242.625442473421, 4108.67130629549, 4110.547839737626, 4146.730462519927, 4147.274227010322, 4154.356074808409, 4204.516480604709, 4212.68404869636, 4214.0, 4215.304474761691, 4219.451471308772, 4847.103285892122, 4851.878703032382, 4854.17479028566, 4855.223164293696, 4863.294822250825, 5684.772609095672, 5825.563082768809, 5871.442212989759, 5912.231409916683, 5917.408259174134, 1457.9781303280458, 1474.3070756744312, 1474.8746356559814, 1476.1659662290772, 1476.6234610174338, 958.0706714486937, 971.3249291742171, 976.5800705696619, 981.1611072533025, 991.6826615482976, 5145.125328694131, 5146.099432599281, 5150.4334523202415, 5154.407243167008, 5175.0, 6034.24571928509, 6140.0, 6212.627242365473, 6215.036669316263, 6239.0, 5169.612279079084, 5180.844574662766, 5200.063988482109, 5204.42751297357, 5209.895802084013, 3289.095498737818, 3340.1983523954564, 3341.8830340937952, 3342.398368293682, 3345.9665403346144, 5678.176664383641, 5739.971300143556, 5747.482726554546, 5802.100674395476, 5811.099279611667, 6280.654563999024, 6303.590266632746, 6361.332060133619, 6362.331955144736, 6388.233411990498, 5698.458646428666, 5736.19693242938, 5777.942220577825, 5795.594308398456, 5807.419258074159, 6045.788397406246, 6159.4456498915315, 6169.951108311688, 6170.10533472653, 6217.347178546188, 6039.63762174271, 6043.8186854394435, 6046.244219472566, 6067.484263837623, 6068.575199735976, 5787.045137552257, 5789.9710501447225, 5797.739101740347, 5800.4489573490455, 5820.5925585210325, 4089.26393249218, 4125.752454852598, 4127.587241275968, 4130.710850240454, 4145.3781932710335, 5991.970040149771, 6157.568970172224, 6158.538109641705, 6191.566590338633, 6200.720967556561, 5993.160957465871, 5995.700214989321, 5998.400159983967, 6000.579959402975, 6013.699315034319, 5986.880262394815, 5990.610610310316, 6001.519878409629, 6002.519798416203, 6010.9699451502465, 5642.520385767256, 5785.4214578541805, 5821.534277257724, 5821.922944255326, 5866.50134738535, 5968.4926781224085, 5969.701514924323, 5971.28344598651, 6012.158297838394, 6021.909671354845, 5718.399568045462, 5851.882962340645, 5854.765809367577, 5862.413758624103, 5873.970630146993], "unit": "score", "name": "prototype.html", "value": 4411.185341457502}, {"lowerIsBetter": false, "replicates": [691.0843132848975, 728.8979542863997, 760.8059944714097, 800.2237829305575, 802.9397795165364, 10840.6747797566, 11171.050460710829, 11202.831957520644, 11213.607523736671, 11215.214934954556, 11628.476718547665, 11732.35472049037, 11733.53065877366, 11741.589044383449, 11787.0, 758.8254701418678, 770.8458308338327, 771.1709911844789, 773.8529679360931, 774.2528460036064, 620.888240116781, 623.498084042348, 767.6507189228915, 778.5678948183755, 783.4985609210111, 819.1071731812324, 822.9958850205741, 826.8842362069315, 831.0442990560873, 831.8253166834942, 815.3966065111774, 817.4073796497552, 817.497239197892, 818.2758258940821, 819.7008092046419, 613.5306490534745, 613.8526753579154, 614.2659521871366, 614.3303798859221, 614.8800983808159, 11330.263532870336, 11378.544858205662, 11408.771824563462, 11410.030147437477, 11438.256513326607, 11088.33469991795, 11088.390138542378, 11161.776764464747, 11219.719507012309, 11412.20114591979, 9474.90525094754, 9515.952420237854, 9566.426014439086, 9652.420854748738, 9787.951060244653, 3215.742740580748, 3301.323228738103, 3312.9503057453917, 3324.268660894599, 3324.7173990210863, 38752.61247387518, 38897.22205555901, 38988.61011389906, 39032.0, 39223.215535689415, 9044.909550904538, 9129.08709129095, 9247.26021918245, 9443.386179898285, 9566.808663826685, 16844.15779211059, 16876.15619219034, 16992.320307187572, 16998.660026799644, 17008.659826803396, 6164.7842325518395, 6402.167718196698, 6481.611103333815, 6491.545591808528, 6740.359665831738, 3551.5738111426795, 3570.285942811396, 3575.5173051638217, 3583.552055993001, 3592.7305452091196, 2622.2002289301563, 2665.506881226979, 2670.893164273446, 2675.1573254424793, 2680.1557509384484, 464.4241140985222, 466.1609103613482, 466.8062753957138, 475.18742949556645, 478.4904077157816, 559.4377650461261, 562.1202817590458, 579.7304253522133, 586.8356860079184, 590.7046476761619, 7245.818854528574, 7246.93232446433, 7526.811829704301, 7600.087989441303, 7616.619169041525, 465.49726295601346, 501.8143286983839, 574.167457187072, 586.560079940045, 594.6342999055549, 3734.215814678948, 3864.1885204107452, 3881.7088718346236, 3928.5482169550296, 3952.486176796998, 46840.0, 47314.5268547317, 47329.05341893143, 47406.5259347409, 49448.50551494439, 43022.56977430248, 43601.78199109047, 43610.563894360654, 43678.0, 43970.560294396644, 39452.80273598614, 39558.80220598879, 39914.600853991666, 40600.0, 40876.38685419721, 7889.171636978264, 8011.0, 8152.877706834283, 8216.0, 8234.258916697527, 29752.404951901273, 30133.246668832577, 30604.693953060185, 30728.231794205323, 30850.382992340477, 35835.28329433344, 36245.45631815472, 36379.27241455209, 38202.94376449822, 39855.80072099621, 9734.659286924923, 10007.449590272605, 10032.64885728996, 10200.540975656262, 10216.693499195037, 11161.330320180816, 11164.776704466027, 11203.439828008404, 11210.831837522606, 11278.0, 10146.086852183338, 10302.948485257526, 10765.46172691378, 10981.61564345244, 11067.83398249043, 9984.500774961369, 10078.546465409221, 10144.137748291478, 10559.78880422372, 10641.787164256826, 10468.528916198637, 10473.842892356774, 10532.473376331305, 10671.89328106709, 10683.0, 9268.582913768762, 9276.814463710823, 9280.118388753328, 9477.857832132386, 9628.277879158952, 8799.384043116921, 8921.330900182642, 9081.863772043556, 9085.13691199335, 9116.225120864801], "unit": "score", "name": "yui.html", "value": 5657.065282084335}], "name": "dromaeo_css", "value": 6324.640577902029}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [114.0, 108.0, 112.0, 111.0, 112.0, 125.0, 113.0, 113.0, 112.0, 111.0], "unit": "score", "name": "ai-astar", "value": 113.1}, {"lowerIsBetter": true, "replicates": [152.0, 132.0, 152.0, 169.0, 131.0, 160.0, 157.0, 129.0, 167.0, 140.0], "unit": "score", "name": "audio-beat-detection", "value": 148.9}, {"lowerIsBetter": true, "replicates": [162.0, 164.0, 163.0, 160.0, 160.0, 164.0, 172.0, 162.0, 166.0, 162.0], "unit": "score", "name": "audio-dft", "value": 163.5}, {"lowerIsBetter": true, "replicates": [73.0, 72.0, 74.0, 72.0, 73.0, 133.0, 73.0, 79.0, 72.0, 74.0], "unit": "score", "name": "audio-fft", "value": 79.5}, {"lowerIsBetter": true, "replicates": [101.0, 94.0, 129.0, 94.0, 96.0, 95.0, 139.0, 95.0, 94.0, 124.0], "unit": "score", "name": "audio-oscillator", "value": 106.1}, {"lowerIsBetter": true, "replicates": [111.0, 110.0, 110.0, 111.0, 110.0, 110.0, 109.0, 110.0, 111.0, 110.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 110.2}, {"lowerIsBetter": true, "replicates": [148.0, 148.0, 147.0, 149.0, 149.0, 148.0, 148.0, 149.0, 147.0, 148.0], "unit": "score", "name": "imaging-darkroom", "value": 148.1}, {"lowerIsBetter": true, "replicates": [97.0, 96.0, 97.0, 96.0, 96.0, 96.0, 97.0, 99.0, 96.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 96.6}, {"lowerIsBetter": true, "replicates": [75.0, 74.0, 75.0, 73.0, 76.0, 75.0, 78.0, 77.0, 75.0, 76.0], "unit": "score", "name": "json-parse-financial", "value": 75.4}, {"lowerIsBetter": true, "replicates": [68.0, 69.0, 68.0, 69.0, 67.0, 68.0, 67.0, 68.0, 69.0, 69.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 68.2}, {"lowerIsBetter": true, "replicates": [83.0, 92.0, 84.0, 83.0, 83.0, 83.0, 84.0, 84.0, 84.0, 100.0], "unit": "score", "name": "stanford-crypto-aes", "value": 86.0}, {"lowerIsBetter": true, "replicates": [129.0, 128.0, 122.0, 125.0, 129.0, 125.0, 131.0, 128.0, 119.0, 124.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 126.0}, {"lowerIsBetter": true, "replicates": [134.0, 157.0, 148.0, 151.0, 146.0, 134.0, 146.0, 152.0, 152.0, 146.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 146.6}, {"lowerIsBetter": true, "replicates": [70.0, 69.0, 68.0, 69.0, 72.0, 68.0, 69.0, 71.0, 70.0, 75.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 70.1}], "name": "kraken", "value": 1538.3}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [167.7008217340265], "unit": "score", "name": "Richards", "value": 210.50582599999998}, {"lowerIsBetter": false, "replicates": [186.46280067126608], "unit": "score", "name": "DeltaBlue", "value": 354.59083400000003}, {"lowerIsBetter": false, "replicates": [278.00945232137894], "unit": "score", "name": "Encrypt", "value": 957.4530570000002}, {"lowerIsBetter": false, "replicates": [5251.308900523561], "unit": "score", "name": "Decrypt", "value": 50.68850548354939}, {"lowerIsBetter": false, "replicates": [1055.9662090813094], "unit": "score", "name": "RayTrace", "value": 700.7695829999997}, {"lowerIsBetter": false, "replicates": [719.626168224299], "unit": "score", "name": "Earley", "value": 926.1239090909096}, {"lowerIsBetter": false, "replicates": [12207.317073170732], "unit": "score", "name": "Boyer", "value": 54.595370629370635}, {"lowerIsBetter": false, "replicates": [26179.48717948718], "unit": "score", "name": "RegExp", "value": 34.79766405484819}, {"lowerIsBetter": false, "replicates": [595.2522255192879], "unit": "score", "name": "Splay", "value": 136.90163010967092}, {"lowerIsBetter": false, "replicates": [4826.923076923077], "unit": "score", "name": "NavierStokes", "value": 307.4422310756973}], "name": "v8_7", "value": 21339.79650600154}]} 13:15:58 INFO - RETURN: dromaeo_css: 6324.64 13:15:58 INFO - RETURN: kraken: 1538.30 13:15:58 INFO - RETURN: v8_7: 21339.80 13:15:58 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,132,43]]", "result": "21339.80"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,132,43]]", "result": "1538.30"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,132,43]]", "result": "6324.64"}}} 13:15:58 INFO - Return code: 0 13:15:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 13:15:58 INFO - # TBPL SUCCESS # 13:15:58 INFO - Running post-action listener: _resource_record_post_action 13:15:58 INFO - Running post-run listener: _resource_record_post_run 13:15:59 INFO - Total resource usage - Wall time: 1233s; CPU: 13.0%; Read bytes: 12288; Write bytes: 546144256; Read time: 244; Write time: 316452 13:15:59 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 227434496; Read time: 0; Write time: 168992 13:15:59 INFO - run-tests - Wall time: 1217s; CPU: 13.0%; Read bytes: 12288; Write bytes: 318709760; Read time: 244; Write time: 147460 13:15:59 INFO - Running post-run listener: _upload_blobber_files 13:15:59 INFO - Blob upload gear active. 13:15:59 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 13:15:59 INFO - Copying logs to upload dir... 13:15:59 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1273.826983 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2015-12-18 13:15:59.993607) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-18 13:15:59.997555) ========= 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=d6a7e047ef025f707024e163000001c3-1450472083.740019-905888863 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/dZqfn1r_TIiKsZVfqrCifw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010087 build_url: 'https://queue.taskcluster.net/v1/task/dZqfn1r_TIiKsZVfqrCifw/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-18 13:16:00.048322) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 13:16:00.048686) ========= 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=d6a7e047ef025f707024e163000001c3-1450472083.740019-905888863 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004487 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 13:16:00.099120) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-18 13:16:00.099455) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-18 13:16:00.099794) ========= ========= Total master_lag: 0.23 =========