builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-056 starttime: 1450298373.39 results: success (0) buildid: 20151216114502 builduid: 65ac83e824e249959ccd21219d3cd1c9 revision: 2c0d64cea673b90bc49aa8d4cfa63787931782cc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-16 12:39:33.393421) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-16 12:39:33.393995) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-16 12:39:33.394355) ========= 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=0ae9de487587ba4b94dd3100000001b5-1450294446.516140-759424473 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.006341 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-16 12:39:33.420724) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-16 12:39:33.421059) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-16 12:39:33.464639) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 12:39:33.464967) ========= 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=0ae9de487587ba4b94dd3100000001b5-1450294446.516140-759424473 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004575 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 12:39:33.518622) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-16 12:39:33.518917) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-16 12:39:33.519280) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 12:39:33.519614) ========= 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=0ae9de487587ba4b94dd3100000001b5-1450294446.516140-759424473 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-16 12:39:34-- 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% 64.1M=0s 2015-12-16 12:39:34 (64.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.190102 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 12:39:33.736977) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 12:39:33.737289) ========= 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=0ae9de487587ba4b94dd3100000001b5-1450294446.516140-759424473 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018000 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 12:39:33.794633) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-12-16 12:39:33.795103) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 2c0d64cea673b90bc49aa8d4cfa63787931782cc --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 2c0d64cea673b90bc49aa8d4cfa63787931782cc --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=0ae9de487587ba4b94dd3100000001b5-1450294446.516140-759424473 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-16 12:39:34,345 truncating revision to first 12 chars 2015-12-16 12:39:34,345 Setting DEBUG logging. 2015-12-16 12:39:34,346 attempt 1/10 2015-12-16 12:39:34,346 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/2c0d64cea673?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-16 12:39:35,298 unpacking tar archive at: fx-team-2c0d64cea673/testing/mozharness/ program finished with exit code 0 elapsedTime=1.217792 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-12-16 12:39:35.027498) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-16 12:39:35.027856) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-16 12:39:35.037066) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-16 12:39:35.037577) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-16 12:39:35.038271) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2015-12-16 12:39:35.038754) ========= /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=0ae9de487587ba4b94dd3100000001b5-1450294446.516140-759424473 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:39:35 INFO - MultiFileLogger online at 20151216 12:39:35 in /builds/slave/test 12:39:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 12:39:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:39:35 INFO - {'append_to_log': False, 12:39:35 INFO - 'base_work_dir': '/builds/slave/test', 12:39:35 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 12:39:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:39:35 INFO - 'branch': 'Fx-Team-Non-PGO', 12:39:35 INFO - 'buildbot_json_path': 'buildprops.json', 12:39:35 INFO - 'config_files': ('talos/linux_config.py',), 12:39:35 INFO - 'default_actions': ('clobber', 12:39:35 INFO - 'read-buildbot-config', 12:39:35 INFO - 'download-and-extract', 12:39:35 INFO - 'populate-webroot', 12:39:35 INFO - 'create-virtualenv', 12:39:35 INFO - 'install', 12:39:35 INFO - 'run-tests'), 12:39:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:39:35 INFO - 'download_minidump_stackwalk': True, 12:39:35 INFO - 'download_symbols': 'ondemand', 12:39:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:39:35 INFO - 'tooltool.py': '/tools/tooltool.py', 12:39:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:39:35 INFO - '/tools/misc-python/virtualenv.py')}, 12:39:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:39:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:39:35 INFO - 'installer_path': 'installer.exe', 12:39:35 INFO - 'log_level': 'info', 12:39:35 INFO - 'log_name': 'talos', 12:39:35 INFO - 'log_to_console': True, 12:39:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:39:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:39:35 INFO - 'opt_config_files': (), 12:39:35 INFO - 'pip_index': False, 12:39:35 INFO - 'sps_profile': False, 12:39:35 INFO - 'sps_profile_interval': 0, 12:39:35 INFO - 'suite': 'dromaeojs-e10s', 12:39:35 INFO - 'system_bits': '64', 12:39:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:39:35 INFO - 'title': 'talos-linux64-ix-056', 12:39:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:39:35 INFO - 'use_talos_json': True, 12:39:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:39:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:39:35 INFO - 'work_dir': 'build'} 12:39:35 INFO - ##### 12:39:35 INFO - ##### Running clobber step. 12:39:35 INFO - ##### 12:39:35 INFO - Running pre-action listener: _resource_record_pre_action 12:39:35 INFO - Running main action method: clobber 12:39:35 INFO - rmtree: /builds/slave/test/build 12:39:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:39:36 INFO - Running post-action listener: _resource_record_post_action 12:39:36 INFO - ##### 12:39:36 INFO - ##### Running read-buildbot-config step. 12:39:36 INFO - ##### 12:39:36 INFO - Running pre-action listener: _resource_record_pre_action 12:39:36 INFO - Running main action method: read_buildbot_config 12:39:36 INFO - Using buildbot properties: 12:39:36 INFO - { 12:39:36 INFO - "project": "", 12:39:36 INFO - "product": "firefox", 12:39:36 INFO - "script_repo_revision": "production", 12:39:36 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 12:39:36 INFO - "repository": "", 12:39:36 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs-e10s", 12:39:36 INFO - "buildid": "20151216114502", 12:39:36 INFO - "pgo_build": "False", 12:39:36 INFO - "basedir": "/builds/slave/test", 12:39:36 INFO - "buildnumber": 590, 12:39:36 INFO - "slavename": "talos-linux64-ix-056", 12:39:36 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 12:39:36 INFO - "platform": "ubuntu64_hw", 12:39:36 INFO - "scheduler": "tests-fx-team-linux64-talos", 12:39:36 INFO - "branch": "fx-team", 12:39:36 INFO - "repo_path": "integration/fx-team", 12:39:36 INFO - "revision": "2c0d64cea673b90bc49aa8d4cfa63787931782cc", 12:39:36 INFO - "stage_platform": "linux64", 12:39:36 INFO - "builduid": "65ac83e824e249959ccd21219d3cd1c9", 12:39:36 INFO - "slavebuilddir": "test" 12:39:36 INFO - } 12:39:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/4-p-qWeRTKW4vLZSumhtXQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 12:39:36 INFO - Running post-action listener: _resource_record_post_action 12:39:36 INFO - ##### 12:39:36 INFO - ##### Running download-and-extract step. 12:39:36 INFO - ##### 12:39:36 INFO - Running pre-action listener: _resource_record_pre_action 12:39:36 INFO - Running main action method: download_and_extract 12:39:36 INFO - mkdir: /builds/slave/test/build/tests 12:39:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:39:36 INFO - https://queue.taskcluster.net/v1/task/4-p-qWeRTKW4vLZSumhtXQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 12:39:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4-p-qWeRTKW4vLZSumhtXQ/artifacts/public/build/test_packages.json 12:39:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4-p-qWeRTKW4vLZSumhtXQ/artifacts/public/build/test_packages.json 12:39:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4-p-qWeRTKW4vLZSumhtXQ/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 12:39:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4-p-qWeRTKW4vLZSumhtXQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 12:39:41 INFO - Downloaded 1302 bytes. 12:39:41 INFO - Reading from file /builds/slave/test/build/test_packages.json 12:39:41 INFO - Using the following test package requirements: 12:39:41 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 12:39:41 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 12:39:41 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 12:39:41 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 12:39:41 INFO - u'jsshell-linux-x86_64.zip'], 12:39:41 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 12:39:41 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 12:39:41 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 12:39:41 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 12:39:41 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 12:39:41 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 12:39:41 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 12:39:41 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 12:39:41 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 12:39:41 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 12:39:41 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 12:39:41 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 12:39:41 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:39:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:39:41 INFO - https://queue.taskcluster.net/v1/task/4-p-qWeRTKW4vLZSumhtXQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:39:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4-p-qWeRTKW4vLZSumhtXQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 12:39:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4-p-qWeRTKW4vLZSumhtXQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 12:39:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4-p-qWeRTKW4vLZSumhtXQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 12:39:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4-p-qWeRTKW4vLZSumhtXQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:39:44 INFO - Downloaded 21870297 bytes. 12:39:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 12:39:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 12:39:44 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 12:39:45 INFO - Return code: 0 12:39:45 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 12:39:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:39:45 INFO - https://queue.taskcluster.net/v1/task/4-p-qWeRTKW4vLZSumhtXQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:39:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4-p-qWeRTKW4vLZSumhtXQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 12:39:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4-p-qWeRTKW4vLZSumhtXQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 12:39:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4-p-qWeRTKW4vLZSumhtXQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 12:39:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4-p-qWeRTKW4vLZSumhtXQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:39:54 INFO - Downloaded 21870297 bytes. 12:39:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 12:39:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 12:39:54 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 12:39:55 INFO - Return code: 0 12:39:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:39:55 INFO - https://queue.taskcluster.net/v1/task/4-p-qWeRTKW4vLZSumhtXQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 12:39:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4-p-qWeRTKW4vLZSumhtXQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 12:39:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4-p-qWeRTKW4vLZSumhtXQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 12:39:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4-p-qWeRTKW4vLZSumhtXQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 12:39:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4-p-qWeRTKW4vLZSumhtXQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 12:39:56 INFO - Downloaded 11132052 bytes. 12:39:56 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test/build/tests 12:39:56 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 12:39:56 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 12:39:57 INFO - Return code: 0 12:39:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:39:57 INFO - https://queue.taskcluster.net/v1/task/4-p-qWeRTKW4vLZSumhtXQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 12:39:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4-p-qWeRTKW4vLZSumhtXQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 12:39:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4-p-qWeRTKW4vLZSumhtXQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 12:39:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4-p-qWeRTKW4vLZSumhtXQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 12:39:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/4-p-qWeRTKW4vLZSumhtXQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 12:39:58 INFO - Downloaded 57772574 bytes. 12:39:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/4-p-qWeRTKW4vLZSumhtXQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 12:39:58 INFO - mkdir: /builds/slave/test/properties 12:39:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:39:58 INFO - Writing to file /builds/slave/test/properties/build_url 12:39:58 INFO - Contents: 12:39:58 INFO - build_url:https://queue.taskcluster.net/v1/task/4-p-qWeRTKW4vLZSumhtXQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 12:39:58 INFO - Running post-action listener: _resource_record_post_action 12:39:58 INFO - Running post-action listener: set_extra_try_arguments 12:39:58 INFO - ##### 12:39:58 INFO - ##### Running populate-webroot step. 12:39:58 INFO - ##### 12:39:58 INFO - Running pre-action listener: _resource_record_pre_action 12:39:58 INFO - Running main action method: populate_webroot 12:39:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:39:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:39:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:39:58 INFO - '--tppagecycles', 12:39:58 INFO - '7'], 12:39:58 INFO - 'tests': ['tsvgm']}}, 12:39:58 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 12:39:58 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 12:39:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:39:58 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 12:39:58 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 12:39:58 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 12:39:58 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:39:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:39:58 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:39:58 INFO - 'talos_options': ['--e10s'], 12:39:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:39:58 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:39:58 INFO - 'tests': ['damp', 'tps']}, 12:39:58 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:39:58 INFO - 'talos_options': ['--e10s'], 12:39:58 INFO - 'tests': ['damp', 'tps']}, 12:39:58 INFO - 'g3': {'tests': ['dromaeo_dom']}, 12:39:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:39:58 INFO - 'other': {'tests': ['a11yr', 12:39:58 INFO - 'ts_paint', 12:39:58 INFO - 'tpaint', 12:39:58 INFO - 'sessionrestore', 12:39:58 INFO - 'sessionrestore_no_auto_restore']}, 12:39:58 INFO - 'other-e10s': {'talos_options': ['--e10s'], 12:39:58 INFO - 'tests': ['a11yr', 12:39:58 INFO - 'ts_paint', 12:39:58 INFO - 'tpaint', 12:39:58 INFO - 'sessionrestore', 12:39:58 INFO - 'sessionrestore_no_auto_restore']}, 12:39:58 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 12:39:58 INFO - 'tests': ['a11yr', 12:39:58 INFO - 'ts_paint', 12:39:58 INFO - 'tpaint', 12:39:58 INFO - 'sessionrestore', 12:39:58 INFO - 'sessionrestore_no_auto_restore']}, 12:39:58 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 12:39:58 INFO - 'tests': ['a11yr', 12:39:58 INFO - 'ts_paint', 12:39:58 INFO - 'tpaint', 12:39:58 INFO - 'sessionrestore', 12:39:58 INFO - 'sessionrestore_no_auto_restore']}, 12:39:58 INFO - 'other_l64': {'tests': ['a11yr', 12:39:58 INFO - 'ts_paint', 12:39:58 INFO - 'tpaint', 12:39:58 INFO - 'sessionrestore', 12:39:58 INFO - 'sessionrestore_no_auto_restore']}, 12:39:58 INFO - 'other_nol64': {'tests': ['a11yr', 12:39:58 INFO - 'ts_paint', 12:39:58 INFO - 'tpaint', 12:39:58 INFO - 'sessionrestore', 12:39:58 INFO - 'sessionrestore_no_auto_restore']}, 12:39:58 INFO - 'svgr': {'tests': ['tsvgx', 12:39:58 INFO - 'tsvgr_opacity', 12:39:58 INFO - 'tart', 12:39:58 INFO - 'tscrollx', 12:39:58 INFO - 'cart']}, 12:39:58 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 12:39:58 INFO - 'tests': ['tsvgx', 12:39:58 INFO - 'tsvgr_opacity', 12:39:58 INFO - 'tart', 12:39:58 INFO - 'tscrollx', 12:39:58 INFO - 'cart']}, 12:39:58 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:39:58 INFO - 'tests': ['tp5o']}, 12:39:58 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:39:58 INFO - 'talos_options': ['--e10s'], 12:39:58 INFO - 'tests': ['tp5o']}, 12:39:58 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:39:58 INFO - 'talos_options': ['--xperf_path', 12:39:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:39:58 INFO - 'tests': ['tp5n']}, 12:39:58 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:39:58 INFO - 'talos_options': ['--e10s', 12:39:58 INFO - '--xperf_path', 12:39:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:39:58 INFO - 'tests': ['tp5n']}}, 12:39:58 INFO - 'talos.zip': {'path': '', 12:39:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:39:58 INFO - Running post-action listener: _resource_record_post_action 12:39:58 INFO - ##### 12:39:58 INFO - ##### Running create-virtualenv step. 12:39:58 INFO - ##### 12:39:58 INFO - Running pre-action listener: _resource_record_pre_action 12:39:58 INFO - Running main action method: create_virtualenv 12:39:58 INFO - Creating virtualenv /builds/slave/test/build/venv 12:39:58 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:39:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:39:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:39:58 INFO - Using real prefix '/usr' 12:39:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:39:58 INFO - Installing distribute.............................................................................................................................................................................................done. 12:40:01 INFO - Installing pip.................done. 12:40:01 INFO - Return code: 0 12:40:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:40:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:40:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:40:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--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 0x7f15f72bdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd7240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c660b0>, '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 0x2c66fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c67440>, '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': '0ae9de487587ba4b94dd3100000001b5-1450294446.516140-759424473', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:40:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:40:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:40:01 INFO - Using env: {'DISPLAY': ':0', 12:40:01 INFO - 'HOME': '/home/cltbld', 12:40:01 INFO - 'LANG': 'en_US.UTF-8', 12:40:01 INFO - 'LANGUAGE': 'en_US:en', 12:40:01 INFO - 'LOGNAME': 'cltbld', 12:40:01 INFO - 'MAIL': '/var/mail/cltbld', 12:40:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:01 INFO - 'MOZ_NO_REMOTE': '1', 12:40:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:40:01 INFO - 'NO_EM_RESTART': '1', 12:40:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:40:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:01 INFO - 'PWD': '/builds/slave/test', 12:40:01 INFO - 'SHELL': '/bin/bash', 12:40:01 INFO - 'SHLVL': '1', 12:40:01 INFO - 'TERM': 'linux', 12:40:01 INFO - 'TMOUT': '86400', 12:40:01 INFO - 'USER': 'cltbld', 12:40:01 INFO - 'XDG_SESSION_COOKIE': '0ae9de487587ba4b94dd3100000001b5-1450294446.516140-759424473', 12:40:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:01 INFO - '_': '/tools/buildbot/bin/python'} 12:40:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:01 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 12:40:01 INFO - Cleaning up... 12:40:01 INFO - Return code: 0 12:40:01 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 12:40:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:40:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:40:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--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 0x7f15f72bdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd7240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c660b0>, '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 0x2c66fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c67440>, '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': '0ae9de487587ba4b94dd3100000001b5-1450294446.516140-759424473', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:40:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 12:40:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 12:40:01 INFO - Using env: {'DISPLAY': ':0', 12:40:01 INFO - 'HOME': '/home/cltbld', 12:40:01 INFO - 'LANG': 'en_US.UTF-8', 12:40:01 INFO - 'LANGUAGE': 'en_US:en', 12:40:01 INFO - 'LOGNAME': 'cltbld', 12:40:01 INFO - 'MAIL': '/var/mail/cltbld', 12:40:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:01 INFO - 'MOZ_NO_REMOTE': '1', 12:40:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:40:01 INFO - 'NO_EM_RESTART': '1', 12:40:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:40:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:01 INFO - 'PWD': '/builds/slave/test', 12:40:01 INFO - 'SHELL': '/bin/bash', 12:40:01 INFO - 'SHLVL': '1', 12:40:01 INFO - 'TERM': 'linux', 12:40:01 INFO - 'TMOUT': '86400', 12:40:01 INFO - 'USER': 'cltbld', 12:40:01 INFO - 'XDG_SESSION_COOKIE': '0ae9de487587ba4b94dd3100000001b5-1450294446.516140-759424473', 12:40:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:01 INFO - '_': '/tools/buildbot/bin/python'} 12:40:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:01 INFO - Downloading/unpacking psutil>=0.7.1 12:40: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 12:40: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 12:40: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 12:40: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 12:40: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 12:40: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 12:40:03 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 12:40:03 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 12:40:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:40:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:40:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:40:03 INFO - Installing collected packages: psutil 12:40:03 INFO - Running setup.py install for psutil 12:40:03 INFO - building 'psutil._psutil_linux' extension 12:40:03 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 12:40:03 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 12:40:03 INFO - building 'psutil._psutil_posix' extension 12:40:03 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 12:40:03 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 12:40:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:40:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:40:03 INFO - Successfully installed psutil 12:40:03 INFO - Cleaning up... 12:40:03 INFO - Return code: 0 12:40:03 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 12:40: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')]} 12:40:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40: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')]} 12:40:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40: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', '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 0x7f15f72bdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd7240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c660b0>, '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 0x2c66fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c67440>, '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': '0ae9de487587ba4b94dd3100000001b5-1450294446.516140-759424473', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:40: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 12:40: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 mozsystemmonitor==0.0.0 12:40:03 INFO - Using env: {'DISPLAY': ':0', 12:40:03 INFO - 'HOME': '/home/cltbld', 12:40:03 INFO - 'LANG': 'en_US.UTF-8', 12:40:03 INFO - 'LANGUAGE': 'en_US:en', 12:40:03 INFO - 'LOGNAME': 'cltbld', 12:40:03 INFO - 'MAIL': '/var/mail/cltbld', 12:40:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:03 INFO - 'MOZ_NO_REMOTE': '1', 12:40:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:40:03 INFO - 'NO_EM_RESTART': '1', 12:40:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:40:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:03 INFO - 'PWD': '/builds/slave/test', 12:40:03 INFO - 'SHELL': '/bin/bash', 12:40:03 INFO - 'SHLVL': '1', 12:40:03 INFO - 'TERM': 'linux', 12:40:03 INFO - 'TMOUT': '86400', 12:40:03 INFO - 'USER': 'cltbld', 12:40:03 INFO - 'XDG_SESSION_COOKIE': '0ae9de487587ba4b94dd3100000001b5-1450294446.516140-759424473', 12:40:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:03 INFO - '_': '/tools/buildbot/bin/python'} 12:40:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:04 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 12:40:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:40:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:40:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:40:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:40:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:40:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:40:05 INFO - Downloading mozsystemmonitor-0.0.tar.gz 12:40:05 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 12:40:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:40:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 12:40:05 INFO - Installing collected packages: mozsystemmonitor 12:40:05 INFO - Running setup.py install for mozsystemmonitor 12:40:05 INFO - Successfully installed mozsystemmonitor 12:40:05 INFO - Cleaning up... 12:40:05 INFO - Return code: 0 12:40:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:40:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:40:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:40:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40: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', '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 0x7f15f72bdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd7240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c660b0>, '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 0x2c66fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c67440>, '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': '0ae9de487587ba4b94dd3100000001b5-1450294446.516140-759424473', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:40: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:40: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 blobuploader==1.2.4 12:40:05 INFO - Using env: {'DISPLAY': ':0', 12:40:05 INFO - 'HOME': '/home/cltbld', 12:40:05 INFO - 'LANG': 'en_US.UTF-8', 12:40:05 INFO - 'LANGUAGE': 'en_US:en', 12:40:05 INFO - 'LOGNAME': 'cltbld', 12:40:05 INFO - 'MAIL': '/var/mail/cltbld', 12:40:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:05 INFO - 'MOZ_NO_REMOTE': '1', 12:40:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:40:05 INFO - 'NO_EM_RESTART': '1', 12:40:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:40:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:05 INFO - 'PWD': '/builds/slave/test', 12:40:05 INFO - 'SHELL': '/bin/bash', 12:40:05 INFO - 'SHLVL': '1', 12:40:05 INFO - 'TERM': 'linux', 12:40:05 INFO - 'TMOUT': '86400', 12:40:05 INFO - 'USER': 'cltbld', 12:40:05 INFO - 'XDG_SESSION_COOKIE': '0ae9de487587ba4b94dd3100000001b5-1450294446.516140-759424473', 12:40:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:05 INFO - '_': '/tools/buildbot/bin/python'} 12:40:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:06 INFO - Downloading/unpacking blobuploader==1.2.4 12:40: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 12:40: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 12:40: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 12:40: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 12:40: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 12:40: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 12:40:07 INFO - Downloading blobuploader-1.2.4.tar.gz 12:40:07 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 12:40:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:40:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:40: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 12:40: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 12:40: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 12:40: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 12:40: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 12:40: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 12:40:08 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 12:40:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:40:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:40:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:40:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:40:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:40:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:40:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:40:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:40:08 INFO - Downloading docopt-0.6.1.tar.gz 12:40:08 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 12:40:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:40:08 INFO - Installing collected packages: blobuploader, requests, docopt 12:40:08 INFO - Running setup.py install for blobuploader 12:40:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:40:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:40:08 INFO - Running setup.py install for requests 12:40:08 INFO - Running setup.py install for docopt 12:40:09 INFO - Successfully installed blobuploader requests docopt 12:40:09 INFO - Cleaning up... 12:40:09 INFO - Return code: 0 12:40:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:40:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:40:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:40:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:09 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 0x7f15f72bdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd7240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c660b0>, '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 0x2c66fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c67440>, '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': '0ae9de487587ba4b94dd3100000001b5-1450294446.516140-759424473', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:40:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:40:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test/build/venv/cache --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:40:09 INFO - Using env: {'DISPLAY': ':0', 12:40:09 INFO - 'HOME': '/home/cltbld', 12:40:09 INFO - 'LANG': 'en_US.UTF-8', 12:40:09 INFO - 'LANGUAGE': 'en_US:en', 12:40:09 INFO - 'LOGNAME': 'cltbld', 12:40:09 INFO - 'MAIL': '/var/mail/cltbld', 12:40:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:09 INFO - 'MOZ_NO_REMOTE': '1', 12:40:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:40:09 INFO - 'NO_EM_RESTART': '1', 12:40:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:40:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:09 INFO - 'PWD': '/builds/slave/test', 12:40:09 INFO - 'SHELL': '/bin/bash', 12:40:09 INFO - 'SHLVL': '1', 12:40:09 INFO - 'TERM': 'linux', 12:40:09 INFO - 'TMOUT': '86400', 12:40:09 INFO - 'USER': 'cltbld', 12:40:09 INFO - 'XDG_SESSION_COOKIE': '0ae9de487587ba4b94dd3100000001b5-1450294446.516140-759424473', 12:40:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:09 INFO - '_': '/tools/buildbot/bin/python'} 12:40:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:40:09 INFO - Running setup.py (path:/tmp/pip-k7dSpp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:40:09 INFO - Running setup.py (path:/tmp/pip-mEXDyH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:40:09 INFO - Running setup.py (path:/tmp/pip-loHjfm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:40:09 INFO - Running setup.py (path:/tmp/pip-M60tgZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:40:09 INFO - Running setup.py (path:/tmp/pip-fqJZQE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:40:09 INFO - Running setup.py (path:/tmp/pip-MCFQTH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:40:09 INFO - Running setup.py (path:/tmp/pip-oXyPGZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:40:09 INFO - Running setup.py (path:/tmp/pip-2en060-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:40:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:40:10 INFO - Running setup.py (path:/tmp/pip-5AHckj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:40:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:40:10 INFO - Running setup.py (path:/tmp/pip-EEsLyA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:40:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:40:10 INFO - Running setup.py (path:/tmp/pip-iJ8f1t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:40:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:40:10 INFO - Running setup.py (path:/tmp/pip-4oJOBY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:40:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:40:10 INFO - Running setup.py (path:/tmp/pip-G07d7U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:40:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:40:10 INFO - Running setup.py (path:/tmp/pip-4SwgoJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:40:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:40:10 INFO - Running setup.py (path:/tmp/pip-fcaaoE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:40:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:40:10 INFO - Running setup.py (path:/tmp/pip-nPZ95v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:40:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:40:10 INFO - Running setup.py (path:/tmp/pip-SF7f8_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:40:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:40:10 INFO - Running setup.py install for manifestparser 12:40:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:40:10 INFO - Running setup.py install for mozcrash 12:40:10 INFO - Running setup.py install for mozdebug 12:40:11 INFO - Running setup.py install for mozdevice 12:40:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:40:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:40:11 INFO - Running setup.py install for mozfile 12:40:11 INFO - Running setup.py install for mozhttpd 12:40:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:40:11 INFO - Running setup.py install for mozinfo 12:40:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:40:11 INFO - Running setup.py install for mozInstall 12:40:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:40:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:40:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:40:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:40:11 INFO - Running setup.py install for mozleak 12:40:11 INFO - Running setup.py install for mozlog 12:40:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:40:11 INFO - Running setup.py install for moznetwork 12:40:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:40:11 INFO - Running setup.py install for mozprocess 12:40:11 INFO - Running setup.py install for mozprofile 12:40:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:40:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:40:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:40:12 INFO - Running setup.py install for mozrunner 12:40:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:40:12 INFO - Running setup.py install for mozscreenshot 12:40:12 INFO - Running setup.py install for moztest 12:40:12 INFO - Running setup.py install for mozversion 12:40:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:40:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:40:12 INFO - Cleaning up... 12:40:12 INFO - Return code: 0 12:40:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:40:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:40:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:40:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:12 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 0x7f15f72bdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd7240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c660b0>, '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 0x2c66fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c67440>, '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': '0ae9de487587ba4b94dd3100000001b5-1450294446.516140-759424473', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:40:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:40:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:40:12 INFO - Using env: {'DISPLAY': ':0', 12:40:12 INFO - 'HOME': '/home/cltbld', 12:40:12 INFO - 'LANG': 'en_US.UTF-8', 12:40:12 INFO - 'LANGUAGE': 'en_US:en', 12:40:12 INFO - 'LOGNAME': 'cltbld', 12:40:12 INFO - 'MAIL': '/var/mail/cltbld', 12:40:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:12 INFO - 'MOZ_NO_REMOTE': '1', 12:40:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:40:12 INFO - 'NO_EM_RESTART': '1', 12:40:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:40:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:12 INFO - 'PWD': '/builds/slave/test', 12:40:12 INFO - 'SHELL': '/bin/bash', 12:40:12 INFO - 'SHLVL': '1', 12:40:12 INFO - 'TERM': 'linux', 12:40:12 INFO - 'TMOUT': '86400', 12:40:12 INFO - 'USER': 'cltbld', 12:40:12 INFO - 'XDG_SESSION_COOKIE': '0ae9de487587ba4b94dd3100000001b5-1450294446.516140-759424473', 12:40:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:12 INFO - '_': '/tools/buildbot/bin/python'} 12:40:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:40:12 INFO - Running setup.py (path:/tmp/pip-WD9eoS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:40:12 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 12:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:40:12 INFO - Running setup.py (path:/tmp/pip-uU134B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:40:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:40:12 INFO - Running setup.py (path:/tmp/pip-CegqfU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:40:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:40:12 INFO - Running setup.py (path:/tmp/pip-2irzH7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:40:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:40:13 INFO - Running setup.py (path:/tmp/pip-PVRnsp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:40:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 12:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:40:13 INFO - Running setup.py (path:/tmp/pip-2_ZOug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:40:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 12:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:40:13 INFO - Running setup.py (path:/tmp/pip-hLrydW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:40:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 12:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:40:13 INFO - Running setup.py (path:/tmp/pip-DId7KT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:40:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:40:13 INFO - Running setup.py (path:/tmp/pip-3S3rlo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:40:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 12:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:40:13 INFO - Running setup.py (path:/tmp/pip-03TI88-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:40:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:40:13 INFO - Running setup.py (path:/tmp/pip-2cdSti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:40:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 12:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:40:13 INFO - Running setup.py (path:/tmp/pip-FQioI5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:40:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 12:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:40:13 INFO - Running setup.py (path:/tmp/pip-Rxz1_u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:40:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 12:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:40:13 INFO - Running setup.py (path:/tmp/pip-8dTPGj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:40:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 12:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:40:13 INFO - Running setup.py (path:/tmp/pip-G5XYY2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:40:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 12:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:40:13 INFO - Running setup.py (path:/tmp/pip-5z1xWU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:40:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 12:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:40:13 INFO - Running setup.py (path:/tmp/pip-Mtj33f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:40:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 12:40:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:40:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:40:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:40:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:40:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:40:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:40:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:40:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:40:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:40:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:40:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:40:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:40:15 INFO - Downloading blessings-1.5.1.tar.gz 12:40:15 INFO - Storing download in cache at /builds/slave/test/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 12:40:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:40:15 INFO - Installing collected packages: blessings 12:40:15 INFO - Running setup.py install for blessings 12:40:15 INFO - Successfully installed blessings 12:40:15 INFO - Cleaning up... 12:40:15 INFO - Return code: 0 12:40:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:40:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:40:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:40:16 INFO - Reading from file tmpfile_stdout 12:40:16 INFO - Current package versions: 12:40:16 INFO - argparse == 1.2.1 12:40:16 INFO - blessings == 1.5.1 12:40:16 INFO - blobuploader == 1.2.4 12:40:16 INFO - docopt == 0.6.1 12:40:16 INFO - manifestparser == 1.1 12:40:16 INFO - mozInstall == 1.12 12:40:16 INFO - mozcrash == 0.16 12:40:16 INFO - mozdebug == 0.1 12:40:16 INFO - mozdevice == 0.47 12:40:16 INFO - mozfile == 1.2 12:40:16 INFO - mozhttpd == 0.7 12:40:16 INFO - mozinfo == 0.9 12:40:16 INFO - mozleak == 0.1 12:40:16 INFO - mozlog == 3.1 12:40:16 INFO - moznetwork == 0.27 12:40:16 INFO - mozprocess == 0.22 12:40:16 INFO - mozprofile == 0.27 12:40:16 INFO - mozrunner == 6.11 12:40:16 INFO - mozscreenshot == 0.1 12:40:16 INFO - mozsystemmonitor == 0.0 12:40:16 INFO - moztest == 0.7 12:40:16 INFO - mozversion == 1.4 12:40:16 INFO - psutil == 3.1.1 12:40:16 INFO - requests == 1.2.3 12:40:16 INFO - wsgiref == 0.1.2 12:40:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:40:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:40:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:40:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/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 0x7f15f72bdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd7240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c660b0>, '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 0x2c66fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c67440>, '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': '0ae9de487587ba4b94dd3100000001b5-1450294446.516140-759424473', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:40:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 12:40:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:40:16 INFO - Using env: {'DISPLAY': ':0', 12:40:16 INFO - 'HOME': '/home/cltbld', 12:40:16 INFO - 'LANG': 'en_US.UTF-8', 12:40:16 INFO - 'LANGUAGE': 'en_US:en', 12:40:16 INFO - 'LOGNAME': 'cltbld', 12:40:16 INFO - 'MAIL': '/var/mail/cltbld', 12:40:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:16 INFO - 'MOZ_NO_REMOTE': '1', 12:40:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:40:16 INFO - 'NO_EM_RESTART': '1', 12:40:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:40:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:16 INFO - 'PWD': '/builds/slave/test', 12:40:16 INFO - 'SHELL': '/bin/bash', 12:40:16 INFO - 'SHLVL': '1', 12:40:16 INFO - 'TERM': 'linux', 12:40:16 INFO - 'TMOUT': '86400', 12:40:16 INFO - 'USER': 'cltbld', 12:40:16 INFO - 'XDG_SESSION_COOKIE': '0ae9de487587ba4b94dd3100000001b5-1450294446.516140-759424473', 12:40:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:16 INFO - '_': '/tools/buildbot/bin/python'} 12:40:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:40:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 12:40:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 12:40:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:40:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 12:40:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 12:40:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 12:40:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 12:40:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:40:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 12:40:16 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:40:16 INFO - Cleaning up... 12:40:16 INFO - Return code: 0 12:40:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:40:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:40:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:40:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--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 0x7f15f72bdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd7240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c660b0>, '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 0x2c66fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c67440>, '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': '0ae9de487587ba4b94dd3100000001b5-1450294446.516140-759424473', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:40:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:40:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:40:16 INFO - Using env: {'DISPLAY': ':0', 12:40:16 INFO - 'HOME': '/home/cltbld', 12:40:16 INFO - 'LANG': 'en_US.UTF-8', 12:40:16 INFO - 'LANGUAGE': 'en_US:en', 12:40:16 INFO - 'LOGNAME': 'cltbld', 12:40:16 INFO - 'MAIL': '/var/mail/cltbld', 12:40:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:16 INFO - 'MOZ_NO_REMOTE': '1', 12:40:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:40:16 INFO - 'NO_EM_RESTART': '1', 12:40:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:40:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:16 INFO - 'PWD': '/builds/slave/test', 12:40:16 INFO - 'SHELL': '/bin/bash', 12:40:16 INFO - 'SHLVL': '1', 12:40:16 INFO - 'TERM': 'linux', 12:40:16 INFO - 'TMOUT': '86400', 12:40:16 INFO - 'USER': 'cltbld', 12:40:16 INFO - 'XDG_SESSION_COOKIE': '0ae9de487587ba4b94dd3100000001b5-1450294446.516140-759424473', 12:40:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:16 INFO - '_': '/tools/buildbot/bin/python'} 12:40:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:16 INFO - Downloading/unpacking jsonschema 12:40:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:40:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:40:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:40:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:40:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:40:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:40:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:40:18 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 12:40:18 INFO - Downloading/unpacking functools32 (from jsonschema) 12:40:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:40:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:40:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:40:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:40:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:40:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:40:18 INFO - Downloading functools32-3.2.3-2.tar.gz 12:40:18 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 12:40:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:40:18 INFO - warning: no files found matching '*.txt' 12:40:18 INFO - no previously-included directories found matching 'build' 12:40:18 INFO - no previously-included directories found matching 'dist' 12:40:18 INFO - no previously-included directories found matching '.git*' 12:40:18 INFO - Installing collected packages: jsonschema, functools32 12:40:18 INFO - Running setup.py install for functools32 12:40:18 INFO - warning: no files found matching '*.txt' 12:40:18 INFO - no previously-included directories found matching 'build' 12:40:18 INFO - no previously-included directories found matching 'dist' 12:40:18 INFO - no previously-included directories found matching '.git*' 12:40:18 INFO - Successfully installed jsonschema functools32 12:40:18 INFO - Cleaning up... 12:40:18 INFO - Return code: 0 12:40:18 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 12:40:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:40:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:40:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:18 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 0x7f15f72bdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd7240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c660b0>, '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 0x2c66fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c67440>, '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': '0ae9de487587ba4b94dd3100000001b5-1450294446.516140-759424473', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:40:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 12:40:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 12:40:18 INFO - Using env: {'DISPLAY': ':0', 12:40:18 INFO - 'HOME': '/home/cltbld', 12:40:18 INFO - 'LANG': 'en_US.UTF-8', 12:40:18 INFO - 'LANGUAGE': 'en_US:en', 12:40:18 INFO - 'LOGNAME': 'cltbld', 12:40:18 INFO - 'MAIL': '/var/mail/cltbld', 12:40:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:18 INFO - 'MOZ_NO_REMOTE': '1', 12:40:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:40:18 INFO - 'NO_EM_RESTART': '1', 12:40:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:40:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:18 INFO - 'PWD': '/builds/slave/test', 12:40:18 INFO - 'SHELL': '/bin/bash', 12:40:18 INFO - 'SHLVL': '1', 12:40:18 INFO - 'TERM': 'linux', 12:40:18 INFO - 'TMOUT': '86400', 12:40:18 INFO - 'USER': 'cltbld', 12:40:18 INFO - 'XDG_SESSION_COOKIE': '0ae9de487587ba4b94dd3100000001b5-1450294446.516140-759424473', 12:40:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:18 INFO - '_': '/tools/buildbot/bin/python'} 12:40:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:18 INFO - Downloading/unpacking flake8 12:40:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:40:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:40:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:40:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:40:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:40:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:40:20 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 12:40:20 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fflake8-2.5.0-py2.py3-none-any.whl 12:40:20 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 12:40:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:40:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:40:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:40:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:40:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:40:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:40:20 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 12:40:20 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmccabe-0.3.1-py2.py3-none-any.whl 12:40:20 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 12:40:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:40:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:40:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:40:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:40:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:40:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:40:21 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpyflakes-1.0.0-py2.py3-none-any.whl 12:40:21 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 12:40:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:40:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:40:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:40:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:40:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:40:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:40:21 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 12:40:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 12:40:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:40:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:40:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:40:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:40:21 INFO - no previously-included directories found matching 'docs/_build' 12:40:21 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 12:40:21 INFO - Running setup.py install for pep8 12:40:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:40:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:40:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:40:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:40:21 INFO - no previously-included directories found matching 'docs/_build' 12:40:21 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 12:40:21 INFO - Successfully installed flake8 mccabe pyflakes pep8 12:40:21 INFO - Cleaning up... 12:40:21 INFO - Return code: 0 12:40:21 INFO - Running post-action listener: _resource_record_post_action 12:40:21 INFO - Running post-action listener: _start_resource_monitoring 12:40:21 INFO - Starting resource monitoring. 12:40:21 INFO - ##### 12:40:21 INFO - ##### Running install step. 12:40:21 INFO - ##### 12:40:21 INFO - Running pre-action listener: _resource_record_pre_action 12:40:21 INFO - Running main action method: install 12:40:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:40:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:40:21 INFO - Reading from file tmpfile_stdout 12:40:21 INFO - Detecting whether we're running mozinstall >=1.0... 12:40:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:40:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:40:21 INFO - Reading from file tmpfile_stdout 12:40:21 INFO - Output received: 12:40:21 INFO - Usage: mozinstall [options] installer 12:40:21 INFO - Options: 12:40:21 INFO - -h, --help show this help message and exit 12:40:21 INFO - -d DEST, --destination=DEST 12:40:21 INFO - Directory to install application into. [default: 12:40:21 INFO - "/builds/slave/test"] 12:40:21 INFO - --app=APP Application being installed. [default: firefox] 12:40:21 INFO - mkdir: /builds/slave/test/build/application 12:40:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 12:40:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 12:40:36 INFO - Reading from file tmpfile_stdout 12:40:36 INFO - Output received: 12:40:36 INFO - /builds/slave/test/build/application/firefox/firefox 12:40:36 INFO - Running post-action listener: _resource_record_post_action 12:40:36 INFO - ##### 12:40:36 INFO - ##### Running run-tests step. 12:40:36 INFO - ##### 12:40:36 INFO - Running pre-action listener: _resource_record_pre_action 12:40:36 INFO - Running main action method: run_tests 12:40:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:40:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:40:36 INFO - Python 2.7.3 12:40:36 INFO - Return code: 0 12:40:36 INFO - grabbing minidump binary from tooltool 12:40:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:40:36 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 0x2c660b0>, '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 0x2c66fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c67440>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:40:36 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 12:40:36 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 12:40:36 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:40:36 INFO - Return code: 0 12:40:36 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:40:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:40:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:40:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:40:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:40:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 12:40:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 12:40:37 INFO - Return code: 0 12:40:37 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/4-p-qWeRTKW4vLZSumhtXQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--webServer', 'localhost'] in /builds/slave/test/build 12:40:37 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/4-p-qWeRTKW4vLZSumhtXQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-056 --webServer localhost 12:40:37 INFO - Using env: {'DISPLAY': ':0', 12:40:37 INFO - 'HOME': '/home/cltbld', 12:40:37 INFO - 'LANG': 'en_US.UTF-8', 12:40:37 INFO - 'LANGUAGE': 'en_US:en', 12:40:37 INFO - 'LOGNAME': 'cltbld', 12:40:37 INFO - 'MAIL': '/var/mail/cltbld', 12:40:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:40:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:40:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:37 INFO - 'MOZ_NO_REMOTE': '1', 12:40:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:40:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:40:37 INFO - 'NO_EM_RESTART': '1', 12:40:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:40:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:37 INFO - 'PWD': '/builds/slave/test', 12:40:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:40:37 INFO - 'SHELL': '/bin/bash', 12:40:37 INFO - 'SHLVL': '1', 12:40:37 INFO - 'TERM': 'linux', 12:40:37 INFO - 'TMOUT': '86400', 12:40:37 INFO - 'USER': 'cltbld', 12:40:37 INFO - 'XDG_SESSION_COOKIE': '0ae9de487587ba4b94dd3100000001b5-1450294446.516140-759424473', 12:40:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:37 INFO - '_': '/tools/buildbot/bin/python'} 12:40:37 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/4-p-qWeRTKW4vLZSumhtXQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--webServer', 'localhost'] with output_timeout 3600 12:40:37 INFO - mozversion INFO | application_buildid: 20151216114502 12:40:37 INFO - mozversion INFO | application_changeset: 2c0d64cea673b90bc49aa8d4cfa63787931782cc 12:40:37 INFO - mozversion INFO | application_display_name: Nightly 12:40:37 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:40:37 INFO - mozversion INFO | application_name: Firefox 12:40:37 INFO - mozversion INFO | application_remotingname: firefox 12:40:37 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 12:40:37 INFO - mozversion INFO | application_vendor: Mozilla 12:40:37 INFO - mozversion INFO | application_version: 46.0a1 12:40:37 INFO - mozversion INFO | platform_buildid: 20151216114502 12:40:37 INFO - mozversion INFO | platform_changeset: 2c0d64cea673b90bc49aa8d4cfa63787931782cc 12:40:37 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 12:40:37 INFO - mozversion INFO | platform_version: 46.0a1 12:40:37 INFO - 2015-12-16 12:40:37,634 DEBUG : using testdate: 1450298437 12:40:37 INFO - 2015-12-16 12:40:37,634 DEBUG : actual date: 1450298437 12:40:37 INFO - 2015-12-16 12:40:37,643 INFO : starting webserver on 'localhost:53507' 12:40:37 INFO - 2015-12-16 12:40:37,644 INFO : Starting test suite talos-linux64-ix-056.e 12:40:37 INFO - 2015-12-16 12:40:37,644 INFO : Starting test dromaeo_css 12:40:37 INFO - 2015-12-16 12:40:37,645 DEBUG : operating with platform_type : linux_ 12:40:37 INFO - 2015-12-16 12:40:37,646 INFO : Initialising browser for dromaeo_css test... 12:40:37 INFO - 2015-12-16 12:40:37,661 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGzsuKO/profile http://localhost:53507/getInfo.html 12:40:43 INFO - 2015-12-16 12:40:43,075 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 12:40:43 INFO - 2015-12-16 12:40:43,076 DEBUG : BROWSER_OUTPUT: colorDepth:24 12:40:43 INFO - 2015-12-16 12:40:43,076 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 12:40:43 INFO - 2015-12-16 12:40:43,076 DEBUG : BROWSER_OUTPUT: __metrics 12:40:43 INFO - 2015-12-16 12:40:43,192 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 12:40:44 INFO - 2015-12-16 12:40:44,204 INFO : Browser initialized. 12:40:44 INFO - 2015-12-16 12:40:44,205 INFO : Running cycle 1/1 for dromaeo_css test... 12:40:44 INFO - 2015-12-16 12:40:44,205 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGzsuKO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 12:40:56 INFO - 2015-12-16 12:40:56,286 DEBUG : BROWSER_OUTPUT: RSS: Main: 143015936 12:40:56 INFO - 2015-12-16 12:40:56,286 DEBUG : BROWSER_OUTPUT: 12:41:45 INFO - 2015-12-16 12:41:45,907 DEBUG : BROWSER_OUTPUT: 1450298505903 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 12:43:57 INFO - 2015-12-16 12:43:57,689 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53507/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53507/tests/dromaeo/cssquery-ext.html) 12:43:58 INFO - 2015-12-16 12:43:58,814 DEBUG : BROWSER_OUTPUT: RSS: Main: 145043456 12:43:58 INFO - 2015-12-16 12:43:58,814 DEBUG : BROWSER_OUTPUT: 12:47:00 INFO - 2015-12-16 12:47:00,832 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53507/tests/dromaeo/cssquery-ext.html (next: http://localhost:53507/tests/dromaeo/cssquery-jquery.html) 12:47:01 INFO - 2015-12-16 12:47:01,433 DEBUG : BROWSER_OUTPUT: RSS: Main: 144551936 12:47:01 INFO - 2015-12-16 12:47:01,434 DEBUG : BROWSER_OUTPUT: 12:50:03 INFO - 2015-12-16 12:50:03,304 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53507/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53507/tests/dromaeo/cssquery-mootools.html) 12:50:03 INFO - 2015-12-16 12:50:03,897 DEBUG : BROWSER_OUTPUT: RSS: Main: 146567168 12:50:03 INFO - 2015-12-16 12:50:03,898 DEBUG : BROWSER_OUTPUT: 12:53:05 INFO - 2015-12-16 12:53:05,701 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53507/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53507/tests/dromaeo/cssquery-prototype.html) 12:53:06 INFO - 2015-12-16 12:53:06,322 DEBUG : BROWSER_OUTPUT: RSS: Main: 142319616 12:53:06 INFO - 2015-12-16 12:53:06,322 DEBUG : BROWSER_OUTPUT: 12:56:08 INFO - 2015-12-16 12:56:08,957 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53507/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53507/tests/dromaeo/cssquery-yui.html) 12:56:09 INFO - 2015-12-16 12:56:09,587 DEBUG : BROWSER_OUTPUT: RSS: Main: 144060416 12:56:09 INFO - 2015-12-16 12:56:09,587 DEBUG : BROWSER_OUTPUT: 12:59:11 INFO - 2015-12-16 12:59:11,797 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53507/tests/dromaeo/cssquery-yui.html (next: http://localhost:53507/tests/dromaeo/cssquery-dojo.html) 12:59:12 INFO - 2015-12-16 12:59:12,047 DEBUG : BROWSER_OUTPUT: RSS: Main: 146931712 12:59:12 INFO - 2015-12-16 12:59:12,047 DEBUG : BROWSER_OUTPUT: 12:59:12 INFO - 2015-12-16 12:59:12,047 DEBUG : BROWSER_OUTPUT: __start_tp_report 12:59:12 INFO - 2015-12-16 12:59:12,048 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 12:59:12 INFO - 2015-12-16 12:59:12,048 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 12:59:12 INFO - 2015-12-16 12:59:12,048 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 12:59:12 INFO - 2015-12-16 12:59:12,051 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2677.9471402601034;2740.438210166916;2773.098742908554;2779.861006949652;2784.958225626616;4640.373549570807;4851.054044461332;4854.611631069515;4883.29192267121;4935.999999999995;4700.0599880023965;4716.693414928036;4775.952240477594;4777.593904518111;4833.492483289257;4370.759608221547;4426.424564806571;4510.210713125206;4517.457905051382;4532.708178169222;4274.316109422493;4393.384926110347;4424.402705634747;4495.527969563198;4516.864494065167;4347.456567929009;4355.499117601485;4427.6015158635555;4456.732596044248;4462.531434199427;4427.645788336926;4441.422615060053;4442.466903971512;4445.933311000305;4470.932936005963;4260.978695106535;4391.758453285068;4407.933880991788;4449.933251001237;4457.153140903218;4595.310703394484;4637.907241855144;4758.214894542431;4778.2115950868065;4798.304245884327;5156.226566015091;5166;5169.069567477853;5177.896442071175;5180.300659410969;4931.0630980113665;5058.342415486;5181.248718935733;5192.662476938987;5208.270842082112;4698.553637404441;4729.645276604199;4752.310914917318;4753.928691069636;4791.880202994918;5313.920291195635;5317.494837990384;5325.547328477122;5337.172738225581;5352.812651557177;4888.193448081098;4976.154053810788;4990.251462280687;5102.08162530748;5122.974385128051;5066.670666406671;5067.5185857343495;5075;5089.720065396439;5191.013707395583;5056.519630635157;5113.923291150636;5119.6160287978555;5122;5179.19722443022;5053.343065401475;5086.949130508721;5090.955967296499;5113.769880355393;5146.253793199965;4082.6325630692786;4175.415441838145;4214.7892605370225;4231.4710661167355;4304.203722311382;3738.5887552369218;3747.9250414991548;3752.099496120966;3780.1683629601443;3780.905477363088;4461.375407442961;4465.464144302639;4467.12890986261;4467.5532446755715;4506.662000349987;4957.082939656175;5056.873578160576;5094.49055094495;5157.4584668610005;5182.870428239325;4458.643308535309;4463.888402789957;4490.820367185276;4492.910141797146;4526.366308716782;4376.190404775127;4570.3829982951875;4588.49526552079;4610.423697037871;4626.4910859805395;4820.831270905432;4848.369711937426;4872.4640289567415;4890.046440943982;4931.7040977541465;5191.11751002338;5242.7902883885745;5246.658967167122;5249.7112658804135;5299.920501192562;4751.263554149113;4842.17682993899;4960.355153829979;4976.975115124401;4976.975115124401;4983.825566105169;5051.696898186121;5064.18972964324;5079.92380114306;5118.616103792305;5184.9740751296;5193.37679478465;5197.27349619378;5208.713520756395;5247.947520524746;4760.452547957029;4878.048780487771;4936.851894443173;4998.925016124688;5005.299258103942;5018.949810501995;5090.49095090488;5090.9563539473575;5093.261477085876;5096.159133742891;4895.241237608177;4949.30709700635;4970.8757281068265;4973.303737476828;4992.375953005874;4804.471508134033;4901.191303434894;4974.825881094144;5043.823466178813;5096.625812682254;4940.604751619933;4963.900721985612;4972.701637901738;4983.526564976321;5008.974955125347 12:59:12 INFO - 2015-12-16 12:59:12,053 DEBUG : BROWSER_OUTPUT: |1;ext.html;542.9022775900337;564.6301672404575;572.556268891609;573.4523530028823;577.4052725692535;34754.30491390174;35146.12134696634;35342.82328588353;35442.822785886034;35490;38812.61187388125;39375.21249575014;39385.80307098468;39401.21197576054;39427.40858887119;7906.446548741605;8064.354851611857;8073;8080.515169089844;8085.555294458803;7901.170377110407;7971.760847174594;7996.840063198732;8005.119436861941;8010.67957281708;7954.045514538264;8001.559914204717;8045.798855028613;8050.758477245692;8052.315553177987;7937.523748575104;8012.919870801305;8031.598420079031;8037.5981200940305;8040;7795.727149549739;7871.724489642892;7942.444028917978;7945.284924356778;7952.165022672594;35750.821245893865;36066.45900311471;36284.27431451356;36430;36506;2856.300206449433;2873.8850445982134;2974.4348573770912;3008.991008991009;3653.324135035027;27298.5905211422;27441.31396715098;27828;28124;28484.28789280264;3240.8055516669074;3379.493076038589;3414.8804791832167;3423.6747508986605;3482.5820901491984;200484;200718;201239;201514;201996;46296.7685161572;47116.528834711215;53887.461125389025;55756.72121639366;56716.43283567194;50836.49163508318;50999.235011474855;51176;51220.48779512232;51345.486545134816;13141.67145821343;13315.600531984055;13418.194908305533;13436.731265374834;13441.731165376834;9951.751206219758;10043;10057.798844023226;10085.243606729524;10205.285630005978;19212.80787192138;19215.615687686168;19414.320498782476;19420.902895485713;19469.12388942501;7605;7780.677284861587;7868.331191848642;8017.6792928283385;8064.193580641889;792.235492749488;853.9402241843011;921.4655499810094;934.4720233068294;939.7885475768035;22805.087796488286;22929.656055159187;23069.307920762403;25609;26407.207783766517;993.8012397520525;1007.9395236285847;1013.6300250408696;1031.8813336466403;1087;12894.161879477804;12911.483540658268;13034.087613867128;13098.623110823433;13194.340282986004;253826;258093;258273;259384;260816;45733.314000290025;46143.538564614595;46401.7679911605;46419.5358046422;46608.766956165004;1188.7325351795778;1190.7439900421277;1198.7063169523349;1209.0025728773796;1213.7997230457052;25217;25520;25675.614865776653;25680.743192567836;25705.61441578415;39959.80020099881;40304.39543406793;40634.1873162541;40705;40853.79573102116;246349;246689;247217;247822;248457;28057.43885122327;30608.234794129436;31291.53062704107;31376.686233137378;31395.529067064468;36409;36743;37025.629743702215;37541.2491750158;37656.24687506289;31367.372652547278;31773.841130794197;31929.68070319267;32652.673473264964;32737.017889463354;32450.67549324571;32559;32652.183695407806;34822.82588587041;36318.45522317114;32191.195220119684;32471.350572988882;32492.350152997282;32586;32685.18287042843;28632.284192895346;29544;29573.556396654498;30141.547876782304;30465.54301685521;21649.13403463844;23715.40711482227;23862.88068559646;24264.63603045991;25373.746262537876 12:59:12 INFO - 2015-12-16 12:59:12,054 DEBUG : BROWSER_OUTPUT: |2;jquery.html;340.56407798018535;342.86456849544413;343.4761987968348;343.5550961504854;344.08988226141855;15069.547913562597;15163.241837908101;16013.919930400332;16358;16610.83389166108;9865.753356166082;9913.107820296173;9929.255305852072;12385.938070309636;15200.54398368048;4254.276772948599;4351.0862718829085;4442.844500442485;4465.419495465585;4491.191585514606;4449.42157519522;4480.439945006874;4490;4497.235469970096;4499.977500112494;3400.727941764665;3476.7566270361085;4441.800118994637;4492.865214043568;4847.2002119650215;4920.0159968006365;4929.827456039039;4932.7914660907945;4936.753162341868;4952.281919121707;4789.880252993668;4791.6166706663735;4799.256115302133;5771.422857714195;6002.879942401171;19764.90117549403;19775.80224197754;21261.68107478389;22773.886130569408;22790.772092279032;1984.900754962246;1992.9701054484194;1998.180745894191;2020.4039808256452;2025.7670367907585;13837.238951857644;13858.584242472742;13948.58154255374;14178.432862685584;14486.927565362212;1863.4316533457425;1888.2163901980662;1889.5087277307862;1903.1150515010447;1929.8166674165932;48369.27446088347;56439.71780141114;60284;63384.36615633831;63771;14619;14843.109413435013;15004.474843380429;15048.473303434328;15071.397144114331;21356.893215533823;21363.25228616991;21516.784832151792;21584;21623.67564486534;8151.633176507072;8195.590220488952;8285.668573257122;8323.66705331792;8460.069392366835;7258.745943892044;7265.09186351706;7295.489315747846;7302.306280903305;7360.815979600553;7769.805754856061;7783.221677832172;7814.6092695365005;7841.294283514511;7846.293833554893;3135.749140068789;3158.6051743532057;3209.261869769966;3224.3873664003763;3253.4469140246215;382.1439974457196;382.829640809837;829.3821103278096;883.6976604797887;997.7555498902815;9473.213398270495;10174.643887463903;15315.46395876139;15449.9227503864;16052.03687778737;817.9386546008955;841.4194205997842;857.7555396711907;870.5603670146536;900.4777229207044;8714.825703485894;8745.169208925143;8818.559072046372;8830.205281524693;8996.59515321812;438671;444962;457167;459430;460996;68726.65636671784;70304;70448.64775676088;71411;71667;1105.0883021507289;1115.3865374044244;1120.193460708305;1120.8318752187238;1131.5417256011328;14335.820230063015;14464.421423142956;14485.203313817841;14506.637334066732;14650.560483185522;32959.67040329662;33096.834515827264;33296.33407331888;33335.33329333351;34082;44894.3265851019;45034.54965450303;46732.53267467282;47205;47366;7967;8618.525981071101;8960.68637597681;8961.327900407627;9022.323325750463;10103.696889093339;10140.64507742255;10183.643572474928;10187.28688991793;10198.745031374276;11897.94051029774;13784.862151378358;13953.720925581634;14478.927605362327;14513.346899389342;13636.181829090287;14055.718885622435;14057.437702491785;14246.145231285742;14632.853671463148;14151.787723184367;14364;14481.782773258621;14904.850951490347;14940.925295373454;8851.424657397507;8944.86582701247;8971.102889710977;9137.131972462605;9212.447253164832;11090.778184436427;13607.115537490032;13697.178169309873;13789.310534473036;13845.930770346084 12:59:12 INFO - 2015-12-16 12:59:12,058 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1505.9021163624357;1637.1077762619373;1728.7925448946128;1745.9912700436496;1777.8044415114334;6073.210482637262;6206;6877.381035706785;7146.4640151988615;7164.247753985828;6975.2675969023285;7037.085178926745;7108.715651373939;7120.537165084266;7195.424366050716;2059.474833917349;2076.9303808538616;2088.248230636977;2104.116271166114;2104.9789502105014;2086.62440760663;2102.274715223253;2120.035383900329;2147.5812216617765;2153.472399262183;2002.2891873384958;2042.305616090529;2065.0810389376734;2070.109852763311;2073.2329038255866;2063.8555301128927;2087.5094352827073;2102.9369118926456;2103.8316934645345;2105.642040853051;2041.9387418377473;2060.9278675246296;2078.1064142418754;2078.231054509841;2086.812186903186;7209.134903811576;7280.38116760076;7283;7296.452766042568;7334.083239595051;4104.076582768883;4161.209370219649;4173.08192197716;4179.414881916534;4192.266353388175;2688.07261494784;2714.3214196450886;2718.877650505732;2742.835429874214;2747.738964798341;854.0264098927166;872.6727477196051;877.2762470961419;877.9736607901773;913.0777914306598;9414.105659962293;9454.57454414553;9463;9480;9512.14390704833;3882.3594106972596;3891.4357418174204;3903.980480097638;3911.726179167431;3954.051027753376;8267.8346433071;8287.8342433151;8301.128381519975;8305.958470207732;8331.125231850569;4198.454200953917;4214.936775948363;4217.1144059747185;4224.809883555247;4239.342901850218;3260.5272235525717;3320.2197483591335;3756.868509602151;3781.697464202912;3788.4885540451687;3453.8445769940413;3461.0828130545424;3474.652534746556;3485.3726329260976;3528.3648943190474;1071.2073065931154;1072.2065671403261;1079.8056349856945;1084.0189628386322;1085.571199376242;1188.863280722728;1189.2448295332526;1210.2617403383929;1210.5339444314002;1234.3026190202506;6633.9336606633315;6687.364700353459;6745.797626071226;6746.561473504206;6747.595144291358;421.02532636945375;423.46008838730864;430.3609140426464;432.1055415290319;432.3320469874052;5371.194320851903;5419.3225846769155;5433.728313584305;5444.074507333763;5452;8371;8431.114732952952;8477.788055298544;8510.617022234015;8565.95717021411;8377.120402357665;8385.203405676451;8432.367572432093;8445.155484451629;8458.703945361884;6669.966650166718;6753.527253092335;6809.965950170217;6864.656767161622;6865.41643960259;6836.692348844414;6851.349121833618;6884.51808373409;6899.448044156355;6910.239873613999;7748.92251077482;7750.031246094239;7805.063392392949;7838.451308408585;7969.760907172794;7881.645325960231;8004.879926800986;8023.077346105272;8086.797830054295;8167.224113709188;5377.623566350318;5469.371022332482;6103.938960610515;6479.611223326616;6501.089847421459;6912.930870691229;6923.2038315594355;6970.268121847133;6995.930040699732;7002.299770022957;6466.4503517201565;6533.379328963741;6570.507211959027;6606.339366063356;6670.266270710315;6516.38094381052;6522.21733391996;6538.267359149924;6549;6600.966995164993;6556.3443655634055;6608.867822643617;6622.106015688013;6624.171978502687;6635.933640663725;5805.738741756547;5824.972129320043;6114.052321890114;6127.632342059491;6131.96934015327;6175.475084617858;6197.411245931809;6203.2556093269095;6215.782447614311;6231.065340199007 12:59:12 INFO - 2015-12-16 12:59:12,059 DEBUG : BROWSER_OUTPUT: |4;prototype.html;295.4519366575003;302.42539175566424;308.81779749947526;309.18683861444407;309.77076963047324;5575.191597218401;5606.243157173785;5655.57583181261;5663.952168848766;5681.233033540481;5541.390447050831;5554.86112847179;5555.638883472571;5568.276124103871;5586.050371436855;4221.936670949938;4282.164977829325;4284.421603083582;4293.849715259967;4306.956930430702;4301.763403012833;4309.5690430956965;4324.421586121075;4329.935050974238;4332.480102387718;4259.7231179973205;4269.850555230568;4273.230818452677;4280.743155410685;4320.719153255044;4301.096769678371;4307.418498502725;4307.612314891643;4307.741535507864;4327.394164816928;3815.809209539512;3831.501904752364;3831.5019047523924;3831.616838316174;3833.386658134713;5260;5306.204069389584;5409.026375252453;5429.212764149176;5438.972805135989;4957.231629097496;4965.950340496585;4969.776360063805;4976.626752993504;4980;5922.289325280994;5924.318703349127;5934.970325148347;5937.376575459601;5937.910931336033;4050.5341885682933;4121.052158003676;4129.132882094764;4129.256733787917;4143.56492568279;6148.477379422709;6227.782027629011;6236.220472440945;6237.875242495124;6249.812505624793;4161.479815023122;4185.937210941838;4197.622214000755;4197.790110494524;4204.26425375558;4255.170241802819;4266.466691663542;4269;4269.167512335127;4271.423357846651;4759.405074365704;4883.194272944996;4888.828890988798;4894.608431325485;4906.2640603908985;5867;5887.705614719247;5901.675407852609;5903.675297858573;5922.585419020714;1515.6438237014293;1518.3091693279532;1519.992400038015;1520.0271826031349;1520.3006616956102;1005.9245556583286;1057.7937302225992;1058.899404556566;1064.0636240108809;1078.1751959750798;5120.564751996122;5124.461931497139;5134.871628209325;5135.460776618401;5138.434772175057;6043.576949613484;6075.939240607538;6096.908546371808;6099.664518451527;6111.449969502677;5085.974570127126;5098.413682426494;5133;5140.511651393186;5147.819826306061;3192.9680703193135;3292.0288514888166;3306.702396784301;3307.1732066983254;3320.4853247746637;5671.574631902624;5799.507041901401;5846;5848.444397782204;5870.26621672291;6306.2747784004505;6387;6395.360463953567;6410.0705397718;6410.839729006812;5830.300363956352;5831.271091113526;5832.825015249549;5839.883202336015;5842.824715258549;6143.477804386498;6157.753689852356;6172.753089876354;6182.505399567934;6184.196054513064;5840.766369345179;6004.39956004396;6024;6046.365131661112;6054.424829641177;5823.388544202967;5829.009068458287;5832.562557808096;5837.6497410155525;5841.1238314253205;4109.9589004109575;4146.170765846903;4152.937705934474;4157.293260145843;4157.397177409319;6056.273247210363;6177.444030037139;6179.351168127282;6183.103449999635;6188.690565471798;5833.766649333979;5844.883102337844;5868.471837534642;5875.676837773793;5879.970600146972;5891.469767720926;5914.378990205966;5921.082232254007;5926;5936.614120082181;5643.7742490299925;5771.2208851806145;5773.729779448515;5777.277840269966;5799.217105690678;5932.5550583705535;5939.792107276225;5941.58408911372;5962.344142144274;5964.075568286922;5788.565857560784;5794.391588883106;5795.420457954171;5798.94201057984;5799.188113664006 12:59:12 INFO - 2015-12-16 12:59:12,060 DEBUG : BROWSER_OUTPUT: |5;yui.html;655.0599889159054;764.6100488750737;775.5463054113343;776.2780614028954;790.5019837502373;11630.360330181837;11664.416779161029;11672;11723.941380293087;11741.70645733857;11459.656210313704;11507.252028618133;11714.648560543197;11851.7629647407;11902.940485297582;801.3749275565068;811.8457493076326;816.9264766171043;819.1971867569755;826.5990994367727;831.2186544648011;831.4346244553701;831.8710599857031;834.3241974001048;836.8368168207202;868.8653258744905;876.51791514667;876.8114855306108;877.7191298784392;878.7539488943106;860.2515811244226;861.9655213791441;862.0991064337751;864.2222000199839;864.394923553515;637.6970938804077;642.489221069251;644.7775517446473;649.1139594453584;649.6978904809239;11047.779044419147;11050.281731687415;11078.833817492745;11087.50106245221;11090.500927458284;10602.257841951066;10886.836697449544;10911.890881091167;11087.33475991443;11426.542938282459;9146.517916837161;9291.535423228812;9359.251259899193;9403.106704863096;9425.481598512079;3143.2141964508874;3149.4960806271115;3198.232424218194;3202.1834432219866;3212.3093534889877;38579;38919.41620875689;38994.41508377377;39011.609883901365;39321.80339098315;8981.685641002534;9098.772530686785;9154.86267705985;9235.630574777068;9235.907640923639;15298.388064477518;15454.38182472711;15463.5360939172;15481.612959676098;15543.611409714847;6174;6188.907166392508;6202.534809889276;6224.190855188796;6283.717232724538;3475.6871881530783;3531.9293614128087;3540.314738743005;3541.575010998645;3557.4663800429626;2493.488834788864;2515.3585835611802;2519.9748002520105;2523.343930578026;2525.2802951159197;460.9585137337656;474.8931490414629;476.09304275355504;478.4019975031211;481.6796830108009;525.2856115682669;599.283855792324;601.4136217188207;601.6931365003792;605.9212302400748;7625.847483050419;7704.961475192588;7813.02337207849;7952.761417157494;7997.600119993977;596.274524394183;607.2834055814182;636.2937139774846;644.490852226745;670.8389986403325;3617.764845285048;3654.3422184007136;3799;3809.561900381492;3816.14136819219;44232.778836105615;44609.77695111569;45006.77496612496;45091.323630145576;45329.77335113303;42393;42903;43134.35298470463;43418.565814341455;43454.56545434505;39294;39643;39855.601443985186;40395.1920961585;40567.18865622671;7701.383889288732;7761.262680045386;7761.4178936578855;7767.495112817649;7900.40746943993;29383.559246611745;29563.556546651387;29878.551821723126;29973.400531989675;30133.547996779627;34061.97814065483;34314;35814.64185358113;37130.8143459281;37212.25575488529;9032.187103160748;9056.547172641473;9091.090890910857;9442.669506567237;9468.337216394786;10338.483075846329;10352.637657681946;10414.791704166026;10453.484244784615;10488.370697758159;9587.568559414703;9648.324617276723;9799.069088436587;9831.705048848546;9856.457894815567;9621.9037809621;9699;9714.222862170867;9718.75703107428;9803.068708472683;9603.855942161013;9705.223582113555;9784.9021509784;9804.215662746821;9924.603015879284;8358.66565337404;8684.261837743863;8886.688965886164;8925.821483570162;8926.464412135292;7944.84110317802;8078.919210807817;8115.75652730419;8129.018844948758;8315.835782990507 12:59:12 INFO - 2015-12-16 12:59:12,060 DEBUG : BROWSER_OUTPUT: __end_tp_report 12:59:12 INFO - 2015-12-16 12:59:12,061 DEBUG : BROWSER_OUTPUT: __start_cc_report 12:59:12 INFO - 2015-12-16 12:59:12,061 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,331 12:59:12 INFO - 2015-12-16 12:59:12,061 DEBUG : BROWSER_OUTPUT: __end_cc_report 12:59:12 INFO - 2015-12-16 12:59:12,061 DEBUG : BROWSER_OUTPUT: __startTimestamp1450299552047__endTimestamp 12:59:12 INFO - 2015-12-16 12:59:12,061 DEBUG : BROWSER_OUTPUT: 12:59:12 INFO - 2015-12-16 12:59:12,061 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 12:59:12 INFO - 2015-12-16 12:59:12,061 DEBUG : BROWSER_OUTPUT: Number of tests: 6 12:59:12 INFO - 2015-12-16 12:59:12,062 DEBUG : BROWSER_OUTPUT: 12:59:12 INFO - 2015-12-16 12:59:12,062 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4746.37 Median:4888.19 stddev:499.38 (10.2%) stddev-sans-first:473.82 12:59:12 INFO - 2015-12-16 12:59:12,062 DEBUG : BROWSER_OUTPUT: Values: 2677.9 2740.4 2773.1 2779.9 2785.0 4640.4 4851.1 4854.6 4883.3 4936.0 4700.1 4716.7 4776.0 4777.6 4833.5 4370.8 4426.4 4510.2 4517.5 4532.7 4274.3 4393.4 4424.4 4495.5 4516.9 4347.5 4355.5 4427.6 4456.7 4462.5 4427.6 4441.4 4442.5 4445.9 4470.9 4261.0 4391.8 4407.9 4449.9 4457.2 4595.3 4637.9 4758.2 4778.2 4798.3 5156.2 5166.0 5169.1 5177.9 5180.3 4931.1 5058.3 5181.2 5192.7 5208.3 4698.6 4729.6 4752.3 4753.9 4791.9 5313.9 5317.5 5325.5 5337.2 5352.8 4888.2 4976.2 4990.3 5102.1 5123.0 5066.7 5067.5 5075.0 5089.7 5191.0 5056.5 5113.9 5119.6 5122.0 5179.2 5053.3 5086.9 5091.0 5113.8 5146.3 4082.6 4175.4 4214.8 4231.5 4304.2 3738.6 3747.9 3752.1 3780.2 3780.9 4461.4 4465.5 4467.1 4467.6 4506.7 4957.1 5056.9 5094.5 5157.5 5182.9 4458.6 4463.9 4490.8 4492.9 4526.4 4376.2 4570.4 4588.5 4610.4 4626.5 4820.8 4848.4 4872.5 4890.0 4931.7 5191.1 5242.8 5246.7 5249.7 5299.9 4751.3 4842.2 4960.4 4977.0 4977.0 4983.8 5051.7 5064.2 5079.9 5118.6 5185.0 5193.4 5197.3 5208.7 5247.9 4760.5 4878.0 4936.9 4998.9 5005.3 5018.9 5090.5 5091.0 5093.3 5096.2 4895.2 4949.3 4970.9 4973.3 4992.4 4804.5 4901.2 4974.8 5043.8 5096.6 4940.6 4963.9 4972.7 4983.5 5009.0 12:59:12 INFO - 2015-12-16 12:59:12,062 DEBUG : BROWSER_OUTPUT: 12:59:12 INFO - 2015-12-16 12:59:12,062 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:39384.46 Median:18058.26 stddev:61599.62 (341.1%) stddev-sans-first:61703.39 12:59:12 INFO - 2015-12-16 12:59:12,063 DEBUG : BROWSER_OUTPUT: Values: 542.9 564.6 572.6 573.5 577.4 34754.3 35146.1 35342.8 35442.8 35490.0 38812.6 39375.2 39385.8 39401.2 39427.4 7906.4 8064.4 8073.0 8080.5 8085.6 7901.2 7971.8 7996.8 8005.1 8010.7 7954.0 8001.6 8045.8 8050.8 8052.3 7937.5 8012.9 8031.6 8037.6 8040.0 7795.7 7871.7 7942.4 7945.3 7952.2 35750.8 36066.5 36284.3 36430.0 36506.0 2856.3 2873.9 2974.4 3009.0 3653.3 27298.6 27441.3 27828.0 28124.0 28484.3 3240.8 3379.5 3414.9 3423.7 3482.6 200484.0 200718.0 201239.0 201514.0 201996.0 46296.8 47116.5 53887.5 55756.7 56716.4 50836.5 50999.2 51176.0 51220.5 51345.5 13141.7 13315.6 13418.2 13436.7 13441.7 9951.8 10043.0 10057.8 10085.2 10205.3 19212.8 19215.6 19414.3 19420.9 19469.1 7605.0 7780.7 7868.3 8017.7 8064.2 792.2 853.9 921.5 934.5 939.8 22805.1 22929.7 23069.3 25609.0 26407.2 993.8 1007.9 1013.6 1031.9 1087.0 12894.2 12911.5 13034.1 13098.6 13194.3 253826.0 258093.0 258273.0 259384.0 260816.0 45733.3 46143.5 46401.8 46419.5 46608.8 1188.7 1190.7 1198.7 1209.0 1213.8 25217.0 25520.0 25675.6 25680.7 25705.6 39959.8 40304.4 40634.2 40705.0 40853.8 246349.0 246689.0 247217.0 247822.0 248457.0 28057.4 30608.2 31291.5 31376.7 31395.5 36409.0 36743.0 37025.6 37541.2 37656.2 31367.4 31773.8 31929.7 32652.7 32737.0 32450.7 32559.0 32652.2 34822.8 36318.5 32191.2 32471.4 32492.4 32586.0 32685.2 28632.3 29544.0 29573.6 30141.5 30465.5 21649.1 23715.4 23862.9 24264.6 25373.7 12:59:12 INFO - 2015-12-16 12:59:12,063 DEBUG : BROWSER_OUTPUT: 12:59:12 INFO - 2015-12-16 12:59:12,063 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:26018.16 Median:343.82 stddev:73889.13 (21490.5%) stddev-sans-first:74071.25 12:59:12 INFO - 2015-12-16 12:59:12,064 DEBUG : BROWSER_OUTPUT: Values: 340.6 342.9 343.5 343.6 344.1 15069.5 15163.2 16013.9 16358.0 16610.8 9865.8 9913.1 9929.3 12385.9 15200.5 4254.3 4351.1 4442.8 4465.4 4491.2 4449.4 4480.4 4490.0 4497.2 4500.0 3400.7 3476.8 4441.8 4492.9 4847.2 4920.0 4929.8 4932.8 4936.8 4952.3 4789.9 4791.6 4799.3 5771.4 6002.9 19764.9 19775.8 21261.7 22773.9 22790.8 1984.9 1993.0 1998.2 2020.4 2025.8 13837.2 13858.6 13948.6 14178.4 14486.9 1863.4 1888.2 1889.5 1903.1 1929.8 48369.3 56439.7 60284.0 63384.4 63771.0 14619.0 14843.1 15004.5 15048.5 15071.4 21356.9 21363.3 21516.8 21584.0 21623.7 8151.6 8195.6 8285.7 8323.7 8460.1 7258.7 7265.1 7295.5 7302.3 7360.8 7769.8 7783.2 7814.6 7841.3 7846.3 3135.7 3158.6 3209.3 3224.4 3253.4 382.1 382.8 829.4 883.7 997.8 9473.2 10174.6 15315.5 15449.9 16052.0 817.9 841.4 857.8 870.6 900.5 8714.8 8745.2 8818.6 8830.2 8996.6 438671.0 444962.0 457167.0 459430.0 460996.0 68726.7 70304.0 70448.6 71411.0 71667.0 1105.1 1115.4 1120.2 1120.8 1131.5 14335.8 14464.4 14485.2 14506.6 14650.6 32959.7 33096.8 33296.3 33335.3 34082.0 44894.3 45034.5 46732.5 47205.0 47366.0 7967.0 8618.5 8960.7 8961.3 9022.3 10103.7 10140.6 10183.6 10187.3 10198.7 11897.9 13784.9 13953.7 14478.9 14513.3 13636.2 14055.7 14057.4 14246.1 14632.9 14151.8 14364.0 14481.8 14904.9 14940.9 8851.4 8944.9 8971.1 9137.1 9212.4 11090.8 13607.1 13697.2 13789.3 13845.9 12:59:12 INFO - 2015-12-16 12:59:12,064 DEBUG : BROWSER_OUTPUT: 12:59:12 INFO - 2015-12-16 12:59:12,064 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5000.57 Median:6200.33 stddev:2602.67 (42.0%) stddev-sans-first:2596.72 12:59:12 INFO - 2015-12-16 12:59:12,065 DEBUG : BROWSER_OUTPUT: Values: 1505.9 1637.1 1728.8 1746.0 1777.8 6073.2 6206.0 6877.4 7146.5 7164.2 6975.3 7037.1 7108.7 7120.5 7195.4 2059.5 2076.9 2088.2 2104.1 2105.0 2086.6 2102.3 2120.0 2147.6 2153.5 2002.3 2042.3 2065.1 2070.1 2073.2 2063.9 2087.5 2102.9 2103.8 2105.6 2041.9 2060.9 2078.1 2078.2 2086.8 7209.1 7280.4 7283.0 7296.5 7334.1 4104.1 4161.2 4173.1 4179.4 4192.3 2688.1 2714.3 2718.9 2742.8 2747.7 854.0 872.7 877.3 878.0 913.1 9414.1 9454.6 9463.0 9480.0 9512.1 3882.4 3891.4 3904.0 3911.7 3954.1 8267.8 8287.8 8301.1 8306.0 8331.1 4198.5 4214.9 4217.1 4224.8 4239.3 3260.5 3320.2 3756.9 3781.7 3788.5 3453.8 3461.1 3474.7 3485.4 3528.4 1071.2 1072.2 1079.8 1084.0 1085.6 1188.9 1189.2 1210.3 1210.5 1234.3 6633.9 6687.4 6745.8 6746.6 6747.6 421.0 423.5 430.4 432.1 432.3 5371.2 5419.3 5433.7 5444.1 5452.0 8371.0 8431.1 8477.8 8510.6 8566.0 8377.1 8385.2 8432.4 8445.2 8458.7 6670.0 6753.5 6810.0 6864.7 6865.4 6836.7 6851.3 6884.5 6899.4 6910.2 7748.9 7750.0 7805.1 7838.5 7969.8 7881.6 8004.9 8023.1 8086.8 8167.2 5377.6 5469.4 6103.9 6479.6 6501.1 6912.9 6923.2 6970.3 6995.9 7002.3 6466.5 6533.4 6570.5 6606.3 6670.3 6516.4 6522.2 6538.3 6549.0 6601.0 6556.3 6608.9 6622.1 6624.2 6635.9 5805.7 5825.0 6114.1 6127.6 6132.0 6175.5 6197.4 6203.3 6215.8 6231.1 12:59:12 INFO - 2015-12-16 12:59:12,065 DEBUG : BROWSER_OUTPUT: 12:59:12 INFO - 2015-12-16 12:59:12,065 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4892.57 Median:5548.13 stddev:1446.79 (26.1%) stddev-sans-first:1409.10 12:59:12 INFO - 2015-12-16 12:59:12,065 DEBUG : BROWSER_OUTPUT: Values: 295.5 302.4 308.8 309.2 309.8 5575.2 5606.2 5655.6 5664.0 5681.2 5541.4 5554.9 5555.6 5568.3 5586.1 4221.9 4282.2 4284.4 4293.8 4307.0 4301.8 4309.6 4324.4 4329.9 4332.5 4259.7 4269.9 4273.2 4280.7 4320.7 4301.1 4307.4 4307.6 4307.7 4327.4 3815.8 3831.5 3831.5 3831.6 3833.4 5260.0 5306.2 5409.0 5429.2 5439.0 4957.2 4966.0 4969.8 4976.6 4980.0 5922.3 5924.3 5935.0 5937.4 5937.9 4050.5 4121.1 4129.1 4129.3 4143.6 6148.5 6227.8 6236.2 6237.9 6249.8 4161.5 4185.9 4197.6 4197.8 4204.3 4255.2 4266.5 4269.0 4269.2 4271.4 4759.4 4883.2 4888.8 4894.6 4906.3 5867.0 5887.7 5901.7 5903.7 5922.6 1515.6 1518.3 1520.0 1520.0 1520.3 1005.9 1057.8 1058.9 1064.1 1078.2 5120.6 5124.5 5134.9 5135.5 5138.4 6043.6 6075.9 6096.9 6099.7 6111.4 5086.0 5098.4 5133.0 5140.5 5147.8 3193.0 3292.0 3306.7 3307.2 3320.5 5671.6 5799.5 5846.0 5848.4 5870.3 6306.3 6387.0 6395.4 6410.1 6410.8 5830.3 5831.3 5832.8 5839.9 5842.8 6143.5 6157.8 6172.8 6182.5 6184.2 5840.8 6004.4 6024.0 6046.4 6054.4 5823.4 5829.0 5832.6 5837.6 5841.1 4110.0 4146.2 4152.9 4157.3 4157.4 6056.3 6177.4 6179.4 6183.1 6188.7 5833.8 5844.9 5868.5 5875.7 5880.0 5891.5 5914.4 5921.1 5926.0 5936.6 5643.8 5771.2 5773.7 5777.3 5799.2 5932.6 5939.8 5941.6 5962.3 5964.1 5788.6 5794.4 5795.4 5798.9 5799.2 12:59:12 INFO - 2015-12-16 12:59:12,066 DEBUG : BROWSER_OUTPUT: 12:59:12 INFO - 2015-12-16 12:59:12,066 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:11628.93 Median:606.60 stddev:12979.45 (2139.7%) stddev-sans-first:12989.70 12:59:12 INFO - 2015-12-16 12:59:12,066 DEBUG : BROWSER_OUTPUT: Values: 655.1 764.6 775.5 776.3 790.5 11630.4 11664.4 11672.0 11723.9 11741.7 11459.7 11507.3 11714.6 11851.8 11902.9 801.4 811.8 816.9 819.2 826.6 831.2 831.4 831.9 834.3 836.8 868.9 876.5 876.8 877.7 878.8 860.3 862.0 862.1 864.2 864.4 637.7 642.5 644.8 649.1 649.7 11047.8 11050.3 11078.8 11087.5 11090.5 10602.3 10886.8 10911.9 11087.3 11426.5 9146.5 9291.5 9359.3 9403.1 9425.5 3143.2 3149.5 3198.2 3202.2 3212.3 38579.0 38919.4 38994.4 39011.6 39321.8 8981.7 9098.8 9154.9 9235.6 9235.9 15298.4 15454.4 15463.5 15481.6 15543.6 6174.0 6188.9 6202.5 6224.2 6283.7 3475.7 3531.9 3540.3 3541.6 3557.5 2493.5 2515.4 2520.0 2523.3 2525.3 461.0 474.9 476.1 478.4 481.7 525.3 599.3 601.4 601.7 605.9 7625.8 7705.0 7813.0 7952.8 7997.6 596.3 607.3 636.3 644.5 670.8 3617.8 3654.3 3799.0 3809.6 3816.1 44232.8 44609.8 45006.8 45091.3 45329.8 42393.0 42903.0 43134.4 43418.6 43454.6 39294.0 39643.0 39855.6 40395.2 40567.2 7701.4 7761.3 7761.4 7767.5 7900.4 29383.6 29563.6 29878.6 29973.4 30133.5 34062.0 34314.0 35814.6 37130.8 37212.3 9032.2 9056.5 9091.1 9442.7 9468.3 10338.5 10352.6 10414.8 10453.5 10488.4 9587.6 9648.3 9799.1 9831.7 9856.5 9621.9 9699.0 9714.2 9718.8 9803.1 9603.9 9705.2 9784.9 9804.2 9924.6 8358.7 8684.3 8886.7 8925.8 8926.5 7944.8 8078.9 8115.8 8129.0 8315.8 12:59:12 INFO - 2015-12-16 12:59:12,066 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 12:59:12 INFO - 2015-12-16 12:59:12,066 DEBUG : BROWSER_OUTPUT: 12:59:12 INFO - 2015-12-16 12:59:12,800 INFO : Browser exited with error code: 0 12:59:12 INFO - 2015-12-16 12:59:12,821 INFO : Completed test dromaeo_css (00:18:35) 12:59:12 INFO - 2015-12-16 12:59:12,821 INFO : Starting test kraken 12:59:12 INFO - 2015-12-16 12:59:12,822 DEBUG : operating with platform_type : linux_ 12:59:12 INFO - 2015-12-16 12:59:12,822 INFO : Initialising browser for kraken test... 12:59:12 INFO - 2015-12-16 12:59:12,828 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOHP877/profile http://localhost:53507/getInfo.html 12:59:18 INFO - 2015-12-16 12:59:18,060 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 12:59:18 INFO - 2015-12-16 12:59:18,060 DEBUG : BROWSER_OUTPUT: colorDepth:24 12:59:18 INFO - 2015-12-16 12:59:18,060 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 12:59:18 INFO - 2015-12-16 12:59:18,060 DEBUG : BROWSER_OUTPUT: __metrics 12:59:18 INFO - 2015-12-16 12:59:18,177 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 12:59:19 INFO - 2015-12-16 12:59:19,076 INFO : Browser initialized. 12:59:19 INFO - 2015-12-16 12:59:19,077 INFO : Running cycle 1/1 for kraken test... 12:59:19 INFO - 2015-12-16 12:59:19,077 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOHP877/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 12:59:31 INFO - 2015-12-16 12:59:31,134 DEBUG : BROWSER_OUTPUT: RSS: Main: 143347712 12:59:31 INFO - 2015-12-16 12:59:31,134 DEBUG : BROWSER_OUTPUT: 12:59:33 INFO - 2015-12-16 12:59:33,715 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53507/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53507/tests/kraken/driver.html?testName=audio-beat-detection) 12:59:34 INFO - 2015-12-16 12:59:34,337 DEBUG : BROWSER_OUTPUT: RSS: Main: 142716928 12:59:34 INFO - 2015-12-16 12:59:34,337 DEBUG : BROWSER_OUTPUT: 12:59:37 INFO - 2015-12-16 12:59:37,067 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53507/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53507/tests/kraken/driver.html?testName=audio-dft) 12:59:37 INFO - 2015-12-16 12:59:37,681 DEBUG : BROWSER_OUTPUT: RSS: Main: 142336000 12:59:37 INFO - 2015-12-16 12:59:37,681 DEBUG : BROWSER_OUTPUT: 12:59:40 INFO - 2015-12-16 12:59:40,594 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53507/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53507/tests/kraken/driver.html?testName=audio-fft) 12:59:41 INFO - 2015-12-16 12:59:41,181 DEBUG : BROWSER_OUTPUT: RSS: Main: 138194944 12:59:41 INFO - 2015-12-16 12:59:41,181 DEBUG : BROWSER_OUTPUT: 12:59:43 INFO - 2015-12-16 12:59:43,254 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53507/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53507/tests/kraken/driver.html?testName=audio-oscillator) 12:59:43 INFO - 2015-12-16 12:59:43,835 DEBUG : BROWSER_OUTPUT: RSS: Main: 139411456 12:59:43 INFO - 2015-12-16 12:59:43,835 DEBUG : BROWSER_OUTPUT: 12:59:46 INFO - 2015-12-16 12:59:46,089 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53507/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53507/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:59:46 INFO - 2015-12-16 12:59:46,670 DEBUG : BROWSER_OUTPUT: RSS: Main: 139030528 12:59:46 INFO - 2015-12-16 12:59:46,671 DEBUG : BROWSER_OUTPUT: 12:59:49 INFO - 2015-12-16 12:59:49,722 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53507/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53507/tests/kraken/driver.html?testName=imaging-darkroom) 12:59:50 INFO - 2015-12-16 12:59:50,315 DEBUG : BROWSER_OUTPUT: RSS: Main: 140595200 12:59:50 INFO - 2015-12-16 12:59:50,315 DEBUG : BROWSER_OUTPUT: 12:59:53 INFO - 2015-12-16 12:59:53,804 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53507/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53507/tests/kraken/driver.html?testName=imaging-desaturate) 12:59:54 INFO - 2015-12-16 12:59:54,397 DEBUG : BROWSER_OUTPUT: RSS: Main: 137416704 12:59:54 INFO - 2015-12-16 12:59:54,397 DEBUG : BROWSER_OUTPUT: 12:59:57 INFO - 2015-12-16 12:59:57,281 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53507/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53507/tests/kraken/driver.html?testName=json-parse-financial) 12:59:57 INFO - 2015-12-16 12:59:57,888 DEBUG : BROWSER_OUTPUT: RSS: Main: 137572352 12:59:57 INFO - 2015-12-16 12:59:57,888 DEBUG : BROWSER_OUTPUT: 12:59:59 INFO - 2015-12-16 12:59:59,657 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53507/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53507/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:00:00 INFO - 2015-12-16 13:00:00,243 DEBUG : BROWSER_OUTPUT: RSS: Main: 140550144 13:00:00 INFO - 2015-12-16 13:00:00,243 DEBUG : BROWSER_OUTPUT: 13:00:02 INFO - 2015-12-16 13:00:02,155 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53507/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53507/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:00:02 INFO - 2015-12-16 13:00:02,776 DEBUG : BROWSER_OUTPUT: RSS: Main: 139882496 13:00:02 INFO - 2015-12-16 13:00:02,777 DEBUG : BROWSER_OUTPUT: 13:00:04 INFO - 2015-12-16 13:00:04,992 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53507/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53507/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:00:05 INFO - 2015-12-16 13:00:05,593 DEBUG : BROWSER_OUTPUT: RSS: Main: 139980800 13:00:05 INFO - 2015-12-16 13:00:05,594 DEBUG : BROWSER_OUTPUT: 13:00:08 INFO - 2015-12-16 13:00:08,150 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53507/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53507/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:00:08 INFO - 2015-12-16 13:00:08,756 DEBUG : BROWSER_OUTPUT: RSS: Main: 140468224 13:00:08 INFO - 2015-12-16 13:00:08,756 DEBUG : BROWSER_OUTPUT: 13:00:11 INFO - 2015-12-16 13:00:11,433 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53507/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53507/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:00:12 INFO - 2015-12-16 13:00:12,022 DEBUG : BROWSER_OUTPUT: RSS: Main: 140296192 13:00:12 INFO - 2015-12-16 13:00:12,022 DEBUG : BROWSER_OUTPUT: 13:00:13 INFO - 2015-12-16 13:00:13,813 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53507/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53507/tests/kraken/driver.html?testName=ai-astar) 13:00:14 INFO - 2015-12-16 13:00:14,070 DEBUG : BROWSER_OUTPUT: RSS: Main: 140181504 13:00:14 INFO - 2015-12-16 13:00:14,070 DEBUG : BROWSER_OUTPUT: 13:00:14 INFO - 2015-12-16 13:00:14,070 DEBUG : BROWSER_OUTPUT: __start_tp_report 13:00:14 INFO - 2015-12-16 13:00:14,071 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 13:00:14 INFO - 2015-12-16 13:00:14,071 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 13:00:14 INFO - 2015-12-16 13:00:14,071 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 13:00:14 INFO - 2015-12-16 13:00:14,071 DEBUG : BROWSER_OUTPUT: |0;ai-astar;118;111;116;116;115;126;114;114;113;117 13:00:14 INFO - 2015-12-16 13:00:14,071 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;156;134;153;166;133;156;156;132;181;132 13:00:14 INFO - 2015-12-16 13:00:14,071 DEBUG : BROWSER_OUTPUT: |2;audio-dft;164;165;165;163;164;166;173;163;165;163 13:00:14 INFO - 2015-12-16 13:00:14,071 DEBUG : BROWSER_OUTPUT: |3;audio-fft;76;72;74;74;126;73;79;73;73;87 13:00:14 INFO - 2015-12-16 13:00:14,072 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;97;96;136;96;97;95;130;96;95;126 13:00:14 INFO - 2015-12-16 13:00:14,072 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;112;110;110;111;110;110;111;110;110;111 13:00:14 INFO - 2015-12-16 13:00:14,072 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;150;150;149;150;149;149;148;148;148;150 13:00:14 INFO - 2015-12-16 13:00:14,072 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;97;98;96;96;96;96;96;96;95 13:00:14 INFO - 2015-12-16 13:00:14,072 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;77;76;76;74;75;74;74;80;72;71 13:00:14 INFO - 2015-12-16 13:00:14,072 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;67;70;68;70;69;69;68;69;69;68 13:00:14 INFO - 2015-12-16 13:00:14,072 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;84;96;87;97;86;88;90;89;88;88 13:00:14 INFO - 2015-12-16 13:00:14,073 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;126;128;120;121;122;136;131;125;127;127 13:00:14 INFO - 2015-12-16 13:00:14,073 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;141;153;150;151;157;142;171;152;154;154 13:00:14 INFO - 2015-12-16 13:00:14,073 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;74;71;71;76;72;72;73;70;72;73 13:00:14 INFO - 2015-12-16 13:00:14,073 DEBUG : BROWSER_OUTPUT: __end_tp_report 13:00:14 INFO - 2015-12-16 13:00:14,073 DEBUG : BROWSER_OUTPUT: __start_cc_report 13:00:14 INFO - 2015-12-16 13:00:14,073 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,792 13:00:14 INFO - 2015-12-16 13:00:14,073 DEBUG : BROWSER_OUTPUT: __end_cc_report 13:00:14 INFO - 2015-12-16 13:00:14,073 DEBUG : BROWSER_OUTPUT: __startTimestamp1450299614066__endTimestamp 13:00:14 INFO - 2015-12-16 13:00:14,074 DEBUG : BROWSER_OUTPUT: 13:00:14 INFO - 2015-12-16 13:00:14,075 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 13:00:14 INFO - 2015-12-16 13:00:14,075 DEBUG : BROWSER_OUTPUT: Number of tests: 14 13:00:14 INFO - 2015-12-16 13:00:14,075 DEBUG : BROWSER_OUTPUT: 13:00:14 INFO - 2015-12-16 13:00:14,076 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:116.00 Median:116.00 stddev:4.06 (3.5%) stddev-sans-first:4.24 13:00:14 INFO - 2015-12-16 13:00:14,076 DEBUG : BROWSER_OUTPUT: Values: 118.0 111.0 116.0 116.0 115.0 126.0 114.0 114.0 113.0 117.0 13:00:14 INFO - 2015-12-16 13:00:14,076 DEBUG : BROWSER_OUTPUT: 13:00:14 INFO - 2015-12-16 13:00:14,076 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:149.90 Median:156.00 stddev:16.76 (10.7%) stddev-sans-first:17.63 13:00:14 INFO - 2015-12-16 13:00:14,076 DEBUG : BROWSER_OUTPUT: Values: 156.0 134.0 153.0 166.0 133.0 156.0 156.0 132.0 181.0 132.0 13:00:14 INFO - 2015-12-16 13:00:14,076 DEBUG : BROWSER_OUTPUT: 13:00:14 INFO - 2015-12-16 13:00:14,076 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:165.10 Median:165.00 stddev:2.96 (1.8%) stddev-sans-first:3.11 13:00:14 INFO - 2015-12-16 13:00:14,076 DEBUG : BROWSER_OUTPUT: Values: 164.0 165.0 165.0 163.0 164.0 166.0 173.0 163.0 165.0 163.0 13:00:14 INFO - 2015-12-16 13:00:14,076 DEBUG : BROWSER_OUTPUT: 13:00:14 INFO - 2015-12-16 13:00:14,077 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:80.70 Median:74.00 stddev:16.53 (22.3%) stddev-sans-first:17.45 13:00:14 INFO - 2015-12-16 13:00:14,077 DEBUG : BROWSER_OUTPUT: Values: 76.0 72.0 74.0 74.0 126.0 73.0 79.0 73.0 73.0 87.0 13:00:14 INFO - 2015-12-16 13:00:14,077 DEBUG : BROWSER_OUTPUT: 13:00:14 INFO - 2015-12-16 13:00:14,077 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.40 Median:96.00 stddev:16.93 (17.6%) stddev-sans-first:17.61 13:00:14 INFO - 2015-12-16 13:00:14,077 DEBUG : BROWSER_OUTPUT: Values: 97.0 96.0 136.0 96.0 97.0 95.0 130.0 96.0 95.0 126.0 13:00:14 INFO - 2015-12-16 13:00:14,077 DEBUG : BROWSER_OUTPUT: 13:00:14 INFO - 2015-12-16 13:00:14,077 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:110.50 Median:110.50 stddev:0.71 (0.6%) stddev-sans-first:0.50 13:00:14 INFO - 2015-12-16 13:00:14,077 DEBUG : BROWSER_OUTPUT: Values: 112.0 110.0 110.0 111.0 110.0 110.0 111.0 110.0 110.0 111.0 13:00:14 INFO - 2015-12-16 13:00:14,077 DEBUG : BROWSER_OUTPUT: 13:00:14 INFO - 2015-12-16 13:00:14,077 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:149.10 Median:149.50 stddev:0.88 (0.6%) stddev-sans-first:0.87 13:00:14 INFO - 2015-12-16 13:00:14,077 DEBUG : BROWSER_OUTPUT: Values: 150.0 150.0 149.0 150.0 149.0 149.0 148.0 148.0 148.0 150.0 13:00:14 INFO - 2015-12-16 13:00:14,077 DEBUG : BROWSER_OUTPUT: 13:00:14 INFO - 2015-12-16 13:00:14,077 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.20 Median:96.00 stddev:0.79 (0.8%) stddev-sans-first:0.83 13:00:14 INFO - 2015-12-16 13:00:14,078 DEBUG : BROWSER_OUTPUT: Values: 96.0 97.0 98.0 96.0 96.0 96.0 96.0 96.0 96.0 95.0 13:00:14 INFO - 2015-12-16 13:00:14,078 DEBUG : BROWSER_OUTPUT: 13:00:14 INFO - 2015-12-16 13:00:14,078 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:74.90 Median:75.50 stddev:2.56 (3.4%) stddev-sans-first:2.60 13:00:14 INFO - 2015-12-16 13:00:14,078 DEBUG : BROWSER_OUTPUT: Values: 77.0 76.0 76.0 74.0 75.0 74.0 74.0 80.0 72.0 71.0 13:00:14 INFO - 2015-12-16 13:00:14,078 DEBUG : BROWSER_OUTPUT: 13:00:14 INFO - 2015-12-16 13:00:14,078 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:68.70 Median:69.00 stddev:0.95 (1.4%) stddev-sans-first:0.78 13:00:14 INFO - 2015-12-16 13:00:14,078 DEBUG : BROWSER_OUTPUT: Values: 67.0 70.0 68.0 70.0 69.0 69.0 68.0 69.0 69.0 68.0 13:00:14 INFO - 2015-12-16 13:00:14,078 DEBUG : BROWSER_OUTPUT: 13:00:14 INFO - 2015-12-16 13:00:14,078 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:89.30 Median:88.50 stddev:4.14 (4.7%) stddev-sans-first:3.92 13:00:14 INFO - 2015-12-16 13:00:14,078 DEBUG : BROWSER_OUTPUT: Values: 84.0 96.0 87.0 97.0 86.0 88.0 90.0 89.0 88.0 88.0 13:00:14 INFO - 2015-12-16 13:00:14,079 DEBUG : BROWSER_OUTPUT: 13:00:14 INFO - 2015-12-16 13:00:14,079 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:126.30 Median:127.00 stddev:4.81 (3.8%) stddev-sans-first:5.10 13:00:14 INFO - 2015-12-16 13:00:14,079 DEBUG : BROWSER_OUTPUT: Values: 126.0 128.0 120.0 121.0 122.0 136.0 131.0 125.0 127.0 127.0 13:00:14 INFO - 2015-12-16 13:00:14,079 DEBUG : BROWSER_OUTPUT: 13:00:14 INFO - 2015-12-16 13:00:14,079 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.50 Median:153.50 stddev:8.29 (5.4%) stddev-sans-first:7.68 13:00:14 INFO - 2015-12-16 13:00:14,080 DEBUG : BROWSER_OUTPUT: Values: 141.0 153.0 150.0 151.0 157.0 142.0 171.0 152.0 154.0 154.0 13:00:14 INFO - 2015-12-16 13:00:14,080 DEBUG : BROWSER_OUTPUT: 13:00:14 INFO - 2015-12-16 13:00:14,080 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.40 Median:72.50 stddev:1.71 (2.4%) stddev-sans-first:1.72 13:00:14 INFO - 2015-12-16 13:00:14,081 DEBUG : BROWSER_OUTPUT: Values: 74.0 71.0 71.0 76.0 72.0 72.0 73.0 70.0 72.0 73.0 13:00:14 INFO - 2015-12-16 13:00:14,081 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 13:00:14 INFO - 2015-12-16 13:00:14,081 DEBUG : BROWSER_OUTPUT: 13:00:15 INFO - 2015-12-16 13:00:15,012 INFO : Browser exited with error code: 0 13:00:15 INFO - 2015-12-16 13:00:15,064 INFO : Completed test kraken (00:01:02) 13:00:15 INFO - 2015-12-16 13:00:15,064 INFO : Starting test v8_7 13:00:15 INFO - 2015-12-16 13:00:15,064 DEBUG : operating with platform_type : linux_ 13:00:15 INFO - 2015-12-16 13:00:15,064 INFO : Initialising browser for v8_7 test... 13:00:15 INFO - 2015-12-16 13:00:15,070 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFjt5Ah/profile http://localhost:53507/getInfo.html 13:00:20 INFO - 2015-12-16 13:00:20,267 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 13:00:20 INFO - 2015-12-16 13:00:20,267 DEBUG : BROWSER_OUTPUT: colorDepth:24 13:00:20 INFO - 2015-12-16 13:00:20,267 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 13:00:20 INFO - 2015-12-16 13:00:20,267 DEBUG : BROWSER_OUTPUT: __metrics 13:00:20 INFO - 2015-12-16 13:00:20,424 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 13:00:21 INFO - 2015-12-16 13:00:21,392 INFO : Browser initialized. 13:00:21 INFO - 2015-12-16 13:00:21,393 INFO : Running cycle 1/1 for v8_7 test... 13:00:21 INFO - 2015-12-16 13:00:21,393 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFjt5Ah/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 13:00:33 INFO - 2015-12-16 13:00:33,484 DEBUG : BROWSER_OUTPUT: RSS: Main: 143781888 13:00:33 INFO - 2015-12-16 13:00:33,484 DEBUG : BROWSER_OUTPUT: 13:00:54 INFO - 2015-12-16 13:00:54,970 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53507/tests/v8_7/run.html (next: http://localhost:53507/tests/v8_7/run.html) 13:00:55 INFO - 2015-12-16 13:00:55,229 DEBUG : BROWSER_OUTPUT: RSS: Main: 138018816 13:00:55 INFO - 2015-12-16 13:00:55,229 DEBUG : BROWSER_OUTPUT: 13:00:55 INFO - 2015-12-16 13:00:55,229 DEBUG : BROWSER_OUTPUT: __start_tp_report 13:00:55 INFO - 2015-12-16 13:00:55,229 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 13:00:55 INFO - 2015-12-16 13:00:55,229 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 13:00:55 INFO - 2015-12-16 13:00:55,229 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 13:00:55 INFO - 2015-12-16 13:00:55,229 DEBUG : BROWSER_OUTPUT: |0;Richards;166.5001665001665 13:00:55 INFO - 2015-12-16 13:00:55,229 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;186.1850679575498 13:00:55 INFO - 2015-12-16 13:00:55,229 DEBUG : BROWSER_OUTPUT: |2;Encrypt;282.4858757062147 13:00:55 INFO - 2015-12-16 13:00:55,229 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5181.347150259067 13:00:55 INFO - 2015-12-16 13:00:55,229 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1077.5862068965516 13:00:55 INFO - 2015-12-16 13:00:55,229 DEBUG : BROWSER_OUTPUT: |5;Earley;716.0228898426324 13:00:55 INFO - 2015-12-16 13:00:55,230 DEBUG : BROWSER_OUTPUT: |6;Boyer;12216.867469879518 13:00:55 INFO - 2015-12-16 13:00:55,230 DEBUG : BROWSER_OUTPUT: |7;RegExp;26578.947368421053 13:00:55 INFO - 2015-12-16 13:00:55,230 DEBUG : BROWSER_OUTPUT: |8;Splay;592.4170616113744 13:00:55 INFO - 2015-12-16 13:00:55,230 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4850.241545893719 13:00:55 INFO - 2015-12-16 13:00:55,230 DEBUG : BROWSER_OUTPUT: __end_tp_report 13:00:55 INFO - 2015-12-16 13:00:55,230 DEBUG : BROWSER_OUTPUT: __startTimestamp1450299655223__endTimestamp 13:00:55 INFO - 2015-12-16 13:00:55,230 DEBUG : BROWSER_OUTPUT: 13:00:55 INFO - 2015-12-16 13:00:55,230 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 13:00:55 INFO - 2015-12-16 13:00:55,230 DEBUG : BROWSER_OUTPUT: Number of tests: 10 13:00:55 INFO - 2015-12-16 13:00:55,230 DEBUG : BROWSER_OUTPUT: 13:00:55 INFO - 2015-12-16 13:00:55,230 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:166.50 Median:166.50 stddev:0.00 (0.0%) 13:00:55 INFO - 2015-12-16 13:00:55,230 DEBUG : BROWSER_OUTPUT: Values: 166.5 13:00:55 INFO - 2015-12-16 13:00:55,230 DEBUG : BROWSER_OUTPUT: 13:00:55 INFO - 2015-12-16 13:00:55,230 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:186.19 Median:186.19 stddev:0.00 (0.0%) 13:00:55 INFO - 2015-12-16 13:00:55,230 DEBUG : BROWSER_OUTPUT: Values: 186.2 13:00:55 INFO - 2015-12-16 13:00:55,231 DEBUG : BROWSER_OUTPUT: 13:00:55 INFO - 2015-12-16 13:00:55,231 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:282.49 Median:282.49 stddev:0.00 (0.0%) 13:00:55 INFO - 2015-12-16 13:00:55,231 DEBUG : BROWSER_OUTPUT: Values: 282.5 13:00:55 INFO - 2015-12-16 13:00:55,231 DEBUG : BROWSER_OUTPUT: 13:00:55 INFO - 2015-12-16 13:00:55,231 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5181.35 Median:5181.35 stddev:0.00 (0.0%) 13:00:55 INFO - 2015-12-16 13:00:55,231 DEBUG : BROWSER_OUTPUT: Values: 5181.3 13:00:55 INFO - 2015-12-16 13:00:55,231 DEBUG : BROWSER_OUTPUT: 13:00:55 INFO - 2015-12-16 13:00:55,231 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1077.59 Median:1077.59 stddev:0.00 (0.0%) 13:00:55 INFO - 2015-12-16 13:00:55,231 DEBUG : BROWSER_OUTPUT: Values: 1077.6 13:00:55 INFO - 2015-12-16 13:00:55,231 DEBUG : BROWSER_OUTPUT: 13:00:55 INFO - 2015-12-16 13:00:55,231 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:716.02 Median:716.02 stddev:0.00 (0.0%) 13:00:55 INFO - 2015-12-16 13:00:55,231 DEBUG : BROWSER_OUTPUT: Values: 716.0 13:00:55 INFO - 2015-12-16 13:00:55,231 DEBUG : BROWSER_OUTPUT: 13:00:55 INFO - 2015-12-16 13:00:55,232 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12216.87 Median:12216.87 stddev:0.00 (0.0%) 13:00:55 INFO - 2015-12-16 13:00:55,232 DEBUG : BROWSER_OUTPUT: Values: 12216.9 13:00:55 INFO - 2015-12-16 13:00:55,232 DEBUG : BROWSER_OUTPUT: 13:00:55 INFO - 2015-12-16 13:00:55,232 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26578.95 Median:26578.95 stddev:0.00 (0.0%) 13:00:55 INFO - 2015-12-16 13:00:55,232 DEBUG : BROWSER_OUTPUT: Values: 26578.9 13:00:55 INFO - 2015-12-16 13:00:55,232 DEBUG : BROWSER_OUTPUT: 13:00:55 INFO - 2015-12-16 13:00:55,232 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:592.42 Median:592.42 stddev:0.00 (0.0%) 13:00:55 INFO - 2015-12-16 13:00:55,232 DEBUG : BROWSER_OUTPUT: Values: 592.4 13:00:55 INFO - 2015-12-16 13:00:55,232 DEBUG : BROWSER_OUTPUT: 13:00:55 INFO - 2015-12-16 13:00:55,233 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4850.24 Median:4850.24 stddev:0.00 (0.0%) 13:00:55 INFO - 2015-12-16 13:00:55,233 DEBUG : BROWSER_OUTPUT: Values: 4850.2 13:00:55 INFO - 2015-12-16 13:00:55,233 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 13:00:55 INFO - 2015-12-16 13:00:55,233 DEBUG : BROWSER_OUTPUT: 13:00:56 INFO - 2015-12-16 13:00:56,172 INFO : Browser exited with error code: 0 13:00:56 INFO - 2015-12-16 13:00:56,187 INFO : Completed test v8_7 (00:00:41) 13:00:56 INFO - 2015-12-16 13:00:56,226 INFO : Completed test suite (00:20:18) 13:00:56 INFO - 2015-12-16 13:00:56,226 DEBUG : Working with test: dromaeo_css 13:00:56 INFO - 2015-12-16 13:00:56,226 DEBUG : Generating results file: dromaeo_css 13:00:56 INFO - 2015-12-16 13:00:56,227 DEBUG : Working with test: kraken 13:00:56 INFO - 2015-12-16 13:00:56,228 DEBUG : Generating results file: kraken 13:00:56 INFO - 2015-12-16 13:00:56,228 INFO : javascript benchmark 13:00:56 INFO - 2015-12-16 13:00:56,228 DEBUG : Working with test: v8_7 13:00:56 INFO - 2015-12-16 13:00:56,228 DEBUG : Generating results file: v8_7 13:00:56 INFO - 2015-12-16 13:00:56,229 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 13:00:56 INFO - 2015-12-16 13:00:56,291 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,132,43]] 13:00:56 INFO - 2015-12-16 13:00:56,291 DEBUG : process_Request line: dromaeo_css 6391.86 graph.html#tests=[[72,132,43]] 13:00:56 INFO - 2015-12-16 13:00:56,291 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 13:00:56 INFO - 2015-12-16 13:00:56,447 DEBUG : process_Request line: kraken 1558.00 graph.html#tests=[[232,132,43]] 13:00:56 INFO - 2015-12-16 13:00:56,448 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 13:00:56 INFO - 2015-12-16 13:00:56,586 DEBUG : process_Request line: v8_7 21286.40 graph.html#tests=[[230,132,43]] 13:00:56 INFO - 2015-12-16 13:00:56,587 INFO : javascript benchmark 13:00:56 INFO - 2015-12-16 13:00:56,591 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2677.9471402601034, 2740.438210166916, 2773.098742908554, 2779.861006949652, 2784.958225626616, 4640.373549570807, 4851.054044461332, 4854.611631069515, 4883.29192267121, 4935.999999999995, 4700.0599880023965, 4716.693414928036, 4775.952240477594, 4777.593904518111, 4833.492483289257, 4370.759608221547, 4426.424564806571, 4510.210713125206, 4517.457905051382, 4532.708178169222, 4274.316109422493, 4393.384926110347, 4424.402705634747, 4495.527969563198, 4516.864494065167, 4347.456567929009, 4355.499117601485, 4427.6015158635555, 4456.732596044248, 4462.531434199427, 4427.645788336926, 4441.422615060053, 4442.466903971512, 4445.933311000305, 4470.932936005963, 4260.978695106535, 4391.758453285068, 4407.933880991788, 4449.933251001237, 4457.153140903218, 4595.310703394484, 4637.907241855144, 4758.214894542431, 4778.2115950868065, 4798.304245884327, 5156.226566015091, 5166.0, 5169.069567477853, 5177.896442071175, 5180.300659410969, 4931.0630980113665, 5058.342415486, 5181.248718935733, 5192.662476938987, 5208.270842082112, 4698.553637404441, 4729.645276604199, 4752.310914917318, 4753.928691069636, 4791.880202994918, 5313.920291195635, 5317.494837990384, 5325.547328477122, 5337.172738225581, 5352.812651557177, 4888.193448081098, 4976.154053810788, 4990.251462280687, 5102.08162530748, 5122.974385128051, 5066.670666406671, 5067.5185857343495, 5075.0, 5089.720065396439, 5191.013707395583, 5056.519630635157, 5113.923291150636, 5119.6160287978555, 5122.0, 5179.19722443022, 5053.343065401475, 5086.949130508721, 5090.955967296499, 5113.769880355393, 5146.253793199965, 4082.6325630692786, 4175.415441838145, 4214.7892605370225, 4231.4710661167355, 4304.203722311382, 3738.5887552369218, 3747.9250414991548, 3752.099496120966, 3780.1683629601443, 3780.905477363088, 4461.375407442961, 4465.464144302639, 4467.12890986261, 4467.5532446755715, 4506.662000349987, 4957.082939656175, 5056.873578160576, 5094.49055094495, 5157.4584668610005, 5182.870428239325, 4458.643308535309, 4463.888402789957, 4490.820367185276, 4492.910141797146, 4526.366308716782, 4376.190404775127, 4570.3829982951875, 4588.49526552079, 4610.423697037871, 4626.4910859805395, 4820.831270905432, 4848.369711937426, 4872.4640289567415, 4890.046440943982, 4931.7040977541465, 5191.11751002338, 5242.7902883885745, 5246.658967167122, 5249.7112658804135, 5299.920501192562, 4751.263554149113, 4842.17682993899, 4960.355153829979, 4976.975115124401, 4976.975115124401, 4983.825566105169, 5051.696898186121, 5064.18972964324, 5079.92380114306, 5118.616103792305, 5184.9740751296, 5193.37679478465, 5197.27349619378, 5208.713520756395, 5247.947520524746, 4760.452547957029, 4878.048780487771, 4936.851894443173, 4998.925016124688, 5005.299258103942, 5018.949810501995, 5090.49095090488, 5090.9563539473575, 5093.261477085876, 5096.159133742891, 4895.241237608177, 4949.30709700635, 4970.8757281068265, 4973.303737476828, 4992.375953005874, 4804.471508134033, 4901.191303434894, 4974.825881094144, 5043.823466178813, 5096.625812682254, 4940.604751619933, 4963.900721985612, 4972.701637901738, 4983.526564976321, 5008.974955125347], "unit": "score", "name": "dojo.html", "value": 4715.151433835531}, {"lowerIsBetter": false, "replicates": [542.9022775900337, 564.6301672404575, 572.556268891609, 573.4523530028823, 577.4052725692535, 34754.30491390174, 35146.12134696634, 35342.82328588353, 35442.822785886034, 35490.0, 38812.61187388125, 39375.21249575014, 39385.80307098468, 39401.21197576054, 39427.40858887119, 7906.446548741605, 8064.354851611857, 8073.0, 8080.515169089844, 8085.555294458803, 7901.170377110407, 7971.760847174594, 7996.840063198732, 8005.119436861941, 8010.67957281708, 7954.045514538264, 8001.559914204717, 8045.798855028613, 8050.758477245692, 8052.315553177987, 7937.523748575104, 8012.919870801305, 8031.598420079031, 8037.5981200940305, 8040.0, 7795.727149549739, 7871.724489642892, 7942.444028917978, 7945.284924356778, 7952.165022672594, 35750.821245893865, 36066.45900311471, 36284.27431451356, 36430.0, 36506.0, 2856.300206449433, 2873.8850445982134, 2974.4348573770912, 3008.991008991009, 3653.324135035027, 27298.5905211422, 27441.31396715098, 27828.0, 28124.0, 28484.28789280264, 3240.8055516669074, 3379.493076038589, 3414.8804791832167, 3423.6747508986605, 3482.5820901491984, 200484.0, 200718.0, 201239.0, 201514.0, 201996.0, 46296.7685161572, 47116.528834711215, 53887.461125389025, 55756.72121639366, 56716.43283567194, 50836.49163508318, 50999.235011474855, 51176.0, 51220.48779512232, 51345.486545134816, 13141.67145821343, 13315.600531984055, 13418.194908305533, 13436.731265374834, 13441.731165376834, 9951.751206219758, 10043.0, 10057.798844023226, 10085.243606729524, 10205.285630005978, 19212.80787192138, 19215.615687686168, 19414.320498782476, 19420.902895485713, 19469.12388942501, 7605.0, 7780.677284861587, 7868.331191848642, 8017.6792928283385, 8064.193580641889, 792.235492749488, 853.9402241843011, 921.4655499810094, 934.4720233068294, 939.7885475768035, 22805.087796488286, 22929.656055159187, 23069.307920762403, 25609.0, 26407.207783766517, 993.8012397520525, 1007.9395236285847, 1013.6300250408696, 1031.8813336466403, 1087.0, 12894.161879477804, 12911.483540658268, 13034.087613867128, 13098.623110823433, 13194.340282986004, 253826.0, 258093.0, 258273.0, 259384.0, 260816.0, 45733.314000290025, 46143.538564614595, 46401.7679911605, 46419.5358046422, 46608.766956165004, 1188.7325351795778, 1190.7439900421277, 1198.7063169523349, 1209.0025728773796, 1213.7997230457052, 25217.0, 25520.0, 25675.614865776653, 25680.743192567836, 25705.61441578415, 39959.80020099881, 40304.39543406793, 40634.1873162541, 40705.0, 40853.79573102116, 246349.0, 246689.0, 247217.0, 247822.0, 248457.0, 28057.43885122327, 30608.234794129436, 31291.53062704107, 31376.686233137378, 31395.529067064468, 36409.0, 36743.0, 37025.629743702215, 37541.2491750158, 37656.24687506289, 31367.372652547278, 31773.841130794197, 31929.68070319267, 32652.673473264964, 32737.017889463354, 32450.67549324571, 32559.0, 32652.183695407806, 34822.82588587041, 36318.45522317114, 32191.195220119684, 32471.350572988882, 32492.350152997282, 32586.0, 32685.18287042843, 28632.284192895346, 29544.0, 29573.556396654498, 30141.547876782304, 30465.54301685521, 21649.13403463844, 23715.40711482227, 23862.88068559646, 24264.63603045991, 25373.746262537876], "unit": "score", "name": "ext.html", "value": 16617.28044413226}, {"lowerIsBetter": false, "replicates": [340.56407798018535, 342.86456849544413, 343.4761987968348, 343.5550961504854, 344.08988226141855, 15069.547913562597, 15163.241837908101, 16013.919930400332, 16358.0, 16610.83389166108, 9865.753356166082, 9913.107820296173, 9929.255305852072, 12385.938070309636, 15200.54398368048, 4254.276772948599, 4351.0862718829085, 4442.844500442485, 4465.419495465585, 4491.191585514606, 4449.42157519522, 4480.439945006874, 4490.0, 4497.235469970096, 4499.977500112494, 3400.727941764665, 3476.7566270361085, 4441.800118994637, 4492.865214043568, 4847.2002119650215, 4920.0159968006365, 4929.827456039039, 4932.7914660907945, 4936.753162341868, 4952.281919121707, 4789.880252993668, 4791.6166706663735, 4799.256115302133, 5771.422857714195, 6002.879942401171, 19764.90117549403, 19775.80224197754, 21261.68107478389, 22773.886130569408, 22790.772092279032, 1984.900754962246, 1992.9701054484194, 1998.180745894191, 2020.4039808256452, 2025.7670367907585, 13837.238951857644, 13858.584242472742, 13948.58154255374, 14178.432862685584, 14486.927565362212, 1863.4316533457425, 1888.2163901980662, 1889.5087277307862, 1903.1150515010447, 1929.8166674165932, 48369.27446088347, 56439.71780141114, 60284.0, 63384.36615633831, 63771.0, 14619.0, 14843.109413435013, 15004.474843380429, 15048.473303434328, 15071.397144114331, 21356.893215533823, 21363.25228616991, 21516.784832151792, 21584.0, 21623.67564486534, 8151.633176507072, 8195.590220488952, 8285.668573257122, 8323.66705331792, 8460.069392366835, 7258.745943892044, 7265.09186351706, 7295.489315747846, 7302.306280903305, 7360.815979600553, 7769.805754856061, 7783.221677832172, 7814.6092695365005, 7841.294283514511, 7846.293833554893, 3135.749140068789, 3158.6051743532057, 3209.261869769966, 3224.3873664003763, 3253.4469140246215, 382.1439974457196, 382.829640809837, 829.3821103278096, 883.6976604797887, 997.7555498902815, 9473.213398270495, 10174.643887463903, 15315.46395876139, 15449.9227503864, 16052.03687778737, 817.9386546008955, 841.4194205997842, 857.7555396711907, 870.5603670146536, 900.4777229207044, 8714.825703485894, 8745.169208925143, 8818.559072046372, 8830.205281524693, 8996.59515321812, 438671.0, 444962.0, 457167.0, 459430.0, 460996.0, 68726.65636671784, 70304.0, 70448.64775676088, 71411.0, 71667.0, 1105.0883021507289, 1115.3865374044244, 1120.193460708305, 1120.8318752187238, 1131.5417256011328, 14335.820230063015, 14464.421423142956, 14485.203313817841, 14506.637334066732, 14650.560483185522, 32959.67040329662, 33096.834515827264, 33296.33407331888, 33335.33329333351, 34082.0, 44894.3265851019, 45034.54965450303, 46732.53267467282, 47205.0, 47366.0, 7967.0, 8618.525981071101, 8960.68637597681, 8961.327900407627, 9022.323325750463, 10103.696889093339, 10140.64507742255, 10183.643572474928, 10187.28688991793, 10198.745031374276, 11897.94051029774, 13784.862151378358, 13953.720925581634, 14478.927605362327, 14513.346899389342, 13636.181829090287, 14055.718885622435, 14057.437702491785, 14246.145231285742, 14632.853671463148, 14151.787723184367, 14364.0, 14481.782773258621, 14904.850951490347, 14940.925295373454, 8851.424657397507, 8944.86582701247, 8971.102889710977, 9137.131972462605, 9212.447253164832, 11090.778184436427, 13607.115537490032, 13697.178169309873, 13789.310534473036, 13845.930770346084], "unit": "score", "name": "jquery.html", "value": 8713.487021528528}, {"lowerIsBetter": false, "replicates": [1505.9021163624357, 1637.1077762619373, 1728.7925448946128, 1745.9912700436496, 1777.8044415114334, 6073.210482637262, 6206.0, 6877.381035706785, 7146.4640151988615, 7164.247753985828, 6975.2675969023285, 7037.085178926745, 7108.715651373939, 7120.537165084266, 7195.424366050716, 2059.474833917349, 2076.9303808538616, 2088.248230636977, 2104.116271166114, 2104.9789502105014, 2086.62440760663, 2102.274715223253, 2120.035383900329, 2147.5812216617765, 2153.472399262183, 2002.2891873384958, 2042.305616090529, 2065.0810389376734, 2070.109852763311, 2073.2329038255866, 2063.8555301128927, 2087.5094352827073, 2102.9369118926456, 2103.8316934645345, 2105.642040853051, 2041.9387418377473, 2060.9278675246296, 2078.1064142418754, 2078.231054509841, 2086.812186903186, 7209.134903811576, 7280.38116760076, 7283.0, 7296.452766042568, 7334.083239595051, 4104.076582768883, 4161.209370219649, 4173.08192197716, 4179.414881916534, 4192.266353388175, 2688.07261494784, 2714.3214196450886, 2718.877650505732, 2742.835429874214, 2747.738964798341, 854.0264098927166, 872.6727477196051, 877.2762470961419, 877.9736607901773, 913.0777914306598, 9414.105659962293, 9454.57454414553, 9463.0, 9480.0, 9512.14390704833, 3882.3594106972596, 3891.4357418174204, 3903.980480097638, 3911.726179167431, 3954.051027753376, 8267.8346433071, 8287.8342433151, 8301.128381519975, 8305.958470207732, 8331.125231850569, 4198.454200953917, 4214.936775948363, 4217.1144059747185, 4224.809883555247, 4239.342901850218, 3260.5272235525717, 3320.2197483591335, 3756.868509602151, 3781.697464202912, 3788.4885540451687, 3453.8445769940413, 3461.0828130545424, 3474.652534746556, 3485.3726329260976, 3528.3648943190474, 1071.2073065931154, 1072.2065671403261, 1079.8056349856945, 1084.0189628386322, 1085.571199376242, 1188.863280722728, 1189.2448295332526, 1210.2617403383929, 1210.5339444314002, 1234.3026190202506, 6633.9336606633315, 6687.364700353459, 6745.797626071226, 6746.561473504206, 6747.595144291358, 421.02532636945375, 423.46008838730864, 430.3609140426464, 432.1055415290319, 432.3320469874052, 5371.194320851903, 5419.3225846769155, 5433.728313584305, 5444.074507333763, 5452.0, 8371.0, 8431.114732952952, 8477.788055298544, 8510.617022234015, 8565.95717021411, 8377.120402357665, 8385.203405676451, 8432.367572432093, 8445.155484451629, 8458.703945361884, 6669.966650166718, 6753.527253092335, 6809.965950170217, 6864.656767161622, 6865.41643960259, 6836.692348844414, 6851.349121833618, 6884.51808373409, 6899.448044156355, 6910.239873613999, 7748.92251077482, 7750.031246094239, 7805.063392392949, 7838.451308408585, 7969.760907172794, 7881.645325960231, 8004.879926800986, 8023.077346105272, 8086.797830054295, 8167.224113709188, 5377.623566350318, 5469.371022332482, 6103.938960610515, 6479.611223326616, 6501.089847421459, 6912.930870691229, 6923.2038315594355, 6970.268121847133, 6995.930040699732, 7002.299770022957, 6466.4503517201565, 6533.379328963741, 6570.507211959027, 6606.339366063356, 6670.266270710315, 6516.38094381052, 6522.21733391996, 6538.267359149924, 6549.0, 6600.966995164993, 6556.3443655634055, 6608.867822643617, 6622.106015688013, 6624.171978502687, 6635.933640663725, 5805.738741756547, 5824.972129320043, 6114.052321890114, 6127.632342059491, 6131.96934015327, 6175.475084617858, 6197.411245931809, 6203.2556093269095, 6215.782447614311, 6231.065340199007], "unit": "score", "name": "mootools.html", "value": 4042.1813318156405}, {"lowerIsBetter": false, "replicates": [295.4519366575003, 302.42539175566424, 308.81779749947526, 309.18683861444407, 309.77076963047324, 5575.191597218401, 5606.243157173785, 5655.57583181261, 5663.952168848766, 5681.233033540481, 5541.390447050831, 5554.86112847179, 5555.638883472571, 5568.276124103871, 5586.050371436855, 4221.936670949938, 4282.164977829325, 4284.421603083582, 4293.849715259967, 4306.956930430702, 4301.763403012833, 4309.5690430956965, 4324.421586121075, 4329.935050974238, 4332.480102387718, 4259.7231179973205, 4269.850555230568, 4273.230818452677, 4280.743155410685, 4320.719153255044, 4301.096769678371, 4307.418498502725, 4307.612314891643, 4307.741535507864, 4327.394164816928, 3815.809209539512, 3831.501904752364, 3831.5019047523924, 3831.616838316174, 3833.386658134713, 5260.0, 5306.204069389584, 5409.026375252453, 5429.212764149176, 5438.972805135989, 4957.231629097496, 4965.950340496585, 4969.776360063805, 4976.626752993504, 4980.0, 5922.289325280994, 5924.318703349127, 5934.970325148347, 5937.376575459601, 5937.910931336033, 4050.5341885682933, 4121.052158003676, 4129.132882094764, 4129.256733787917, 4143.56492568279, 6148.477379422709, 6227.782027629011, 6236.220472440945, 6237.875242495124, 6249.812505624793, 4161.479815023122, 4185.937210941838, 4197.622214000755, 4197.790110494524, 4204.26425375558, 4255.170241802819, 4266.466691663542, 4269.0, 4269.167512335127, 4271.423357846651, 4759.405074365704, 4883.194272944996, 4888.828890988798, 4894.608431325485, 4906.2640603908985, 5867.0, 5887.705614719247, 5901.675407852609, 5903.675297858573, 5922.585419020714, 1515.6438237014293, 1518.3091693279532, 1519.992400038015, 1520.0271826031349, 1520.3006616956102, 1005.9245556583286, 1057.7937302225992, 1058.899404556566, 1064.0636240108809, 1078.1751959750798, 5120.564751996122, 5124.461931497139, 5134.871628209325, 5135.460776618401, 5138.434772175057, 6043.576949613484, 6075.939240607538, 6096.908546371808, 6099.664518451527, 6111.449969502677, 5085.974570127126, 5098.413682426494, 5133.0, 5140.511651393186, 5147.819826306061, 3192.9680703193135, 3292.0288514888166, 3306.702396784301, 3307.1732066983254, 3320.4853247746637, 5671.574631902624, 5799.507041901401, 5846.0, 5848.444397782204, 5870.26621672291, 6306.2747784004505, 6387.0, 6395.360463953567, 6410.0705397718, 6410.839729006812, 5830.300363956352, 5831.271091113526, 5832.825015249549, 5839.883202336015, 5842.824715258549, 6143.477804386498, 6157.753689852356, 6172.753089876354, 6182.505399567934, 6184.196054513064, 5840.766369345179, 6004.39956004396, 6024.0, 6046.365131661112, 6054.424829641177, 5823.388544202967, 5829.009068458287, 5832.562557808096, 5837.6497410155525, 5841.1238314253205, 4109.9589004109575, 4146.170765846903, 4152.937705934474, 4157.293260145843, 4157.397177409319, 6056.273247210363, 6177.444030037139, 6179.351168127282, 6183.103449999635, 6188.690565471798, 5833.766649333979, 5844.883102337844, 5868.471837534642, 5875.676837773793, 5879.970600146972, 5891.469767720926, 5914.378990205966, 5921.082232254007, 5926.0, 5936.614120082181, 5643.7742490299925, 5771.2208851806145, 5773.729779448515, 5777.277840269966, 5799.217105690678, 5932.5550583705535, 5939.792107276225, 5941.58408911372, 5962.344142144274, 5964.075568286922, 5788.565857560784, 5794.391588883106, 5795.420457954171, 5798.94201057984, 5799.188113664006], "unit": "score", "name": "prototype.html", "value": 4424.908778684384}, {"lowerIsBetter": false, "replicates": [655.0599889159054, 764.6100488750737, 775.5463054113343, 776.2780614028954, 790.5019837502373, 11630.360330181837, 11664.416779161029, 11672.0, 11723.941380293087, 11741.70645733857, 11459.656210313704, 11507.252028618133, 11714.648560543197, 11851.7629647407, 11902.940485297582, 801.3749275565068, 811.8457493076326, 816.9264766171043, 819.1971867569755, 826.5990994367727, 831.2186544648011, 831.4346244553701, 831.8710599857031, 834.3241974001048, 836.8368168207202, 868.8653258744905, 876.51791514667, 876.8114855306108, 877.7191298784392, 878.7539488943106, 860.2515811244226, 861.9655213791441, 862.0991064337751, 864.2222000199839, 864.394923553515, 637.6970938804077, 642.489221069251, 644.7775517446473, 649.1139594453584, 649.6978904809239, 11047.779044419147, 11050.281731687415, 11078.833817492745, 11087.50106245221, 11090.500927458284, 10602.257841951066, 10886.836697449544, 10911.890881091167, 11087.33475991443, 11426.542938282459, 9146.517916837161, 9291.535423228812, 9359.251259899193, 9403.106704863096, 9425.481598512079, 3143.2141964508874, 3149.4960806271115, 3198.232424218194, 3202.1834432219866, 3212.3093534889877, 38579.0, 38919.41620875689, 38994.41508377377, 39011.609883901365, 39321.80339098315, 8981.685641002534, 9098.772530686785, 9154.86267705985, 9235.630574777068, 9235.907640923639, 15298.388064477518, 15454.38182472711, 15463.5360939172, 15481.612959676098, 15543.611409714847, 6174.0, 6188.907166392508, 6202.534809889276, 6224.190855188796, 6283.717232724538, 3475.6871881530783, 3531.9293614128087, 3540.314738743005, 3541.575010998645, 3557.4663800429626, 2493.488834788864, 2515.3585835611802, 2519.9748002520105, 2523.343930578026, 2525.2802951159197, 460.9585137337656, 474.8931490414629, 476.09304275355504, 478.4019975031211, 481.6796830108009, 525.2856115682669, 599.283855792324, 601.4136217188207, 601.6931365003792, 605.9212302400748, 7625.847483050419, 7704.961475192588, 7813.02337207849, 7952.761417157494, 7997.600119993977, 596.274524394183, 607.2834055814182, 636.2937139774846, 644.490852226745, 670.8389986403325, 3617.764845285048, 3654.3422184007136, 3799.0, 3809.561900381492, 3816.14136819219, 44232.778836105615, 44609.77695111569, 45006.77496612496, 45091.323630145576, 45329.77335113303, 42393.0, 42903.0, 43134.35298470463, 43418.565814341455, 43454.56545434505, 39294.0, 39643.0, 39855.601443985186, 40395.1920961585, 40567.18865622671, 7701.383889288732, 7761.262680045386, 7761.4178936578855, 7767.495112817649, 7900.40746943993, 29383.559246611745, 29563.556546651387, 29878.551821723126, 29973.400531989675, 30133.547996779627, 34061.97814065483, 34314.0, 35814.64185358113, 37130.8143459281, 37212.25575488529, 9032.187103160748, 9056.547172641473, 9091.090890910857, 9442.669506567237, 9468.337216394786, 10338.483075846329, 10352.637657681946, 10414.791704166026, 10453.484244784615, 10488.370697758159, 9587.568559414703, 9648.324617276723, 9799.069088436587, 9831.705048848546, 9856.457894815567, 9621.9037809621, 9699.0, 9714.222862170867, 9718.75703107428, 9803.068708472683, 9603.855942161013, 9705.223582113555, 9784.9021509784, 9804.215662746821, 9924.603015879284, 8358.66565337404, 8684.261837743863, 8886.688965886164, 8925.821483570162, 8926.464412135292, 7944.84110317802, 8078.919210807817, 8115.75652730419, 8129.018844948758, 8315.835782990507], "unit": "score", "name": "yui.html", "value": 5584.064459782137}], "name": "dromaeo_css", "value": 6391.8559720266385}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [118.0, 111.0, 116.0, 116.0, 115.0, 126.0, 114.0, 114.0, 113.0, 117.0], "unit": "score", "name": "ai-astar", "value": 116.0}, {"lowerIsBetter": true, "replicates": [156.0, 134.0, 153.0, 166.0, 133.0, 156.0, 156.0, 132.0, 181.0, 132.0], "unit": "score", "name": "audio-beat-detection", "value": 149.9}, {"lowerIsBetter": true, "replicates": [164.0, 165.0, 165.0, 163.0, 164.0, 166.0, 173.0, 163.0, 165.0, 163.0], "unit": "score", "name": "audio-dft", "value": 165.1}, {"lowerIsBetter": true, "replicates": [76.0, 72.0, 74.0, 74.0, 126.0, 73.0, 79.0, 73.0, 73.0, 87.0], "unit": "score", "name": "audio-fft", "value": 80.7}, {"lowerIsBetter": true, "replicates": [97.0, 96.0, 136.0, 96.0, 97.0, 95.0, 130.0, 96.0, 95.0, 126.0], "unit": "score", "name": "audio-oscillator", "value": 106.4}, {"lowerIsBetter": true, "replicates": [112.0, 110.0, 110.0, 111.0, 110.0, 110.0, 111.0, 110.0, 110.0, 111.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 110.5}, {"lowerIsBetter": true, "replicates": [150.0, 150.0, 149.0, 150.0, 149.0, 149.0, 148.0, 148.0, 148.0, 150.0], "unit": "score", "name": "imaging-darkroom", "value": 149.1}, {"lowerIsBetter": true, "replicates": [96.0, 97.0, 98.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 95.0], "unit": "score", "name": "imaging-desaturate", "value": 96.2}, {"lowerIsBetter": true, "replicates": [77.0, 76.0, 76.0, 74.0, 75.0, 74.0, 74.0, 80.0, 72.0, 71.0], "unit": "score", "name": "json-parse-financial", "value": 74.9}, {"lowerIsBetter": true, "replicates": [67.0, 70.0, 68.0, 70.0, 69.0, 69.0, 68.0, 69.0, 69.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 68.7}, {"lowerIsBetter": true, "replicates": [84.0, 96.0, 87.0, 97.0, 86.0, 88.0, 90.0, 89.0, 88.0, 88.0], "unit": "score", "name": "stanford-crypto-aes", "value": 89.3}, {"lowerIsBetter": true, "replicates": [126.0, 128.0, 120.0, 121.0, 122.0, 136.0, 131.0, 125.0, 127.0, 127.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 126.3}, {"lowerIsBetter": true, "replicates": [141.0, 153.0, 150.0, 151.0, 157.0, 142.0, 171.0, 152.0, 154.0, 154.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 152.5}, {"lowerIsBetter": true, "replicates": [74.0, 71.0, 71.0, 76.0, 72.0, 72.0, 73.0, 70.0, 72.0, 73.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 72.4}], "name": "kraken", "value": 1558.0000000000002}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.5001665001665], "unit": "score", "name": "Richards", "value": 212.02381199999994}, {"lowerIsBetter": false, "replicates": [186.1850679575498], "unit": "score", "name": "DeltaBlue", "value": 355.1197780000001}, {"lowerIsBetter": false, "replicates": [282.4858757062147], "unit": "score", "name": "Encrypt", "value": 942.2807399999999}, {"lowerIsBetter": false, "replicates": [5181.347150259067], "unit": "score", "name": "Decrypt", "value": 51.372933}, {"lowerIsBetter": false, "replicates": [1077.5862068965516], "unit": "score", "name": "RayTrace", "value": 686.7097920000001}, {"lowerIsBetter": false, "replicates": [716.0228898426324], "unit": "score", "name": "Earley", "value": 930.7844895104892}, {"lowerIsBetter": false, "replicates": [12216.867469879518], "unit": "score", "name": "Boyer", "value": 54.55269132149902}, {"lowerIsBetter": false, "replicates": [26578.947368421053], "unit": "score", "name": "RegExp", "value": 34.27468316831682}, {"lowerIsBetter": false, "replicates": [592.4170616113744], "unit": "score", "name": "Splay", "value": 137.55680799999996}, {"lowerIsBetter": false, "replicates": [4850.241545893719], "unit": "score", "name": "NavierStokes", "value": 305.9641434262948}], "name": "v8_7", "value": 21286.398442964175}]} 13:00:56 INFO - RETURN: dromaeo_css: 6391.86 13:00:56 INFO - RETURN: kraken: 1558.00 13:00:56 INFO - RETURN: v8_7: 21286.40 13:00:56 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,132,43]]", "result": "21286.40"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,132,43]]", "result": "1558.00"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,132,43]]", "result": "6391.86"}}} 13:00:56 INFO - Return code: 0 13:00:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 13:00:56 INFO - # TBPL SUCCESS # 13:00:56 INFO - Running post-action listener: _resource_record_post_action 13:00:56 INFO - Running post-run listener: _resource_record_post_run 13:00:57 INFO - Total resource usage - Wall time: 1235s; CPU: 13.0%; Read bytes: 4096; Write bytes: 504152064; Read time: 12; Write time: 760260 13:00:57 INFO - install - Wall time: 15s; CPU: 16.0%; Read bytes: 0; Write bytes: 231440384; Read time: 0; Write time: 507940 13:00:57 INFO - run-tests - Wall time: 1220s; CPU: 13.0%; Read bytes: 4096; Write bytes: 272711680; Read time: 12; Write time: 252320 13:00:57 INFO - Running post-run listener: _upload_blobber_files 13:00:57 INFO - Blob upload gear active. 13:00:57 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 13:00:57 INFO - Copying logs to upload dir... 13:00:57 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1282.290643 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2015-12-16 13:00:57.329371) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-16 13:00:57.336783) ========= 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=0ae9de487587ba4b94dd3100000001b5-1450294446.516140-759424473 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/4-p-qWeRTKW4vLZSumhtXQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009916 build_url: 'https://queue.taskcluster.net/v1/task/4-p-qWeRTKW4vLZSumhtXQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-16 13:00:57.387568) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 13:00:57.387924) ========= 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=0ae9de487587ba4b94dd3100000001b5-1450294446.516140-759424473 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004602 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 13:00:57.437150) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-16 13:00:57.437470) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-16 13:00:57.437743) ========= ========= Total master_lag: 0.24 =========