builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-002 starttime: 1450372824.26 results: success (0) buildid: 20151217075729 builduid: 6befe0d091dd475b9112a01e174edd4a revision: 81e696a6c8ea2256096021d42a0e24f4386d045f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-17 09:20:24.264997) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-17 09:20:24.265447) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-17 09:20:24.265736) ========= 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=dd132779db58144225c4cf24000001c8-1450372216.270435-485160866 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.006283 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-17 09:20:24.290010) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-17 09:20:24.290333) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-17 09:20:24.303806) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 09:20:24.304112) ========= 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=dd132779db58144225c4cf24000001c8-1450372216.270435-485160866 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004612 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 09:20:24.364103) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-17 09:20:24.364391) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-17 09:20:24.364759) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 09:20:24.365036) ========= 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=dd132779db58144225c4cf24000001c8-1450372216.270435-485160866 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-17 09:20:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 222M=0s 2015-12-17 09:20:25 (222 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.106648 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 09:20:24.504123) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 09:20:24.504404) ========= 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=dd132779db58144225c4cf24000001c8-1450372216.270435-485160866 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022367 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 09:20:24.575403) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 09:20:24.575723) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 81e696a6c8ea2256096021d42a0e24f4386d045f --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 81e696a6c8ea2256096021d42a0e24f4386d045f --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=dd132779db58144225c4cf24000001c8-1450372216.270435-485160866 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-17 09:20:25,470 truncating revision to first 12 chars 2015-12-17 09:20:25,470 Setting DEBUG logging. 2015-12-17 09:20:25,470 attempt 1/10 2015-12-17 09:20:25,470 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/81e696a6c8ea?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-17 09:20:25,929 unpacking tar archive at: fx-team-81e696a6c8ea/testing/mozharness/ program finished with exit code 0 elapsedTime=0.733536 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 09:20:25.329284) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-17 09:20:25.329627) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-17 09:20:25.336849) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-17 09:20:25.337108) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-17 09:20:25.337523) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2015-12-17 09:20:25.337808) ========= /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=dd132779db58144225c4cf24000001c8-1450372216.270435-485160866 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:20:26 INFO - MultiFileLogger online at 20151217 09:20:26 in /builds/slave/test 09:20:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 09:20:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:20:26 INFO - {'append_to_log': False, 09:20:26 INFO - 'base_work_dir': '/builds/slave/test', 09:20:26 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 09:20:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:20:26 INFO - 'branch': 'Fx-Team-Non-PGO', 09:20:26 INFO - 'buildbot_json_path': 'buildprops.json', 09:20:26 INFO - 'config_files': ('talos/linux_config.py',), 09:20:26 INFO - 'default_actions': ('clobber', 09:20:26 INFO - 'read-buildbot-config', 09:20:26 INFO - 'download-and-extract', 09:20:26 INFO - 'populate-webroot', 09:20:26 INFO - 'create-virtualenv', 09:20:26 INFO - 'install', 09:20:26 INFO - 'run-tests'), 09:20:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:20:26 INFO - 'download_minidump_stackwalk': True, 09:20:26 INFO - 'download_symbols': 'ondemand', 09:20:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:20:26 INFO - 'tooltool.py': '/tools/tooltool.py', 09:20:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:20:26 INFO - '/tools/misc-python/virtualenv.py')}, 09:20:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:20:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:20:26 INFO - 'installer_path': 'installer.exe', 09:20:26 INFO - 'log_level': 'info', 09:20:26 INFO - 'log_name': 'talos', 09:20:26 INFO - 'log_to_console': True, 09:20:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:20:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:20:26 INFO - 'opt_config_files': (), 09:20:26 INFO - 'pip_index': False, 09:20:26 INFO - 'sps_profile': False, 09:20:26 INFO - 'sps_profile_interval': 0, 09:20:26 INFO - 'suite': 'dromaeojs-e10s', 09:20:26 INFO - 'system_bits': '64', 09:20:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:20:26 INFO - 'title': 'talos-linux64-ix-002', 09:20:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:20:26 INFO - 'use_talos_json': True, 09:20:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:20:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:20:26 INFO - 'work_dir': 'build'} 09:20:26 INFO - ##### 09:20:26 INFO - ##### Running clobber step. 09:20:26 INFO - ##### 09:20:26 INFO - Running pre-action listener: _resource_record_pre_action 09:20:26 INFO - Running main action method: clobber 09:20:26 INFO - rmtree: /builds/slave/test/build 09:20:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:20:27 INFO - Running post-action listener: _resource_record_post_action 09:20:27 INFO - ##### 09:20:27 INFO - ##### Running read-buildbot-config step. 09:20:27 INFO - ##### 09:20:27 INFO - Running pre-action listener: _resource_record_pre_action 09:20:27 INFO - Running main action method: read_buildbot_config 09:20:27 INFO - Using buildbot properties: 09:20:27 INFO - { 09:20:27 INFO - "project": "", 09:20:27 INFO - "product": "firefox", 09:20:27 INFO - "script_repo_revision": "production", 09:20:27 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 09:20:27 INFO - "repository": "", 09:20:27 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs-e10s", 09:20:27 INFO - "buildid": "20151217075729", 09:20:27 INFO - "pgo_build": "False", 09:20:27 INFO - "basedir": "/builds/slave/test", 09:20:27 INFO - "buildnumber": 579, 09:20:27 INFO - "slavename": "talos-linux64-ix-002", 09:20:27 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:20:27 INFO - "platform": "ubuntu64_hw", 09:20:27 INFO - "scheduler": "tests-fx-team-linux64-talos", 09:20:27 INFO - "branch": "fx-team", 09:20:27 INFO - "repo_path": "integration/fx-team", 09:20:27 INFO - "revision": "81e696a6c8ea2256096021d42a0e24f4386d045f", 09:20:27 INFO - "stage_platform": "linux64", 09:20:27 INFO - "builduid": "6befe0d091dd475b9112a01e174edd4a", 09:20:27 INFO - "slavebuilddir": "test" 09:20:27 INFO - } 09:20:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/E-xBxCxAR2yXPnbI9c9yYg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 09:20:27 INFO - Running post-action listener: _resource_record_post_action 09:20:27 INFO - ##### 09:20:27 INFO - ##### Running download-and-extract step. 09:20:27 INFO - ##### 09:20:27 INFO - Running pre-action listener: _resource_record_pre_action 09:20:27 INFO - Running main action method: download_and_extract 09:20:27 INFO - mkdir: /builds/slave/test/build/tests 09:20:27 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')]} 09:20:27 INFO - https://queue.taskcluster.net/v1/task/E-xBxCxAR2yXPnbI9c9yYg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 09:20:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E-xBxCxAR2yXPnbI9c9yYg/artifacts/public/build/test_packages.json 09:20:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E-xBxCxAR2yXPnbI9c9yYg/artifacts/public/build/test_packages.json 09:20:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E-xBxCxAR2yXPnbI9c9yYg/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 09:20:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E-xBxCxAR2yXPnbI9c9yYg/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 09:20:31 INFO - Downloaded 1302 bytes. 09:20:31 INFO - Reading from file /builds/slave/test/build/test_packages.json 09:20:31 INFO - Using the following test package requirements: 09:20:31 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 09:20:31 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 09:20:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:20:31 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 09:20:31 INFO - u'jsshell-linux-x86_64.zip'], 09:20:31 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 09:20:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:20:31 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 09:20:31 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 09:20:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:20:31 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 09:20:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:20:31 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 09:20:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:20:31 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 09:20:31 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 09:20:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:20:31 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:20:31 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')]} 09:20:31 INFO - https://queue.taskcluster.net/v1/task/E-xBxCxAR2yXPnbI9c9yYg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:20:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E-xBxCxAR2yXPnbI9c9yYg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 09:20:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E-xBxCxAR2yXPnbI9c9yYg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 09:20:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E-xBxCxAR2yXPnbI9c9yYg/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 09:20:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E-xBxCxAR2yXPnbI9c9yYg/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 09:20:38 INFO - Downloaded 21870957 bytes. 09:20:38 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 09:20:38 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 09:20:38 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 09:20:39 INFO - Return code: 0 09:20:39 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 09:20:39 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')]} 09:20:39 INFO - https://queue.taskcluster.net/v1/task/E-xBxCxAR2yXPnbI9c9yYg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:20:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E-xBxCxAR2yXPnbI9c9yYg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 09:20:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E-xBxCxAR2yXPnbI9c9yYg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 09:20:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E-xBxCxAR2yXPnbI9c9yYg/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 09:20:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E-xBxCxAR2yXPnbI9c9yYg/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 09:20:41 INFO - Downloaded 21870957 bytes. 09:20:41 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 09:20:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 09:20:41 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 09:20:43 INFO - Return code: 0 09:20:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:20:43 INFO - https://queue.taskcluster.net/v1/task/E-xBxCxAR2yXPnbI9c9yYg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:20:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E-xBxCxAR2yXPnbI9c9yYg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 09:20:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E-xBxCxAR2yXPnbI9c9yYg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 09:20:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E-xBxCxAR2yXPnbI9c9yYg/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 09:20:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E-xBxCxAR2yXPnbI9c9yYg/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 09:20:44 INFO - Downloaded 11132039 bytes. 09:20:44 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 09:20:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 09:20:44 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 09:20:44 INFO - Return code: 0 09:20:44 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')]} 09:20:44 INFO - https://queue.taskcluster.net/v1/task/E-xBxCxAR2yXPnbI9c9yYg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:20:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E-xBxCxAR2yXPnbI9c9yYg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 09:20:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E-xBxCxAR2yXPnbI9c9yYg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 09:20:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E-xBxCxAR2yXPnbI9c9yYg/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 09:20:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E-xBxCxAR2yXPnbI9c9yYg/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 09:20:45 INFO - Downloaded 53018241 bytes. 09:20:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/E-xBxCxAR2yXPnbI9c9yYg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 09:20:45 INFO - mkdir: /builds/slave/test/properties 09:20:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:20:45 INFO - Writing to file /builds/slave/test/properties/build_url 09:20:45 INFO - Contents: 09:20:45 INFO - build_url:https://queue.taskcluster.net/v1/task/E-xBxCxAR2yXPnbI9c9yYg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 09:20:45 INFO - Running post-action listener: _resource_record_post_action 09:20:45 INFO - Running post-action listener: set_extra_try_arguments 09:20:45 INFO - ##### 09:20:45 INFO - ##### Running populate-webroot step. 09:20:45 INFO - ##### 09:20:45 INFO - Running pre-action listener: _resource_record_pre_action 09:20:45 INFO - Running main action method: populate_webroot 09:20:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:20:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:20:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:20:45 INFO - '--tppagecycles', 09:20:45 INFO - '7'], 09:20:45 INFO - 'tests': ['tsvgm']}}, 09:20:45 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 09:20:45 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 09:20:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:20:45 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:20:45 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 09:20:45 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:20:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:20:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:20:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:20:45 INFO - 'talos_options': ['--e10s'], 09:20:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:20:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:20:45 INFO - 'tests': ['damp', 'tps']}, 09:20:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:20:45 INFO - 'talos_options': ['--e10s'], 09:20:45 INFO - 'tests': ['damp', 'tps']}, 09:20:45 INFO - 'g3': {'tests': ['dromaeo_dom']}, 09:20:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:20:45 INFO - 'other': {'tests': ['a11yr', 09:20:45 INFO - 'ts_paint', 09:20:45 INFO - 'tpaint', 09:20:45 INFO - 'sessionrestore', 09:20:45 INFO - 'sessionrestore_no_auto_restore']}, 09:20:45 INFO - 'other-e10s': {'talos_options': ['--e10s'], 09:20:45 INFO - 'tests': ['a11yr', 09:20:45 INFO - 'ts_paint', 09:20:45 INFO - 'tpaint', 09:20:45 INFO - 'sessionrestore', 09:20:45 INFO - 'sessionrestore_no_auto_restore']}, 09:20:45 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 09:20:45 INFO - 'tests': ['a11yr', 09:20:45 INFO - 'ts_paint', 09:20:45 INFO - 'tpaint', 09:20:45 INFO - 'sessionrestore', 09:20:45 INFO - 'sessionrestore_no_auto_restore']}, 09:20:45 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 09:20:45 INFO - 'tests': ['a11yr', 09:20:45 INFO - 'ts_paint', 09:20:45 INFO - 'tpaint', 09:20:45 INFO - 'sessionrestore', 09:20:45 INFO - 'sessionrestore_no_auto_restore']}, 09:20:45 INFO - 'other_l64': {'tests': ['a11yr', 09:20:45 INFO - 'ts_paint', 09:20:45 INFO - 'tpaint', 09:20:45 INFO - 'sessionrestore', 09:20:45 INFO - 'sessionrestore_no_auto_restore']}, 09:20:45 INFO - 'other_nol64': {'tests': ['a11yr', 09:20:45 INFO - 'ts_paint', 09:20:45 INFO - 'tpaint', 09:20:45 INFO - 'sessionrestore', 09:20:45 INFO - 'sessionrestore_no_auto_restore']}, 09:20:45 INFO - 'svgr': {'tests': ['tsvgx', 09:20:45 INFO - 'tsvgr_opacity', 09:20:45 INFO - 'tart', 09:20:45 INFO - 'tscrollx', 09:20:45 INFO - 'cart']}, 09:20:45 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 09:20:45 INFO - 'tests': ['tsvgx', 09:20:45 INFO - 'tsvgr_opacity', 09:20:45 INFO - 'tart', 09:20:45 INFO - 'tscrollx', 09:20:45 INFO - 'cart']}, 09:20:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:20:45 INFO - 'tests': ['tp5o']}, 09:20:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:20:45 INFO - 'talos_options': ['--e10s'], 09:20:45 INFO - 'tests': ['tp5o']}, 09:20:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:20:45 INFO - 'talos_options': ['--xperf_path', 09:20:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:20:45 INFO - 'tests': ['tp5n']}, 09:20:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:20:45 INFO - 'talos_options': ['--e10s', 09:20:45 INFO - '--xperf_path', 09:20:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:20:45 INFO - 'tests': ['tp5n']}}, 09:20:45 INFO - 'talos.zip': {'path': '', 09:20:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:20:45 INFO - Running post-action listener: _resource_record_post_action 09:20:45 INFO - ##### 09:20:45 INFO - ##### Running create-virtualenv step. 09:20:45 INFO - ##### 09:20:45 INFO - Running pre-action listener: _resource_record_pre_action 09:20:45 INFO - Running main action method: create_virtualenv 09:20:45 INFO - Creating virtualenv /builds/slave/test/build/venv 09:20:45 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 09:20:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:20:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:20:45 INFO - Using real prefix '/usr' 09:20:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:20:46 INFO - Installing distribute.............................................................................................................................................................................................done. 09:20:48 INFO - Installing pip.................done. 09:20:48 INFO - Return code: 0 09:20:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:20: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')]} 09:20:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20: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')]} 09:20:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:48 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 0x7f7402fa6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23f1240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24800b0>, '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 0x2480fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2481440>, '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': 'dd132779db58144225c4cf24000001c8-1450372216.270435-485160866', '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 09:20:48 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 09:20:48 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 09:20:48 INFO - Using env: {'DISPLAY': ':0', 09:20:48 INFO - 'HOME': '/home/cltbld', 09:20:48 INFO - 'LANG': 'en_US.UTF-8', 09:20:48 INFO - 'LANGUAGE': 'en_US:en', 09:20:48 INFO - 'LOGNAME': 'cltbld', 09:20:48 INFO - 'MAIL': '/var/mail/cltbld', 09:20:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:48 INFO - 'MOZ_NO_REMOTE': '1', 09:20:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:20:48 INFO - 'NO_EM_RESTART': '1', 09:20:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:20:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:48 INFO - 'PWD': '/builds/slave/test', 09:20:48 INFO - 'SHELL': '/bin/bash', 09:20:48 INFO - 'SHLVL': '1', 09:20:48 INFO - 'TERM': 'linux', 09:20:48 INFO - 'TMOUT': '86400', 09:20:48 INFO - 'USER': 'cltbld', 09:20:48 INFO - 'XDG_SESSION_COOKIE': 'dd132779db58144225c4cf24000001c8-1450372216.270435-485160866', 09:20:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:48 INFO - '_': '/tools/buildbot/bin/python'} 09:20:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:48 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 09:20:48 INFO - Cleaning up... 09:20:48 INFO - Return code: 0 09:20:48 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:20: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')]} 09:20:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20: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')]} 09:20:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:48 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 0x7f7402fa6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23f1240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24800b0>, '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 0x2480fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2481440>, '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': 'dd132779db58144225c4cf24000001c8-1450372216.270435-485160866', '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 09:20:48 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 09:20:48 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 09:20:48 INFO - Using env: {'DISPLAY': ':0', 09:20:48 INFO - 'HOME': '/home/cltbld', 09:20:48 INFO - 'LANG': 'en_US.UTF-8', 09:20:48 INFO - 'LANGUAGE': 'en_US:en', 09:20:48 INFO - 'LOGNAME': 'cltbld', 09:20:48 INFO - 'MAIL': '/var/mail/cltbld', 09:20:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:48 INFO - 'MOZ_NO_REMOTE': '1', 09:20:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:20:48 INFO - 'NO_EM_RESTART': '1', 09:20:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:20:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:48 INFO - 'PWD': '/builds/slave/test', 09:20:48 INFO - 'SHELL': '/bin/bash', 09:20:48 INFO - 'SHLVL': '1', 09:20:48 INFO - 'TERM': 'linux', 09:20:48 INFO - 'TMOUT': '86400', 09:20:48 INFO - 'USER': 'cltbld', 09:20:48 INFO - 'XDG_SESSION_COOKIE': 'dd132779db58144225c4cf24000001c8-1450372216.270435-485160866', 09:20:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:48 INFO - '_': '/tools/buildbot/bin/python'} 09:20:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:48 INFO - Downloading/unpacking psutil>=0.7.1 09:20:48 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 09:20:48 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 09:20:48 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 09:20:48 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 09:20:48 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 09:20:48 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 09:20:50 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 09:20:50 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 09:20:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:20:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:20:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:20:50 INFO - Installing collected packages: psutil 09:20:50 INFO - Running setup.py install for psutil 09:20:50 INFO - building 'psutil._psutil_linux' extension 09:20:50 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 09:20:50 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:20:50 INFO - building 'psutil._psutil_posix' extension 09:20:50 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 09:20:50 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:20:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:20:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:20:51 INFO - Successfully installed psutil 09:20:51 INFO - Cleaning up... 09:20:51 INFO - Return code: 0 09:20:51 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:20: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')]} 09:20:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20: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')]} 09:20:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7402fa6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23f1240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24800b0>, '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 0x2480fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2481440>, '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': 'dd132779db58144225c4cf24000001c8-1450372216.270435-485160866', '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 09:20:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 09:20:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 09:20:51 INFO - Using env: {'DISPLAY': ':0', 09:20:51 INFO - 'HOME': '/home/cltbld', 09:20:51 INFO - 'LANG': 'en_US.UTF-8', 09:20:51 INFO - 'LANGUAGE': 'en_US:en', 09:20:51 INFO - 'LOGNAME': 'cltbld', 09:20:51 INFO - 'MAIL': '/var/mail/cltbld', 09:20:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:51 INFO - 'MOZ_NO_REMOTE': '1', 09:20:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:20:51 INFO - 'NO_EM_RESTART': '1', 09:20:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:20:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:51 INFO - 'PWD': '/builds/slave/test', 09:20:51 INFO - 'SHELL': '/bin/bash', 09:20:51 INFO - 'SHLVL': '1', 09:20:51 INFO - 'TERM': 'linux', 09:20:51 INFO - 'TMOUT': '86400', 09:20:51 INFO - 'USER': 'cltbld', 09:20:51 INFO - 'XDG_SESSION_COOKIE': 'dd132779db58144225c4cf24000001c8-1450372216.270435-485160866', 09:20:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:51 INFO - '_': '/tools/buildbot/bin/python'} 09:20:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:51 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:20:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:52 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:20:52 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 09:20:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:20:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:20:53 INFO - Installing collected packages: mozsystemmonitor 09:20:53 INFO - Running setup.py install for mozsystemmonitor 09:20:53 INFO - Successfully installed mozsystemmonitor 09:20:53 INFO - Cleaning up... 09:20:53 INFO - Return code: 0 09:20:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:20:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:20:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:20:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f7402fa6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23f1240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24800b0>, '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 0x2480fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2481440>, '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': 'dd132779db58144225c4cf24000001c8-1450372216.270435-485160866', '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 09:20:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:20:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:20:53 INFO - Using env: {'DISPLAY': ':0', 09:20:53 INFO - 'HOME': '/home/cltbld', 09:20:53 INFO - 'LANG': 'en_US.UTF-8', 09:20:53 INFO - 'LANGUAGE': 'en_US:en', 09:20:53 INFO - 'LOGNAME': 'cltbld', 09:20:53 INFO - 'MAIL': '/var/mail/cltbld', 09:20:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:53 INFO - 'MOZ_NO_REMOTE': '1', 09:20:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:20:53 INFO - 'NO_EM_RESTART': '1', 09:20:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:20:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:53 INFO - 'PWD': '/builds/slave/test', 09:20:53 INFO - 'SHELL': '/bin/bash', 09:20:53 INFO - 'SHLVL': '1', 09:20:53 INFO - 'TERM': 'linux', 09:20:53 INFO - 'TMOUT': '86400', 09:20:53 INFO - 'USER': 'cltbld', 09:20:53 INFO - 'XDG_SESSION_COOKIE': 'dd132779db58144225c4cf24000001c8-1450372216.270435-485160866', 09:20:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:53 INFO - '_': '/tools/buildbot/bin/python'} 09:20:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:53 INFO - Downloading/unpacking blobuploader==1.2.4 09:20:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:55 INFO - Downloading blobuploader-1.2.4.tar.gz 09:20:55 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 09:20:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:20:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:20:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:55 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 09:20:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:20:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:20:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:55 INFO - Downloading docopt-0.6.1.tar.gz 09:20:55 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 09:20:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:20:55 INFO - Installing collected packages: blobuploader, requests, docopt 09:20:55 INFO - Running setup.py install for blobuploader 09:20:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:20:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:20:55 INFO - Running setup.py install for requests 09:20:56 INFO - Running setup.py install for docopt 09:20:56 INFO - Successfully installed blobuploader requests docopt 09:20:56 INFO - Cleaning up... 09:20:56 INFO - Return code: 0 09:20:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:20: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')]} 09:20:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20: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')]} 09:20:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7402fa6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23f1240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24800b0>, '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 0x2480fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2481440>, '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': 'dd132779db58144225c4cf24000001c8-1450372216.270435-485160866', '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 09:20:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:20:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test/build/venv/cache --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:20:56 INFO - Using env: {'DISPLAY': ':0', 09:20:56 INFO - 'HOME': '/home/cltbld', 09:20:56 INFO - 'LANG': 'en_US.UTF-8', 09:20:56 INFO - 'LANGUAGE': 'en_US:en', 09:20:56 INFO - 'LOGNAME': 'cltbld', 09:20:56 INFO - 'MAIL': '/var/mail/cltbld', 09:20:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:56 INFO - 'MOZ_NO_REMOTE': '1', 09:20:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:20:56 INFO - 'NO_EM_RESTART': '1', 09:20:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:20:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:56 INFO - 'PWD': '/builds/slave/test', 09:20:56 INFO - 'SHELL': '/bin/bash', 09:20:56 INFO - 'SHLVL': '1', 09:20:56 INFO - 'TERM': 'linux', 09:20:56 INFO - 'TMOUT': '86400', 09:20:56 INFO - 'USER': 'cltbld', 09:20:56 INFO - 'XDG_SESSION_COOKIE': 'dd132779db58144225c4cf24000001c8-1450372216.270435-485160866', 09:20:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:56 INFO - '_': '/tools/buildbot/bin/python'} 09:20:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:20:56 INFO - Running setup.py (path:/tmp/pip-OcSJNs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:20:56 INFO - Running setup.py (path:/tmp/pip-EYbXdo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:20:56 INFO - Running setup.py (path:/tmp/pip-lu8ZxS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:20:56 INFO - Running setup.py (path:/tmp/pip-oTUGvm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:20:56 INFO - Running setup.py (path:/tmp/pip-6Wd5Ga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:20:56 INFO - Running setup.py (path:/tmp/pip-fEZvXp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:20:57 INFO - Running setup.py (path:/tmp/pip-kCJNwq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:20:57 INFO - Running setup.py (path:/tmp/pip-Z9Bifh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:20:57 INFO - Running setup.py (path:/tmp/pip-LuP32X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:20:57 INFO - Running setup.py (path:/tmp/pip-jnhPVH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:20:57 INFO - Running setup.py (path:/tmp/pip-yUzAYU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:20:57 INFO - Running setup.py (path:/tmp/pip-BQa7j_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:20:57 INFO - Running setup.py (path:/tmp/pip-mYAHSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:20:57 INFO - Running setup.py (path:/tmp/pip-lQWrDh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:20:57 INFO - Running setup.py (path:/tmp/pip-l453Gk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:20:57 INFO - Running setup.py (path:/tmp/pip-wN1L85-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:20:57 INFO - Running setup.py (path:/tmp/pip-odtzLI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:20:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:20:57 INFO - Running setup.py install for manifestparser 09:20:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:20:57 INFO - Running setup.py install for mozcrash 09:20:58 INFO - Running setup.py install for mozdebug 09:20:58 INFO - Running setup.py install for mozdevice 09:20:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:20:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:20:58 INFO - Running setup.py install for mozfile 09:20:58 INFO - Running setup.py install for mozhttpd 09:20:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:20:58 INFO - Running setup.py install for mozinfo 09:20:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:20:58 INFO - Running setup.py install for mozInstall 09:20:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:20:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:20:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:20:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:20:58 INFO - Running setup.py install for mozleak 09:20:58 INFO - Running setup.py install for mozlog 09:20:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:20:58 INFO - Running setup.py install for moznetwork 09:20:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:20:58 INFO - Running setup.py install for mozprocess 09:20:59 INFO - Running setup.py install for mozprofile 09:20:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:20:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:20:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:20:59 INFO - Running setup.py install for mozrunner 09:20:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:20:59 INFO - Running setup.py install for mozscreenshot 09:20:59 INFO - Running setup.py install for moztest 09:20:59 INFO - Running setup.py install for mozversion 09:20:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:20:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:20:59 INFO - Cleaning up... 09:20:59 INFO - Return code: 0 09:20:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:20: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')]} 09:20:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20: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')]} 09:20:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7402fa6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23f1240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24800b0>, '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 0x2480fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2481440>, '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': 'dd132779db58144225c4cf24000001c8-1450372216.270435-485160866', '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 09:20:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:20:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:20:59 INFO - Using env: {'DISPLAY': ':0', 09:20:59 INFO - 'HOME': '/home/cltbld', 09:20:59 INFO - 'LANG': 'en_US.UTF-8', 09:20:59 INFO - 'LANGUAGE': 'en_US:en', 09:20:59 INFO - 'LOGNAME': 'cltbld', 09:20:59 INFO - 'MAIL': '/var/mail/cltbld', 09:20:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:59 INFO - 'MOZ_NO_REMOTE': '1', 09:20:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:20:59 INFO - 'NO_EM_RESTART': '1', 09:20:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:20:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:59 INFO - 'PWD': '/builds/slave/test', 09:20:59 INFO - 'SHELL': '/bin/bash', 09:20:59 INFO - 'SHLVL': '1', 09:20:59 INFO - 'TERM': 'linux', 09:20:59 INFO - 'TMOUT': '86400', 09:20:59 INFO - 'USER': 'cltbld', 09:20:59 INFO - 'XDG_SESSION_COOKIE': 'dd132779db58144225c4cf24000001c8-1450372216.270435-485160866', 09:20:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:59 INFO - '_': '/tools/buildbot/bin/python'} 09:20:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:20:59 INFO - Running setup.py (path:/tmp/pip-1GDqJu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:20:59 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:20:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:20:59 INFO - Running setup.py (path:/tmp/pip-hENwxk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:20:59 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)) 09:20:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:20:59 INFO - Running setup.py (path:/tmp/pip-9yb_Q9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:21:00 INFO - Running setup.py (path:/tmp/pip-tFuAYx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:21:00 INFO - Running setup.py (path:/tmp/pip-6UkRzN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:21:00 INFO - Running setup.py (path:/tmp/pip-7uND9j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:21:00 INFO - Running setup.py (path:/tmp/pip-jN_dHq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:21:00 INFO - Running setup.py (path:/tmp/pip-Zvv2qv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:21:00 INFO - Running setup.py (path:/tmp/pip-5Y8hd_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:21:00 INFO - Running setup.py (path:/tmp/pip-WpoJOG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:21:00 INFO - Running setup.py (path:/tmp/pip-R0V_eq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:21:00 INFO - Running setup.py (path:/tmp/pip-MrM5Rm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:21:00 INFO - Running setup.py (path:/tmp/pip-c7damw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:21:00 INFO - Running setup.py (path:/tmp/pip-8JjfLD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:21:00 INFO - Running setup.py (path:/tmp/pip-LFnbKW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:21:00 INFO - Running setup.py (path:/tmp/pip-KEa9VV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:21:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:21:01 INFO - Running setup.py (path:/tmp/pip-yXu1vh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:21:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:21:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:21:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:21:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:21:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:21:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:21:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:21:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:21:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:21:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:02 INFO - Downloading blessings-1.5.1.tar.gz 09:21:02 INFO - Storing download in cache at /builds/slave/test/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 09:21:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:21:02 INFO - Installing collected packages: blessings 09:21:02 INFO - Running setup.py install for blessings 09:21:02 INFO - Successfully installed blessings 09:21:02 INFO - Cleaning up... 09:21:02 INFO - Return code: 0 09:21:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:21:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:21:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:21:03 INFO - Reading from file tmpfile_stdout 09:21:03 INFO - Current package versions: 09:21:03 INFO - argparse == 1.2.1 09:21:03 INFO - blessings == 1.5.1 09:21:03 INFO - blobuploader == 1.2.4 09:21:03 INFO - docopt == 0.6.1 09:21:03 INFO - manifestparser == 1.1 09:21:03 INFO - mozInstall == 1.12 09:21:03 INFO - mozcrash == 0.16 09:21:03 INFO - mozdebug == 0.1 09:21:03 INFO - mozdevice == 0.47 09:21:03 INFO - mozfile == 1.2 09:21:03 INFO - mozhttpd == 0.7 09:21:03 INFO - mozinfo == 0.9 09:21:03 INFO - mozleak == 0.1 09:21:03 INFO - mozlog == 3.1 09:21:03 INFO - moznetwork == 0.27 09:21:03 INFO - mozprocess == 0.22 09:21:03 INFO - mozprofile == 0.27 09:21:03 INFO - mozrunner == 6.11 09:21:03 INFO - mozscreenshot == 0.1 09:21:03 INFO - mozsystemmonitor == 0.0 09:21:03 INFO - moztest == 0.7 09:21:03 INFO - mozversion == 1.4 09:21:03 INFO - psutil == 3.1.1 09:21:03 INFO - requests == 1.2.3 09:21:03 INFO - wsgiref == 0.1.2 09:21:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:21:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:21:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:21:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7402fa6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23f1240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24800b0>, '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 0x2480fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2481440>, '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': 'dd132779db58144225c4cf24000001c8-1450372216.270435-485160866', '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 09:21:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:21:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:21:03 INFO - Using env: {'DISPLAY': ':0', 09:21:03 INFO - 'HOME': '/home/cltbld', 09:21:03 INFO - 'LANG': 'en_US.UTF-8', 09:21:03 INFO - 'LANGUAGE': 'en_US:en', 09:21:03 INFO - 'LOGNAME': 'cltbld', 09:21:03 INFO - 'MAIL': '/var/mail/cltbld', 09:21:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:03 INFO - 'MOZ_NO_REMOTE': '1', 09:21:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:21:03 INFO - 'NO_EM_RESTART': '1', 09:21:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:21:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:03 INFO - 'PWD': '/builds/slave/test', 09:21:03 INFO - 'SHELL': '/bin/bash', 09:21:03 INFO - 'SHLVL': '1', 09:21:03 INFO - 'TERM': 'linux', 09:21:03 INFO - 'TMOUT': '86400', 09:21:03 INFO - 'USER': 'cltbld', 09:21:03 INFO - 'XDG_SESSION_COOKIE': 'dd132779db58144225c4cf24000001c8-1450372216.270435-485160866', 09:21:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:03 INFO - '_': '/tools/buildbot/bin/python'} 09:21:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:21:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:21:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:21:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:21:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:21:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:21:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:21:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:21:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:21:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:21:03 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:21:03 INFO - Cleaning up... 09:21:03 INFO - Return code: 0 09:21:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:21:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:21:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:21:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7402fa6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23f1240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24800b0>, '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 0x2480fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2481440>, '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': 'dd132779db58144225c4cf24000001c8-1450372216.270435-485160866', '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 09:21:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:21:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:21:03 INFO - Using env: {'DISPLAY': ':0', 09:21:03 INFO - 'HOME': '/home/cltbld', 09:21:03 INFO - 'LANG': 'en_US.UTF-8', 09:21:03 INFO - 'LANGUAGE': 'en_US:en', 09:21:03 INFO - 'LOGNAME': 'cltbld', 09:21:03 INFO - 'MAIL': '/var/mail/cltbld', 09:21:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:03 INFO - 'MOZ_NO_REMOTE': '1', 09:21:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:21:03 INFO - 'NO_EM_RESTART': '1', 09:21:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:21:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:03 INFO - 'PWD': '/builds/slave/test', 09:21:03 INFO - 'SHELL': '/bin/bash', 09:21:03 INFO - 'SHLVL': '1', 09:21:03 INFO - 'TERM': 'linux', 09:21:03 INFO - 'TMOUT': '86400', 09:21:03 INFO - 'USER': 'cltbld', 09:21:03 INFO - 'XDG_SESSION_COOKIE': 'dd132779db58144225c4cf24000001c8-1450372216.270435-485160866', 09:21:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:03 INFO - '_': '/tools/buildbot/bin/python'} 09:21:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:03 INFO - Downloading/unpacking jsonschema 09:21:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:21:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:21:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:21:05 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 09:21:05 INFO - Downloading/unpacking functools32 (from jsonschema) 09:21:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:21:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:21:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:05 INFO - Downloading functools32-3.2.3-2.tar.gz 09:21:05 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 09:21:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:21:05 INFO - warning: no files found matching '*.txt' 09:21:05 INFO - no previously-included directories found matching 'build' 09:21:05 INFO - no previously-included directories found matching 'dist' 09:21:05 INFO - no previously-included directories found matching '.git*' 09:21:05 INFO - Installing collected packages: jsonschema, functools32 09:21:05 INFO - Running setup.py install for functools32 09:21:05 INFO - warning: no files found matching '*.txt' 09:21:05 INFO - no previously-included directories found matching 'build' 09:21:05 INFO - no previously-included directories found matching 'dist' 09:21:05 INFO - no previously-included directories found matching '.git*' 09:21:05 INFO - Successfully installed jsonschema functools32 09:21:05 INFO - Cleaning up... 09:21:05 INFO - Return code: 0 09:21:05 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:21:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:21:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:21:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7402fa6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23f1240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24800b0>, '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 0x2480fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2481440>, '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': 'dd132779db58144225c4cf24000001c8-1450372216.270435-485160866', '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 09:21:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 09:21:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 09:21:05 INFO - Using env: {'DISPLAY': ':0', 09:21:05 INFO - 'HOME': '/home/cltbld', 09:21:05 INFO - 'LANG': 'en_US.UTF-8', 09:21:05 INFO - 'LANGUAGE': 'en_US:en', 09:21:05 INFO - 'LOGNAME': 'cltbld', 09:21:05 INFO - 'MAIL': '/var/mail/cltbld', 09:21:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:05 INFO - 'MOZ_NO_REMOTE': '1', 09:21:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:21:05 INFO - 'NO_EM_RESTART': '1', 09:21:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:21:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:05 INFO - 'PWD': '/builds/slave/test', 09:21:05 INFO - 'SHELL': '/bin/bash', 09:21:05 INFO - 'SHLVL': '1', 09:21:05 INFO - 'TERM': 'linux', 09:21:05 INFO - 'TMOUT': '86400', 09:21:05 INFO - 'USER': 'cltbld', 09:21:05 INFO - 'XDG_SESSION_COOKIE': 'dd132779db58144225c4cf24000001c8-1450372216.270435-485160866', 09:21:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:05 INFO - '_': '/tools/buildbot/bin/python'} 09:21:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:06 INFO - Downloading/unpacking flake8 09:21:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:21:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:21:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:07 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:21:07 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fflake8-2.5.0-py2.py3-none-any.whl 09:21:07 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:21:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:21:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:21:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:07 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:21:07 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmccabe-0.3.1-py2.py3-none-any.whl 09:21:07 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:21:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:21:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:21:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:08 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpyflakes-1.0.0-py2.py3-none-any.whl 09:21:08 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:21: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 09:21: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 09:21: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 09:21: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 09:21: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 09:21: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 09:21:08 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 09:21:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:21:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:21:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:21:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:21:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:21:08 INFO - no previously-included directories found matching 'docs/_build' 09:21:08 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:21:08 INFO - Running setup.py install for pep8 09:21:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:21:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:21:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:21:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:21:08 INFO - no previously-included directories found matching 'docs/_build' 09:21:08 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:21:08 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:21:08 INFO - Cleaning up... 09:21:08 INFO - Return code: 0 09:21:08 INFO - Running post-action listener: _resource_record_post_action 09:21:08 INFO - Running post-action listener: _start_resource_monitoring 09:21:08 INFO - Starting resource monitoring. 09:21:08 INFO - ##### 09:21:08 INFO - ##### Running install step. 09:21:08 INFO - ##### 09:21:08 INFO - Running pre-action listener: _resource_record_pre_action 09:21:08 INFO - Running main action method: install 09:21:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:21:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:21:08 INFO - Reading from file tmpfile_stdout 09:21:08 INFO - Detecting whether we're running mozinstall >=1.0... 09:21:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:21:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:21:08 INFO - Reading from file tmpfile_stdout 09:21:08 INFO - Output received: 09:21:08 INFO - Usage: mozinstall [options] installer 09:21:08 INFO - Options: 09:21:08 INFO - -h, --help show this help message and exit 09:21:08 INFO - -d DEST, --destination=DEST 09:21:08 INFO - Directory to install application into. [default: 09:21:08 INFO - "/builds/slave/test"] 09:21:08 INFO - --app=APP Application being installed. [default: firefox] 09:21:08 INFO - mkdir: /builds/slave/test/build/application 09:21:08 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'] 09:21:08 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 09:21:24 INFO - Reading from file tmpfile_stdout 09:21:24 INFO - Output received: 09:21:24 INFO - /builds/slave/test/build/application/firefox/firefox 09:21:24 INFO - Running post-action listener: _resource_record_post_action 09:21:24 INFO - ##### 09:21:24 INFO - ##### Running run-tests step. 09:21:24 INFO - ##### 09:21:24 INFO - Running pre-action listener: _resource_record_pre_action 09:21:24 INFO - Running main action method: run_tests 09:21:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:21:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:21:24 INFO - Python 2.7.3 09:21:24 INFO - Return code: 0 09:21:24 INFO - grabbing minidump binary from tooltool 09:21:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:21:24 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24800b0>, '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 0x2480fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2481440>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:21:24 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:21:24 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 09:21:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:21:24 INFO - Return code: 0 09:21:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:21:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:21:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:21:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:21:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:21:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:21:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:21:25 INFO - Return code: 0 09:21:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/E-xBxCxAR2yXPnbI9c9yYg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--webServer', 'localhost'] in /builds/slave/test/build 09:21:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --debug --branchName Fx-Team-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/E-xBxCxAR2yXPnbI9c9yYg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-002 --webServer localhost 09:21:25 INFO - Using env: {'DISPLAY': ':0', 09:21:25 INFO - 'HOME': '/home/cltbld', 09:21:25 INFO - 'LANG': 'en_US.UTF-8', 09:21:25 INFO - 'LANGUAGE': 'en_US:en', 09:21:25 INFO - 'LOGNAME': 'cltbld', 09:21:25 INFO - 'MAIL': '/var/mail/cltbld', 09:21:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:21:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:21:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:25 INFO - 'MOZ_NO_REMOTE': '1', 09:21:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:21:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:21:25 INFO - 'NO_EM_RESTART': '1', 09:21:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:21:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:25 INFO - 'PWD': '/builds/slave/test', 09:21:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:21:25 INFO - 'SHELL': '/bin/bash', 09:21:25 INFO - 'SHLVL': '1', 09:21:25 INFO - 'TERM': 'linux', 09:21:25 INFO - 'TMOUT': '86400', 09:21:25 INFO - 'USER': 'cltbld', 09:21:25 INFO - 'XDG_SESSION_COOKIE': 'dd132779db58144225c4cf24000001c8-1450372216.270435-485160866', 09:21:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:25 INFO - '_': '/tools/buildbot/bin/python'} 09:21:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/E-xBxCxAR2yXPnbI9c9yYg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--webServer', 'localhost'] with output_timeout 3600 09:21:25 INFO - mozversion INFO | application_buildid: 20151217075729 09:21:25 INFO - mozversion INFO | application_changeset: 81e696a6c8ea2256096021d42a0e24f4386d045f 09:21:25 INFO - mozversion INFO | application_display_name: Nightly 09:21:25 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:21:25 INFO - mozversion INFO | application_name: Firefox 09:21:25 INFO - mozversion INFO | application_remotingname: firefox 09:21:25 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 09:21:25 INFO - mozversion INFO | application_vendor: Mozilla 09:21:25 INFO - mozversion INFO | application_version: 46.0a1 09:21:25 INFO - mozversion INFO | platform_buildid: 20151217075729 09:21:25 INFO - mozversion INFO | platform_changeset: 81e696a6c8ea2256096021d42a0e24f4386d045f 09:21:25 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 09:21:25 INFO - mozversion INFO | platform_version: 46.0a1 09:21:25 INFO - 2015-12-17 09:21:25,476 DEBUG : using testdate: 1450372885 09:21:25 INFO - 2015-12-17 09:21:25,476 DEBUG : actual date: 1450372885 09:21:25 INFO - 2015-12-17 09:21:25,562 INFO : starting webserver on 'localhost:52630' 09:21:25 INFO - 2015-12-17 09:21:25,563 INFO : Starting test suite talos-linux64-ix-002.e 09:21:25 INFO - 2015-12-17 09:21:25,563 INFO : Starting test dromaeo_css 09:21:25 INFO - 2015-12-17 09:21:25,563 DEBUG : operating with platform_type : linux_ 09:21:25 INFO - 2015-12-17 09:21:25,564 INFO : Initialising browser for dromaeo_css test... 09:21:25 INFO - 2015-12-17 09:21:25,576 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQynnKJ/profile http://localhost:52630/getInfo.html 09:21:30 INFO - 2015-12-17 09:21:30,215 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:21:30 INFO - 2015-12-17 09:21:30,215 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:21:30 INFO - 2015-12-17 09:21:30,215 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:21:30 INFO - 2015-12-17 09:21:30,215 DEBUG : BROWSER_OUTPUT: __metrics 09:21:30 INFO - 2015-12-17 09:21:30,344 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] 09:21:31 INFO - 2015-12-17 09:21:31,322 INFO : Browser initialized. 09:21:31 INFO - 2015-12-17 09:21:31,322 INFO : Running cycle 1/1 for dromaeo_css test... 09:21:31 INFO - 2015-12-17 09:21:31,323 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQynnKJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:21:43 INFO - 2015-12-17 09:21:43,380 DEBUG : BROWSER_OUTPUT: RSS: Main: 151265280 09:21:43 INFO - 2015-12-17 09:21:43,381 DEBUG : BROWSER_OUTPUT: 09:22:32 INFO - 2015-12-17 09:22:32,992 DEBUG : BROWSER_OUTPUT: 1450372952988 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] 09:24:45 INFO - 2015-12-17 09:24:45,332 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52630/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52630/tests/dromaeo/cssquery-ext.html) 09:24:46 INFO - 2015-12-17 09:24:46,361 DEBUG : BROWSER_OUTPUT: RSS: Main: 155435008 09:24:46 INFO - 2015-12-17 09:24:46,361 DEBUG : BROWSER_OUTPUT: 09:27:48 INFO - 2015-12-17 09:27:48,435 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52630/tests/dromaeo/cssquery-ext.html (next: http://localhost:52630/tests/dromaeo/cssquery-jquery.html) 09:27:49 INFO - 2015-12-17 09:27:49,028 DEBUG : BROWSER_OUTPUT: RSS: Main: 152526848 09:27:49 INFO - 2015-12-17 09:27:49,029 DEBUG : BROWSER_OUTPUT: 09:30:50 INFO - 2015-12-17 09:30:50,857 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52630/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52630/tests/dromaeo/cssquery-mootools.html) 09:30:51 INFO - 2015-12-17 09:30:51,442 DEBUG : BROWSER_OUTPUT: RSS: Main: 153620480 09:30:51 INFO - 2015-12-17 09:30:51,443 DEBUG : BROWSER_OUTPUT: 09:33:53 INFO - 2015-12-17 09:33:53,248 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52630/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52630/tests/dromaeo/cssquery-prototype.html) 09:33:53 INFO - 2015-12-17 09:33:53,849 DEBUG : BROWSER_OUTPUT: RSS: Main: 152395776 09:33:53 INFO - 2015-12-17 09:33:53,849 DEBUG : BROWSER_OUTPUT: 09:36:56 INFO - 2015-12-17 09:36:56,484 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52630/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52630/tests/dromaeo/cssquery-yui.html) 09:36:57 INFO - 2015-12-17 09:36:57,073 DEBUG : BROWSER_OUTPUT: RSS: Main: 152297472 09:36:57 INFO - 2015-12-17 09:36:57,073 DEBUG : BROWSER_OUTPUT: 09:39:59 INFO - 2015-12-17 09:39:59,445 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52630/tests/dromaeo/cssquery-yui.html (next: http://localhost:52630/tests/dromaeo/cssquery-dojo.html) 09:39:59 INFO - 2015-12-17 09:39:59,695 DEBUG : BROWSER_OUTPUT: RSS: Main: 155475968 09:39:59 INFO - 2015-12-17 09:39:59,695 DEBUG : BROWSER_OUTPUT: 09:39:59 INFO - 2015-12-17 09:39:59,695 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:39:59 INFO - 2015-12-17 09:39:59,695 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:39:59 INFO - 2015-12-17 09:39:59,695 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:39:59 INFO - 2015-12-17 09:39:59,696 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:39:59 INFO - 2015-12-17 09:39:59,699 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2668.986655066725;2695.4339588686375;2752.5045491811466;2753.3529620539184;2764.336559225785;4606.516315786845;4762.1428142934255;4805.90388192236;4820.542048505391;4827.20351142062;4726.409198850144;4742.952570474295;4775.47469778325;4802.1836287831065;4811.8797030074265;4340.197063543254;4403.93394099088;4472.843450479234;4491.505934347219;4520.50274469808;4314.115606300701;4429.069895321986;4449.666275029385;4456.974895773974;4567.885802854938;4326.394304797331;4343.218220720285;4392.472903251631;4402.779861006937;4415.801288942005;4434.73391596502;4435.29035354345;4438.400815889878;4457.955420445786;4525.818967241306;4194.496660400741;4299.548547402509;4426.579474949875;4441;4478.4178056852725;4578.725276483422;4644.976775116136;4669.603083737852;4674.883127921795;4698.671093023489;5108.208227724708;5135.974320128375;5136.306598609177;5145.2024936134585;5198.40218374888;4955.826546070906;5071.061853557104;5097.388313402415;5158.303629010074;5213;4672.719636821767;4702.835400760957;4707.646926480527;4747.548982846659;4781.067691800135;5183.041137389595;5186.066508028591;5186.688798672092;5188.092083885305;5189.273501709764;4876;4925.975370123198;5006.274090256892;5033.3205017322925;5038.420581633159;5119.897602047939;5122.410922743932;5129.820456284012;5135.332406787168;5178.119719647593;5094.592432605383;5096.872578185575;5098.617603679739;5108.3103780989095;5133.486651334912;4855.878603034882;4966.354373931365;5032.0690672225755;5122.846314610568;5126.666766660155;4242.257604919127;4278.122984788111;4284.421603083606;4287.206866729665;4315.78421078951;3618.4029635109914;3754.6433088856515;3780.678642315379;3782.8108594570162;3801.6958643308467;4325.329573916048;4339.826406943687;4392.407025051577;4393.275109606879;4437.134758722083;5132.5124113209185;5146.0479811235045;5151.793928242828;5158.277841102249;5184.326037615086;4257.40396344512;4364.541723119027;4408.338749187584;4417.53615870329;4444.555544445529;4450.688451808407;4532.818687252539;4533.863984080417;4542.772861356985;4549.681522293474;4768.904621907543;4901.264810278487;4933.432655244693;4935.16102262609;4975.228839529878;4909.68087074339;5017.322661440805;5087.2623469595965;5211.895762084813;5228.790848366023;4893.330160996108;4895.1922932717125;4900.730459824601;4916.778744956414;4927.655064145475;5103.336566246513;5122.846314610568;5125.8718532037;5138.743062846917;5151.072806894645;4970.676906001243;5082.669626474268;5091.719955402488;5191.143461328838;5205.3753549572775;4990.700557966534;5001.8499445016705;5007.674501157413;5012.1980483122525;5061.4938506150565;4984.975075124747;5018.347614810197;5019.648624596243;5030.320906677698;5032.069067222429;4716.221823399238;4815.494373090775;4827.517248275144;4910.140725372974;4914.94328719327;4984.675996060245;5076.6446348755235;5078.238264260391;5096.108181068224;5123.308353372247;4690.249560070372;4933.531314525115;4943.777530011086;4963.925541116814;5004.374453193351 09:39:59 INFO - 2015-12-17 09:39:59,699 DEBUG : BROWSER_OUTPUT: |1;ext.html;545.1114683066601;548.2051026012281;566.4703502225417;574.3940143148978;576.6943519934433;34239;35813.46279805802;35938.64061359386;35970.460443093376;35974.58241467056;39693;40212.39681404774;40220.597794022055;40338.59661403385;40579;7880.211978802131;8006.55963921984;8068.959655201745;8081.797955051112;8094.878576821352;7854.960725196394;7980.4413691041655;7995.880061799077;8010.198980102002;8026.598670066503;7951.085625153123;8035.758927232192;8036.437449378546;8050.51696898185;8076.192380761936;7908.367330613566;8031.759047228593;8037.156098609678;8039.919600804004;8053.597320134028;7890.921090789134;8008.279254867031;8023.919760802376;8025.799355016171;8042.517448953082;36506.087347816254;36838.81580592107;37056.814715926244;37064.258714825555;37124;2394.9880250598635;2656.2819025233093;2706.012305508495;2746.1898739871785;3497.91255218619;26244.60633090505;26375.208743737527;26481.47037059248;26493.602595961074;27221.455570888473;3303.5705358303508;3405.5743032120986;3450;3453.879114231015;3482.331847498558;174856;175059;175119.12440437844;175258.1237093819;175528;38089.42865857014;38896.22207555833;49718.25422618663;50028.49971500311;50140.49859501358;36167.4574881377;36217.275654486766;36349.636503635156;36354.81822590923;36417.81791091028;12966.740665186644;12966.80549791754;12996.155249908727;13078.411471483807;13175.736485270432;10129.493525323704;10132.746681333025;10160.54277557497;10168.237382196217;10190.43952582615;12820.935895320463;13212.669683257995;13253.072284940154;13288.601341959564;13341.132826366254;5282.7622756976025;5426.891462170814;5456.61803673739;5509.807156749494;5511.862203444865;808.1595141053239;828.130463013833;870.0472982084607;912.5710915869531;915.1534830281962;26867.193984180507;27312.453750925266;28077.43845123086;29005.7099429003;31396.215094622818;858.7895965488495;883.2183517586982;921.9585118669676;960.8991055939166;968.2302569457298;13434.731305373838;13849;13888.722225555432;14122.576322710334;14255.21596312192;308206;311732;314461;314570;315769;45250.54749452529;45525.54474455279;45867.54132458699;46184.53815461802;46450.76774616105;1215.4105258949403;1239.5599562155485;1240.0699475393455;1251.6432816647211;1255.12768625806;25111.87444062841;25946.87026564855;26219.4756104873;26377.60433593536;26487.205383838515;39795.21945415181;40123.398149027205;40211.79894100511;40304.1939161221;40456.39315410212;236742;238940;239028;239223;239403;29205.7079429203;34401.65598343984;34795.47806782951;39587.604123958394;40473.79763101166;37448;37800.43299350457;37993.810030949666;38125.046873828374;38481.23037539177;31971.68028319687;32722.50916236211;32761.50857737088;33092.83453582717;33428;32539.511907320935;32980.34039319152;32980.67019329776;33131.846429981975;33134;32541;32852.507212391356;33150;33179.50230746589;33283;28136;29301.41397172087;29985.250368740955;30457.39085218239;33173.50239746358;22923.88538057299;23327;23387.181448649215;23413.297601071994;23474 09:39:59 INFO - 2015-12-17 09:39:59,699 DEBUG : BROWSER_OUTPUT: |2;jquery.html;324.7418302449552;325.56374458225974;327.44988419455314;328.1762775433661;330.6016250418245;14954.401823927043;15251.542453726392;15338.69322613547;15815.367385304602;16403.58991025225;14726.337314820832;14744.041637293569;15127.168005759679;15186.088834669927;15201;4570.565796249351;5070.822521211759;5078.771455284503;5097.3373461449955;5099.4390617032095;5117.667351622151;5123.2827404163445;5142.665726727751;5149.716765577892;5151.81968631098;4829.589484893788;4869.610431165498;4992.750362481879;5033.169527028036;5047.293378926953;4571.588557029883;4972.303877457159;5005.624578156653;5018.322526458918;5048.066107770056;5051.419086804991;5058.367704036996;5067.062593420229;5086.643934924557;5090.9745451272875;11480.311181329147;11664.241824281395;14165.929170354186;18632.72050919237;18705.906470467697;1767.469759072273;1822.7903791063932;1860.3581764291298;1877.8873267603988;1941.38846263428;8937.865932011026;9114.77213069672;9115.134062264075;9119.954400228024;9199.080091990814;1711.195738003149;1776.3249965013342;1804.8285412885755;1844.8431883289936;1848.0944337274773;47783.283250751265;48560.51439485595;56200.43799561993;58956.41043589552;59163.70418147925;9178.265738740885;9430;9462.432254064779;9534.09426104519;9543.236541076698;11788.115891308014;11903.583374581847;11910.404479775976;11928;11928.821067683992;7030.613316267654;7041.260667629826;7112.92887071133;7113.679884405215;7120.608366539786;6772.187337519529;6962.1297337832775;6988.40598549129;6994.090768200102;7010.369066783912;4083.9795801020805;4150.626443620089;4190.476190476191;4191.203671302443;4225.429567008476;1757.736339549078;1770.513108895064;1776.4049043570549;1789.391606853676;1789.454216463991;795.3557618329172;854.6410507586828;931.1247427418206;938.5354249646468;952.0812416018437;15459.922700386425;15651.608709782118;15825.366985320688;16304.673906521803;16324.34702611906;836.7280633794041;852.3607294529103;868.8392647360258;900;914.8536234202496;8906.376553641312;9101.908980910239;9173.77065573366;9225.815483690289;9289.042836851237;446912;450787;455100;455905;465495;69327.65336173287;71970.2802971974;72734;73337;73398.63300683463;1047.8899715529913;1065.765531583066;1068.4657671164418;1071.8285074388061;1074.2211896375065;14409.495667651581;14499.130052196902;14701.191434471206;14839.629009274855;14939.77590336124;32217.677823221467;32586.837065814518;32986.17534561559;33817.49273760847;34191.14522136966;42820.571794281655;43436.56563434325;44143.77928110339;44470.110597787214;44755.32867006932;8587.270082043096;8785.253253473526;11916.940415297868;12376.62870113898;12396.132270740962;17045.40341088056;17097.48707538776;17164.31342746326;17456.82543174552;17712.291508339524;14244.928775356471;14376.856231437552;14803.259837008321;14934.253287335807;15021.6995660084;14126.434942602591;14359.92820035893;14485.348159332645;14555.490557830424;14853.851461485247;15524.37902483888;15540;15605.297761600528;15694.21528923572;15799.36802527932;13973.231472269123;13996.580102596938;14106;14163.929180354033;14280.857191427953;14729.92635036818;14982;15259.923700381427;15284.465043723416;15357.539273821803 09:39:59 INFO - 2015-12-17 09:39:59,701 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1466.860648238417;1620.4085508789292;1652.2647421897252;1669.28220865028;1688.1053041887797;6217.5958562693395;6408.134901788258;6801.421879140273;7237.529560578565;7267;6848.589084654925;6861.656917154147;6883.139607549057;6948.444124470043;6969.303069693028;2005.9297924572643;2050.5693804301113;2051.640962831506;2054.198862443648;2054.979450205501;2010.5375763574386;2052.302217246136;2052.6100040992164;2056.2391914991476;2058.4442200605827;1992.4321568353023;2004.5189154602863;2008.2770202727006;2018.596280743857;2032.1363420546284;2012.758468983724;2050.292649036088;2051.7640471345835;2062.896855157251;2063.1128614695676;1982.9008549572463;2004.5088953206412;2022.7876073012417;2025.6455120353878;2027.310714357111;7022;7111.4666400020205;7111.573305601681;7116.679749411289;7131.429485641188;3936.409538569209;3962.0293028208275;3982.681385489154;3997.5003124609425;4001.919961600781;2570.9228723138335;2589.1067581684288;2615.333090062041;2625.658664373619;2635.0645520840017;857.065798279878;859.415597393772;873.5151990645189;877.5612193903048;905.9864102038474;9328.533573321374;9397.201237894787;9481.004494528044;9489.430634161972;9492.715218543455;3696.852125914987;3703.2223233121344;3709.962900370962;3812.1041555234497;3830.885073447801;8276.83446331082;8323.417360784686;8356.749297521084;8373.037100733372;8376.790580235422;4123.60825721556;4151.709380343407;4154.356074808409;4157.875263742093;4157.958420415817;3164.5411415344647;3228.0799972007867;3631.618680038611;3718.9628103718615;3720.088578298334;3374.3588718143474;3397.8470968806464;3436.1753179236803;3436.381451338783;3453.2575496268423;1069.534752382716;1072.254782925859;1086.6414083352474;1087.907527860125;1092.9726756831142;922.4465320807597;951.5242378810594;1093.9343639381664;1099.290957332516;1108.4623957380663;6662.067310576524;6684.465242780663;6691.631960242234;6701.262861085277;6749.358810912955;416.10329739411617;416.2943810241672;416.74578507110635;418.45600719065095;419.29976938512755;5263.736813159327;5293.417724050351;5327.627066105413;5333.6266461348105;5337.973310133424;7905.328047115944;8213.425060245725;8228.30059444942;8260.215279548433;8280.792980175424;8131.146229645802;8140.959295203486;8183.795405114801;8217.013958325038;8224.958875205586;6432.807015789534;6538.869222615616;6564.934350656527;6565.901511477332;6614.239362473377;6638.634875081917;6655.966720166368;6667.399934005963;6667.43326817226;6692.431143352869;7471.103467583925;7616.9238307616215;7682.385409167141;7708.383329333527;7713.421493387906;7363.705451782083;7506.361959233526;7516.924830751623;7575.507592006502;7821.061472623322;5373.596980226421;5579.163125531203;5783.045797443543;6060.878782424239;6132.724027418688;6524.9021264682015;6551.377619126175;6591.077249185022;6598.670066496752;6658.067870498231;6221.906671399842;6309.432151106423;6433.839154021;6461.8061458156335;6472.579282346633;6281.089242059968;6296.622202667854;6379.234491861006;6424.453921416732;6435.099086127852;6293.087502312231;6300.338464461165;6334;6334.3665633436285;6342.936570634234;5548.140038294071;5835.183074369676;5851.414858514115;5886.470217680429;5888.970555147197;5696.003199440164;5722.084466485342;5727.599068065196;5728.484436400744;5737.311522617312 09:39:59 INFO - 2015-12-17 09:39:59,702 DEBUG : BROWSER_OUTPUT: |4;prototype.html;290.52354139211684;292.0537458634025;298.21569272812513;301.13423906269475;312.704494252931;5466.371367292762;5480.095784195613;5495.999841184762;5560.52735517481;5680.602357834953;5511.53151982081;5515.420880807517;5568.1091025435935;5584.776608935637;5589.441055894408;4145.170965806836;4258.105797782469;4262.062346283828;4262.936055959163;4265.573442655741;4233.788310584474;4254.149170165963;4274.465691788527;4277.1018086201775;4277.721948073366;4217.557156498569;4222.514410842747;4227.450431443924;4228.682848786353;4234.470691163605;4190.476190476191;4213.747175169499;4225.852095176654;4227.492700875884;4227.556106608792;3691.169486865461;3691.187938653492;3696.537932758405;3698.5746639136305;3701.9444708329397;5207.505286997767;5350.357957045179;5374.946250537484;5383.057964856173;5386.326709161355;4849.175640141149;4850.102730994778;4851.054044461332;4862.635302352338;4868.391451068616;5876.382979787104;5879.7648094076185;5881.647101173943;5881.764729410818;5909.320428150775;3969.206158768258;3977.880663580097;3978.701597380179;3992.461017762594;3999.7600143991453;6014.548908831812;6079.483243924272;6118.969405152946;6140.416660417253;6148.200733904609;4106.178764247103;4119.155573107506;4122.711410201317;4123.525794533607;4124.546299907008;4182.975171083104;4185.309423945015;4186.727862688916;4187.895302617459;4191.203671302443;4824.903501930012;4831.275308703653;4833.734144622009;4842.58837998767;4845.466998630148;5711.485966263056;5847.26909136358;5851.239338885917;5852.209951656557;5861.589688721749;1439.6688761584894;1466.2595389328535;1481.5036962617428;1485.3093311609937;1486.8364479907202;966.4104896013426;978.4325091446943;984.0749695286407;992.5186284651938;1003.4882210072769;5069.771860266297;5078.771455284521;5088.363954505687;5104.9744751276;5107.489251074863;5904.498117659961;5959.612625179349;6060;6103.1455596216565;6104.053871649901;5062.82280120194;5099.184130539096;5114.48855114493;5117.974410128;5119.846404607793;3263.6246831614194;3303.19071827399;3319.5020746887676;3327.085051610778;3331.400347937346;5602.551795856321;5731.283589551306;5751.338596061423;5752.36723960364;5764.221830052973;6291.245050593958;6292.937070629327;6316.715747791361;6318.273398559132;6321.304656487875;5749.051406517878;5758.62568933018;5761.711914404263;5765.827025189251;5775.0471172257785;6072.271327440735;6122.540809439399;6129.877402452015;6143.139960405636;6145.6927153641245;5835.708214589338;5956.910646340395;5961.612495187799;5965.6718880461995;5979.312379076462;5732.025555655632;5736.42635736423;5740.3685594585395;5748.338941021669;5753.482186603226;4116.835326586903;4133.04939863827;4133.9793301033305;4139.875803725772;4139.917201656011;5948.940510594839;6113.205283313141;6126.816195514141;6135.509159267159;6138.631682099089;5857.26784151981;5868.853278668067;5875.412458754261;5880.441358070976;5886.558508111823;5825.3883342248455;5829.300483941955;5871.2073870027;5872.471477566869;5882.264716910387;5591.944080559305;5732.512736417451;5760.596758226884;5764.567657425794;5767.394423585631;5907.084401917709;5939.91090133657;5946.316173640087;5957.791477298274;5969.9701501493955;5768.2501274834;5782;5783.942160578508;5792.536597072309;5804.970975145097 09:39:59 INFO - 2015-12-17 09:39:59,702 DEBUG : BROWSER_OUTPUT: |5;yui.html;696.2584847137799;716.8817145171047;727.5452841973766;754.6000619671572;765.6898955922851;9473.715788526339;9630.518474076303;10290.691279261624;10658.733531661723;10664.466776661175;12377.62867113988;12561.748765024695;12562.560310389139;12640.747185056294;12645.936770316159;775.2673723331454;786.1862971824163;787.208855100623;788.9053313602376;789.4631650477672;783.322426101421;787.5471603827793;789.3093543149744;790.7983464216617;790.8220650353682;819.0949001353508;826.1903334731936;828.8093738440132;829.2412442615;830.6511265268601;818.1368656067846;819.6147810529071;820.5897051474263;821.4414198345123;822.9465084769471;609.7500024989733;610.2890132995055;610.7160170520684;610.9236345456818;610.9725062372204;10550;10740.08709259714;10748.570057197703;10755;10865.293755905841;9984;10241.436720980342;10313.381197128196;10722.0886224671;11009.559617615285;8799.956000220021;8828.028916819145;8923.375363724543;9221.216196623294;9259.6296148154;3139.3564319314487;3181.9204519886957;3201.791883527563;3209.3741720364537;3276.410246155691;33597.83201084004;33811.66188338134;34039;34063.829680851435;34086.48870266948;8056;8970.955145224363;9065.954670226607;9203.309751768644;9278.582463789146;13937.372818223204;13979.440822367194;14050.578482645536;14079.436822527188;14085.647858803406;6166.260048794174;6355.745770169141;6363.427291543783;6379.840503987438;6404.583702059351;3438.398280300937;3510.26284480262;3565.3404120237838;3583.4445660922597;3607.9897628664016;2550.6046562782426;2565.8717064146717;2566.7818235450195;2584.211815396322;2586.2629150691964;467.5277969251013;468.47998721419196;468.59934755783996;476.0502796920097;478.3494447551397;380.5110433093462;387.5852837463943;409.30418288908976;410.64479225469756;432.2371015158248;7093.113360829896;7430.256974302527;7674.0805639075425;7701.768946931601;7767.766966990999;522.2427480153792;530.361984446022;541.228749032627;564.2376122175721;579.3135134865198;3341.4743557792663;3437.5359326491102;3548.27260411615;3643.0892276930767;3653.8538458461894;44731;47713.76143119262;47805.28292075622;47847.76076119597;47877.521224788004;39938.40092398616;40062.198756024714;40231.798841006195;40624.79687601543;43256.56743432588;37978.430323545166;39264.21471570552;40881.88976377953;40959.38560921588;43496.78251608785;7088.929110708827;7332.5967071811565;7476.214997425192;7519.210482899435;7647.541147531166;31071.84464077665;31461.685383145876;31906.52140217852;31908.680913190572;32693.836530817192;38408.42387364136;38436.80781596186;38749.80625096856;38786.2242755149;39292.41061384024;9289.814203716023;9515.238780897651;9702.805943881223;9905.356151850106;9932.503374831374;10037.096661300518;10386.844197337197;10556.208284378856;10977.28647637901;11146.275492092987;9229.261659067393;9566.56950437218;9623.027279407564;9853.802923941625;9908.35595686278;9742.318037737574;9763.853542197015;9832.950835245778;9834.950825245827;9844.114029737359;9665.3234273603;9798.216142708709;9837.852432213665;9844.261680373857;9857.704268871945;8775.341849361452;8809.295256379603;8981.14679105484;8984.685536006209;9063.954680226556;8512.361572881935;8602.870956935525;8673.219410253107;8683.609237584451;9039.502827344559 09:39:59 INFO - 2015-12-17 09:39:59,702 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:39:59 INFO - 2015-12-17 09:39:59,702 DEBUG : BROWSER_OUTPUT: __start_cc_report 09:39:59 INFO - 2015-12-17 09:39:59,702 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,330 09:39:59 INFO - 2015-12-17 09:39:59,702 DEBUG : BROWSER_OUTPUT: __end_cc_report 09:39:59 INFO - 2015-12-17 09:39:59,703 DEBUG : BROWSER_OUTPUT: __startTimestamp1450373999694__endTimestamp 09:39:59 INFO - 2015-12-17 09:39:59,703 DEBUG : BROWSER_OUTPUT: 09:39:59 INFO - 2015-12-17 09:39:59,703 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:39:59 INFO - 2015-12-17 09:39:59,703 DEBUG : BROWSER_OUTPUT: Number of tests: 6 09:39:59 INFO - 2015-12-17 09:39:59,703 DEBUG : BROWSER_OUTPUT: 09:39:59 INFO - 2015-12-17 09:39:59,703 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4722.68 Median:4893.33 stddev:495.59 (10.1%) stddev-sans-first:470.20 09:39:59 INFO - 2015-12-17 09:39:59,703 DEBUG : BROWSER_OUTPUT: Values: 2669.0 2695.4 2752.5 2753.4 2764.3 4606.5 4762.1 4805.9 4820.5 4827.2 4726.4 4743.0 4775.5 4802.2 4811.9 4340.2 4403.9 4472.8 4491.5 4520.5 4314.1 4429.1 4449.7 4457.0 4567.9 4326.4 4343.2 4392.5 4402.8 4415.8 4434.7 4435.3 4438.4 4458.0 4525.8 4194.5 4299.5 4426.6 4441.0 4478.4 4578.7 4645.0 4669.6 4674.9 4698.7 5108.2 5136.0 5136.3 5145.2 5198.4 4955.8 5071.1 5097.4 5158.3 5213.0 4672.7 4702.8 4707.6 4747.5 4781.1 5183.0 5186.1 5186.7 5188.1 5189.3 4876.0 4926.0 5006.3 5033.3 5038.4 5119.9 5122.4 5129.8 5135.3 5178.1 5094.6 5096.9 5098.6 5108.3 5133.5 4855.9 4966.4 5032.1 5122.8 5126.7 4242.3 4278.1 4284.4 4287.2 4315.8 3618.4 3754.6 3780.7 3782.8 3801.7 4325.3 4339.8 4392.4 4393.3 4437.1 5132.5 5146.0 5151.8 5158.3 5184.3 4257.4 4364.5 4408.3 4417.5 4444.6 4450.7 4532.8 4533.9 4542.8 4549.7 4768.9 4901.3 4933.4 4935.2 4975.2 4909.7 5017.3 5087.3 5211.9 5228.8 4893.3 4895.2 4900.7 4916.8 4927.7 5103.3 5122.8 5125.9 5138.7 5151.1 4970.7 5082.7 5091.7 5191.1 5205.4 4990.7 5001.8 5007.7 5012.2 5061.5 4985.0 5018.3 5019.6 5030.3 5032.1 4716.2 4815.5 4827.5 4910.1 4914.9 4984.7 5076.6 5078.2 5096.1 5123.3 4690.2 4933.5 4943.8 4963.9 5004.4 09:39:59 INFO - 2015-12-17 09:39:59,704 DEBUG : BROWSER_OUTPUT: 09:39:59 INFO - 2015-12-17 09:39:59,705 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:39439.68 Median:19124.68 stddev:65078.31 (340.3%) stddev-sans-first:65195.34 09:39:59 INFO - 2015-12-17 09:39:59,705 DEBUG : BROWSER_OUTPUT: Values: 545.1 548.2 566.5 574.4 576.7 34239.0 35813.5 35938.6 35970.5 35974.6 39693.0 40212.4 40220.6 40338.6 40579.0 7880.2 8006.6 8069.0 8081.8 8094.9 7855.0 7980.4 7995.9 8010.2 8026.6 7951.1 8035.8 8036.4 8050.5 8076.2 7908.4 8031.8 8037.2 8039.9 8053.6 7890.9 8008.3 8023.9 8025.8 8042.5 36506.1 36838.8 37056.8 37064.3 37124.0 2395.0 2656.3 2706.0 2746.2 3497.9 26244.6 26375.2 26481.5 26493.6 27221.5 3303.6 3405.6 3450.0 3453.9 3482.3 174856.0 175059.0 175119.1 175258.1 175528.0 38089.4 38896.2 49718.3 50028.5 50140.5 36167.5 36217.3 36349.6 36354.8 36417.8 12966.7 12966.8 12996.2 13078.4 13175.7 10129.5 10132.7 10160.5 10168.2 10190.4 12820.9 13212.7 13253.1 13288.6 13341.1 5282.8 5426.9 5456.6 5509.8 5511.9 808.2 828.1 870.0 912.6 915.2 26867.2 27312.5 28077.4 29005.7 31396.2 858.8 883.2 922.0 960.9 968.2 13434.7 13849.0 13888.7 14122.6 14255.2 308206.0 311732.0 314461.0 314570.0 315769.0 45250.5 45525.5 45867.5 46184.5 46450.8 1215.4 1239.6 1240.1 1251.6 1255.1 25111.9 25946.9 26219.5 26377.6 26487.2 39795.2 40123.4 40211.8 40304.2 40456.4 236742.0 238940.0 239028.0 239223.0 239403.0 29205.7 34401.7 34795.5 39587.6 40473.8 37448.0 37800.4 37993.8 38125.0 38481.2 31971.7 32722.5 32761.5 33092.8 33428.0 32539.5 32980.3 32980.7 33131.8 33134.0 32541.0 32852.5 33150.0 33179.5 33283.0 28136.0 29301.4 29985.3 30457.4 33173.5 22923.9 23327.0 23387.2 23413.3 23474.0 09:39:59 INFO - 2015-12-17 09:39:59,705 DEBUG : BROWSER_OUTPUT: 09:39:59 INFO - 2015-12-17 09:39:59,705 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:25772.66 Median:17074.05 stddev:74281.61 (435.1%) stddev-sans-first:74465.41 09:39:59 INFO - 2015-12-17 09:39:59,705 DEBUG : BROWSER_OUTPUT: Values: 324.7 325.6 327.4 328.2 330.6 14954.4 15251.5 15338.7 15815.4 16403.6 14726.3 14744.0 15127.2 15186.1 15201.0 4570.6 5070.8 5078.8 5097.3 5099.4 5117.7 5123.3 5142.7 5149.7 5151.8 4829.6 4869.6 4992.8 5033.2 5047.3 4571.6 4972.3 5005.6 5018.3 5048.1 5051.4 5058.4 5067.1 5086.6 5091.0 11480.3 11664.2 14165.9 18632.7 18705.9 1767.5 1822.8 1860.4 1877.9 1941.4 8937.9 9114.8 9115.1 9120.0 9199.1 1711.2 1776.3 1804.8 1844.8 1848.1 47783.3 48560.5 56200.4 58956.4 59163.7 9178.3 9430.0 9462.4 9534.1 9543.2 11788.1 11903.6 11910.4 11928.0 11928.8 7030.6 7041.3 7112.9 7113.7 7120.6 6772.2 6962.1 6988.4 6994.1 7010.4 4084.0 4150.6 4190.5 4191.2 4225.4 1757.7 1770.5 1776.4 1789.4 1789.5 795.4 854.6 931.1 938.5 952.1 15459.9 15651.6 15825.4 16304.7 16324.3 836.7 852.4 868.8 900.0 914.9 8906.4 9101.9 9173.8 9225.8 9289.0 446912.0 450787.0 455100.0 455905.0 465495.0 69327.7 71970.3 72734.0 73337.0 73398.6 1047.9 1065.8 1068.5 1071.8 1074.2 14409.5 14499.1 14701.2 14839.6 14939.8 32217.7 32586.8 32986.2 33817.5 34191.1 42820.6 43436.6 44143.8 44470.1 44755.3 8587.3 8785.3 11916.9 12376.6 12396.1 17045.4 17097.5 17164.3 17456.8 17712.3 14244.9 14376.9 14803.3 14934.3 15021.7 14126.4 14359.9 14485.3 14555.5 14853.9 15524.4 15540.0 15605.3 15694.2 15799.4 13973.2 13996.6 14106.0 14163.9 14280.9 14729.9 14982.0 15259.9 15284.5 15357.5 09:39:59 INFO - 2015-12-17 09:39:59,705 DEBUG : BROWSER_OUTPUT: 09:39:59 INFO - 2015-12-17 09:39:59,705 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:4857.77 Median:5974.92 stddev:2544.62 (42.6%) stddev-sans-first:2539.00 09:39:59 INFO - 2015-12-17 09:39:59,706 DEBUG : BROWSER_OUTPUT: Values: 1466.9 1620.4 1652.3 1669.3 1688.1 6217.6 6408.1 6801.4 7237.5 7267.0 6848.6 6861.7 6883.1 6948.4 6969.3 2005.9 2050.6 2051.6 2054.2 2055.0 2010.5 2052.3 2052.6 2056.2 2058.4 1992.4 2004.5 2008.3 2018.6 2032.1 2012.8 2050.3 2051.8 2062.9 2063.1 1982.9 2004.5 2022.8 2025.6 2027.3 7022.0 7111.5 7111.6 7116.7 7131.4 3936.4 3962.0 3982.7 3997.5 4001.9 2570.9 2589.1 2615.3 2625.7 2635.1 857.1 859.4 873.5 877.6 906.0 9328.5 9397.2 9481.0 9489.4 9492.7 3696.9 3703.2 3710.0 3812.1 3830.9 8276.8 8323.4 8356.7 8373.0 8376.8 4123.6 4151.7 4154.4 4157.9 4158.0 3164.5 3228.1 3631.6 3719.0 3720.1 3374.4 3397.8 3436.2 3436.4 3453.3 1069.5 1072.3 1086.6 1087.9 1093.0 922.4 951.5 1093.9 1099.3 1108.5 6662.1 6684.5 6691.6 6701.3 6749.4 416.1 416.3 416.7 418.5 419.3 5263.7 5293.4 5327.6 5333.6 5338.0 7905.3 8213.4 8228.3 8260.2 8280.8 8131.1 8141.0 8183.8 8217.0 8225.0 6432.8 6538.9 6564.9 6565.9 6614.2 6638.6 6656.0 6667.4 6667.4 6692.4 7471.1 7616.9 7682.4 7708.4 7713.4 7363.7 7506.4 7516.9 7575.5 7821.1 5373.6 5579.2 5783.0 6060.9 6132.7 6524.9 6551.4 6591.1 6598.7 6658.1 6221.9 6309.4 6433.8 6461.8 6472.6 6281.1 6296.6 6379.2 6424.5 6435.1 6293.1 6300.3 6334.0 6334.4 6342.9 5548.1 5835.2 5851.4 5886.5 5889.0 5696.0 5722.1 5727.6 5728.5 5737.3 09:39:59 INFO - 2015-12-17 09:39:59,706 DEBUG : BROWSER_OUTPUT: 09:39:59 INFO - 2015-12-17 09:39:59,707 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4837.58 Median:5537.97 stddev:1443.70 (26.1%) stddev-sans-first:1406.83 09:39:59 INFO - 2015-12-17 09:39:59,707 DEBUG : BROWSER_OUTPUT: Values: 290.5 292.1 298.2 301.1 312.7 5466.4 5480.1 5496.0 5560.5 5680.6 5511.5 5515.4 5568.1 5584.8 5589.4 4145.2 4258.1 4262.1 4262.9 4265.6 4233.8 4254.1 4274.5 4277.1 4277.7 4217.6 4222.5 4227.5 4228.7 4234.5 4190.5 4213.7 4225.9 4227.5 4227.6 3691.2 3691.2 3696.5 3698.6 3701.9 5207.5 5350.4 5374.9 5383.1 5386.3 4849.2 4850.1 4851.1 4862.6 4868.4 5876.4 5879.8 5881.6 5881.8 5909.3 3969.2 3977.9 3978.7 3992.5 3999.8 6014.5 6079.5 6119.0 6140.4 6148.2 4106.2 4119.2 4122.7 4123.5 4124.5 4183.0 4185.3 4186.7 4187.9 4191.2 4824.9 4831.3 4833.7 4842.6 4845.5 5711.5 5847.3 5851.2 5852.2 5861.6 1439.7 1466.3 1481.5 1485.3 1486.8 966.4 978.4 984.1 992.5 1003.5 5069.8 5078.8 5088.4 5105.0 5107.5 5904.5 5959.6 6060.0 6103.1 6104.1 5062.8 5099.2 5114.5 5118.0 5119.8 3263.6 3303.2 3319.5 3327.1 3331.4 5602.6 5731.3 5751.3 5752.4 5764.2 6291.2 6292.9 6316.7 6318.3 6321.3 5749.1 5758.6 5761.7 5765.8 5775.0 6072.3 6122.5 6129.9 6143.1 6145.7 5835.7 5956.9 5961.6 5965.7 5979.3 5732.0 5736.4 5740.4 5748.3 5753.5 4116.8 4133.0 4134.0 4139.9 4139.9 5948.9 6113.2 6126.8 6135.5 6138.6 5857.3 5868.9 5875.4 5880.4 5886.6 5825.4 5829.3 5871.2 5872.5 5882.3 5591.9 5732.5 5760.6 5764.6 5767.4 5907.1 5939.9 5946.3 5957.8 5970.0 5768.3 5782.0 5783.9 5792.5 5805.0 09:39:59 INFO - 2015-12-17 09:39:59,707 DEBUG : BROWSER_OUTPUT: 09:39:59 INFO - 2015-12-17 09:39:59,707 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:11558.16 Median:571.78 stddev:13036.03 (2279.9%) stddev-sans-first:13047.07 09:39:59 INFO - 2015-12-17 09:39:59,707 DEBUG : BROWSER_OUTPUT: Values: 696.3 716.9 727.5 754.6 765.7 9473.7 9630.5 10290.7 10658.7 10664.5 12377.6 12561.7 12562.6 12640.7 12645.9 775.3 786.2 787.2 788.9 789.5 783.3 787.5 789.3 790.8 790.8 819.1 826.2 828.8 829.2 830.7 818.1 819.6 820.6 821.4 822.9 609.8 610.3 610.7 610.9 611.0 10550.0 10740.1 10748.6 10755.0 10865.3 9984.0 10241.4 10313.4 10722.1 11009.6 8800.0 8828.0 8923.4 9221.2 9259.6 3139.4 3181.9 3201.8 3209.4 3276.4 33597.8 33811.7 34039.0 34063.8 34086.5 8056.0 8971.0 9066.0 9203.3 9278.6 13937.4 13979.4 14050.6 14079.4 14085.6 6166.3 6355.7 6363.4 6379.8 6404.6 3438.4 3510.3 3565.3 3583.4 3608.0 2550.6 2565.9 2566.8 2584.2 2586.3 467.5 468.5 468.6 476.1 478.3 380.5 387.6 409.3 410.6 432.2 7093.1 7430.3 7674.1 7701.8 7767.8 522.2 530.4 541.2 564.2 579.3 3341.5 3437.5 3548.3 3643.1 3653.9 44731.0 47713.8 47805.3 47847.8 47877.5 39938.4 40062.2 40231.8 40624.8 43256.6 37978.4 39264.2 40881.9 40959.4 43496.8 7088.9 7332.6 7476.2 7519.2 7647.5 31071.8 31461.7 31906.5 31908.7 32693.8 38408.4 38436.8 38749.8 38786.2 39292.4 9289.8 9515.2 9702.8 9905.4 9932.5 10037.1 10386.8 10556.2 10977.3 11146.3 9229.3 9566.6 9623.0 9853.8 9908.4 9742.3 9763.9 9833.0 9835.0 9844.1 9665.3 9798.2 9837.9 9844.3 9857.7 8775.3 8809.3 8981.1 8984.7 9064.0 8512.4 8602.9 8673.2 8683.6 9039.5 09:39:59 INFO - 2015-12-17 09:39:59,708 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:39:59 INFO - 2015-12-17 09:39:59,708 DEBUG : BROWSER_OUTPUT: 09:40:00 INFO - 2015-12-17 09:40:00,466 INFO : Browser exited with error code: 0 09:40:00 INFO - 2015-12-17 09:40:00,485 INFO : Completed test dromaeo_css (00:18:34) 09:40:00 INFO - 2015-12-17 09:40:00,485 INFO : Starting test kraken 09:40:00 INFO - 2015-12-17 09:40:00,485 DEBUG : operating with platform_type : linux_ 09:40:00 INFO - 2015-12-17 09:40:00,485 INFO : Initialising browser for kraken test... 09:40:00 INFO - 2015-12-17 09:40:00,491 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMjFhz_/profile http://localhost:52630/getInfo.html 09:40:05 INFO - 2015-12-17 09:40:05,132 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:40:05 INFO - 2015-12-17 09:40:05,133 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:40:05 INFO - 2015-12-17 09:40:05,133 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:40:05 INFO - 2015-12-17 09:40:05,133 DEBUG : BROWSER_OUTPUT: __metrics 09:40:05 INFO - 2015-12-17 09:40:05,269 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] 09:40:06 INFO - 2015-12-17 09:40:06,162 INFO : Browser initialized. 09:40:06 INFO - 2015-12-17 09:40:06,162 INFO : Running cycle 1/1 for kraken test... 09:40:06 INFO - 2015-12-17 09:40:06,162 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMjFhz_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:40:18 INFO - 2015-12-17 09:40:18,205 DEBUG : BROWSER_OUTPUT: RSS: Main: 150966272 09:40:18 INFO - 2015-12-17 09:40:18,205 DEBUG : BROWSER_OUTPUT: 09:40:20 INFO - 2015-12-17 09:40:20,785 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52630/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52630/tests/kraken/driver.html?testName=audio-beat-detection) 09:40:21 INFO - 2015-12-17 09:40:21,406 DEBUG : BROWSER_OUTPUT: RSS: Main: 152154112 09:40:21 INFO - 2015-12-17 09:40:21,406 DEBUG : BROWSER_OUTPUT: 09:40:24 INFO - 2015-12-17 09:40:24,148 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52630/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52630/tests/kraken/driver.html?testName=audio-dft) 09:40:24 INFO - 2015-12-17 09:40:24,754 DEBUG : BROWSER_OUTPUT: RSS: Main: 151461888 09:40:24 INFO - 2015-12-17 09:40:24,754 DEBUG : BROWSER_OUTPUT: 09:40:27 INFO - 2015-12-17 09:40:27,665 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52630/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52630/tests/kraken/driver.html?testName=audio-fft) 09:40:28 INFO - 2015-12-17 09:40:28,247 DEBUG : BROWSER_OUTPUT: RSS: Main: 146149376 09:40:28 INFO - 2015-12-17 09:40:28,247 DEBUG : BROWSER_OUTPUT: 09:40:30 INFO - 2015-12-17 09:40:30,279 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52630/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52630/tests/kraken/driver.html?testName=audio-oscillator) 09:40:30 INFO - 2015-12-17 09:40:30,900 DEBUG : BROWSER_OUTPUT: RSS: Main: 146878464 09:40:30 INFO - 2015-12-17 09:40:30,901 DEBUG : BROWSER_OUTPUT: 09:40:33 INFO - 2015-12-17 09:40:33,139 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52630/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52630/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:40:33 INFO - 2015-12-17 09:40:33,720 DEBUG : BROWSER_OUTPUT: RSS: Main: 146845696 09:40:33 INFO - 2015-12-17 09:40:33,720 DEBUG : BROWSER_OUTPUT: 09:40:36 INFO - 2015-12-17 09:40:36,763 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52630/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52630/tests/kraken/driver.html?testName=imaging-darkroom) 09:40:37 INFO - 2015-12-17 09:40:37,357 DEBUG : BROWSER_OUTPUT: RSS: Main: 146825216 09:40:37 INFO - 2015-12-17 09:40:37,357 DEBUG : BROWSER_OUTPUT: 09:40:40 INFO - 2015-12-17 09:40:40,810 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52630/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52630/tests/kraken/driver.html?testName=imaging-desaturate) 09:40:41 INFO - 2015-12-17 09:40:41,412 DEBUG : BROWSER_OUTPUT: RSS: Main: 148316160 09:40:41 INFO - 2015-12-17 09:40:41,412 DEBUG : BROWSER_OUTPUT: 09:40:44 INFO - 2015-12-17 09:40:44,314 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52630/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52630/tests/kraken/driver.html?testName=json-parse-financial) 09:40:44 INFO - 2015-12-17 09:40:44,915 DEBUG : BROWSER_OUTPUT: RSS: Main: 148713472 09:40:44 INFO - 2015-12-17 09:40:44,916 DEBUG : BROWSER_OUTPUT: 09:40:46 INFO - 2015-12-17 09:40:46,669 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52630/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52630/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:40:47 INFO - 2015-12-17 09:40:47,255 DEBUG : BROWSER_OUTPUT: RSS: Main: 147939328 09:40:47 INFO - 2015-12-17 09:40:47,255 DEBUG : BROWSER_OUTPUT: 09:40:49 INFO - 2015-12-17 09:40:49,174 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52630/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52630/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:40:49 INFO - 2015-12-17 09:40:49,796 DEBUG : BROWSER_OUTPUT: RSS: Main: 148672512 09:40:49 INFO - 2015-12-17 09:40:49,796 DEBUG : BROWSER_OUTPUT: 09:40:51 INFO - 2015-12-17 09:40:51,928 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52630/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52630/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:40:52 INFO - 2015-12-17 09:40:52,522 DEBUG : BROWSER_OUTPUT: RSS: Main: 148500480 09:40:52 INFO - 2015-12-17 09:40:52,522 DEBUG : BROWSER_OUTPUT: 09:40:55 INFO - 2015-12-17 09:40:55,078 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52630/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52630/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:40:55 INFO - 2015-12-17 09:40:55,680 DEBUG : BROWSER_OUTPUT: RSS: Main: 148463616 09:40:55 INFO - 2015-12-17 09:40:55,680 DEBUG : BROWSER_OUTPUT: 09:40:58 INFO - 2015-12-17 09:40:58,267 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52630/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52630/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:40:58 INFO - 2015-12-17 09:40:58,857 DEBUG : BROWSER_OUTPUT: RSS: Main: 148246528 09:40:58 INFO - 2015-12-17 09:40:58,857 DEBUG : BROWSER_OUTPUT: 09:41:00 INFO - 2015-12-17 09:41:00,628 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52630/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52630/tests/kraken/driver.html?testName=ai-astar) 09:41:00 INFO - 2015-12-17 09:41:00,873 DEBUG : BROWSER_OUTPUT: RSS: Main: 148951040 09:41:00 INFO - 2015-12-17 09:41:00,873 DEBUG : BROWSER_OUTPUT: 09:41:00 INFO - 2015-12-17 09:41:00,877 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:41:00 INFO - 2015-12-17 09:41:00,877 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:41:00 INFO - 2015-12-17 09:41:00,877 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:41:00 INFO - 2015-12-17 09:41:00,877 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:41:00 INFO - 2015-12-17 09:41:00,877 DEBUG : BROWSER_OUTPUT: |0;ai-astar;114;109;114;112;115;124;118;113;113;120 09:41:00 INFO - 2015-12-17 09:41:00,877 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;157;131;155;162;135;158;155;141;173;131 09:41:00 INFO - 2015-12-17 09:41:00,878 DEBUG : BROWSER_OUTPUT: |2;audio-dft;163;161;162;164;164;164;175;163;165;165 09:41:00 INFO - 2015-12-17 09:41:00,878 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;73;73;72;73;74;131;77;71;73 09:41:00 INFO - 2015-12-17 09:41:00,878 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;95;94;128;93;95;94;134;95;94;132 09:41:00 INFO - 2015-12-17 09:41:00,878 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;111;110;110;110;110;110;111;111;111;111 09:41:00 INFO - 2015-12-17 09:41:00,878 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;148;147;147;147;147;148;147;148;147;148 09:41:00 INFO - 2015-12-17 09:41:00,878 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;96;96;96;96;97;96;96;96 09:41:00 INFO - 2015-12-17 09:41:00,879 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;72;72;71;72;72;74;72;71;75;71 09:41:00 INFO - 2015-12-17 09:41:00,879 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;68;68;73;68;73;67;68;68;68;69 09:41:00 INFO - 2015-12-17 09:41:00,879 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;83;85;83;84;83;84;84;86;83;82 09:41:00 INFO - 2015-12-17 09:41:00,879 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;119;127;120;144;122;129;133;123;132;130 09:41:00 INFO - 2015-12-17 09:41:00,879 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;142;147;149;146;147;138;145;146;149;137 09:41:00 INFO - 2015-12-17 09:41:00,880 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;70;70;70;70;71;70;69;71;72;70 09:41:00 INFO - 2015-12-17 09:41:00,880 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:41:00 INFO - 2015-12-17 09:41:00,880 DEBUG : BROWSER_OUTPUT: __start_cc_report 09:41:00 INFO - 2015-12-17 09:41:00,880 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,792 09:41:00 INFO - 2015-12-17 09:41:00,880 DEBUG : BROWSER_OUTPUT: __end_cc_report 09:41:00 INFO - 2015-12-17 09:41:00,881 DEBUG : BROWSER_OUTPUT: __startTimestamp1450374060875__endTimestamp 09:41:00 INFO - 2015-12-17 09:41:00,881 DEBUG : BROWSER_OUTPUT: 09:41:00 INFO - 2015-12-17 09:41:00,884 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:41:00 INFO - 2015-12-17 09:41:00,885 DEBUG : BROWSER_OUTPUT: Number of tests: 14 09:41:00 INFO - 2015-12-17 09:41:00,885 DEBUG : BROWSER_OUTPUT: 09:41:00 INFO - 2015-12-17 09:41:00,885 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:115.20 Median:114.50 stddev:4.34 (3.8%) stddev-sans-first:4.58 09:41:00 INFO - 2015-12-17 09:41:00,886 DEBUG : BROWSER_OUTPUT: Values: 114.0 109.0 114.0 112.0 115.0 124.0 118.0 113.0 113.0 120.0 09:41:00 INFO - 2015-12-17 09:41:00,886 DEBUG : BROWSER_OUTPUT: 09:41:00 INFO - 2015-12-17 09:41:00,886 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:149.80 Median:156.00 stddev:14.39 (9.2%) stddev-sans-first:15.02 09:41:00 INFO - 2015-12-17 09:41:00,886 DEBUG : BROWSER_OUTPUT: Values: 157.0 131.0 155.0 162.0 135.0 158.0 155.0 141.0 173.0 131.0 09:41:00 INFO - 2015-12-17 09:41:00,887 DEBUG : BROWSER_OUTPUT: 09:41:00 INFO - 2015-12-17 09:41:00,887 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:164.60 Median:164.00 stddev:3.86 (2.4%) stddev-sans-first:4.06 09:41:00 INFO - 2015-12-17 09:41:00,887 DEBUG : BROWSER_OUTPUT: Values: 163.0 161.0 162.0 164.0 164.0 164.0 175.0 163.0 165.0 165.0 09:41:00 INFO - 2015-12-17 09:41:00,887 DEBUG : BROWSER_OUTPUT: 09:41:00 INFO - 2015-12-17 09:41:00,887 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:78.90 Median:73.00 stddev:18.38 (25.2%) stddev-sans-first:19.32 09:41:00 INFO - 2015-12-17 09:41:00,887 DEBUG : BROWSER_OUTPUT: Values: 72.0 73.0 73.0 72.0 73.0 74.0 131.0 77.0 71.0 73.0 09:41:00 INFO - 2015-12-17 09:41:00,887 DEBUG : BROWSER_OUTPUT: 09:41:00 INFO - 2015-12-17 09:41:00,887 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:105.40 Median:94.00 stddev:17.96 (19.1%) stddev-sans-first:18.66 09:41:00 INFO - 2015-12-17 09:41:00,887 DEBUG : BROWSER_OUTPUT: Values: 95.0 94.0 128.0 93.0 95.0 94.0 134.0 95.0 94.0 132.0 09:41:00 INFO - 2015-12-17 09:41:00,887 DEBUG : BROWSER_OUTPUT: 09:41:00 INFO - 2015-12-17 09:41:00,888 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:110.50 Median:111.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 09:41:00 INFO - 2015-12-17 09:41:00,888 DEBUG : BROWSER_OUTPUT: Values: 111.0 110.0 110.0 110.0 110.0 110.0 111.0 111.0 111.0 111.0 09:41:00 INFO - 2015-12-17 09:41:00,888 DEBUG : BROWSER_OUTPUT: 09:41:00 INFO - 2015-12-17 09:41:00,888 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:147.40 Median:147.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 09:41:00 INFO - 2015-12-17 09:41:00,888 DEBUG : BROWSER_OUTPUT: Values: 148.0 147.0 147.0 147.0 147.0 148.0 147.0 148.0 147.0 148.0 09:41:00 INFO - 2015-12-17 09:41:00,888 DEBUG : BROWSER_OUTPUT: 09:41:00 INFO - 2015-12-17 09:41:00,888 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.10 Median:96.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 09:41:00 INFO - 2015-12-17 09:41:00,888 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 96.0 96.0 96.0 96.0 97.0 96.0 96.0 96.0 09:41:00 INFO - 2015-12-17 09:41:00,888 DEBUG : BROWSER_OUTPUT: 09:41:00 INFO - 2015-12-17 09:41:00,889 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:72.20 Median:72.00 stddev:1.32 (1.8%) stddev-sans-first:1.39 09:41:00 INFO - 2015-12-17 09:41:00,889 DEBUG : BROWSER_OUTPUT: Values: 72.0 72.0 71.0 72.0 72.0 74.0 72.0 71.0 75.0 71.0 09:41:00 INFO - 2015-12-17 09:41:00,889 DEBUG : BROWSER_OUTPUT: 09:41:00 INFO - 2015-12-17 09:41:00,889 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:69.00 Median:68.00 stddev:2.16 (3.2%) stddev-sans-first:2.26 09:41:00 INFO - 2015-12-17 09:41:00,889 DEBUG : BROWSER_OUTPUT: Values: 68.0 68.0 73.0 68.0 73.0 67.0 68.0 68.0 68.0 69.0 09:41:00 INFO - 2015-12-17 09:41:00,889 DEBUG : BROWSER_OUTPUT: 09:41:00 INFO - 2015-12-17 09:41:00,889 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:83.70 Median:84.00 stddev:1.16 (1.4%) stddev-sans-first:1.20 09:41:00 INFO - 2015-12-17 09:41:00,890 DEBUG : BROWSER_OUTPUT: Values: 83.0 85.0 83.0 84.0 83.0 84.0 84.0 86.0 83.0 82.0 09:41:00 INFO - 2015-12-17 09:41:00,890 DEBUG : BROWSER_OUTPUT: 09:41:00 INFO - 2015-12-17 09:41:00,890 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:127.90 Median:129.50 stddev:7.52 (5.8%) stddev-sans-first:7.25 09:41:00 INFO - 2015-12-17 09:41:00,890 DEBUG : BROWSER_OUTPUT: Values: 119.0 127.0 120.0 144.0 122.0 129.0 133.0 123.0 132.0 130.0 09:41:00 INFO - 2015-12-17 09:41:00,890 DEBUG : BROWSER_OUTPUT: 09:41:00 INFO - 2015-12-17 09:41:00,890 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:144.60 Median:146.50 stddev:4.25 (2.9%) stddev-sans-first:4.40 09:41:00 INFO - 2015-12-17 09:41:00,890 DEBUG : BROWSER_OUTPUT: Values: 142.0 147.0 149.0 146.0 147.0 138.0 145.0 146.0 149.0 137.0 09:41:00 INFO - 2015-12-17 09:41:00,890 DEBUG : BROWSER_OUTPUT: 09:41:00 INFO - 2015-12-17 09:41:00,890 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.30 Median:70.00 stddev:0.82 (1.2%) stddev-sans-first:0.87 09:41:00 INFO - 2015-12-17 09:41:00,891 DEBUG : BROWSER_OUTPUT: Values: 70.0 70.0 70.0 70.0 71.0 70.0 69.0 71.0 72.0 70.0 09:41:00 INFO - 2015-12-17 09:41:00,891 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:41:00 INFO - 2015-12-17 09:41:00,891 DEBUG : BROWSER_OUTPUT: 09:41:01 INFO - 2015-12-17 09:41:01,830 INFO : Browser exited with error code: 0 09:41:01 INFO - 2015-12-17 09:41:01,884 INFO : Completed test kraken (00:01:01) 09:41:01 INFO - 2015-12-17 09:41:01,884 INFO : Starting test v8_7 09:41:01 INFO - 2015-12-17 09:41:01,884 DEBUG : operating with platform_type : linux_ 09:41:01 INFO - 2015-12-17 09:41:01,884 INFO : Initialising browser for v8_7 test... 09:41:01 INFO - 2015-12-17 09:41:01,890 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqUrF2M/profile http://localhost:52630/getInfo.html 09:41:06 INFO - 2015-12-17 09:41:06,469 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:41:06 INFO - 2015-12-17 09:41:06,470 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:41:06 INFO - 2015-12-17 09:41:06,470 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:41:06 INFO - 2015-12-17 09:41:06,470 DEBUG : BROWSER_OUTPUT: __metrics 09:41:06 INFO - 2015-12-17 09:41:06,586 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] 09:41:07 INFO - 2015-12-17 09:41:07,514 INFO : Browser initialized. 09:41:07 INFO - 2015-12-17 09:41:07,514 INFO : Running cycle 1/1 for v8_7 test... 09:41:07 INFO - 2015-12-17 09:41:07,515 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqUrF2M/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:41:19 INFO - 2015-12-17 09:41:19,585 DEBUG : BROWSER_OUTPUT: RSS: Main: 150642688 09:41:19 INFO - 2015-12-17 09:41:19,586 DEBUG : BROWSER_OUTPUT: 09:41:41 INFO - 2015-12-17 09:41:41,069 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52630/tests/v8_7/run.html (next: http://localhost:52630/tests/v8_7/run.html) 09:41:41 INFO - 2015-12-17 09:41:41,327 DEBUG : BROWSER_OUTPUT: RSS: Main: 146165760 09:41:41 INFO - 2015-12-17 09:41:41,327 DEBUG : BROWSER_OUTPUT: 09:41:41 INFO - 2015-12-17 09:41:41,327 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:41:41 INFO - 2015-12-17 09:41:41,327 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:41:41 INFO - 2015-12-17 09:41:41,327 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:41:41 INFO - 2015-12-17 09:41:41,327 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:41:41 INFO - 2015-12-17 09:41:41,328 DEBUG : BROWSER_OUTPUT: |0;Richards;166.5278934221482 09:41:41 INFO - 2015-12-17 09:41:41,328 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;185.5287569573284 09:41:41 INFO - 2015-12-17 09:41:41,328 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.08676307007784 09:41:41 INFO - 2015-12-17 09:41:41,328 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5175.257731958763 09:41:41 INFO - 2015-12-17 09:41:41,328 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1095.290251916758 09:41:41 INFO - 2015-12-17 09:41:41,328 DEBUG : BROWSER_OUTPUT: |5;Earley;711.2375533428165 09:41:41 INFO - 2015-12-17 09:41:41,328 DEBUG : BROWSER_OUTPUT: |6;Boyer;12231.707317073171 09:41:41 INFO - 2015-12-17 09:41:41,328 DEBUG : BROWSER_OUTPUT: |7;RegExp;26051.28205128205 09:41:41 INFO - 2015-12-17 09:41:41,328 DEBUG : BROWSER_OUTPUT: |8;Splay;592.6800472255018 09:41:41 INFO - 2015-12-17 09:41:41,328 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4864.077669902913 09:41:41 INFO - 2015-12-17 09:41:41,328 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:41:41 INFO - 2015-12-17 09:41:41,328 DEBUG : BROWSER_OUTPUT: __startTimestamp1450374101320__endTimestamp 09:41:41 INFO - 2015-12-17 09:41:41,328 DEBUG : BROWSER_OUTPUT: 09:41:41 INFO - 2015-12-17 09:41:41,328 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:41:41 INFO - 2015-12-17 09:41:41,328 DEBUG : BROWSER_OUTPUT: Number of tests: 10 09:41:41 INFO - 2015-12-17 09:41:41,328 DEBUG : BROWSER_OUTPUT: 09:41:41 INFO - 2015-12-17 09:41:41,329 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:166.53 Median:166.53 stddev:0.00 (0.0%) 09:41:41 INFO - 2015-12-17 09:41:41,329 DEBUG : BROWSER_OUTPUT: Values: 166.5 09:41:41 INFO - 2015-12-17 09:41:41,329 DEBUG : BROWSER_OUTPUT: 09:41:41 INFO - 2015-12-17 09:41:41,329 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:185.53 Median:185.53 stddev:0.00 (0.0%) 09:41:41 INFO - 2015-12-17 09:41:41,329 DEBUG : BROWSER_OUTPUT: Values: 185.5 09:41:41 INFO - 2015-12-17 09:41:41,329 DEBUG : BROWSER_OUTPUT: 09:41:41 INFO - 2015-12-17 09:41:41,329 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.09 Median:278.09 stddev:0.00 (0.0%) 09:41:41 INFO - 2015-12-17 09:41:41,329 DEBUG : BROWSER_OUTPUT: Values: 278.1 09:41:41 INFO - 2015-12-17 09:41:41,329 DEBUG : BROWSER_OUTPUT: 09:41:41 INFO - 2015-12-17 09:41:41,329 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5175.26 Median:5175.26 stddev:0.00 (0.0%) 09:41:41 INFO - 2015-12-17 09:41:41,329 DEBUG : BROWSER_OUTPUT: Values: 5175.3 09:41:41 INFO - 2015-12-17 09:41:41,329 DEBUG : BROWSER_OUTPUT: 09:41:41 INFO - 2015-12-17 09:41:41,329 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1095.29 Median:1095.29 stddev:0.00 (0.0%) 09:41:41 INFO - 2015-12-17 09:41:41,329 DEBUG : BROWSER_OUTPUT: Values: 1095.3 09:41:41 INFO - 2015-12-17 09:41:41,329 DEBUG : BROWSER_OUTPUT: 09:41:41 INFO - 2015-12-17 09:41:41,329 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:711.24 Median:711.24 stddev:0.00 (0.0%) 09:41:41 INFO - 2015-12-17 09:41:41,330 DEBUG : BROWSER_OUTPUT: Values: 711.2 09:41:41 INFO - 2015-12-17 09:41:41,330 DEBUG : BROWSER_OUTPUT: 09:41:41 INFO - 2015-12-17 09:41:41,330 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12231.71 Median:12231.71 stddev:0.00 (0.0%) 09:41:41 INFO - 2015-12-17 09:41:41,330 DEBUG : BROWSER_OUTPUT: Values: 12231.7 09:41:41 INFO - 2015-12-17 09:41:41,330 DEBUG : BROWSER_OUTPUT: 09:41:41 INFO - 2015-12-17 09:41:41,330 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26051.28 Median:26051.28 stddev:0.00 (0.0%) 09:41:41 INFO - 2015-12-17 09:41:41,330 DEBUG : BROWSER_OUTPUT: Values: 26051.3 09:41:41 INFO - 2015-12-17 09:41:41,330 DEBUG : BROWSER_OUTPUT: 09:41:41 INFO - 2015-12-17 09:41:41,330 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:592.68 Median:592.68 stddev:0.00 (0.0%) 09:41:41 INFO - 2015-12-17 09:41:41,330 DEBUG : BROWSER_OUTPUT: Values: 592.7 09:41:41 INFO - 2015-12-17 09:41:41,330 DEBUG : BROWSER_OUTPUT: 09:41:41 INFO - 2015-12-17 09:41:41,330 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4864.08 Median:4864.08 stddev:0.00 (0.0%) 09:41:41 INFO - 2015-12-17 09:41:41,330 DEBUG : BROWSER_OUTPUT: Values: 4864.1 09:41:41 INFO - 2015-12-17 09:41:41,330 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:41:41 INFO - 2015-12-17 09:41:41,330 DEBUG : BROWSER_OUTPUT: 09:41:42 INFO - 2015-12-17 09:41:42,250 INFO : Browser exited with error code: 0 09:41:42 INFO - 2015-12-17 09:41:42,264 INFO : Completed test v8_7 (00:00:40) 09:41:42 INFO - 2015-12-17 09:41:42,325 INFO : Completed test suite (00:20:16) 09:41:42 INFO - 2015-12-17 09:41:42,325 DEBUG : Working with test: dromaeo_css 09:41:42 INFO - 2015-12-17 09:41:42,326 DEBUG : Generating results file: dromaeo_css 09:41:42 INFO - 2015-12-17 09:41:42,327 DEBUG : Working with test: kraken 09:41:42 INFO - 2015-12-17 09:41:42,327 DEBUG : Generating results file: kraken 09:41:42 INFO - 2015-12-17 09:41:42,327 INFO : javascript benchmark 09:41:42 INFO - 2015-12-17 09:41:42,327 DEBUG : Working with test: v8_7 09:41:42 INFO - 2015-12-17 09:41:42,327 DEBUG : Generating results file: v8_7 09:41:42 INFO - 2015-12-17 09:41:42,328 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:41:42 INFO - 2015-12-17 09:41:42,386 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,132,43]] 09:41:42 INFO - 2015-12-17 09:41:42,386 DEBUG : process_Request line: dromaeo_css 6253.56 graph.html#tests=[[72,132,43]] 09:41:42 INFO - 2015-12-17 09:41:42,386 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:41:42 INFO - 2015-12-17 09:41:42,420 DEBUG : process_Request line: kraken 1535.60 graph.html#tests=[[232,132,43]] 09:41:42 INFO - 2015-12-17 09:41:42,420 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:41:42 INFO - 2015-12-17 09:41:42,464 DEBUG : process_Request line: v8_7 21341.29 graph.html#tests=[[230,132,43]] 09:41:42 INFO - 2015-12-17 09:41:42,466 INFO : javascript benchmark 09:41:42 INFO - 2015-12-17 09:41:42,469 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2668.986655066725, 2695.4339588686375, 2752.5045491811466, 2753.3529620539184, 2764.336559225785, 4606.516315786845, 4762.1428142934255, 4805.90388192236, 4820.542048505391, 4827.20351142062, 4726.409198850144, 4742.952570474295, 4775.47469778325, 4802.1836287831065, 4811.8797030074265, 4340.197063543254, 4403.93394099088, 4472.843450479234, 4491.505934347219, 4520.50274469808, 4314.115606300701, 4429.069895321986, 4449.666275029385, 4456.974895773974, 4567.885802854938, 4326.394304797331, 4343.218220720285, 4392.472903251631, 4402.779861006937, 4415.801288942005, 4434.73391596502, 4435.29035354345, 4438.400815889878, 4457.955420445786, 4525.818967241306, 4194.496660400741, 4299.548547402509, 4426.579474949875, 4441.0, 4478.4178056852725, 4578.725276483422, 4644.976775116136, 4669.603083737852, 4674.883127921795, 4698.671093023489, 5108.208227724708, 5135.974320128375, 5136.306598609177, 5145.2024936134585, 5198.40218374888, 4955.826546070906, 5071.061853557104, 5097.388313402415, 5158.303629010074, 5213.0, 4672.719636821767, 4702.835400760957, 4707.646926480527, 4747.548982846659, 4781.067691800135, 5183.041137389595, 5186.066508028591, 5186.688798672092, 5188.092083885305, 5189.273501709764, 4876.0, 4925.975370123198, 5006.274090256892, 5033.3205017322925, 5038.420581633159, 5119.897602047939, 5122.410922743932, 5129.820456284012, 5135.332406787168, 5178.119719647593, 5094.592432605383, 5096.872578185575, 5098.617603679739, 5108.3103780989095, 5133.486651334912, 4855.878603034882, 4966.354373931365, 5032.0690672225755, 5122.846314610568, 5126.666766660155, 4242.257604919127, 4278.122984788111, 4284.421603083606, 4287.206866729665, 4315.78421078951, 3618.4029635109914, 3754.6433088856515, 3780.678642315379, 3782.8108594570162, 3801.6958643308467, 4325.329573916048, 4339.826406943687, 4392.407025051577, 4393.275109606879, 4437.134758722083, 5132.5124113209185, 5146.0479811235045, 5151.793928242828, 5158.277841102249, 5184.326037615086, 4257.40396344512, 4364.541723119027, 4408.338749187584, 4417.53615870329, 4444.555544445529, 4450.688451808407, 4532.818687252539, 4533.863984080417, 4542.772861356985, 4549.681522293474, 4768.904621907543, 4901.264810278487, 4933.432655244693, 4935.16102262609, 4975.228839529878, 4909.68087074339, 5017.322661440805, 5087.2623469595965, 5211.895762084813, 5228.790848366023, 4893.330160996108, 4895.1922932717125, 4900.730459824601, 4916.778744956414, 4927.655064145475, 5103.336566246513, 5122.846314610568, 5125.8718532037, 5138.743062846917, 5151.072806894645, 4970.676906001243, 5082.669626474268, 5091.719955402488, 5191.143461328838, 5205.3753549572775, 4990.700557966534, 5001.8499445016705, 5007.674501157413, 5012.1980483122525, 5061.4938506150565, 4984.975075124747, 5018.347614810197, 5019.648624596243, 5030.320906677698, 5032.069067222429, 4716.221823399238, 4815.494373090775, 4827.517248275144, 4910.140725372974, 4914.94328719327, 4984.675996060245, 5076.6446348755235, 5078.238264260391, 5096.108181068224, 5123.308353372247, 4690.249560070372, 4933.531314525115, 4943.777530011086, 4963.925541116814, 5004.374453193351], "unit": "score", "name": "dojo.html", "value": 4691.662929026415}, {"lowerIsBetter": false, "replicates": [545.1114683066601, 548.2051026012281, 566.4703502225417, 574.3940143148978, 576.6943519934433, 34239.0, 35813.46279805802, 35938.64061359386, 35970.460443093376, 35974.58241467056, 39693.0, 40212.39681404774, 40220.597794022055, 40338.59661403385, 40579.0, 7880.211978802131, 8006.55963921984, 8068.959655201745, 8081.797955051112, 8094.878576821352, 7854.960725196394, 7980.4413691041655, 7995.880061799077, 8010.198980102002, 8026.598670066503, 7951.085625153123, 8035.758927232192, 8036.437449378546, 8050.51696898185, 8076.192380761936, 7908.367330613566, 8031.759047228593, 8037.156098609678, 8039.919600804004, 8053.597320134028, 7890.921090789134, 8008.279254867031, 8023.919760802376, 8025.799355016171, 8042.517448953082, 36506.087347816254, 36838.81580592107, 37056.814715926244, 37064.258714825555, 37124.0, 2394.9880250598635, 2656.2819025233093, 2706.012305508495, 2746.1898739871785, 3497.91255218619, 26244.60633090505, 26375.208743737527, 26481.47037059248, 26493.602595961074, 27221.455570888473, 3303.5705358303508, 3405.5743032120986, 3450.0, 3453.879114231015, 3482.331847498558, 174856.0, 175059.0, 175119.12440437844, 175258.1237093819, 175528.0, 38089.42865857014, 38896.22207555833, 49718.25422618663, 50028.49971500311, 50140.49859501358, 36167.4574881377, 36217.275654486766, 36349.636503635156, 36354.81822590923, 36417.81791091028, 12966.740665186644, 12966.80549791754, 12996.155249908727, 13078.411471483807, 13175.736485270432, 10129.493525323704, 10132.746681333025, 10160.54277557497, 10168.237382196217, 10190.43952582615, 12820.935895320463, 13212.669683257995, 13253.072284940154, 13288.601341959564, 13341.132826366254, 5282.7622756976025, 5426.891462170814, 5456.61803673739, 5509.807156749494, 5511.862203444865, 808.1595141053239, 828.130463013833, 870.0472982084607, 912.5710915869531, 915.1534830281962, 26867.193984180507, 27312.453750925266, 28077.43845123086, 29005.7099429003, 31396.215094622818, 858.7895965488495, 883.2183517586982, 921.9585118669676, 960.8991055939166, 968.2302569457298, 13434.731305373838, 13849.0, 13888.722225555432, 14122.576322710334, 14255.21596312192, 308206.0, 311732.0, 314461.0, 314570.0, 315769.0, 45250.54749452529, 45525.54474455279, 45867.54132458699, 46184.53815461802, 46450.76774616105, 1215.4105258949403, 1239.5599562155485, 1240.0699475393455, 1251.6432816647211, 1255.12768625806, 25111.87444062841, 25946.87026564855, 26219.4756104873, 26377.60433593536, 26487.205383838515, 39795.21945415181, 40123.398149027205, 40211.79894100511, 40304.1939161221, 40456.39315410212, 236742.0, 238940.0, 239028.0, 239223.0, 239403.0, 29205.7079429203, 34401.65598343984, 34795.47806782951, 39587.604123958394, 40473.79763101166, 37448.0, 37800.43299350457, 37993.810030949666, 38125.046873828374, 38481.23037539177, 31971.68028319687, 32722.50916236211, 32761.50857737088, 33092.83453582717, 33428.0, 32539.511907320935, 32980.34039319152, 32980.67019329776, 33131.846429981975, 33134.0, 32541.0, 32852.507212391356, 33150.0, 33179.50230746589, 33283.0, 28136.0, 29301.41397172087, 29985.250368740955, 30457.39085218239, 33173.50239746358, 22923.88538057299, 23327.0, 23387.181448649215, 23413.297601071994, 23474.0], "unit": "score", "name": "ext.html", "value": 16171.226805532522}, {"lowerIsBetter": false, "replicates": [324.7418302449552, 325.56374458225974, 327.44988419455314, 328.1762775433661, 330.6016250418245, 14954.401823927043, 15251.542453726392, 15338.69322613547, 15815.367385304602, 16403.58991025225, 14726.337314820832, 14744.041637293569, 15127.168005759679, 15186.088834669927, 15201.0, 4570.565796249351, 5070.822521211759, 5078.771455284503, 5097.3373461449955, 5099.4390617032095, 5117.667351622151, 5123.2827404163445, 5142.665726727751, 5149.716765577892, 5151.81968631098, 4829.589484893788, 4869.610431165498, 4992.750362481879, 5033.169527028036, 5047.293378926953, 4571.588557029883, 4972.303877457159, 5005.624578156653, 5018.322526458918, 5048.066107770056, 5051.419086804991, 5058.367704036996, 5067.062593420229, 5086.643934924557, 5090.9745451272875, 11480.311181329147, 11664.241824281395, 14165.929170354186, 18632.72050919237, 18705.906470467697, 1767.469759072273, 1822.7903791063932, 1860.3581764291298, 1877.8873267603988, 1941.38846263428, 8937.865932011026, 9114.77213069672, 9115.134062264075, 9119.954400228024, 9199.080091990814, 1711.195738003149, 1776.3249965013342, 1804.8285412885755, 1844.8431883289936, 1848.0944337274773, 47783.283250751265, 48560.51439485595, 56200.43799561993, 58956.41043589552, 59163.70418147925, 9178.265738740885, 9430.0, 9462.432254064779, 9534.09426104519, 9543.236541076698, 11788.115891308014, 11903.583374581847, 11910.404479775976, 11928.0, 11928.821067683992, 7030.613316267654, 7041.260667629826, 7112.92887071133, 7113.679884405215, 7120.608366539786, 6772.187337519529, 6962.1297337832775, 6988.40598549129, 6994.090768200102, 7010.369066783912, 4083.9795801020805, 4150.626443620089, 4190.476190476191, 4191.203671302443, 4225.429567008476, 1757.736339549078, 1770.513108895064, 1776.4049043570549, 1789.391606853676, 1789.454216463991, 795.3557618329172, 854.6410507586828, 931.1247427418206, 938.5354249646468, 952.0812416018437, 15459.922700386425, 15651.608709782118, 15825.366985320688, 16304.673906521803, 16324.34702611906, 836.7280633794041, 852.3607294529103, 868.8392647360258, 900.0, 914.8536234202496, 8906.376553641312, 9101.908980910239, 9173.77065573366, 9225.815483690289, 9289.042836851237, 446912.0, 450787.0, 455100.0, 455905.0, 465495.0, 69327.65336173287, 71970.2802971974, 72734.0, 73337.0, 73398.63300683463, 1047.8899715529913, 1065.765531583066, 1068.4657671164418, 1071.8285074388061, 1074.2211896375065, 14409.495667651581, 14499.130052196902, 14701.191434471206, 14839.629009274855, 14939.77590336124, 32217.677823221467, 32586.837065814518, 32986.17534561559, 33817.49273760847, 34191.14522136966, 42820.571794281655, 43436.56563434325, 44143.77928110339, 44470.110597787214, 44755.32867006932, 8587.270082043096, 8785.253253473526, 11916.940415297868, 12376.62870113898, 12396.132270740962, 17045.40341088056, 17097.48707538776, 17164.31342746326, 17456.82543174552, 17712.291508339524, 14244.928775356471, 14376.856231437552, 14803.259837008321, 14934.253287335807, 15021.6995660084, 14126.434942602591, 14359.92820035893, 14485.348159332645, 14555.490557830424, 14853.851461485247, 15524.37902483888, 15540.0, 15605.297761600528, 15694.21528923572, 15799.36802527932, 13973.231472269123, 13996.580102596938, 14106.0, 14163.929180354033, 14280.857191427953, 14729.92635036818, 14982.0, 15259.923700381427, 15284.465043723416, 15357.539273821803], "unit": "score", "name": "jquery.html", "value": 8488.735142947215}, {"lowerIsBetter": false, "replicates": [1466.860648238417, 1620.4085508789292, 1652.2647421897252, 1669.28220865028, 1688.1053041887797, 6217.5958562693395, 6408.134901788258, 6801.421879140273, 7237.529560578565, 7267.0, 6848.589084654925, 6861.656917154147, 6883.139607549057, 6948.444124470043, 6969.303069693028, 2005.9297924572643, 2050.5693804301113, 2051.640962831506, 2054.198862443648, 2054.979450205501, 2010.5375763574386, 2052.302217246136, 2052.6100040992164, 2056.2391914991476, 2058.4442200605827, 1992.4321568353023, 2004.5189154602863, 2008.2770202727006, 2018.596280743857, 2032.1363420546284, 2012.758468983724, 2050.292649036088, 2051.7640471345835, 2062.896855157251, 2063.1128614695676, 1982.9008549572463, 2004.5088953206412, 2022.7876073012417, 2025.6455120353878, 2027.310714357111, 7022.0, 7111.4666400020205, 7111.573305601681, 7116.679749411289, 7131.429485641188, 3936.409538569209, 3962.0293028208275, 3982.681385489154, 3997.5003124609425, 4001.919961600781, 2570.9228723138335, 2589.1067581684288, 2615.333090062041, 2625.658664373619, 2635.0645520840017, 857.065798279878, 859.415597393772, 873.5151990645189, 877.5612193903048, 905.9864102038474, 9328.533573321374, 9397.201237894787, 9481.004494528044, 9489.430634161972, 9492.715218543455, 3696.852125914987, 3703.2223233121344, 3709.962900370962, 3812.1041555234497, 3830.885073447801, 8276.83446331082, 8323.417360784686, 8356.749297521084, 8373.037100733372, 8376.790580235422, 4123.60825721556, 4151.709380343407, 4154.356074808409, 4157.875263742093, 4157.958420415817, 3164.5411415344647, 3228.0799972007867, 3631.618680038611, 3718.9628103718615, 3720.088578298334, 3374.3588718143474, 3397.8470968806464, 3436.1753179236803, 3436.381451338783, 3453.2575496268423, 1069.534752382716, 1072.254782925859, 1086.6414083352474, 1087.907527860125, 1092.9726756831142, 922.4465320807597, 951.5242378810594, 1093.9343639381664, 1099.290957332516, 1108.4623957380663, 6662.067310576524, 6684.465242780663, 6691.631960242234, 6701.262861085277, 6749.358810912955, 416.10329739411617, 416.2943810241672, 416.74578507110635, 418.45600719065095, 419.29976938512755, 5263.736813159327, 5293.417724050351, 5327.627066105413, 5333.6266461348105, 5337.973310133424, 7905.328047115944, 8213.425060245725, 8228.30059444942, 8260.215279548433, 8280.792980175424, 8131.146229645802, 8140.959295203486, 8183.795405114801, 8217.013958325038, 8224.958875205586, 6432.807015789534, 6538.869222615616, 6564.934350656527, 6565.901511477332, 6614.239362473377, 6638.634875081917, 6655.966720166368, 6667.399934005963, 6667.43326817226, 6692.431143352869, 7471.103467583925, 7616.9238307616215, 7682.385409167141, 7708.383329333527, 7713.421493387906, 7363.705451782083, 7506.361959233526, 7516.924830751623, 7575.507592006502, 7821.061472623322, 5373.596980226421, 5579.163125531203, 5783.045797443543, 6060.878782424239, 6132.724027418688, 6524.9021264682015, 6551.377619126175, 6591.077249185022, 6598.670066496752, 6658.067870498231, 6221.906671399842, 6309.432151106423, 6433.839154021, 6461.8061458156335, 6472.579282346633, 6281.089242059968, 6296.622202667854, 6379.234491861006, 6424.453921416732, 6435.099086127852, 6293.087502312231, 6300.338464461165, 6334.0, 6334.3665633436285, 6342.936570634234, 5548.140038294071, 5835.183074369676, 5851.414858514115, 5886.470217680429, 5888.970555147197, 5696.003199440164, 5722.084466485342, 5727.599068065196, 5728.484436400744, 5737.311522617312], "unit": "score", "name": "mootools.html", "value": 3918.9125678985547}, {"lowerIsBetter": false, "replicates": [290.52354139211684, 292.0537458634025, 298.21569272812513, 301.13423906269475, 312.704494252931, 5466.371367292762, 5480.095784195613, 5495.999841184762, 5560.52735517481, 5680.602357834953, 5511.53151982081, 5515.420880807517, 5568.1091025435935, 5584.776608935637, 5589.441055894408, 4145.170965806836, 4258.105797782469, 4262.062346283828, 4262.936055959163, 4265.573442655741, 4233.788310584474, 4254.149170165963, 4274.465691788527, 4277.1018086201775, 4277.721948073366, 4217.557156498569, 4222.514410842747, 4227.450431443924, 4228.682848786353, 4234.470691163605, 4190.476190476191, 4213.747175169499, 4225.852095176654, 4227.492700875884, 4227.556106608792, 3691.169486865461, 3691.187938653492, 3696.537932758405, 3698.5746639136305, 3701.9444708329397, 5207.505286997767, 5350.357957045179, 5374.946250537484, 5383.057964856173, 5386.326709161355, 4849.175640141149, 4850.102730994778, 4851.054044461332, 4862.635302352338, 4868.391451068616, 5876.382979787104, 5879.7648094076185, 5881.647101173943, 5881.764729410818, 5909.320428150775, 3969.206158768258, 3977.880663580097, 3978.701597380179, 3992.461017762594, 3999.7600143991453, 6014.548908831812, 6079.483243924272, 6118.969405152946, 6140.416660417253, 6148.200733904609, 4106.178764247103, 4119.155573107506, 4122.711410201317, 4123.525794533607, 4124.546299907008, 4182.975171083104, 4185.309423945015, 4186.727862688916, 4187.895302617459, 4191.203671302443, 4824.903501930012, 4831.275308703653, 4833.734144622009, 4842.58837998767, 4845.466998630148, 5711.485966263056, 5847.26909136358, 5851.239338885917, 5852.209951656557, 5861.589688721749, 1439.6688761584894, 1466.2595389328535, 1481.5036962617428, 1485.3093311609937, 1486.8364479907202, 966.4104896013426, 978.4325091446943, 984.0749695286407, 992.5186284651938, 1003.4882210072769, 5069.771860266297, 5078.771455284521, 5088.363954505687, 5104.9744751276, 5107.489251074863, 5904.498117659961, 5959.612625179349, 6060.0, 6103.1455596216565, 6104.053871649901, 5062.82280120194, 5099.184130539096, 5114.48855114493, 5117.974410128, 5119.846404607793, 3263.6246831614194, 3303.19071827399, 3319.5020746887676, 3327.085051610778, 3331.400347937346, 5602.551795856321, 5731.283589551306, 5751.338596061423, 5752.36723960364, 5764.221830052973, 6291.245050593958, 6292.937070629327, 6316.715747791361, 6318.273398559132, 6321.304656487875, 5749.051406517878, 5758.62568933018, 5761.711914404263, 5765.827025189251, 5775.0471172257785, 6072.271327440735, 6122.540809439399, 6129.877402452015, 6143.139960405636, 6145.6927153641245, 5835.708214589338, 5956.910646340395, 5961.612495187799, 5965.6718880461995, 5979.312379076462, 5732.025555655632, 5736.42635736423, 5740.3685594585395, 5748.338941021669, 5753.482186603226, 4116.835326586903, 4133.04939863827, 4133.9793301033305, 4139.875803725772, 4139.917201656011, 5948.940510594839, 6113.205283313141, 6126.816195514141, 6135.509159267159, 6138.631682099089, 5857.26784151981, 5868.853278668067, 5875.412458754261, 5880.441358070976, 5886.558508111823, 5825.3883342248455, 5829.300483941955, 5871.2073870027, 5872.471477566869, 5882.264716910387, 5591.944080559305, 5732.512736417451, 5760.596758226884, 5764.567657425794, 5767.394423585631, 5907.084401917709, 5939.91090133657, 5946.316173640087, 5957.791477298274, 5969.9701501493955, 5768.2501274834, 5782.0, 5783.942160578508, 5792.536597072309, 5804.970975145097], "unit": "score", "name": "prototype.html", "value": 4365.506860682689}, {"lowerIsBetter": false, "replicates": [696.2584847137799, 716.8817145171047, 727.5452841973766, 754.6000619671572, 765.6898955922851, 9473.715788526339, 9630.518474076303, 10290.691279261624, 10658.733531661723, 10664.466776661175, 12377.62867113988, 12561.748765024695, 12562.560310389139, 12640.747185056294, 12645.936770316159, 775.2673723331454, 786.1862971824163, 787.208855100623, 788.9053313602376, 789.4631650477672, 783.322426101421, 787.5471603827793, 789.3093543149744, 790.7983464216617, 790.8220650353682, 819.0949001353508, 826.1903334731936, 828.8093738440132, 829.2412442615, 830.6511265268601, 818.1368656067846, 819.6147810529071, 820.5897051474263, 821.4414198345123, 822.9465084769471, 609.7500024989733, 610.2890132995055, 610.7160170520684, 610.9236345456818, 610.9725062372204, 10550.0, 10740.08709259714, 10748.570057197703, 10755.0, 10865.293755905841, 9984.0, 10241.436720980342, 10313.381197128196, 10722.0886224671, 11009.559617615285, 8799.956000220021, 8828.028916819145, 8923.375363724543, 9221.216196623294, 9259.6296148154, 3139.3564319314487, 3181.9204519886957, 3201.791883527563, 3209.3741720364537, 3276.410246155691, 33597.83201084004, 33811.66188338134, 34039.0, 34063.829680851435, 34086.48870266948, 8056.0, 8970.955145224363, 9065.954670226607, 9203.309751768644, 9278.582463789146, 13937.372818223204, 13979.440822367194, 14050.578482645536, 14079.436822527188, 14085.647858803406, 6166.260048794174, 6355.745770169141, 6363.427291543783, 6379.840503987438, 6404.583702059351, 3438.398280300937, 3510.26284480262, 3565.3404120237838, 3583.4445660922597, 3607.9897628664016, 2550.6046562782426, 2565.8717064146717, 2566.7818235450195, 2584.211815396322, 2586.2629150691964, 467.5277969251013, 468.47998721419196, 468.59934755783996, 476.0502796920097, 478.3494447551397, 380.5110433093462, 387.5852837463943, 409.30418288908976, 410.64479225469756, 432.2371015158248, 7093.113360829896, 7430.256974302527, 7674.0805639075425, 7701.768946931601, 7767.766966990999, 522.2427480153792, 530.361984446022, 541.228749032627, 564.2376122175721, 579.3135134865198, 3341.4743557792663, 3437.5359326491102, 3548.27260411615, 3643.0892276930767, 3653.8538458461894, 44731.0, 47713.76143119262, 47805.28292075622, 47847.76076119597, 47877.521224788004, 39938.40092398616, 40062.198756024714, 40231.798841006195, 40624.79687601543, 43256.56743432588, 37978.430323545166, 39264.21471570552, 40881.88976377953, 40959.38560921588, 43496.78251608785, 7088.929110708827, 7332.5967071811565, 7476.214997425192, 7519.210482899435, 7647.541147531166, 31071.84464077665, 31461.685383145876, 31906.52140217852, 31908.680913190572, 32693.836530817192, 38408.42387364136, 38436.80781596186, 38749.80625096856, 38786.2242755149, 39292.41061384024, 9289.814203716023, 9515.238780897651, 9702.805943881223, 9905.356151850106, 9932.503374831374, 10037.096661300518, 10386.844197337197, 10556.208284378856, 10977.28647637901, 11146.275492092987, 9229.261659067393, 9566.56950437218, 9623.027279407564, 9853.802923941625, 9908.35595686278, 9742.318037737574, 9763.853542197015, 9832.950835245778, 9834.950825245827, 9844.114029737359, 9665.3234273603, 9798.216142708709, 9837.852432213665, 9844.261680373857, 9857.704268871945, 8775.341849361452, 8809.295256379603, 8981.14679105484, 8984.685536006209, 9063.954680226556, 8512.361572881935, 8602.870956935525, 8673.219410253107, 8683.609237584451, 9039.502827344559], "unit": "score", "name": "yui.html", "value": 5427.577656773345}], "name": "dromaeo_css", "value": 6253.558389402969}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [114.0, 109.0, 114.0, 112.0, 115.0, 124.0, 118.0, 113.0, 113.0, 120.0], "unit": "score", "name": "ai-astar", "value": 115.2}, {"lowerIsBetter": true, "replicates": [157.0, 131.0, 155.0, 162.0, 135.0, 158.0, 155.0, 141.0, 173.0, 131.0], "unit": "score", "name": "audio-beat-detection", "value": 149.8}, {"lowerIsBetter": true, "replicates": [163.0, 161.0, 162.0, 164.0, 164.0, 164.0, 175.0, 163.0, 165.0, 165.0], "unit": "score", "name": "audio-dft", "value": 164.6}, {"lowerIsBetter": true, "replicates": [72.0, 73.0, 73.0, 72.0, 73.0, 74.0, 131.0, 77.0, 71.0, 73.0], "unit": "score", "name": "audio-fft", "value": 78.9}, {"lowerIsBetter": true, "replicates": [95.0, 94.0, 128.0, 93.0, 95.0, 94.0, 134.0, 95.0, 94.0, 132.0], "unit": "score", "name": "audio-oscillator", "value": 105.4}, {"lowerIsBetter": true, "replicates": [111.0, 110.0, 110.0, 110.0, 110.0, 110.0, 111.0, 111.0, 111.0, 111.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 110.5}, {"lowerIsBetter": true, "replicates": [148.0, 147.0, 147.0, 147.0, 147.0, 148.0, 147.0, 148.0, 147.0, 148.0], "unit": "score", "name": "imaging-darkroom", "value": 147.4}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 97.0, 96.0, 96.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 96.1}, {"lowerIsBetter": true, "replicates": [72.0, 72.0, 71.0, 72.0, 72.0, 74.0, 72.0, 71.0, 75.0, 71.0], "unit": "score", "name": "json-parse-financial", "value": 72.2}, {"lowerIsBetter": true, "replicates": [68.0, 68.0, 73.0, 68.0, 73.0, 67.0, 68.0, 68.0, 68.0, 69.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 69.0}, {"lowerIsBetter": true, "replicates": [83.0, 85.0, 83.0, 84.0, 83.0, 84.0, 84.0, 86.0, 83.0, 82.0], "unit": "score", "name": "stanford-crypto-aes", "value": 83.7}, {"lowerIsBetter": true, "replicates": [119.0, 127.0, 120.0, 144.0, 122.0, 129.0, 133.0, 123.0, 132.0, 130.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 127.9}, {"lowerIsBetter": true, "replicates": [142.0, 147.0, 149.0, 146.0, 147.0, 138.0, 145.0, 146.0, 149.0, 137.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 144.6}, {"lowerIsBetter": true, "replicates": [70.0, 70.0, 70.0, 70.0, 71.0, 70.0, 69.0, 71.0, 72.0, 70.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 70.3}], "name": "kraken", "value": 1535.6}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.5278934221482], "unit": "score", "name": "Richards", "value": 211.98851000000005}, {"lowerIsBetter": false, "replicates": [185.5287569573284], "unit": "score", "name": "DeltaBlue", "value": 356.3760199999999}, {"lowerIsBetter": false, "replicates": [278.08676307007784], "unit": "score", "name": "Encrypt", "value": 957.1868759999999}, {"lowerIsBetter": false, "replicates": [5175.257731958763], "unit": "score", "name": "Decrypt", "value": 51.433380478087685}, {"lowerIsBetter": false, "replicates": [1095.290251916758], "unit": "score", "name": "RayTrace", "value": 675.6099569999998}, {"lowerIsBetter": false, "replicates": [711.2375533428165], "unit": "score", "name": "Earley", "value": 937.0469780000002}, {"lowerIsBetter": false, "replicates": [12231.707317073171], "unit": "score", "name": "Boyer", "value": 54.4865064805583}, {"lowerIsBetter": false, "replicates": [26051.28205128205], "unit": "score", "name": "RegExp", "value": 34.96891240157479}, {"lowerIsBetter": false, "replicates": [592.6800472255018], "unit": "score", "name": "Splay", "value": 137.4957709163346}, {"lowerIsBetter": false, "replicates": [4864.077669902913], "unit": "score", "name": "NavierStokes", "value": 305.0938123752493}], "name": "v8_7", "value": 21341.286422463574}]} 09:41:42 INFO - RETURN: dromaeo_css: 6253.56 09:41:42 INFO - RETURN: kraken: 1535.60 09:41:42 INFO - RETURN: v8_7: 21341.29 09:41:42 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,132,43]]", "result": "21341.29"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,132,43]]", "result": "1535.60"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,132,43]]", "result": "6253.56"}}} 09:41:42 INFO - Return code: 0 09:41:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:41:42 INFO - # TBPL SUCCESS # 09:41:42 INFO - Running post-action listener: _resource_record_post_action 09:41:42 INFO - Running post-run listener: _resource_record_post_run 09:41:43 INFO - Total resource usage - Wall time: 1233s; CPU: 13.0%; Read bytes: 0; Write bytes: 544411648; Read time: 0; Write time: 452688 09:41:43 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 227446784; Read time: 0; Write time: 244364 09:41:43 INFO - run-tests - Wall time: 1218s; CPU: 13.0%; Read bytes: 0; Write bytes: 316964864; Read time: 0; Write time: 208324 09:41:43 INFO - Running post-run listener: _upload_blobber_files 09:41:43 INFO - Blob upload gear active. 09:41:43 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 09:41:43 INFO - Copying logs to upload dir... 09:41:43 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1277.569610 ========= master_lag: 0.15 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2015-12-17 09:41:43.053782) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-17 09:41:43.057474) ========= 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=dd132779db58144225c4cf24000001c8-1450372216.270435-485160866 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/E-xBxCxAR2yXPnbI9c9yYg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009850 build_url: 'https://queue.taskcluster.net/v1/task/E-xBxCxAR2yXPnbI9c9yYg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.02 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-17 09:41:43.088604) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2015-12-17 09:41:43.088923) ========= 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=dd132779db58144225c4cf24000001c8-1450372216.270435-485160866 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004561 ========= master_lag: 1.89 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2015-12-17 09:41:44.985736) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-17 09:41:44.986086) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-17 09:41:44.990269) ========= ========= Total master_lag: 2.23 =========