builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-033 starttime: 1463130327.98 results: success (0) buildid: 20160512233057 builduid: 408cb7e622ff455394f14288e1e842cd revision: 943ca7ad1ea35e01a6ba6bfa0a4f3896f1150e15 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 02:05:27.979357) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 02:05:27.979928) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 02:05:27.980281) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 02:05:27.998987) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 02:05:27.999449) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1463129991.309154-329193671 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-13 02:05:27-- 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% 37.6M=0s 2016-05-13 02:05:28 (37.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.148735 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 02:05:28.162315) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 02:05:28.162697) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', '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=edae0fb5fee9fb758e8af6d3000002a4-1463129991.309154-329193671 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020451 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 02:05:28.212919) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 02:05:28.213330) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 943ca7ad1ea35e01a6ba6bfa0a4f3896f1150e15 --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 943ca7ad1ea35e01a6ba6bfa0a4f3896f1150e15 --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=edae0fb5fee9fb758e8af6d3000002a4-1463129991.309154-329193671 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-13 02:05:28,232 truncating revision to first 12 chars 2016-05-13 02:05:28,232 Setting DEBUG logging. 2016-05-13 02:05:28,232 attempt 1/10 2016-05-13 02:05:28,232 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/943ca7ad1ea3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-13 02:05:28,680 unpacking tar archive at: fx-team-943ca7ad1ea3/testing/mozharness/ program finished with exit code 0 elapsedTime=0.738350 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 02:05:28.968996) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 02:05:28.969800) ========= script_repo_revision: 943ca7ad1ea35e01a6ba6bfa0a4f3896f1150e15 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 02:05:28.970825) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 02:05:28.971455) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 02:05:28.988383) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-05-13 02:05:28.988787) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=edae0fb5fee9fb758e8af6d3000002a4-1463129991.309154-329193671 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:05:29 INFO - MultiFileLogger online at 20160513 02:05:29 in /builds/slave/test 02:05:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 02:05:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:05:29 INFO - {'append_to_log': False, 02:05:29 INFO - 'base_work_dir': '/builds/slave/test', 02:05:29 INFO - 'blob_upload_branch': 'Fx-Team', 02:05:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:05:29 INFO - 'branch': 'Fx-Team', 02:05:29 INFO - 'buildbot_json_path': 'buildprops.json', 02:05:29 INFO - 'config_files': ('talos/linux_config.py',), 02:05:29 INFO - 'default_actions': ('clobber', 02:05:29 INFO - 'read-buildbot-config', 02:05:29 INFO - 'download-and-extract', 02:05:29 INFO - 'populate-webroot', 02:05:29 INFO - 'create-virtualenv', 02:05:29 INFO - 'install', 02:05:29 INFO - 'run-tests'), 02:05:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:05:29 INFO - 'download_minidump_stackwalk': True, 02:05:29 INFO - 'download_symbols': 'ondemand', 02:05:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:05:29 INFO - 'tooltool.py': '/tools/tooltool.py', 02:05:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:05:29 INFO - '/tools/misc-python/virtualenv.py')}, 02:05:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:05:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:05:29 INFO - 'installer_path': 'installer.exe', 02:05:29 INFO - 'log_level': 'info', 02:05:29 INFO - 'log_name': 'talos', 02:05:29 INFO - 'log_to_console': True, 02:05:29 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:05:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:05:29 INFO - 'opt_config_files': (), 02:05:29 INFO - 'pip_index': False, 02:05:29 INFO - 'sps_profile': False, 02:05:29 INFO - 'sps_profile_interval': 0, 02:05:29 INFO - 'suite': 'dromaeojs', 02:05:29 INFO - 'system_bits': '32', 02:05:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:05:29 INFO - 'title': 'talos-linux64-ix-033', 02:05:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:05:29 INFO - 'use_talos_json': True, 02:05:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:05:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:05:29 INFO - 'work_dir': 'build'} 02:05:29 INFO - ##### 02:05:29 INFO - ##### Running clobber step. 02:05:29 INFO - ##### 02:05:29 INFO - Running pre-action listener: _resource_record_pre_action 02:05:29 INFO - Running main action method: clobber 02:05:29 INFO - rmtree: /builds/slave/test/build 02:05:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:05:29 INFO - Running post-action listener: _resource_record_post_action 02:05:29 INFO - ##### 02:05:29 INFO - ##### Finished clobber step (success) 02:05:29 INFO - ##### 02:05:29 INFO - ##### 02:05:29 INFO - ##### Running read-buildbot-config step. 02:05:29 INFO - ##### 02:05:29 INFO - Running pre-action listener: _resource_record_pre_action 02:05:29 INFO - Running main action method: read_buildbot_config 02:05:29 INFO - Using buildbot properties: 02:05:29 INFO - { 02:05:29 INFO - "project": "", 02:05:29 INFO - "product": "firefox", 02:05:29 INFO - "script_repo_revision": "production", 02:05:29 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 02:05:29 INFO - "repository": "", 02:05:29 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 02:05:29 INFO - "buildid": "20160512233057", 02:05:29 INFO - "pgo_build": "True", 02:05:29 INFO - "basedir": "/builds/slave/test", 02:05:29 INFO - "buildnumber": 671, 02:05:29 INFO - "slavename": "talos-linux64-ix-033", 02:05:29 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 02:05:29 INFO - "platform": "ubuntu64_hw", 02:05:29 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 02:05:29 INFO - "branch": "fx-team", 02:05:29 INFO - "repo_path": "integration/fx-team", 02:05:29 INFO - "revision": "943ca7ad1ea35e01a6ba6bfa0a4f3896f1150e15", 02:05:29 INFO - "stage_platform": "linux64-pgo", 02:05:29 INFO - "builduid": "408cb7e622ff455394f14288e1e842cd", 02:05:29 INFO - "slavebuilddir": "test" 02:05:29 INFO - } 02:05:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/AnmEEez0SgWyvykSnIQOxA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 02:05:29 INFO - Running post-action listener: _resource_record_post_action 02:05:29 INFO - ##### 02:05:29 INFO - ##### Finished read-buildbot-config step (success) 02:05:29 INFO - ##### 02:05:29 INFO - ##### 02:05:29 INFO - ##### Running download-and-extract step. 02:05:29 INFO - ##### 02:05:29 INFO - Running pre-action listener: _resource_record_pre_action 02:05:29 INFO - Running main action method: download_and_extract 02:05:29 INFO - mkdir: /builds/slave/test/build/tests 02:05:29 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')]} 02:05:29 INFO - https://queue.taskcluster.net/v1/task/AnmEEez0SgWyvykSnIQOxA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 02:05:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AnmEEez0SgWyvykSnIQOxA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 02:05:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AnmEEez0SgWyvykSnIQOxA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 02:05:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AnmEEez0SgWyvykSnIQOxA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 02:05:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AnmEEez0SgWyvykSnIQOxA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 02:05:30 INFO - Downloaded 1361 bytes. 02:05:30 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 02:05:30 INFO - Using the following test package requirements: 02:05:30 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 02:05:30 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 02:05:30 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 02:05:30 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 02:05:30 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 02:05:30 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 02:05:30 INFO - u'jsshell-linux-x86_64.zip'], 02:05:30 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 02:05:30 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 02:05:30 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 02:05:30 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 02:05:30 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 02:05:30 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 02:05:30 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 02:05:30 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 02:05:30 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 02:05:30 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 02:05:30 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 02:05:30 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 02:05:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:05:30 INFO - https://queue.taskcluster.net/v1/task/AnmEEez0SgWyvykSnIQOxA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 02:05:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AnmEEez0SgWyvykSnIQOxA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 02:05:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AnmEEez0SgWyvykSnIQOxA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 02:05:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AnmEEez0SgWyvykSnIQOxA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 02:05:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AnmEEez0SgWyvykSnIQOxA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 02:05:31 INFO - Downloaded 23903709 bytes. 02:05:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:05:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 02:05:32 INFO - Return code: 0 02:05:32 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 02:05:32 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')]} 02:05:32 INFO - https://queue.taskcluster.net/v1/task/AnmEEez0SgWyvykSnIQOxA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 02:05:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AnmEEez0SgWyvykSnIQOxA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 02:05:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AnmEEez0SgWyvykSnIQOxA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 02:05:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AnmEEez0SgWyvykSnIQOxA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 02:05:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AnmEEez0SgWyvykSnIQOxA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 02:05:41 INFO - Downloaded 23903709 bytes. 02:05:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:05:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 02:05:43 INFO - Return code: 0 02:05:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:05:43 INFO - https://queue.taskcluster.net/v1/task/AnmEEez0SgWyvykSnIQOxA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 02:05:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AnmEEez0SgWyvykSnIQOxA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 02:05:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AnmEEez0SgWyvykSnIQOxA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 02:05:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AnmEEez0SgWyvykSnIQOxA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 02:05:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AnmEEez0SgWyvykSnIQOxA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 02:05:43 INFO - Downloaded 10840239 bytes. 02:05:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:05:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 02:05:44 INFO - Return code: 0 02:05:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:05:44 INFO - https://queue.taskcluster.net/v1/task/AnmEEez0SgWyvykSnIQOxA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 02:05:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AnmEEez0SgWyvykSnIQOxA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 02:05:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AnmEEez0SgWyvykSnIQOxA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 02:05:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AnmEEez0SgWyvykSnIQOxA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 02:05:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AnmEEez0SgWyvykSnIQOxA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 02:05:45 INFO - Downloaded 58371363 bytes. 02:05:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AnmEEez0SgWyvykSnIQOxA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 02:05:45 INFO - mkdir: /builds/slave/test/properties 02:05:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:05:45 INFO - Writing to file /builds/slave/test/properties/build_url 02:05:45 INFO - Contents: 02:05:45 INFO - build_url:https://queue.taskcluster.net/v1/task/AnmEEez0SgWyvykSnIQOxA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 02:05:46 INFO - Running post-action listener: _resource_record_post_action 02:05:46 INFO - Running post-action listener: set_extra_try_arguments 02:05:46 INFO - ##### 02:05:46 INFO - ##### Finished download-and-extract step (success) 02:05:46 INFO - ##### 02:05:46 INFO - ##### 02:05:46 INFO - ##### Running populate-webroot step. 02:05:46 INFO - ##### 02:05:46 INFO - Running pre-action listener: _resource_record_pre_action 02:05:46 INFO - Running main action method: populate_webroot 02:05:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:05:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:05:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:05:46 INFO - '--tppagecycles', 02:05:46 INFO - '7'], 02:05:46 INFO - 'tests': ['tsvgm']}}, 02:05:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:05:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:05:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:05:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:05:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:05:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:05:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:05:46 INFO - 'talos_options': ['--disable-e10s'], 02:05:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:05:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:05:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:05:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:05:46 INFO - 'talos_options': ['--disable-e10s'], 02:05:46 INFO - 'tests': ['damp', 'tps']}, 02:05:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:05:46 INFO - 'tests': ['damp', 'tps']}, 02:05:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:05:46 INFO - 'tests': ['dromaeo_dom']}, 02:05:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:05:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:05:46 INFO - 'tests': ['a11yr', 02:05:46 INFO - 'ts_paint', 02:05:46 INFO - 'tpaint', 02:05:46 INFO - 'sessionrestore', 02:05:46 INFO - 'sessionrestore_no_auto_restore', 02:05:46 INFO - 'tabpaint']}, 02:05:46 INFO - 'other-e10s': {'tests': ['a11yr', 02:05:46 INFO - 'ts_paint', 02:05:46 INFO - 'tpaint', 02:05:46 INFO - 'sessionrestore', 02:05:46 INFO - 'sessionrestore_no_auto_restore', 02:05:46 INFO - 'tabpaint']}, 02:05:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:05:46 INFO - 'ts_paint', 02:05:46 INFO - 'tpaint', 02:05:46 INFO - 'sessionrestore', 02:05:46 INFO - 'sessionrestore_no_auto_restore', 02:05:46 INFO - 'tabpaint']}, 02:05:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:05:46 INFO - 'ts_paint', 02:05:46 INFO - 'tpaint', 02:05:46 INFO - 'sessionrestore', 02:05:46 INFO - 'sessionrestore_no_auto_restore', 02:05:46 INFO - 'tabpaint']}, 02:05:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:05:46 INFO - 'tests': ['a11yr', 02:05:46 INFO - 'ts_paint', 02:05:46 INFO - 'tpaint', 02:05:46 INFO - 'sessionrestore', 02:05:46 INFO - 'sessionrestore_no_auto_restore', 02:05:46 INFO - 'tabpaint']}, 02:05:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:05:46 INFO - 'tests': ['a11yr', 02:05:46 INFO - 'ts_paint', 02:05:46 INFO - 'tpaint', 02:05:46 INFO - 'sessionrestore', 02:05:46 INFO - 'sessionrestore_no_auto_restore', 02:05:46 INFO - 'tabpaint']}, 02:05:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:05:46 INFO - 'tests': ['tsvgx', 02:05:46 INFO - 'tsvgr_opacity', 02:05:46 INFO - 'tart', 02:05:46 INFO - 'tscrollx', 02:05:46 INFO - 'cart']}, 02:05:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:05:46 INFO - 'tsvgr_opacity', 02:05:46 INFO - 'tart', 02:05:46 INFO - 'tscrollx', 02:05:46 INFO - 'cart']}, 02:05:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:05:46 INFO - 'talos_options': ['--disable-e10s'], 02:05:46 INFO - 'tests': ['tp5o']}, 02:05:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:05:46 INFO - 'tests': ['tp5o']}, 02:05:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:05:46 INFO - 'talos_options': ['--disable-e10s', 02:05:46 INFO - '--xperf_path', 02:05:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:05:46 INFO - 'tests': ['tp5n']}, 02:05:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:05:46 INFO - 'talos_options': ['--xperf_path', 02:05:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:05:46 INFO - 'tests': ['tp5n']}}, 02:05:46 INFO - 'talos.zip': {'path': '', 02:05:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:05:46 INFO - Running post-action listener: _resource_record_post_action 02:05:46 INFO - ##### 02:05:46 INFO - ##### Finished populate-webroot step (success) 02:05:46 INFO - ##### 02:05:46 INFO - ##### 02:05:46 INFO - ##### Running create-virtualenv step. 02:05:46 INFO - ##### 02:05:46 INFO - Running pre-action listener: _resource_record_pre_action 02:05:46 INFO - Running main action method: create_virtualenv 02:05:46 INFO - Creating virtualenv /builds/slave/test/build/venv 02:05:46 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 02:05:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:05:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:05:46 INFO - Using real prefix '/usr' 02:05:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:05:46 INFO - Installing distribute.............................................................................................................................................................................................done. 02:05:48 INFO - Installing pip.................done. 02:05:48 INFO - Return code: 0 02:05:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:05:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:05:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:05:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:05:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:05:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:05:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:05:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x7f4c760b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25da8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27349c0>, '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 0x27358c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2735d50>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1463129991.309154-329193671', '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 02:05:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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 02:05:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 02:05:48 INFO - Using env: {'DISPLAY': ':0', 02:05:48 INFO - 'HOME': '/home/cltbld', 02:05:48 INFO - 'LANG': 'en_US.UTF-8', 02:05:48 INFO - 'LANGUAGE': 'en_US:en', 02:05:48 INFO - 'LOGNAME': 'cltbld', 02:05:48 INFO - 'MAIL': '/var/mail/cltbld', 02:05:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:05:48 INFO - 'MOZ_NO_REMOTE': '1', 02:05:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:05:48 INFO - 'NO_EM_RESTART': '1', 02:05:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:05:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:05:48 INFO - 'PWD': '/builds/slave/test', 02:05:48 INFO - 'SHELL': '/bin/bash', 02:05:48 INFO - 'SHLVL': '1', 02:05:48 INFO - 'TERM': 'linux', 02:05:48 INFO - 'TMOUT': '86400', 02:05:48 INFO - 'USER': 'cltbld', 02:05:48 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1463129991.309154-329193671', 02:05:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:05:48 INFO - '_': '/tools/buildbot/bin/python'} 02:05:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:05:49 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 02:05:49 INFO - Cleaning up... 02:05:49 INFO - Return code: 0 02:05:49 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 02:05:49 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')]} 02:05:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:05:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:05:49 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')]} 02:05:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:05:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:05:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x7f4c760b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25da8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27349c0>, '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 0x27358c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2735d50>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1463129991.309154-329193671', '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 02:05:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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 02:05:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 02:05:49 INFO - Using env: {'DISPLAY': ':0', 02:05:49 INFO - 'HOME': '/home/cltbld', 02:05:49 INFO - 'LANG': 'en_US.UTF-8', 02:05:49 INFO - 'LANGUAGE': 'en_US:en', 02:05:49 INFO - 'LOGNAME': 'cltbld', 02:05:49 INFO - 'MAIL': '/var/mail/cltbld', 02:05:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:05:49 INFO - 'MOZ_NO_REMOTE': '1', 02:05:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:05:49 INFO - 'NO_EM_RESTART': '1', 02:05:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:05:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:05:49 INFO - 'PWD': '/builds/slave/test', 02:05:49 INFO - 'SHELL': '/bin/bash', 02:05:49 INFO - 'SHLVL': '1', 02:05:49 INFO - 'TERM': 'linux', 02:05:49 INFO - 'TMOUT': '86400', 02:05:49 INFO - 'USER': 'cltbld', 02:05:49 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1463129991.309154-329193671', 02:05:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:05:49 INFO - '_': '/tools/buildbot/bin/python'} 02:05:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:05:49 INFO - Downloading/unpacking psutil>=0.7.1 02:05:49 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 02:05:49 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 02:05:49 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 02:05:49 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 02:05:49 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 02:05:49 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 02:05:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:05:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:05:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:05:51 INFO - Installing collected packages: psutil 02:05:51 INFO - Running setup.py install for psutil 02:05:51 INFO - building 'psutil._psutil_linux' extension 02:05:51 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 02:05:51 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 02:05:51 INFO - building 'psutil._psutil_posix' extension 02:05:51 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 02:05:51 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 02:05:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:05:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:05:51 INFO - Successfully installed psutil 02:05:51 INFO - Cleaning up... 02:05:51 INFO - Return code: 0 02:05:51 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 02:05:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:05:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:05:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:05:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:05:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:05:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:05:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x7f4c760b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25da8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27349c0>, '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 0x27358c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2735d50>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1463129991.309154-329193671', '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 02:05:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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 02:05:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 02:05:51 INFO - Using env: {'DISPLAY': ':0', 02:05:51 INFO - 'HOME': '/home/cltbld', 02:05:51 INFO - 'LANG': 'en_US.UTF-8', 02:05:51 INFO - 'LANGUAGE': 'en_US:en', 02:05:51 INFO - 'LOGNAME': 'cltbld', 02:05:51 INFO - 'MAIL': '/var/mail/cltbld', 02:05:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:05:51 INFO - 'MOZ_NO_REMOTE': '1', 02:05:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:05:51 INFO - 'NO_EM_RESTART': '1', 02:05:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:05:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:05:51 INFO - 'PWD': '/builds/slave/test', 02:05:51 INFO - 'SHELL': '/bin/bash', 02:05:51 INFO - 'SHLVL': '1', 02:05:51 INFO - 'TERM': 'linux', 02:05:51 INFO - 'TMOUT': '86400', 02:05:51 INFO - 'USER': 'cltbld', 02:05:51 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1463129991.309154-329193671', 02:05:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:05:51 INFO - '_': '/tools/buildbot/bin/python'} 02:05:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:05:52 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:05:52 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 02:05:52 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 02:05:52 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 02:05:52 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 02:05:52 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 02:05:52 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 02:05:53 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:05:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:05:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 02:05:53 INFO - Installing collected packages: mozsystemmonitor 02:05:53 INFO - Running setup.py install for mozsystemmonitor 02:05:54 INFO - Successfully installed mozsystemmonitor 02:05:54 INFO - Cleaning up... 02:05:54 INFO - Return code: 0 02:05:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:05:54 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')]} 02:05:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:05:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:05:54 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')]} 02:05:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:05:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:05:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x7f4c760b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25da8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27349c0>, '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 0x27358c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2735d50>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1463129991.309154-329193671', '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 02:05:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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 02:05:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 02:05:54 INFO - Using env: {'DISPLAY': ':0', 02:05:54 INFO - 'HOME': '/home/cltbld', 02:05:54 INFO - 'LANG': 'en_US.UTF-8', 02:05:54 INFO - 'LANGUAGE': 'en_US:en', 02:05:54 INFO - 'LOGNAME': 'cltbld', 02:05:54 INFO - 'MAIL': '/var/mail/cltbld', 02:05:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:05:54 INFO - 'MOZ_NO_REMOTE': '1', 02:05:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:05:54 INFO - 'NO_EM_RESTART': '1', 02:05:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:05:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:05:54 INFO - 'PWD': '/builds/slave/test', 02:05:54 INFO - 'SHELL': '/bin/bash', 02:05:54 INFO - 'SHLVL': '1', 02:05:54 INFO - 'TERM': 'linux', 02:05:54 INFO - 'TMOUT': '86400', 02:05:54 INFO - 'USER': 'cltbld', 02:05:54 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1463129991.309154-329193671', 02:05:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:05:54 INFO - '_': '/tools/buildbot/bin/python'} 02:05:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:05:54 INFO - Downloading/unpacking blobuploader==1.2.4 02:05:54 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 02:05:54 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 02:05:54 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 02:05:54 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 02:05:54 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 02:05:54 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 02:05:56 INFO - Downloading blobuploader-1.2.4.tar.gz 02:05:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:05:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:05:56 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 02:05:56 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 02:05:56 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 02:05:56 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 02:05:56 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 02:05:56 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 02:05:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:05:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:05:56 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 02:05:56 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 02:05:56 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 02:05:56 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 02:05:56 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 02:05:56 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 02:05:56 INFO - Downloading docopt-0.6.1.tar.gz 02:05:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:05:56 INFO - Installing collected packages: blobuploader, requests, docopt 02:05:56 INFO - Running setup.py install for blobuploader 02:05:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:05:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:05:57 INFO - Running setup.py install for requests 02:05:57 INFO - Running setup.py install for docopt 02:05:57 INFO - Successfully installed blobuploader requests docopt 02:05:57 INFO - Cleaning up... 02:05:57 INFO - Return code: 0 02:05:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:05: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')]} 02:05:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:05:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:05: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')]} 02:05:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:05:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:05:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--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 0x7f4c760b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25da8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27349c0>, '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 0x27358c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2735d50>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1463129991.309154-329193671', '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 02:05:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--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 02:05:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --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 02:05:57 INFO - Using env: {'DISPLAY': ':0', 02:05:57 INFO - 'HOME': '/home/cltbld', 02:05:57 INFO - 'LANG': 'en_US.UTF-8', 02:05:57 INFO - 'LANGUAGE': 'en_US:en', 02:05:57 INFO - 'LOGNAME': 'cltbld', 02:05:57 INFO - 'MAIL': '/var/mail/cltbld', 02:05:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:05:57 INFO - 'MOZ_NO_REMOTE': '1', 02:05:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:05:57 INFO - 'NO_EM_RESTART': '1', 02:05:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:05:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:05:57 INFO - 'PWD': '/builds/slave/test', 02:05:57 INFO - 'SHELL': '/bin/bash', 02:05:57 INFO - 'SHLVL': '1', 02:05:57 INFO - 'TERM': 'linux', 02:05:57 INFO - 'TMOUT': '86400', 02:05:57 INFO - 'USER': 'cltbld', 02:05:57 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1463129991.309154-329193671', 02:05:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:05:57 INFO - '_': '/tools/buildbot/bin/python'} 02:05:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:05:57 INFO - Running setup.py (path:/tmp/pip-WdzVzV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:05:57 INFO - Running setup.py (path:/tmp/pip-sUcik_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:05:57 INFO - Running setup.py (path:/tmp/pip-ZVXu4Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:05:57 INFO - Running setup.py (path:/tmp/pip-MnsYPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:05:58 INFO - Running setup.py (path:/tmp/pip-3GxzrR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:05:58 INFO - Running setup.py (path:/tmp/pip-yId3Ti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:05:58 INFO - Running setup.py (path:/tmp/pip-v91p6f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:05:58 INFO - Running setup.py (path:/tmp/pip-s16b4x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:05:58 INFO - Running setup.py (path:/tmp/pip-u6nKUc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:05:58 INFO - Running setup.py (path:/tmp/pip-6hLc5g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:05:58 INFO - Running setup.py (path:/tmp/pip-3fn8Qg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:05:58 INFO - Running setup.py (path:/tmp/pip-Whnk1V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:05:58 INFO - Running setup.py (path:/tmp/pip-6xOiBP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:05:58 INFO - Running setup.py (path:/tmp/pip-hHcDSR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:05:58 INFO - Running setup.py (path:/tmp/pip-ZWT8uX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:05:58 INFO - Running setup.py (path:/tmp/pip-9JVJZj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:05:58 INFO - Running setup.py (path:/tmp/pip-X5Dgwn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:05:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:05:58 INFO - Running setup.py install for manifestparser 02:05:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:05:59 INFO - Running setup.py install for mozcrash 02:05:59 INFO - Running setup.py install for mozdebug 02:05:59 INFO - Running setup.py install for mozdevice 02:05:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:05:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:05:59 INFO - Running setup.py install for mozfile 02:05:59 INFO - Running setup.py install for mozhttpd 02:05:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:05:59 INFO - Running setup.py install for mozinfo 02:05:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:05:59 INFO - Running setup.py install for mozInstall 02:05:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:05:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:05:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:05:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:05:59 INFO - Running setup.py install for mozleak 02:05:59 INFO - Running setup.py install for mozlog 02:05:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:05:59 INFO - Running setup.py install for moznetwork 02:06:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:06:00 INFO - Running setup.py install for mozprocess 02:06:00 INFO - Running setup.py install for mozprofile 02:06:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:06:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:06:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:06:00 INFO - Running setup.py install for mozrunner 02:06:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:06:00 INFO - Running setup.py install for mozscreenshot 02:06:00 INFO - Running setup.py install for moztest 02:06:00 INFO - Running setup.py install for mozversion 02:06:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:06:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:06:00 INFO - Cleaning up... 02:06:00 INFO - Return code: 0 02:06:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:06:00 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')]} 02:06:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:06:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:00 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')]} 02:06:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:06:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x7f4c760b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25da8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27349c0>, '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 0x27358c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2735d50>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1463129991.309154-329193671', '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 02:06:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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 02:06:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 02:06:00 INFO - Using env: {'DISPLAY': ':0', 02:06:00 INFO - 'HOME': '/home/cltbld', 02:06:00 INFO - 'LANG': 'en_US.UTF-8', 02:06:00 INFO - 'LANGUAGE': 'en_US:en', 02:06:00 INFO - 'LOGNAME': 'cltbld', 02:06:00 INFO - 'MAIL': '/var/mail/cltbld', 02:06:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:06:00 INFO - 'MOZ_NO_REMOTE': '1', 02:06:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:06:00 INFO - 'NO_EM_RESTART': '1', 02:06:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:06:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:06:00 INFO - 'PWD': '/builds/slave/test', 02:06:00 INFO - 'SHELL': '/bin/bash', 02:06:00 INFO - 'SHLVL': '1', 02:06:00 INFO - 'TERM': 'linux', 02:06:00 INFO - 'TMOUT': '86400', 02:06:00 INFO - 'USER': 'cltbld', 02:06:00 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1463129991.309154-329193671', 02:06:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:06:00 INFO - '_': '/tools/buildbot/bin/python'} 02:06:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:06:00 INFO - Running setup.py (path:/tmp/pip-mnwxZp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:06:01 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:06:01 INFO - Running setup.py (path:/tmp/pip-FK4mRB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:06:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 02:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:06:01 INFO - Running setup.py (path:/tmp/pip-3V6hdn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:06:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:06:01 INFO - Running setup.py (path:/tmp/pip-O7rJeF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:06:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 02:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:06:01 INFO - Running setup.py (path:/tmp/pip-14oiZH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:06:01 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)) 02:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:06:01 INFO - Running setup.py (path:/tmp/pip-s1iBNy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:06:01 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)) 02:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:06:01 INFO - Running setup.py (path:/tmp/pip-ozV5UP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:06:01 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)) 02:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:06:01 INFO - Running setup.py (path:/tmp/pip-1bjHMF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:06:01 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)) 02:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:06:01 INFO - Running setup.py (path:/tmp/pip-yptbif-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:06:01 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)) 02:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:06:01 INFO - Running setup.py (path:/tmp/pip-r9LqCH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:06:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 02:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:06:01 INFO - Running setup.py (path:/tmp/pip-D5AYjS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:06:01 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)) 02:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:06:01 INFO - Running setup.py (path:/tmp/pip-hz0KS5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:06:01 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)) 02:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:06:01 INFO - Running setup.py (path:/tmp/pip-q58wmM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:06:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 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)) 02:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:06:01 INFO - Running setup.py (path:/tmp/pip-6JsR55-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:06:01 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)) 02:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:06:01 INFO - Running setup.py (path:/tmp/pip-3ktBgi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:06:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:06:02 INFO - Running setup.py (path:/tmp/pip-VJefzS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:06:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:06:02 INFO - Running setup.py (path:/tmp/pip-oZRRLp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:06:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:06:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:06:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:06:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:06:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:06:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:06:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:06:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:06:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:06:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:06:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:06:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:06:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:06:04 INFO - Downloading blessings-1.6.tar.gz 02:06:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:06:04 INFO - Installing collected packages: blessings 02:06:04 INFO - Running setup.py install for blessings 02:06:04 INFO - Successfully installed blessings 02:06:04 INFO - Cleaning up... 02:06:04 INFO - Return code: 0 02:06:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:06:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:06:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:06:04 INFO - Reading from file tmpfile_stdout 02:06:04 INFO - Current package versions: 02:06:04 INFO - argparse == 1.2.1 02:06:04 INFO - blessings == 1.6 02:06:04 INFO - blobuploader == 1.2.4 02:06:04 INFO - docopt == 0.6.1 02:06:04 INFO - manifestparser == 1.1 02:06:04 INFO - mozInstall == 1.12 02:06:04 INFO - mozcrash == 0.17 02:06:04 INFO - mozdebug == 0.1 02:06:04 INFO - mozdevice == 0.48 02:06:04 INFO - mozfile == 1.2 02:06:04 INFO - mozhttpd == 0.7 02:06:04 INFO - mozinfo == 0.9 02:06:04 INFO - mozleak == 0.1 02:06:04 INFO - mozlog == 3.2 02:06:04 INFO - moznetwork == 0.27 02:06:04 INFO - mozprocess == 0.22 02:06:04 INFO - mozprofile == 0.28 02:06:04 INFO - mozrunner == 6.11 02:06:04 INFO - mozscreenshot == 0.1 02:06:04 INFO - mozsystemmonitor == 0.0 02:06:04 INFO - moztest == 0.7 02:06:04 INFO - mozversion == 1.4 02:06:04 INFO - psutil == 3.1.1 02:06:04 INFO - requests == 1.2.3 02:06:04 INFO - wsgiref == 0.1.2 02:06:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:06:04 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')]} 02:06:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:06:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:04 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')]} 02:06:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:06:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x7f4c760b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25da8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27349c0>, '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 0x27358c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2735d50>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1463129991.309154-329193671', '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 02:06:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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 02:06:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 02:06:04 INFO - Using env: {'DISPLAY': ':0', 02:06:04 INFO - 'HOME': '/home/cltbld', 02:06:04 INFO - 'LANG': 'en_US.UTF-8', 02:06:04 INFO - 'LANGUAGE': 'en_US:en', 02:06:04 INFO - 'LOGNAME': 'cltbld', 02:06:04 INFO - 'MAIL': '/var/mail/cltbld', 02:06:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:06:04 INFO - 'MOZ_NO_REMOTE': '1', 02:06:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:06:04 INFO - 'NO_EM_RESTART': '1', 02:06:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:06:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:06:04 INFO - 'PWD': '/builds/slave/test', 02:06:04 INFO - 'SHELL': '/bin/bash', 02:06:04 INFO - 'SHLVL': '1', 02:06:04 INFO - 'TERM': 'linux', 02:06:04 INFO - 'TMOUT': '86400', 02:06:04 INFO - 'USER': 'cltbld', 02:06:04 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1463129991.309154-329193671', 02:06:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:06:04 INFO - '_': '/tools/buildbot/bin/python'} 02:06:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:06:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:06:04 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 2)) 02:06:04 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 3)) 02:06:04 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 4)) 02:06:04 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 5)) 02:06:04 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 6)) 02:06:04 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 7)) 02:06:04 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 8)) 02:06:04 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 9)) 02:06:04 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.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:06:04 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 4)) 02:06:04 INFO - Cleaning up... 02:06:04 INFO - Return code: 0 02:06:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:06:04 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')]} 02:06:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:06:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:04 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')]} 02:06:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:06:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x7f4c760b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25da8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27349c0>, '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 0x27358c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2735d50>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1463129991.309154-329193671', '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 02:06:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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 02:06:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 02:06:04 INFO - Using env: {'DISPLAY': ':0', 02:06:04 INFO - 'HOME': '/home/cltbld', 02:06:04 INFO - 'LANG': 'en_US.UTF-8', 02:06:04 INFO - 'LANGUAGE': 'en_US:en', 02:06:04 INFO - 'LOGNAME': 'cltbld', 02:06:04 INFO - 'MAIL': '/var/mail/cltbld', 02:06:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:06:04 INFO - 'MOZ_NO_REMOTE': '1', 02:06:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:06:04 INFO - 'NO_EM_RESTART': '1', 02:06:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:06:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:06:04 INFO - 'PWD': '/builds/slave/test', 02:06:04 INFO - 'SHELL': '/bin/bash', 02:06:04 INFO - 'SHLVL': '1', 02:06:04 INFO - 'TERM': 'linux', 02:06:04 INFO - 'TMOUT': '86400', 02:06:04 INFO - 'USER': 'cltbld', 02:06:04 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1463129991.309154-329193671', 02:06:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:06:04 INFO - '_': '/tools/buildbot/bin/python'} 02:06:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:06:04 INFO - Downloading/unpacking jsonschema 02:06: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 02:06: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 02:06: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 02:06: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 02:06: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 02:06: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 02:06:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:06:06 INFO - Downloading/unpacking functools32 (from jsonschema) 02:06: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 02:06: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 02:06: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 02:06: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 02:06: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 02:06: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 02:06:06 INFO - Downloading functools32-3.2.3-2.tar.gz 02:06:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:06:07 INFO - warning: no files found matching '*.txt' 02:06:07 INFO - no previously-included directories found matching 'build' 02:06:07 INFO - no previously-included directories found matching 'dist' 02:06:07 INFO - no previously-included directories found matching '.git*' 02:06:07 INFO - Installing collected packages: jsonschema, functools32 02:06:07 INFO - Running setup.py install for functools32 02:06:07 INFO - warning: no files found matching '*.txt' 02:06:07 INFO - no previously-included directories found matching 'build' 02:06:07 INFO - no previously-included directories found matching 'dist' 02:06:07 INFO - no previously-included directories found matching '.git*' 02:06:07 INFO - Successfully installed jsonschema functools32 02:06:07 INFO - Cleaning up... 02:06:07 INFO - Return code: 0 02:06:07 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:06:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:06:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:06:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:06:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:06:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x7f4c760b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25da8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27349c0>, '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 0x27358c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2735d50>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1463129991.309154-329193671', '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 02:06:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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 02:06:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 02:06:07 INFO - Using env: {'DISPLAY': ':0', 02:06:07 INFO - 'HOME': '/home/cltbld', 02:06:07 INFO - 'LANG': 'en_US.UTF-8', 02:06:07 INFO - 'LANGUAGE': 'en_US:en', 02:06:07 INFO - 'LOGNAME': 'cltbld', 02:06:07 INFO - 'MAIL': '/var/mail/cltbld', 02:06:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:06:07 INFO - 'MOZ_NO_REMOTE': '1', 02:06:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:06:07 INFO - 'NO_EM_RESTART': '1', 02:06:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:06:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:06:07 INFO - 'PWD': '/builds/slave/test', 02:06:07 INFO - 'SHELL': '/bin/bash', 02:06:07 INFO - 'SHLVL': '1', 02:06:07 INFO - 'TERM': 'linux', 02:06:07 INFO - 'TMOUT': '86400', 02:06:07 INFO - 'USER': 'cltbld', 02:06:07 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1463129991.309154-329193671', 02:06:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:06:07 INFO - '_': '/tools/buildbot/bin/python'} 02:06:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:06:07 INFO - Downloading/unpacking flake8 02:06: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 02:06: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 02:06: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 02:06: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 02:06: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 02:06: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 02:06:09 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:06:09 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:06:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:06:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:06:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:06:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:06:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:06:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:06:09 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:06:09 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:06:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:06:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:06:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:06:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:06:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:06:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:06:09 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:06:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:06:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:06:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:06:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:06:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:06:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:06:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:06:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:06:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:06:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:06:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:06:10 INFO - no previously-included directories found matching 'docs/_build' 02:06:10 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:06:10 INFO - Running setup.py install for pep8 02:06:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:06:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:06:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:06:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:06:10 INFO - no previously-included directories found matching 'docs/_build' 02:06:10 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:06:10 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:06:10 INFO - Cleaning up... 02:06:10 INFO - Return code: 0 02:06:10 INFO - Running post-action listener: _resource_record_post_action 02:06:10 INFO - Running post-action listener: _start_resource_monitoring 02:06:10 INFO - Starting resource monitoring. 02:06:10 INFO - ##### 02:06:10 INFO - ##### Finished create-virtualenv step (success) 02:06:10 INFO - ##### 02:06:10 INFO - ##### 02:06:10 INFO - ##### Running install step. 02:06:10 INFO - ##### 02:06:10 INFO - Running pre-action listener: _resource_record_pre_action 02:06:10 INFO - Running main action method: install 02:06:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:06:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:06:10 INFO - Reading from file tmpfile_stdout 02:06:10 INFO - Detecting whether we're running mozinstall >=1.0... 02:06:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:06:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:06:10 INFO - Reading from file tmpfile_stdout 02:06:10 INFO - Output received: 02:06:10 INFO - Usage: mozinstall [options] installer 02:06:10 INFO - Options: 02:06:10 INFO - -h, --help show this help message and exit 02:06:10 INFO - -d DEST, --destination=DEST 02:06:10 INFO - Directory to install application into. [default: 02:06:10 INFO - "/builds/slave/test"] 02:06:10 INFO - --app=APP Application being installed. [default: firefox] 02:06:10 INFO - mkdir: /builds/slave/test/build/application 02:06:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 02:06:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 02:06:27 INFO - Reading from file tmpfile_stdout 02:06:27 INFO - Output received: 02:06:27 INFO - /builds/slave/test/build/application/firefox/firefox 02:06:27 INFO - Running post-action listener: _resource_record_post_action 02:06:27 INFO - ##### 02:06:27 INFO - ##### Finished install step (success) 02:06:27 INFO - ##### 02:06:27 INFO - ##### 02:06:27 INFO - ##### Running run-tests step. 02:06:27 INFO - ##### 02:06:27 INFO - Running pre-action listener: _resource_record_pre_action 02:06:27 INFO - Running main action method: run_tests 02:06:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:06:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:06:27 INFO - Python 2.7.3 02:06:27 INFO - Return code: 0 02:06:27 INFO - grabbing minidump binary from tooltool 02:06:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:06:27 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27349c0>, '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 0x27358c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2735d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:06:27 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 02:06:27 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 02:06:27 INFO - Calling ['/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'] with output_timeout 600 02:06:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:06:27 INFO - Return code: 0 02:06:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:06:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:06:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:06:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:06:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:06:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:06:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:06:28 INFO - Return code: 0 02:06:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AnmEEez0SgWyvykSnIQOxA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 02:06:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AnmEEez0SgWyvykSnIQOxA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-033 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 02:06:28 INFO - Using env: {'DISPLAY': ':0', 02:06:28 INFO - 'HOME': '/home/cltbld', 02:06:28 INFO - 'LANG': 'en_US.UTF-8', 02:06:28 INFO - 'LANGUAGE': 'en_US:en', 02:06:28 INFO - 'LOGNAME': 'cltbld', 02:06:28 INFO - 'MAIL': '/var/mail/cltbld', 02:06:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:06:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:06:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:06:28 INFO - 'MOZ_NO_REMOTE': '1', 02:06:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:06:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:06:28 INFO - 'NO_EM_RESTART': '1', 02:06:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:06:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:06:28 INFO - 'PWD': '/builds/slave/test', 02:06:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:06:28 INFO - 'SHELL': '/bin/bash', 02:06:28 INFO - 'SHLVL': '1', 02:06:28 INFO - 'TERM': 'linux', 02:06:28 INFO - 'TMOUT': '86400', 02:06:28 INFO - 'USER': 'cltbld', 02:06:28 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1463129991.309154-329193671', 02:06:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:06:28 INFO - '_': '/tools/buildbot/bin/python'} 02:06:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AnmEEez0SgWyvykSnIQOxA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 02:06:28 INFO - mozversion application_buildid: 20160512233057 02:06:28 INFO - mozversion application_changeset: 943ca7ad1ea35e01a6ba6bfa0a4f3896f1150e15 02:06:28 INFO - mozversion application_display_name: Nightly 02:06:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:06:28 INFO - mozversion application_name: Firefox 02:06:28 INFO - mozversion application_remotingname: firefox 02:06:28 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 02:06:28 INFO - mozversion application_vendor: Mozilla 02:06:28 INFO - mozversion application_version: 49.0a1 02:06:28 INFO - mozversion platform_buildid: 20160512233057 02:06:28 INFO - mozversion platform_changeset: 943ca7ad1ea35e01a6ba6bfa0a4f3896f1150e15 02:06:28 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 02:06:28 INFO - mozversion platform_version: 49.0a1 02:06:28 INFO - using testdate: 1463130388 02:06:28 INFO - actual date: 1463130388 02:06:28 INFO - starting webserver on 'localhost:49806' 02:06:28 INFO - SUITE-START | Running 2 tests 02:06:28 INFO - TEST-START | dromaeo_css 02:06:28 INFO - operating with platform_type : linux_ 02:06:28 INFO - Initialising browser for dromaeo_css test... 02:06:28 INFO - TEST-INFO | started process 12408 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNgjuSz/profile http://localhost:49806/getInfo.html) 02:06:33 INFO - PROCESS | 12408 | __metrics Screen width/height:1600/1200 02:06:33 INFO - PROCESS | 12408 | colorDepth:24 02:06:33 INFO - PROCESS | 12408 | Browser inner width/height: 1024/697 02:06:33 INFO - PROCESS | 12408 | __metrics 02:06:34 ERROR - PROCESS | 12408 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:06:34 INFO - PROCESS | 12408 | console.error: PushService: 02:06:34 INFO - PROCESS | 12408 | stateChangeProcessEnqueue: Error transitioning state 02:06:34 INFO - PROCESS | 12408 | UnknownError 02:06:35 INFO - TEST-INFO | 12408: exit 0 02:06:35 INFO - Browser initialized. 02:06:35 INFO - Running cycle 1/1 for dromaeo_css test... 02:06:35 INFO - TEST-INFO | started process 12491 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNgjuSz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:06:47 INFO - PROCESS | 12491 | RSS: Main: 152264704 02:06:47 INFO - PROCESS | 12491 | 02:07:38 INFO - PROCESS | 12491 | 1463130458806 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] 02:09:46 INFO - PROCESS | 12491 | Cycle 1(1): loaded http://localhost:49806/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49806/tests/dromaeo/cssquery-ext.html) 02:09:47 INFO - PROCESS | 12491 | RSS: Main: 253345792 02:09:47 INFO - PROCESS | 12491 | 02:12:50 INFO - PROCESS | 12491 | Cycle 1(1): loaded http://localhost:49806/tests/dromaeo/cssquery-ext.html (next: http://localhost:49806/tests/dromaeo/cssquery-jquery.html) 02:12:50 INFO - PROCESS | 12491 | RSS: Main: 236662784 02:12:50 INFO - PROCESS | 12491 | 02:15:52 INFO - PROCESS | 12491 | Cycle 1(1): loaded http://localhost:49806/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49806/tests/dromaeo/cssquery-mootools.html) 02:15:53 INFO - PROCESS | 12491 | RSS: Main: 239996928 02:15:53 INFO - PROCESS | 12491 | 02:18:55 INFO - PROCESS | 12491 | Cycle 1(1): loaded http://localhost:49806/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49806/tests/dromaeo/cssquery-prototype.html) 02:18:55 INFO - PROCESS | 12491 | RSS: Main: 237436928 02:18:55 INFO - PROCESS | 12491 | 02:21:58 INFO - PROCESS | 12491 | Cycle 1(1): loaded http://localhost:49806/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49806/tests/dromaeo/cssquery-yui.html) 02:21:59 INFO - PROCESS | 12491 | RSS: Main: 220418048 02:21:59 INFO - PROCESS | 12491 | 02:25:01 INFO - PROCESS | 12491 | Cycle 1(1): loaded http://localhost:49806/tests/dromaeo/cssquery-yui.html (next: http://localhost:49806/tests/dromaeo/cssquery-dojo.html) 02:25:01 INFO - PROCESS | 12491 | RSS: Main: 268607488 02:25:01 INFO - PROCESS | 12491 | 02:25:01 INFO - PROCESS | 12491 | __start_tp_report 02:25:01 INFO - PROCESS | 12491 | _x_x_mozilla_page_load 02:25:01 INFO - PROCESS | 12491 | _x_x_mozilla_page_load_details 02:25:01 INFO - PROCESS | 12491 | |i|pagename|runs| 02:25:01 INFO - PROCESS | 12491 | |0;dojo.html;3111.159986803563;3119.2513796688786;3141.246100935775;3159.9526007109885;3177.2374630088784;5093.159628661267;5137.434882162959;5210.374755029392;5281.63028587999;5305.602079844012;5280.551153151987;5286.3392075990505;5286.577073834093;5313.3092697949305;5328.413874473815;4787.425508938933;4852.078105160026;4868.97565512172;4874.65877388583;5050.823221187259;4923.729194894279;4940.629452791037;4949.158643030675;5001.774920128603;5014.2979982802435;4709.599684026862;4723.102610504027;4736.2658787887585;4825.010872771073;4889.902201955976;4776.928346074812;4786.736729479877;4852.223644216909;4862.610991120702;4895.31465594817;4711.811527538928;4790.281457781326;4838.467768545457;4848.490908454597;4850.223964165716;5129.9230511542355;5180.274761533388;5186.351706036746;5263.552598029134;5266.131088370415;5651.18057881609;5665.773369065232;5683;5692.459216374479;5697.572682048863;5556.611037227396;5679.346875109374;5681.886362272773;5687.061634830248;5689.573282003825;5392.946070539323;5404.216388623628;5413.323334583177;5420.837374878721;5425.7829686812875;5886.499647529922;5891.911621325684;5905.911411328748;5911.733971971272;5912.9113063304085;5453.7818487260065;5461.2354270402175;5509.421510741395;5518.944810551843;5677.574181936371;5609.887802243932;5611.158326251095;5620.325560932714;5666.433356664301;5668.631538949955;5613.326400831927;5617.943820561742;5622.409646987007;5622.662640241599;5624.775008999676;5480.287562616834;5622.212890195376;5673.3475650299915;5689.402612725687;5722.113072473773;4336.219480493542;4340.674449416307;4343.522212556616;4351.173277077345;4380.737155770664;3806.638369354907;3877.263319969197;3888.3389823730035;3936.7244292899795;3940.5271367435516;5353.732313384393;5390.649607775482;5403.810866619728;5471.261379713687;5480.26016487777;5653.773849045992;5692.8861422771315;5703.54371650267;5703.800366987136;5742.483176514133;5392.433794451605;5417.349918009864;5436.972815135899;5441.075017247077;5476.726163691799;4680.765961701902;4703.60019398355;4733.290006499053;4740.19416699162;4741.312509686076;5826.329972053183;5830.47525722687;5831.950248955229;5836.9124463133085;5840.88318233633;5562.082256427806;5664.745086471036;5675.290588676416;5679.744411501575;5721.084626459747;5853.94146058551;5887.20522729426;5897.115432685132;5957.434043765836;5978.4021597839865;5709.4861462466915;5716.456936591017;5720.427957204246;5721.8569535761935;5722.599418040697;5567.164925261243;5623.409541998195;5779.335376431764;5789.3921138279875;5793.8551536211935;5492.258545096361;5565.193047008081;5571.916421253765;5572.526335261386;5626.915596266142;5548.278723765885;5556.666600003851;5556.916646250229;5557.249771280825;5559.63862348946;5329.920051199313;5442.782288708408;5453.209284653623;5498.285222920995;5519.061759500974;5663.801766938137;5700.287464066992;5702.971485142548;5704.68624225655;5714.742836572447;5490.752916118704;5527.806526771544;5529.640573362719;5529.917051244154;5530.198121272473 02:25:01 INFO - PROCESS | 12491 | |1;ext.html;621.2669050520387;645.6448953075809;648.0214875537938;651.3258777165629;659.3801826283293;32255;43522.78238608807;44077.55922440775;44541.77729111358;44566.55433445665;44063.55936440643;44477;44537.55462445375;44598.77700611501;44639.553604464025;7991.920080799176;8553.272971797389;8676.783080422976;8787.780305492382;10400.687979360631;10404.739881502946;10487.58049678012;10488.475576221197;10490.475476226196;10493.475326233696;10506.106980906632;10548.156147508182;10549.6307629233;10557.049865512103;10560.68317950459;10462.319949203316;10506.684799456028;10510.211734119934;10510.78978420435;10513.211509136769;10332.845007324897;10394.48027598617;10406.687799366031;10408.479576021244;10413;41155.382669259685;41494.79252603718;41528.584714153076;41573.79213103945;41618.1676366471;3496.807675577842;3672.981635091834;3696.445533170019;3725.4784330193793;3864.516935383077;31685.84157079223;31943.84028079868;32092;32242.677573224202;32813.507797383056;3977.7215594908366;4024.63778259958;4028.7213641161447;4060.4924384451942;4127.876163715094;307916;310215;310673;311071;313949;58304;58677.41322586805;59079.40920590739;59259.70370148208;59264.70367648134;36652.63347366492;37723.62276377201;37879.62120378816;62104;62274;14756.852431475763;14943.850561494462;14978.62553436151;15003.32485038176;15011.099334039774;9628.32601717873;11172.273802202832;11352.318860868374;11354.659360219206;12468;19154.808451915582;19432;19433.708494372597;19490;19548.21807127699;6399.232092148972;7167.462440316997;7238.508144070862;7327.303906128909;7420.183779784219;1040.7718891707707;1080.524569189554;1127.4531852051748;1209.4315671634317;1214.7874122028786;32442.513362299585;32982.835085824416;33181.3363732724;33414.16464588356;33779.49330760041;1114.4817659788237;1121.7195701074731;1177.735009622828;1190.8451901252781;1193.3436609864539;17496.825031749773;17503.38738144159;17560.121993900255;17577.648447030988;17641.911790440965;470045;471468;476633;476658;477914;59271.703641481516;59716;59746;59775;59785.40214597798;1275.0755702115891;1374.305975482375;1402.9579112626639;1410.2948525737131;1440.6758479342063;31999.68000319967;33250;33464;33975.32049359049;34005.65994340025;49406;49769.502304977934;49887.25169122394;50040.49959500504;50046;331004;332504;332863;333460;334164;26049.869750651127;28256.576151358156;29221.707782921898;29505.704942950295;30350.544741829333;47033.52966470292;47705.761471192425;48024;48858;49405;39864.402033970095;40729.38905916473;41073.589264108174;41942.58057419509;41974.58025419829;40371.394429084175;41018;41099.58900410958;41159.58840411557;41826;41110;42962.35556466718;43670.78164609284;43683.78158109189;45647.543524565655;34601;37635;37841.62158378381;37843;38704.41943370908;28295.2926176839;28548.85725571359;28807.711922880502;28978.710212898444;28991.5651265227 02:25:01 INFO - PROCESS | 12491 | |2;jquery.html;382.79329162252384;385.75504554607835;398.1042654028436;400.871721049264;401.6345125935399;17400.39098631548;19130.33043843466;19305.938173400475;19313.71029434558;19332.226710931547;18449.90775046126;18974.62050758984;19078.713819292687;19254.6521743478;19414.22343106274;5511.063119269724;5590.2732644756325;5610.270664813589;5685.687287199203;5686.175504551838;5539.9169012464845;5604;5623.437656234385;5651.660900345993;5657.20799088128;5560.77756889726;5627.8593035174035;5635.520980716644;5640.492355687988;5643.68959707216;5455.427180146087;5580.497755202051;5601.3558440779425;5603.3836278009385;5604.747786349603;4985;4989.825356112518;5052.469490703496;5353.491418315254;5363.275957745694;21793.891030544903;21965.780342196533;22196.22313219046;22226.444338891495;22288.66567001496;2296.7703229677068;2354.5997180479358;2376.0377047295874;2383.41606306456;2467.5558399488086;10337.689869303933;10699.144068474503;10889;11632.651020469399;14657.92671036638;1858.8419984301352;1882.200064972395;1914.4543805015503;1938.331275709892;1992.980070199294;56212.718936405465;70193.29806701919;71431;78805.60597196977;85915.57042214811;10923.726906827393;11003.944980275048;11274.774504509864;11296.830547541793;11318.660440186808;13120.278384688934;13735.519256825963;13953.511627093207;13987.79018314726;14549.12705237689;5931.940680593225;6047.244094488189;7002.684879180449;7943.364530837634;8133.918660813316;7277.89083163753;7332.926670733331;7564.167941526428;7665.23347665229;7765.805854853674;4665.603423708955;4747.691400058985;4799.088173247072;4829.2997515360075;4867.9269810952865;1862.7950925398195;1944.7666280046485;1958.285225892539;2019.8182163605052;2024.5849600831793;980.529345913951;1001.9649312274181;1014.7564584499667;1050.4275170032352;1098.1653943003375;18643.627127457374;19213.903930480257;19410.70883936742;19440.416787496397;19703.310384136486;1005.6631028605353;1031.242037102729;1034.177828626244;1047.7171163785736;1116.1851848150895;10450.686479405478;10932.945335273273;11021.173411993972;11037.613683521198;11043.83434248487;363757;364022;366907;470712;479002;58303.4169658298;83758.16241837626;86601;103220;103765.48117259366;1033.235405799703;1065.3501364168005;1081.5835903177333;1085.1915323084352;1092.5902786455079;17095.57261068483;17233.741493877853;17389.739153912447;17402.825971740625;17721.55696107556;31453;31658.208544786565;37027;40657.1868562633;41125;47881.76059119682;51940.740296298274;52634;57036.71481642565;58324.4167558336;8546.401751877558;8837.646494140163;8942.37403381757;9003.954980225319;9187.03536128697;12693.555725549562;13557.661058473617;13679;13700.24648644334;13728.313584320544;5699.629524080921;9059.641053841977;10173.643922462677;11870.347130907538;11917.82123268169;11820.76358472843;11867.58463453775;12037;12243.081768867549;12265.080118991289;11826.940865295963;12074.27554346742;12196.695082623006;12224.616199840946;12465.93767031159;8765.430247033923;8823.426477279214;8861.158189971942;8889.777755556162;9101.362904596615;9062.229710474683;11362.14783891228;11637.418129093681;11713.238639488405;11753.177277590486 02:25:01 INFO - PROCESS | 12491 | |3;mootools.html;1428.1716604369467;1547.3578464937048;1572.300326354772;1730.2905808618461;1801.351513455156;8386.119457456964;8721.215090641848;8751.649934002648;8929.91070089299;8982.910170898289;8739.781505462366;8832.867506987384;8890.42212256203;8915.73252802417;8916.108389161082;2044.826189773871;2275.7496675365696;2335.789778919893;2363.2555744940287;2376.0852071952336;2376.976230237701;2383.1539803369815;2386.284114765572;2387.2241521505493;2388.79695225906;2262.083856038307;2280.6579013148075;2301.9079236830507;2305.3545007397956;2316.4324740438533;1832.6334733053377;2226.944326391837;2294.862308261493;2301.3096071178747;2311.0986715181093;2312.514371981869;2316.5714342846627;2335.1943579465055;2337.6610391493136;2340.566995105911;8654.78363040929;8692.869606955901;8697.95651021741;8705.912940870574;8709.738707838775;5175.482451754794;5303.231031500411;5305.363356397218;5336;5342.038433082043;3213.3091385352263;3300.455424854896;3370.9494357584654;3562.8574857005688;3563.1804684922354;1142.120567163288;1157.617986064605;1164.4352489042808;1179.7050737315672;1209.6431552691977;12394.56619018339;12413;12496.062795290389;12497;12528.937355313165;4640.976795116003;4740.1230772307235;4776.808927642855;4795.232762757942;4837.927431088537;10328.535215915303;10343.741406464747;10357.274990750575;10359.741006474747;10378.377297362182;5325.3875804282225;5413.404525502192;5424.647397919122;5427.0773968425465;5429;4357.891052723708;4795.568398844121;4889.291052797395;4895.216765317532;4986.950130498722;4038.7980600969836;4053.310937140693;4055.465762604987;4060.0661847774586;4060.675145988314;1295.6242689620094;1295.6631275868342;1323.7484877873173;1326.602019394178;1336.7861142217198;885.751989442957;980.6616717232544;989.5794287427815;1011.838105903052;1017.008069522036;5712.371639119693;5776.942230577641;5933.3473317934995;5938.079597662283;5943.3462319144855;446.5980617444326;453.73910001749124;463.14318510754833;464.1737706881756;468.70705808869457;3781.2815565042556;3908.8241029153755;3919.490466239428;3923.6861051115843;3931.351327031065;10636.255462117577;10945.945270273598;11141.554337826396;11190.776184476264;11196.160287978271;10757.78484430307;10996.12031037531;11072.39101849406;11077.889221107685;11154.944225278983;8203.097659257477;8331.500109993298;8342.12407697183;8395.70615028471;8444;5624.718764061862;5762.510186634183;5776.595638305278;5793.36273009977;5882.676452795137;10090.899091008996;10102;10151.086402223835;10165.593376264867;10283.845742313722;9733.659321923982;10011;10370.170386369224;10404;10407.167426605702;6938.8550889102735;7571.053618297713;7759.650815713419;7775.339096176888;8179.550124743197;8725.65097396097;8761.649534018568;8773.166549178073;8790.824183516423;8824.691135810215;7647.464677472523;7700.460967732205;7742.187070357531;7947.801304967422;7966.203379662219;7644.923550764421;7736.806579835549;7790.298873101448;7842.196294852102;7875.724349647735;6523.934760652332;6574.671266436755;6599.043138744951;6603.372679595431;6609.041688955171;4886.609071274218;4892.363992680928;5030.673006254582;5031.899362012666;5074.82238121664;4723.858284251478;4787.234042553175;4809.735464549483;4820.903581928272;4857.222844344888 02:25:01 INFO - PROCESS | 12491 | |4;prototype.html;336.70033670033666;339.1064544924124;345.8616553378648;348.9040513858687;352.3411221016699;5238.449962753908;5321.494458026486;5326.268962587165;5338.466153384659;5363.785448582062;5236.397814251362;5241.947580524194;5252.3980186043245;5252.6848389096685;5302.151655735083;3965.9603403966025;4034.6368826805583;4138.793060346986;4169.353750168729;4175;4117.505899292089;4138.723701963923;4151.60559746825;4158.688098392618;4166.229247589191;3995.3407687731633;4142.937855932163;4148.834046638146;4160.583941605845;4162.958370416302;4048.1701251243426;4054.2702313583545;4085.468889044435;4139.668826493873;4151.3150330195485;3692.778433294011;3693.3351996640595;3704.499677630564;3715.832787524541;3725.701943844513;5132.897342053138;5152.552537544674;5171.250168725552;5184.377874655065;5191.351081114861;4514.413126293593;4723.622110231173;4741.5495527924795;4753.382060332135;4796.904061918742;5555.138953462179;5558.833235002957;5560.2493663355335;5563.692532254917;5605.887882242332;3824.7322687411893;3975.1056012397266;3991.6806655467494;4028.1742242840146;4045.37296719009;5743.253377060997;5748.942510574882;5752.13717942312;5752.188060760847;5778.3354914184565;3996.9200615988098;4016.3975403689096;4024.215278020758;4030.6170913763144;4039.2123535910214;3918.6480664170817;4087.305158123126;4102.364133559303;4108.301588729923;4140.296149654566;4695.530446955346;4701.48283688794;4711.929321060187;4726.905461890743;4728.40894888139;5594.272744543184;5604.271444712162;5613.382527921925;5635.1828984796975;5644.463775941279;1372.9588112356446;1426.850180731029;1435.7200345932652;1440.3446431873472;1446.2985452055746;904.3533873280636;908.7500937242179;920.9907900921039;922.727795300388;1019.403648865422;5279.973600131974;5335.519803217729;5337.466253374709;5347.786088556492;5358.732063396815;5582.24159389799;5689.573282003867;5701.2018317435595;5709.516360383246;5710.571707121982;5146.716930568855;5346.251524786533;5356.625036247426;5392.735051278472;5401.783928642889;2962.2890506278336;2983.4033193361415;3001.4747419201553;3002;3006.902480594611;5724.4561766632105;5756.7121643917635;5801.796937107181;5802;5802.941970580325;5554.389017208104;5856.180134781134;5873.618214816023;5873.619699370644;5880.441358071062;5900.409959004065;5939.613925094855;5941.168236446901;5945.40545945402;6011.609245399035;5576.470235327637;5744.195812586157;5759.712014399347;5763.740631671669;5822.592418530662;5619.971900140473;5645.830625081254;5656.5757568181725;5660.773569057356;5685.744141513725;5928.733207005781;5936.248806602149;5952.5237980960555;5977.342492325927;5985.880282394415;3871.1676989447406;4094.6314831665295;4100;4116.382542618631;4129.669626429818;5731.8567035824435;5754.856128596651;5758.539316854725;5790.623609465371;5817.156512305607;5396.64921780083;5569.637973531708;5608.411116832837;5660.405657405913;5666.206731057738;5540.22436858832;5570.5543556516195;5601.355844077983;5667.631603945731;5673.602847800615;5328.653637513549;5386.569074474111;5511.724413779375;5533.170024496358;5536.889262214652;5579.442055794388;5619;5669.688167150846;5693.893259494013;5708.857278568069;5496.917546236889;5506.834794956158;5515.420880807618;5520.1443776214755;5531.253280807039 02:25:01 INFO - PROCESS | 12491 | |5;yui.html;692.4495026453968;697.3270793684097;697.49431662045;699.2343383994528;699.2378307644666;14306.427742890271;15412.537623871287;15919.68160636788;15964.28160732767;16104.436344727937;15073.170975596338;15286.082835029905;15295.69408611827;15312.157831319273;15396.769048464254;703.9577625342496;829.4235506323108;849.7875531117221;857.1428571428571;857.5755001274377;848.0332421039989;854.1629203380691;854.5599016506504;854.7307598106607;855.9657613695476;884.3632584539154;890.5413711938357;891.8483857744167;892.3084609427681;892.9687460938836;734.8456824066952;857.3655494933766;872.358816270041;887.152769105506;888.7467071884514;637.9744810207586;640.0814830717935;641.8972964325732;642.4250295985114;644.7324360390433;12854.742905141939;12922.676933076655;12984.545540906023;12997.870021299856;13019.958403327806;12427.254364738144;12727.427265773062;12891.806622900664;12954.106135788506;12956;11332.943335283353;11437.714057148554;11627.883721162849;11658.067354611696;11801.763964720658;4347.586979236968;4359.302511598161;4408.955910440886;4434.5343738907595;4452.777361131963;50983.490165098614;51219;51412.74293628508;51585;51931.74034129843;9350.95324523373;9845.212383009486;10180.236482263712;10350.06849383544;10427.791444171075;18665.720014199796;18794.90602547006;18821.90589047046;18972.146253418352;19119.330823421114;8011.839763204704;8051;8104.878426823602;8146.75559733209;8233.34133269337;4558.430196225472;4568.862934111982;4616.469106052779;4691.061787642485;4706.129366067288;3145.5281707743566;3240.4977228529615;3242.2866969266724;3247.594050743657;3261.951070733941;573.8049063318492;621.627023785734;623.2240860129168;635.3233805996599;641.1632819171026;555.8665920179128;720.2617317249861;725.3037084398931;728.5665029307554;733.7761982595252;8665.436746611576;8861.379703420698;9353.298502612332;9355.532223388804;9457.10157535033;580.7909152705023;678.6233640329566;698.9620413685673;699.1575151941897;723.863534251231;4139.668826493873;4140.632748190011;4163.125743593819;4198.286291330481;4226.788660566959;59950.70024649849;60021.69989150026;60778.69610652007;60874.39125608776;61997.380026200066;51972.74013629908;52512.737436313335;52652.47347526552;53685.46314536805;54065;48692.51307486951;48990.75504622454;49818.75090624524;49828;50103.24845127253;8002.07976082758;8122.83754324922;8140.918590814016;8159.102498725017;8218.465799722999;33360;33535.93314206355;33692.157696057795;33882.49176262407;34804.82597587097;43114.56885431231;43500.78249608732;43790;44989;45587.54412455833;10681.19891008193;10715.196360272856;10826;10854.511546980248;10925.617603383844;10980.615678451215;11146.49840757184;11180.385078820744;11242.494087765906;11282.210245282751;10559.577616895545;10622.946885265525;10693.518791654238;10701.785964280514;10968.506417211085;10787.6224332148;10844.96972787584;10929.617463388744;10931.562737490729;11051.005409513182;10505.474726263808;10542;10668.946655266674;10785.460726963778;10822.40476773785;9309.162175404246;9492.620295188115;9558.95220523893;9662.391154388417;9710.465924374228;9701.466419347003;9977.551010204414;10029.04724051214;10072.899271007489;10073.597056117966 02:25:01 INFO - PROCESS | 12491 | __end_tp_report 02:25:01 INFO - PROCESS | 12491 | __start_cc_report 02:25:01 INFO - PROCESS | 12491 | _x_x_mozilla_cycle_collect,864 02:25:01 INFO - PROCESS | 12491 | __end_cc_report 02:25:01 INFO - PROCESS | 12491 | __startTimestamp1463131501884__endTimestamp 02:25:01 INFO - PROCESS | 12491 | 02:25:01 INFO - PROCESS | 12491 | ------- Summary: start ------- 02:25:01 INFO - PROCESS | 12491 | Number of tests: 6 02:25:01 INFO - PROCESS | 12491 | 02:25:01 INFO - PROCESS | 12491 | [#0] dojo.html Cycles:165 Average:5282.73 Median:5492.26 stddev:597.25 (10.9%) stddev-sans-first:574.27 02:25:01 INFO - PROCESS | 12491 | Values: 3111.2 3119.3 3141.2 3160.0 3177.2 5093.2 5137.4 5210.4 5281.6 5305.6 5280.6 5286.3 5286.6 5313.3 5328.4 4787.4 4852.1 4869.0 4874.7 5050.8 4923.7 4940.6 4949.2 5001.8 5014.3 4709.6 4723.1 4736.3 4825.0 4889.9 4776.9 4786.7 4852.2 4862.6 4895.3 4711.8 4790.3 4838.5 4848.5 4850.2 5129.9 5180.3 5186.4 5263.6 5266.1 5651.2 5665.8 5683.0 5692.5 5697.6 5556.6 5679.3 5681.9 5687.1 5689.6 5392.9 5404.2 5413.3 5420.8 5425.8 5886.5 5891.9 5905.9 5911.7 5912.9 5453.8 5461.2 5509.4 5518.9 5677.6 5609.9 5611.2 5620.3 5666.4 5668.6 5613.3 5617.9 5622.4 5622.7 5624.8 5480.3 5622.2 5673.3 5689.4 5722.1 4336.2 4340.7 4343.5 4351.2 4380.7 3806.6 3877.3 3888.3 3936.7 3940.5 5353.7 5390.6 5403.8 5471.3 5480.3 5653.8 5692.9 5703.5 5703.8 5742.5 5392.4 5417.3 5437.0 5441.1 5476.7 4680.8 4703.6 4733.3 4740.2 4741.3 5826.3 5830.5 5832.0 5836.9 5840.9 5562.1 5664.7 5675.3 5679.7 5721.1 5853.9 5887.2 5897.1 5957.4 5978.4 5709.5 5716.5 5720.4 5721.9 5722.6 5567.2 5623.4 5779.3 5789.4 5793.9 5492.3 5565.2 5571.9 5572.5 5626.9 5548.3 5556.7 5556.9 5557.2 5559.6 5329.9 5442.8 5453.2 5498.3 5519.1 5663.8 5700.3 5703.0 5704.7 5714.7 5490.8 5527.8 5529.6 5529.9 5530.2 02:25:01 INFO - PROCESS | 12491 | 02:25:01 INFO - PROCESS | 12491 | [#1] ext.html Cycles:180 Average:54495.59 Median:34303.33 stddev:100071.68 (291.7%) stddev-sans-first:100270.65 02:25:01 INFO - PROCESS | 12491 | Values: 621.3 645.6 648.0 651.3 659.4 32255.0 43522.8 44077.6 44541.8 44566.6 44063.6 44477.0 44537.6 44598.8 44639.6 7991.9 8553.3 8676.8 8787.8 10400.7 10404.7 10487.6 10488.5 10490.5 10493.5 10506.1 10548.2 10549.6 10557.0 10560.7 10462.3 10506.7 10510.2 10510.8 10513.2 10332.8 10394.5 10406.7 10408.5 10413.0 41155.4 41494.8 41528.6 41573.8 41618.2 3496.8 3673.0 3696.4 3725.5 3864.5 31685.8 31943.8 32092.0 32242.7 32813.5 3977.7 4024.6 4028.7 4060.5 4127.9 307916.0 310215.0 310673.0 311071.0 313949.0 58304.0 58677.4 59079.4 59259.7 59264.7 36652.6 37723.6 37879.6 62104.0 62274.0 14756.9 14943.9 14978.6 15003.3 15011.1 9628.3 11172.3 11352.3 11354.7 12468.0 19154.8 19432.0 19433.7 19490.0 19548.2 6399.2 7167.5 7238.5 7327.3 7420.2 1040.8 1080.5 1127.5 1209.4 1214.8 32442.5 32982.8 33181.3 33414.2 33779.5 1114.5 1121.7 1177.7 1190.8 1193.3 17496.8 17503.4 17560.1 17577.6 17641.9 470045.0 471468.0 476633.0 476658.0 477914.0 59271.7 59716.0 59746.0 59775.0 59785.4 1275.1 1374.3 1403.0 1410.3 1440.7 31999.7 33250.0 33464.0 33975.3 34005.7 49406.0 49769.5 49887.3 50040.5 50046.0 331004.0 332504.0 332863.0 333460.0 334164.0 26049.9 28256.6 29221.7 29505.7 30350.5 47033.5 47705.8 48024.0 48858.0 49405.0 39864.4 40729.4 41073.6 41942.6 41974.6 40371.4 41018.0 41099.6 41159.6 41826.0 41110.0 42962.4 43670.8 43683.8 45647.5 34601.0 37635.0 37841.6 37843.0 38704.4 28295.3 28548.9 28807.7 28978.7 28991.6 02:25:01 INFO - PROCESS | 12491 | 02:25:01 INFO - PROCESS | 12491 | [#2] jquery.html Cycles:180 Average:25992.20 Median:22211.33 stddev:68264.88 (307.3%) stddev-sans-first:68429.30 02:25:01 INFO - PROCESS | 12491 | Values: 382.8 385.8 398.1 400.9 401.6 17400.4 19130.3 19305.9 19313.7 19332.2 18449.9 18974.6 19078.7 19254.7 19414.2 5511.1 5590.3 5610.3 5685.7 5686.2 5539.9 5604.0 5623.4 5651.7 5657.2 5560.8 5627.9 5635.5 5640.5 5643.7 5455.4 5580.5 5601.4 5603.4 5604.7 4985.0 4989.8 5052.5 5353.5 5363.3 21793.9 21965.8 22196.2 22226.4 22288.7 2296.8 2354.6 2376.0 2383.4 2467.6 10337.7 10699.1 10889.0 11632.7 14657.9 1858.8 1882.2 1914.5 1938.3 1993.0 56212.7 70193.3 71431.0 78805.6 85915.6 10923.7 11003.9 11274.8 11296.8 11318.7 13120.3 13735.5 13953.5 13987.8 14549.1 5931.9 6047.2 7002.7 7943.4 8133.9 7277.9 7332.9 7564.2 7665.2 7765.8 4665.6 4747.7 4799.1 4829.3 4867.9 1862.8 1944.8 1958.3 2019.8 2024.6 980.5 1002.0 1014.8 1050.4 1098.2 18643.6 19213.9 19410.7 19440.4 19703.3 1005.7 1031.2 1034.2 1047.7 1116.2 10450.7 10932.9 11021.2 11037.6 11043.8 363757.0 364022.0 366907.0 470712.0 479002.0 58303.4 83758.2 86601.0 103220.0 103765.5 1033.2 1065.4 1081.6 1085.2 1092.6 17095.6 17233.7 17389.7 17402.8 17721.6 31453.0 31658.2 37027.0 40657.2 41125.0 47881.8 51940.7 52634.0 57036.7 58324.4 8546.4 8837.6 8942.4 9004.0 9187.0 12693.6 13557.7 13679.0 13700.2 13728.3 5699.6 9059.6 10173.6 11870.3 11917.8 11820.8 11867.6 12037.0 12243.1 12265.1 11826.9 12074.3 12196.7 12224.6 12465.9 8765.4 8823.4 8861.2 8889.8 9101.4 9062.2 11362.1 11637.4 11713.2 11753.2 02:25:01 INFO - PROCESS | 12491 | 02:25:01 INFO - PROCESS | 12491 | [#3] mootools.html Cycles:180 Average:5790.13 Median:2552.58 stddev:3346.74 (131.1%) stddev-sans-first:3340.08 02:25:01 INFO - PROCESS | 12491 | Values: 1428.2 1547.4 1572.3 1730.3 1801.4 8386.1 8721.2 8751.6 8929.9 8982.9 8739.8 8832.9 8890.4 8915.7 8916.1 2044.8 2275.7 2335.8 2363.3 2376.1 2377.0 2383.2 2386.3 2387.2 2388.8 2262.1 2280.7 2301.9 2305.4 2316.4 1832.6 2226.9 2294.9 2301.3 2311.1 2312.5 2316.6 2335.2 2337.7 2340.6 8654.8 8692.9 8698.0 8705.9 8709.7 5175.5 5303.2 5305.4 5336.0 5342.0 3213.3 3300.5 3370.9 3562.9 3563.2 1142.1 1157.6 1164.4 1179.7 1209.6 12394.6 12413.0 12496.1 12497.0 12528.9 4641.0 4740.1 4776.8 4795.2 4837.9 10328.5 10343.7 10357.3 10359.7 10378.4 5325.4 5413.4 5424.6 5427.1 5429.0 4357.9 4795.6 4889.3 4895.2 4987.0 4038.8 4053.3 4055.5 4060.1 4060.7 1295.6 1295.7 1323.7 1326.6 1336.8 885.8 980.7 989.6 1011.8 1017.0 5712.4 5776.9 5933.3 5938.1 5943.3 446.6 453.7 463.1 464.2 468.7 3781.3 3908.8 3919.5 3923.7 3931.4 10636.3 10945.9 11141.6 11190.8 11196.2 10757.8 10996.1 11072.4 11077.9 11154.9 8203.1 8331.5 8342.1 8395.7 8444.0 5624.7 5762.5 5776.6 5793.4 5882.7 10090.9 10102.0 10151.1 10165.6 10283.8 9733.7 10011.0 10370.2 10404.0 10407.2 6938.9 7571.1 7759.7 7775.3 8179.6 8725.7 8761.6 8773.2 8790.8 8824.7 7647.5 7700.5 7742.2 7947.8 7966.2 7644.9 7736.8 7790.3 7842.2 7875.7 6523.9 6574.7 6599.0 6603.4 6609.0 4886.6 4892.4 5030.7 5031.9 5074.8 4723.9 4787.2 4809.7 4820.9 4857.2 02:25:01 INFO - PROCESS | 12491 | 02:25:01 INFO - PROCESS | 12491 | [#4] prototype.html Cycles:180 Average:4691.62 Median:5347.02 stddev:1386.52 (25.9%) stddev-sans-first:1351.33 02:25:01 INFO - PROCESS | 12491 | Values: 336.7 339.1 345.9 348.9 352.3 5238.4 5321.5 5326.3 5338.5 5363.8 5236.4 5241.9 5252.4 5252.7 5302.2 3966.0 4034.6 4138.8 4169.4 4175.0 4117.5 4138.7 4151.6 4158.7 4166.2 3995.3 4142.9 4148.8 4160.6 4163.0 4048.2 4054.3 4085.5 4139.7 4151.3 3692.8 3693.3 3704.5 3715.8 3725.7 5132.9 5152.6 5171.3 5184.4 5191.4 4514.4 4723.6 4741.5 4753.4 4796.9 5555.1 5558.8 5560.2 5563.7 5605.9 3824.7 3975.1 3991.7 4028.2 4045.4 5743.3 5748.9 5752.1 5752.2 5778.3 3996.9 4016.4 4024.2 4030.6 4039.2 3918.6 4087.3 4102.4 4108.3 4140.3 4695.5 4701.5 4711.9 4726.9 4728.4 5594.3 5604.3 5613.4 5635.2 5644.5 1373.0 1426.9 1435.7 1440.3 1446.3 904.4 908.8 921.0 922.7 1019.4 5280.0 5335.5 5337.5 5347.8 5358.7 5582.2 5689.6 5701.2 5709.5 5710.6 5146.7 5346.3 5356.6 5392.7 5401.8 2962.3 2983.4 3001.5 3002.0 3006.9 5724.5 5756.7 5801.8 5802.0 5802.9 5554.4 5856.2 5873.6 5873.6 5880.4 5900.4 5939.6 5941.2 5945.4 6011.6 5576.5 5744.2 5759.7 5763.7 5822.6 5620.0 5645.8 5656.6 5660.8 5685.7 5928.7 5936.2 5952.5 5977.3 5985.9 3871.2 4094.6 4100.0 4116.4 4129.7 5731.9 5754.9 5758.5 5790.6 5817.2 5396.6 5569.6 5608.4 5660.4 5666.2 5540.2 5570.6 5601.4 5667.6 5673.6 5328.7 5386.6 5511.7 5533.2 5536.9 5579.4 5619.0 5669.7 5693.9 5708.9 5496.9 5506.8 5515.4 5520.1 5531.3 02:25:01 INFO - PROCESS | 12491 | 02:25:01 INFO - PROCESS | 12491 | [#5] yui.html Cycles:180 Average:14222.73 Median:26699.32 stddev:16607.53 (62.2%) stddev-sans-first:16623.04 02:25:01 INFO - PROCESS | 12491 | Values: 692.4 697.3 697.5 699.2 699.2 14306.4 15412.5 15919.7 15964.3 16104.4 15073.2 15286.1 15295.7 15312.2 15396.8 704.0 829.4 849.8 857.1 857.6 848.0 854.2 854.6 854.7 856.0 884.4 890.5 891.8 892.3 893.0 734.8 857.4 872.4 887.2 888.7 638.0 640.1 641.9 642.4 644.7 12854.7 12922.7 12984.5 12997.9 13020.0 12427.3 12727.4 12891.8 12954.1 12956.0 11332.9 11437.7 11627.9 11658.1 11801.8 4347.6 4359.3 4409.0 4434.5 4452.8 50983.5 51219.0 51412.7 51585.0 51931.7 9351.0 9845.2 10180.2 10350.1 10427.8 18665.7 18794.9 18821.9 18972.1 19119.3 8011.8 8051.0 8104.9 8146.8 8233.3 4558.4 4568.9 4616.5 4691.1 4706.1 3145.5 3240.5 3242.3 3247.6 3262.0 573.8 621.6 623.2 635.3 641.2 555.9 720.3 725.3 728.6 733.8 8665.4 8861.4 9353.3 9355.5 9457.1 580.8 678.6 699.0 699.2 723.9 4139.7 4140.6 4163.1 4198.3 4226.8 59950.7 60021.7 60778.7 60874.4 61997.4 51972.7 52512.7 52652.5 53685.5 54065.0 48692.5 48990.8 49818.8 49828.0 50103.2 8002.1 8122.8 8140.9 8159.1 8218.5 33360.0 33535.9 33692.2 33882.5 34804.8 43114.6 43500.8 43790.0 44989.0 45587.5 10681.2 10715.2 10826.0 10854.5 10925.6 10980.6 11146.5 11180.4 11242.5 11282.2 10559.6 10622.9 10693.5 10701.8 10968.5 10787.6 10845.0 10929.6 10931.6 11051.0 10505.5 10542.0 10668.9 10785.5 10822.4 9309.2 9492.6 9559.0 9662.4 9710.5 9701.5 9977.6 10029.0 10072.9 10073.6 02:25:01 INFO - PROCESS | 12491 | -------- Summary: end -------- 02:25:01 INFO - PROCESS | 12491 | 02:25:02 INFO - TEST-INFO | 12491: exit 0 02:25:02 INFO - TEST-OK | dromaeo_css | took 1114247ms 02:25:02 INFO - TEST-START | kraken 02:25:02 INFO - operating with platform_type : linux_ 02:25:02 INFO - Initialising browser for kraken test... 02:25:02 INFO - TEST-INFO | started process 12669 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpknc1o3/profile http://localhost:49806/getInfo.html) 02:25:07 INFO - PROCESS | 12669 | __metrics Screen width/height:1600/1200 02:25:07 INFO - PROCESS | 12669 | colorDepth:24 02:25:07 INFO - PROCESS | 12669 | Browser inner width/height: 1024/697 02:25:07 INFO - PROCESS | 12669 | __metrics 02:25:08 ERROR - PROCESS | 12669 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:25:09 INFO - TEST-INFO | 12669: exit 0 02:25:09 INFO - Browser initialized. 02:25:09 INFO - Running cycle 1/1 for kraken test... 02:25:09 INFO - TEST-INFO | started process 12748 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpknc1o3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:25:21 INFO - PROCESS | 12748 | RSS: Main: 152395776 02:25:21 INFO - PROCESS | 12748 | 02:25:24 INFO - PROCESS | 12748 | Cycle 1(1): loaded http://localhost:49806/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49806/tests/kraken/driver.html?testName=audio-beat-detection) 02:25:24 INFO - PROCESS | 12748 | RSS: Main: 208859136 02:25:24 INFO - PROCESS | 12748 | 02:25:27 INFO - PROCESS | 12748 | Cycle 1(1): loaded http://localhost:49806/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49806/tests/kraken/driver.html?testName=audio-dft) 02:25:28 INFO - PROCESS | 12748 | RSS: Main: 196472832 02:25:28 INFO - PROCESS | 12748 | 02:25:31 INFO - PROCESS | 12748 | Cycle 1(1): loaded http://localhost:49806/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49806/tests/kraken/driver.html?testName=audio-fft) 02:25:32 INFO - PROCESS | 12748 | RSS: Main: 265404416 02:25:32 INFO - PROCESS | 12748 | 02:25:34 INFO - PROCESS | 12748 | Cycle 1(1): loaded http://localhost:49806/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49806/tests/kraken/driver.html?testName=audio-oscillator) 02:25:34 INFO - PROCESS | 12748 | RSS: Main: 174239744 02:25:34 INFO - PROCESS | 12748 | 02:25:37 INFO - PROCESS | 12748 | Cycle 1(1): loaded http://localhost:49806/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49806/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:25:37 INFO - PROCESS | 12748 | RSS: Main: 178737152 02:25:37 INFO - PROCESS | 12748 | 02:25:40 INFO - PROCESS | 12748 | Cycle 1(1): loaded http://localhost:49806/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49806/tests/kraken/driver.html?testName=imaging-darkroom) 02:25:41 INFO - PROCESS | 12748 | RSS: Main: 213962752 02:25:41 INFO - PROCESS | 12748 | 02:25:44 INFO - PROCESS | 12748 | Cycle 1(1): loaded http://localhost:49806/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49806/tests/kraken/driver.html?testName=imaging-desaturate) 02:25:45 INFO - PROCESS | 12748 | RSS: Main: 214376448 02:25:45 INFO - PROCESS | 12748 | 02:25:48 INFO - PROCESS | 12748 | Cycle 1(1): loaded http://localhost:49806/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49806/tests/kraken/driver.html?testName=json-parse-financial) 02:25:49 INFO - PROCESS | 12748 | RSS: Main: 214921216 02:25:49 INFO - PROCESS | 12748 | 02:25:50 INFO - PROCESS | 12748 | Cycle 1(1): loaded http://localhost:49806/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49806/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:25:51 INFO - PROCESS | 12748 | RSS: Main: 201805824 02:25:51 INFO - PROCESS | 12748 | 02:25:53 INFO - PROCESS | 12748 | Cycle 1(1): loaded http://localhost:49806/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49806/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:25:53 INFO - PROCESS | 12748 | RSS: Main: 206581760 02:25:53 INFO - PROCESS | 12748 | 02:25:55 INFO - PROCESS | 12748 | Cycle 1(1): loaded http://localhost:49806/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49806/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:25:56 INFO - PROCESS | 12748 | RSS: Main: 215822336 02:25:56 INFO - PROCESS | 12748 | 02:25:59 INFO - PROCESS | 12748 | Cycle 1(1): loaded http://localhost:49806/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49806/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:25:59 INFO - PROCESS | 12748 | RSS: Main: 224215040 02:25:59 INFO - PROCESS | 12748 | 02:26:02 INFO - PROCESS | 12748 | Cycle 1(1): loaded http://localhost:49806/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49806/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:26:03 INFO - PROCESS | 12748 | RSS: Main: 218312704 02:26:03 INFO - PROCESS | 12748 | 02:26:04 INFO - PROCESS | 12748 | Cycle 1(1): loaded http://localhost:49806/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49806/tests/kraken/driver.html?testName=ai-astar) 02:26:05 INFO - PROCESS | 12748 | RSS: Main: 264077312 02:26:05 INFO - PROCESS | 12748 | 02:26:05 INFO - PROCESS | 12748 | __start_tp_report 02:26:05 INFO - PROCESS | 12748 | _x_x_mozilla_page_load 02:26:05 INFO - PROCESS | 12748 | _x_x_mozilla_page_load_details 02:26:05 INFO - PROCESS | 12748 | |i|pagename|runs| 02:26:05 INFO - PROCESS | 12748 | |0;ai-astar;112;106;111;110;111;113;111;109;109;112 02:26:05 INFO - PROCESS | 12748 | |1;audio-beat-detection;154;132;149;215;128;148;159;126;181;135 02:26:05 INFO - PROCESS | 12748 | |2;audio-dft;169;160;160;159;160;163;161;161;164;161 02:26:05 INFO - PROCESS | 12748 | |3;audio-fft;76;76;75;145;75;75;77;75;135;80 02:26:05 INFO - PROCESS | 12748 | |4;audio-oscillator;92;91;155;93;92;92;122;92;91;121 02:26:05 INFO - PROCESS | 12748 | |5;imaging-gaussian-blur;116;118;116;117;116;116;116;117;116;116 02:26:05 INFO - PROCESS | 12748 | |6;imaging-darkroom;146;145;146;146;145;145;146;147;145;145 02:26:05 INFO - PROCESS | 12748 | |7;imaging-desaturate;95;95;95;95;96;95;95;96;96;95 02:26:05 INFO - PROCESS | 12748 | |8;json-parse-financial;57;56;57;55;56;55;56;112;54;55 02:26:05 INFO - PROCESS | 12748 | |9;json-stringify-tinderbox;58;60;59;58;59;58;58;58;59;58 02:26:05 INFO - PROCESS | 12748 | |10;stanford-crypto-aes;79;78;78;78;78;79;79;81;78;83 02:26:05 INFO - PROCESS | 12748 | |11;stanford-crypto-ccm;123;124;127;130;124;138;122;131;189;126 02:26:05 INFO - PROCESS | 12748 | |12;stanford-crypto-pbkdf2;167;165;163;164;163;167;167;164;168;163 02:26:05 INFO - PROCESS | 12748 | |13;stanford-crypto-sha256-iterative;65;63;64;62;63;67;66;63;66;62 02:26:05 INFO - PROCESS | 12748 | __end_tp_report 02:26:05 INFO - PROCESS | 12748 | __start_cc_report 02:26:05 INFO - PROCESS | 12748 | _x_x_mozilla_cycle_collect,1833 02:26:05 INFO - PROCESS | 12748 | __end_cc_report 02:26:05 INFO - PROCESS | 12748 | __startTimestamp1463131565223__endTimestamp 02:26:05 INFO - PROCESS | 12748 | 02:26:05 INFO - PROCESS | 12748 | ------- Summary: start ------- 02:26:05 INFO - PROCESS | 12748 | Number of tests: 14 02:26:05 INFO - PROCESS | 12748 | 02:26:05 INFO - PROCESS | 12748 | [#0] ai-astar Cycles:10 Average:110.40 Median:111.00 stddev:2.01 (1.8%) stddev-sans-first:2.05 02:26:05 INFO - PROCESS | 12748 | Values: 112.0 106.0 111.0 110.0 111.0 113.0 111.0 109.0 109.0 112.0 02:26:05 INFO - PROCESS | 12748 | 02:26:05 INFO - PROCESS | 12748 | [#1] audio-beat-detection Cycles:10 Average:152.70 Median:151.50 stddev:27.54 (18.2%) stddev-sans-first:29.20 02:26:05 INFO - PROCESS | 12748 | Values: 154.0 132.0 149.0 215.0 128.0 148.0 159.0 126.0 181.0 135.0 02:26:05 INFO - PROCESS | 12748 | 02:26:05 INFO - PROCESS | 12748 | [#2] audio-dft Cycles:10 Average:161.80 Median:161.00 stddev:2.94 (1.8%) stddev-sans-first:1.58 02:26:05 INFO - PROCESS | 12748 | Values: 169.0 160.0 160.0 159.0 160.0 163.0 161.0 161.0 164.0 161.0 02:26:05 INFO - PROCESS | 12748 | 02:26:05 INFO - PROCESS | 12748 | [#3] audio-fft Cycles:10 Average:88.90 Median:75.50 stddev:27.08 (35.9%) stddev-sans-first:28.32 02:26:05 INFO - PROCESS | 12748 | Values: 76.0 76.0 75.0 145.0 75.0 75.0 77.0 75.0 135.0 80.0 02:26:05 INFO - PROCESS | 12748 | 02:26:05 INFO - PROCESS | 12748 | [#4] audio-oscillator Cycles:10 Average:104.10 Median:92.00 stddev:21.73 (23.6%) stddev-sans-first:22.60 02:26:05 INFO - PROCESS | 12748 | Values: 92.0 91.0 155.0 93.0 92.0 92.0 122.0 92.0 91.0 121.0 02:26:05 INFO - PROCESS | 12748 | 02:26:05 INFO - PROCESS | 12748 | [#5] imaging-gaussian-blur Cycles:10 Average:116.40 Median:116.00 stddev:0.70 (0.6%) stddev-sans-first:0.73 02:26:05 INFO - PROCESS | 12748 | Values: 116.0 118.0 116.0 117.0 116.0 116.0 116.0 117.0 116.0 116.0 02:26:05 INFO - PROCESS | 12748 | 02:26:05 INFO - PROCESS | 12748 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 02:26:05 INFO - PROCESS | 12748 | Values: 146.0 145.0 146.0 146.0 145.0 145.0 146.0 147.0 145.0 145.0 02:26:05 INFO - PROCESS | 12748 | 02:26:05 INFO - PROCESS | 12748 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 02:26:05 INFO - PROCESS | 12748 | Values: 95.0 95.0 95.0 95.0 96.0 95.0 95.0 96.0 96.0 95.0 02:26:05 INFO - PROCESS | 12748 | 02:26:05 INFO - PROCESS | 12748 | [#8] json-parse-financial Cycles:10 Average:61.30 Median:56.00 stddev:17.84 (31.9%) stddev-sans-first:18.85 02:26:05 INFO - PROCESS | 12748 | Values: 57.0 56.0 57.0 55.0 56.0 55.0 56.0 112.0 54.0 55.0 02:26:05 INFO - PROCESS | 12748 | 02:26:05 INFO - PROCESS | 12748 | [#9] json-stringify-tinderbox Cycles:10 Average:58.50 Median:58.50 stddev:0.71 (1.2%) stddev-sans-first:0.73 02:26:05 INFO - PROCESS | 12748 | Values: 58.0 60.0 59.0 58.0 59.0 58.0 58.0 58.0 59.0 58.0 02:26:05 INFO - PROCESS | 12748 | 02:26:05 INFO - PROCESS | 12748 | [#10] stanford-crypto-aes Cycles:10 Average:79.10 Median:79.00 stddev:1.66 (2.1%) stddev-sans-first:1.76 02:26:05 INFO - PROCESS | 12748 | Values: 79.0 78.0 78.0 78.0 78.0 79.0 79.0 81.0 78.0 83.0 02:26:05 INFO - PROCESS | 12748 | 02:26:05 INFO - PROCESS | 12748 | [#11] stanford-crypto-ccm Cycles:10 Average:133.40 Median:128.50 stddev:20.11 (15.7%) stddev-sans-first:20.98 02:26:05 INFO - PROCESS | 12748 | Values: 123.0 124.0 127.0 130.0 124.0 138.0 122.0 131.0 189.0 126.0 02:26:05 INFO - PROCESS | 12748 | 02:26:05 INFO - PROCESS | 12748 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.10 Median:166.00 stddev:1.97 (1.2%) stddev-sans-first:1.96 02:26:05 INFO - PROCESS | 12748 | Values: 167.0 165.0 163.0 164.0 163.0 167.0 167.0 164.0 168.0 163.0 02:26:05 INFO - PROCESS | 12748 | 02:26:05 INFO - PROCESS | 12748 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.10 Median:64.50 stddev:1.79 (2.8%) stddev-sans-first:1.87 02:26:05 INFO - PROCESS | 12748 | Values: 65.0 63.0 64.0 62.0 63.0 67.0 66.0 63.0 66.0 62.0 02:26:05 INFO - PROCESS | 12748 | -------- Summary: end -------- 02:26:05 INFO - PROCESS | 12748 | 02:26:06 INFO - TEST-INFO | 12748: exit 0 02:26:06 INFO - TEST-OK | kraken | took 63610ms 02:26:06 INFO - SUITE-END | took 1177s 02:26:06 INFO - Completed test suite (00:19:37) 02:26:06 INFO - javascript benchmark 02:26:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3111.159986803563, 3119.2513796688786, 3141.246100935775, 3159.9526007109885, 3177.2374630088784, 5093.159628661267, 5137.434882162959, 5210.374755029392, 5281.63028587999, 5305.602079844012, 5280.551153151987, 5286.3392075990505, 5286.577073834093, 5313.3092697949305, 5328.413874473815, 4787.425508938933, 4852.078105160026, 4868.97565512172, 4874.65877388583, 5050.823221187259, 4923.729194894279, 4940.629452791037, 4949.158643030675, 5001.774920128603, 5014.2979982802435, 4709.599684026862, 4723.102610504027, 4736.2658787887585, 4825.010872771073, 4889.902201955976, 4776.928346074812, 4786.736729479877, 4852.223644216909, 4862.610991120702, 4895.31465594817, 4711.811527538928, 4790.281457781326, 4838.467768545457, 4848.490908454597, 4850.223964165716, 5129.9230511542355, 5180.274761533388, 5186.351706036746, 5263.552598029134, 5266.131088370415, 5651.18057881609, 5665.773369065232, 5683.0, 5692.459216374479, 5697.572682048863, 5556.611037227396, 5679.346875109374, 5681.886362272773, 5687.061634830248, 5689.573282003825, 5392.946070539323, 5404.216388623628, 5413.323334583177, 5420.837374878721, 5425.7829686812875, 5886.499647529922, 5891.911621325684, 5905.911411328748, 5911.733971971272, 5912.9113063304085, 5453.7818487260065, 5461.2354270402175, 5509.421510741395, 5518.944810551843, 5677.574181936371, 5609.887802243932, 5611.158326251095, 5620.325560932714, 5666.433356664301, 5668.631538949955, 5613.326400831927, 5617.943820561742, 5622.409646987007, 5622.662640241599, 5624.775008999676, 5480.287562616834, 5622.212890195376, 5673.3475650299915, 5689.402612725687, 5722.113072473773, 4336.219480493542, 4340.674449416307, 4343.522212556616, 4351.173277077345, 4380.737155770664, 3806.638369354907, 3877.263319969197, 3888.3389823730035, 3936.7244292899795, 3940.5271367435516, 5353.732313384393, 5390.649607775482, 5403.810866619728, 5471.261379713687, 5480.26016487777, 5653.773849045992, 5692.8861422771315, 5703.54371650267, 5703.800366987136, 5742.483176514133, 5392.433794451605, 5417.349918009864, 5436.972815135899, 5441.075017247077, 5476.726163691799, 4680.765961701902, 4703.60019398355, 4733.290006499053, 4740.19416699162, 4741.312509686076, 5826.329972053183, 5830.47525722687, 5831.950248955229, 5836.9124463133085, 5840.88318233633, 5562.082256427806, 5664.745086471036, 5675.290588676416, 5679.744411501575, 5721.084626459747, 5853.94146058551, 5887.20522729426, 5897.115432685132, 5957.434043765836, 5978.4021597839865, 5709.4861462466915, 5716.456936591017, 5720.427957204246, 5721.8569535761935, 5722.599418040697, 5567.164925261243, 5623.409541998195, 5779.335376431764, 5789.3921138279875, 5793.8551536211935, 5492.258545096361, 5565.193047008081, 5571.916421253765, 5572.526335261386, 5626.915596266142, 5548.278723765885, 5556.666600003851, 5556.916646250229, 5557.249771280825, 5559.63862348946, 5329.920051199313, 5442.782288708408, 5453.209284653623, 5498.285222920995, 5519.061759500974, 5663.801766938137, 5700.287464066992, 5702.971485142548, 5704.68624225655, 5714.742836572447, 5490.752916118704, 5527.806526771544, 5529.640573362719, 5529.917051244154, 5530.198121272473], "value": 5243.0403067095895, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [621.2669050520387, 645.6448953075809, 648.0214875537938, 651.3258777165629, 659.3801826283293, 32255.0, 43522.78238608807, 44077.55922440775, 44541.77729111358, 44566.55433445665, 44063.55936440643, 44477.0, 44537.55462445375, 44598.77700611501, 44639.553604464025, 7991.920080799176, 8553.272971797389, 8676.783080422976, 8787.780305492382, 10400.687979360631, 10404.739881502946, 10487.58049678012, 10488.475576221197, 10490.475476226196, 10493.475326233696, 10506.106980906632, 10548.156147508182, 10549.6307629233, 10557.049865512103, 10560.68317950459, 10462.319949203316, 10506.684799456028, 10510.211734119934, 10510.78978420435, 10513.211509136769, 10332.845007324897, 10394.48027598617, 10406.687799366031, 10408.479576021244, 10413.0, 41155.382669259685, 41494.79252603718, 41528.584714153076, 41573.79213103945, 41618.1676366471, 3496.807675577842, 3672.981635091834, 3696.445533170019, 3725.4784330193793, 3864.516935383077, 31685.84157079223, 31943.84028079868, 32092.0, 32242.677573224202, 32813.507797383056, 3977.7215594908366, 4024.63778259958, 4028.7213641161447, 4060.4924384451942, 4127.876163715094, 307916.0, 310215.0, 310673.0, 311071.0, 313949.0, 58304.0, 58677.41322586805, 59079.40920590739, 59259.70370148208, 59264.70367648134, 36652.63347366492, 37723.62276377201, 37879.62120378816, 62104.0, 62274.0, 14756.852431475763, 14943.850561494462, 14978.62553436151, 15003.32485038176, 15011.099334039774, 9628.32601717873, 11172.273802202832, 11352.318860868374, 11354.659360219206, 12468.0, 19154.808451915582, 19432.0, 19433.708494372597, 19490.0, 19548.21807127699, 6399.232092148972, 7167.462440316997, 7238.508144070862, 7327.303906128909, 7420.183779784219, 1040.7718891707707, 1080.524569189554, 1127.4531852051748, 1209.4315671634317, 1214.7874122028786, 32442.513362299585, 32982.835085824416, 33181.3363732724, 33414.16464588356, 33779.49330760041, 1114.4817659788237, 1121.7195701074731, 1177.735009622828, 1190.8451901252781, 1193.3436609864539, 17496.825031749773, 17503.38738144159, 17560.121993900255, 17577.648447030988, 17641.911790440965, 470045.0, 471468.0, 476633.0, 476658.0, 477914.0, 59271.703641481516, 59716.0, 59746.0, 59775.0, 59785.40214597798, 1275.0755702115891, 1374.305975482375, 1402.9579112626639, 1410.2948525737131, 1440.6758479342063, 31999.68000319967, 33250.0, 33464.0, 33975.32049359049, 34005.65994340025, 49406.0, 49769.502304977934, 49887.25169122394, 50040.49959500504, 50046.0, 331004.0, 332504.0, 332863.0, 333460.0, 334164.0, 26049.869750651127, 28256.576151358156, 29221.707782921898, 29505.704942950295, 30350.544741829333, 47033.52966470292, 47705.761471192425, 48024.0, 48858.0, 49405.0, 39864.402033970095, 40729.38905916473, 41073.589264108174, 41942.58057419509, 41974.58025419829, 40371.394429084175, 41018.0, 41099.58900410958, 41159.58840411557, 41826.0, 41110.0, 42962.35556466718, 43670.78164609284, 43683.78158109189, 45647.543524565655, 34601.0, 37635.0, 37841.62158378381, 37843.0, 38704.41943370908, 28295.2926176839, 28548.85725571359, 28807.711922880502, 28978.710212898444, 28991.5651265227], "value": 20164.672014484473, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.79329162252384, 385.75504554607835, 398.1042654028436, 400.871721049264, 401.6345125935399, 17400.39098631548, 19130.33043843466, 19305.938173400475, 19313.71029434558, 19332.226710931547, 18449.90775046126, 18974.62050758984, 19078.713819292687, 19254.6521743478, 19414.22343106274, 5511.063119269724, 5590.2732644756325, 5610.270664813589, 5685.687287199203, 5686.175504551838, 5539.9169012464845, 5604.0, 5623.437656234385, 5651.660900345993, 5657.20799088128, 5560.77756889726, 5627.8593035174035, 5635.520980716644, 5640.492355687988, 5643.68959707216, 5455.427180146087, 5580.497755202051, 5601.3558440779425, 5603.3836278009385, 5604.747786349603, 4985.0, 4989.825356112518, 5052.469490703496, 5353.491418315254, 5363.275957745694, 21793.891030544903, 21965.780342196533, 22196.22313219046, 22226.444338891495, 22288.66567001496, 2296.7703229677068, 2354.5997180479358, 2376.0377047295874, 2383.41606306456, 2467.5558399488086, 10337.689869303933, 10699.144068474503, 10889.0, 11632.651020469399, 14657.92671036638, 1858.8419984301352, 1882.200064972395, 1914.4543805015503, 1938.331275709892, 1992.980070199294, 56212.718936405465, 70193.29806701919, 71431.0, 78805.60597196977, 85915.57042214811, 10923.726906827393, 11003.944980275048, 11274.774504509864, 11296.830547541793, 11318.660440186808, 13120.278384688934, 13735.519256825963, 13953.511627093207, 13987.79018314726, 14549.12705237689, 5931.940680593225, 6047.244094488189, 7002.684879180449, 7943.364530837634, 8133.918660813316, 7277.89083163753, 7332.926670733331, 7564.167941526428, 7665.23347665229, 7765.805854853674, 4665.603423708955, 4747.691400058985, 4799.088173247072, 4829.2997515360075, 4867.9269810952865, 1862.7950925398195, 1944.7666280046485, 1958.285225892539, 2019.8182163605052, 2024.5849600831793, 980.529345913951, 1001.9649312274181, 1014.7564584499667, 1050.4275170032352, 1098.1653943003375, 18643.627127457374, 19213.903930480257, 19410.70883936742, 19440.416787496397, 19703.310384136486, 1005.6631028605353, 1031.242037102729, 1034.177828626244, 1047.7171163785736, 1116.1851848150895, 10450.686479405478, 10932.945335273273, 11021.173411993972, 11037.613683521198, 11043.83434248487, 363757.0, 364022.0, 366907.0, 470712.0, 479002.0, 58303.4169658298, 83758.16241837626, 86601.0, 103220.0, 103765.48117259366, 1033.235405799703, 1065.3501364168005, 1081.5835903177333, 1085.1915323084352, 1092.5902786455079, 17095.57261068483, 17233.741493877853, 17389.739153912447, 17402.825971740625, 17721.55696107556, 31453.0, 31658.208544786565, 37027.0, 40657.1868562633, 41125.0, 47881.76059119682, 51940.740296298274, 52634.0, 57036.71481642565, 58324.4167558336, 8546.401751877558, 8837.646494140163, 8942.37403381757, 9003.954980225319, 9187.03536128697, 12693.555725549562, 13557.661058473617, 13679.0, 13700.24648644334, 13728.313584320544, 5699.629524080921, 9059.641053841977, 10173.643922462677, 11870.347130907538, 11917.82123268169, 11820.76358472843, 11867.58463453775, 12037.0, 12243.081768867549, 12265.080118991289, 11826.940865295963, 12074.27554346742, 12196.695082623006, 12224.616199840946, 12465.93767031159, 8765.430247033923, 8823.426477279214, 8861.158189971942, 8889.777755556162, 9101.362904596615, 9062.229710474683, 11362.14783891228, 11637.418129093681, 11713.238639488405, 11753.177277590486], "value": 9008.480681212997, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1428.1716604369467, 1547.3578464937048, 1572.300326354772, 1730.2905808618461, 1801.351513455156, 8386.119457456964, 8721.215090641848, 8751.649934002648, 8929.91070089299, 8982.910170898289, 8739.781505462366, 8832.867506987384, 8890.42212256203, 8915.73252802417, 8916.108389161082, 2044.826189773871, 2275.7496675365696, 2335.789778919893, 2363.2555744940287, 2376.0852071952336, 2376.976230237701, 2383.1539803369815, 2386.284114765572, 2387.2241521505493, 2388.79695225906, 2262.083856038307, 2280.6579013148075, 2301.9079236830507, 2305.3545007397956, 2316.4324740438533, 1832.6334733053377, 2226.944326391837, 2294.862308261493, 2301.3096071178747, 2311.0986715181093, 2312.514371981869, 2316.5714342846627, 2335.1943579465055, 2337.6610391493136, 2340.566995105911, 8654.78363040929, 8692.869606955901, 8697.95651021741, 8705.912940870574, 8709.738707838775, 5175.482451754794, 5303.231031500411, 5305.363356397218, 5336.0, 5342.038433082043, 3213.3091385352263, 3300.455424854896, 3370.9494357584654, 3562.8574857005688, 3563.1804684922354, 1142.120567163288, 1157.617986064605, 1164.4352489042808, 1179.7050737315672, 1209.6431552691977, 12394.56619018339, 12413.0, 12496.062795290389, 12497.0, 12528.937355313165, 4640.976795116003, 4740.1230772307235, 4776.808927642855, 4795.232762757942, 4837.927431088537, 10328.535215915303, 10343.741406464747, 10357.274990750575, 10359.741006474747, 10378.377297362182, 5325.3875804282225, 5413.404525502192, 5424.647397919122, 5427.0773968425465, 5429.0, 4357.891052723708, 4795.568398844121, 4889.291052797395, 4895.216765317532, 4986.950130498722, 4038.7980600969836, 4053.310937140693, 4055.465762604987, 4060.0661847774586, 4060.675145988314, 1295.6242689620094, 1295.6631275868342, 1323.7484877873173, 1326.602019394178, 1336.7861142217198, 885.751989442957, 980.6616717232544, 989.5794287427815, 1011.838105903052, 1017.008069522036, 5712.371639119693, 5776.942230577641, 5933.3473317934995, 5938.079597662283, 5943.3462319144855, 446.5980617444326, 453.73910001749124, 463.14318510754833, 464.1737706881756, 468.70705808869457, 3781.2815565042556, 3908.8241029153755, 3919.490466239428, 3923.6861051115843, 3931.351327031065, 10636.255462117577, 10945.945270273598, 11141.554337826396, 11190.776184476264, 11196.160287978271, 10757.78484430307, 10996.12031037531, 11072.39101849406, 11077.889221107685, 11154.944225278983, 8203.097659257477, 8331.500109993298, 8342.12407697183, 8395.70615028471, 8444.0, 5624.718764061862, 5762.510186634183, 5776.595638305278, 5793.36273009977, 5882.676452795137, 10090.899091008996, 10102.0, 10151.086402223835, 10165.593376264867, 10283.845742313722, 9733.659321923982, 10011.0, 10370.170386369224, 10404.0, 10407.167426605702, 6938.8550889102735, 7571.053618297713, 7759.650815713419, 7775.339096176888, 8179.550124743197, 8725.65097396097, 8761.649534018568, 8773.166549178073, 8790.824183516423, 8824.691135810215, 7647.464677472523, 7700.460967732205, 7742.187070357531, 7947.801304967422, 7966.203379662219, 7644.923550764421, 7736.806579835549, 7790.298873101448, 7842.196294852102, 7875.724349647735, 6523.934760652332, 6574.671266436755, 6599.043138744951, 6603.372679595431, 6609.041688955171, 4886.609071274218, 4892.363992680928, 5030.673006254582, 5031.899362012666, 5074.82238121664, 4723.858284251478, 4787.234042553175, 4809.735464549483, 4820.903581928272, 4857.222844344888], "value": 4551.186922079993, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.70033670033666, 339.1064544924124, 345.8616553378648, 348.9040513858687, 352.3411221016699, 5238.449962753908, 5321.494458026486, 5326.268962587165, 5338.466153384659, 5363.785448582062, 5236.397814251362, 5241.947580524194, 5252.3980186043245, 5252.6848389096685, 5302.151655735083, 3965.9603403966025, 4034.6368826805583, 4138.793060346986, 4169.353750168729, 4175.0, 4117.505899292089, 4138.723701963923, 4151.60559746825, 4158.688098392618, 4166.229247589191, 3995.3407687731633, 4142.937855932163, 4148.834046638146, 4160.583941605845, 4162.958370416302, 4048.1701251243426, 4054.2702313583545, 4085.468889044435, 4139.668826493873, 4151.3150330195485, 3692.778433294011, 3693.3351996640595, 3704.499677630564, 3715.832787524541, 3725.701943844513, 5132.897342053138, 5152.552537544674, 5171.250168725552, 5184.377874655065, 5191.351081114861, 4514.413126293593, 4723.622110231173, 4741.5495527924795, 4753.382060332135, 4796.904061918742, 5555.138953462179, 5558.833235002957, 5560.2493663355335, 5563.692532254917, 5605.887882242332, 3824.7322687411893, 3975.1056012397266, 3991.6806655467494, 4028.1742242840146, 4045.37296719009, 5743.253377060997, 5748.942510574882, 5752.13717942312, 5752.188060760847, 5778.3354914184565, 3996.9200615988098, 4016.3975403689096, 4024.215278020758, 4030.6170913763144, 4039.2123535910214, 3918.6480664170817, 4087.305158123126, 4102.364133559303, 4108.301588729923, 4140.296149654566, 4695.530446955346, 4701.48283688794, 4711.929321060187, 4726.905461890743, 4728.40894888139, 5594.272744543184, 5604.271444712162, 5613.382527921925, 5635.1828984796975, 5644.463775941279, 1372.9588112356446, 1426.850180731029, 1435.7200345932652, 1440.3446431873472, 1446.2985452055746, 904.3533873280636, 908.7500937242179, 920.9907900921039, 922.727795300388, 1019.403648865422, 5279.973600131974, 5335.519803217729, 5337.466253374709, 5347.786088556492, 5358.732063396815, 5582.24159389799, 5689.573282003867, 5701.2018317435595, 5709.516360383246, 5710.571707121982, 5146.716930568855, 5346.251524786533, 5356.625036247426, 5392.735051278472, 5401.783928642889, 2962.2890506278336, 2983.4033193361415, 3001.4747419201553, 3002.0, 3006.902480594611, 5724.4561766632105, 5756.7121643917635, 5801.796937107181, 5802.0, 5802.941970580325, 5554.389017208104, 5856.180134781134, 5873.618214816023, 5873.619699370644, 5880.441358071062, 5900.409959004065, 5939.613925094855, 5941.168236446901, 5945.40545945402, 6011.609245399035, 5576.470235327637, 5744.195812586157, 5759.712014399347, 5763.740631671669, 5822.592418530662, 5619.971900140473, 5645.830625081254, 5656.5757568181725, 5660.773569057356, 5685.744141513725, 5928.733207005781, 5936.248806602149, 5952.5237980960555, 5977.342492325927, 5985.880282394415, 3871.1676989447406, 4094.6314831665295, 4100.0, 4116.382542618631, 4129.669626429818, 5731.8567035824435, 5754.856128596651, 5758.539316854725, 5790.623609465371, 5817.156512305607, 5396.64921780083, 5569.637973531708, 5608.411116832837, 5660.405657405913, 5666.206731057738, 5540.22436858832, 5570.5543556516195, 5601.355844077983, 5667.631603945731, 5673.602847800615, 5328.653637513549, 5386.569074474111, 5511.724413779375, 5533.170024496358, 5536.889262214652, 5579.442055794388, 5619.0, 5669.688167150846, 5693.893259494013, 5708.857278568069, 5496.917546236889, 5506.834794956158, 5515.420880807618, 5520.1443776214755, 5531.253280807039], "value": 4252.176509635134, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [692.4495026453968, 697.3270793684097, 697.49431662045, 699.2343383994528, 699.2378307644666, 14306.427742890271, 15412.537623871287, 15919.68160636788, 15964.28160732767, 16104.436344727937, 15073.170975596338, 15286.082835029905, 15295.69408611827, 15312.157831319273, 15396.769048464254, 703.9577625342496, 829.4235506323108, 849.7875531117221, 857.1428571428571, 857.5755001274377, 848.0332421039989, 854.1629203380691, 854.5599016506504, 854.7307598106607, 855.9657613695476, 884.3632584539154, 890.5413711938357, 891.8483857744167, 892.3084609427681, 892.9687460938836, 734.8456824066952, 857.3655494933766, 872.358816270041, 887.152769105506, 888.7467071884514, 637.9744810207586, 640.0814830717935, 641.8972964325732, 642.4250295985114, 644.7324360390433, 12854.742905141939, 12922.676933076655, 12984.545540906023, 12997.870021299856, 13019.958403327806, 12427.254364738144, 12727.427265773062, 12891.806622900664, 12954.106135788506, 12956.0, 11332.943335283353, 11437.714057148554, 11627.883721162849, 11658.067354611696, 11801.763964720658, 4347.586979236968, 4359.302511598161, 4408.955910440886, 4434.5343738907595, 4452.777361131963, 50983.490165098614, 51219.0, 51412.74293628508, 51585.0, 51931.74034129843, 9350.95324523373, 9845.212383009486, 10180.236482263712, 10350.06849383544, 10427.791444171075, 18665.720014199796, 18794.90602547006, 18821.90589047046, 18972.146253418352, 19119.330823421114, 8011.839763204704, 8051.0, 8104.878426823602, 8146.75559733209, 8233.34133269337, 4558.430196225472, 4568.862934111982, 4616.469106052779, 4691.061787642485, 4706.129366067288, 3145.5281707743566, 3240.4977228529615, 3242.2866969266724, 3247.594050743657, 3261.951070733941, 573.8049063318492, 621.627023785734, 623.2240860129168, 635.3233805996599, 641.1632819171026, 555.8665920179128, 720.2617317249861, 725.3037084398931, 728.5665029307554, 733.7761982595252, 8665.436746611576, 8861.379703420698, 9353.298502612332, 9355.532223388804, 9457.10157535033, 580.7909152705023, 678.6233640329566, 698.9620413685673, 699.1575151941897, 723.863534251231, 4139.668826493873, 4140.632748190011, 4163.125743593819, 4198.286291330481, 4226.788660566959, 59950.70024649849, 60021.69989150026, 60778.69610652007, 60874.39125608776, 61997.380026200066, 51972.74013629908, 52512.737436313335, 52652.47347526552, 53685.46314536805, 54065.0, 48692.51307486951, 48990.75504622454, 49818.75090624524, 49828.0, 50103.24845127253, 8002.07976082758, 8122.83754324922, 8140.918590814016, 8159.102498725017, 8218.465799722999, 33360.0, 33535.93314206355, 33692.157696057795, 33882.49176262407, 34804.82597587097, 43114.56885431231, 43500.78249608732, 43790.0, 44989.0, 45587.54412455833, 10681.19891008193, 10715.196360272856, 10826.0, 10854.511546980248, 10925.617603383844, 10980.615678451215, 11146.49840757184, 11180.385078820744, 11242.494087765906, 11282.210245282751, 10559.577616895545, 10622.946885265525, 10693.518791654238, 10701.785964280514, 10968.506417211085, 10787.6224332148, 10844.96972787584, 10929.617463388744, 10931.562737490729, 11051.005409513182, 10505.474726263808, 10542.0, 10668.946655266674, 10785.460726963778, 10822.40476773785, 9309.162175404246, 9492.620295188115, 9558.95220523893, 9662.391154388417, 9710.465924374228, 9701.466419347003, 9977.551010204414, 10029.04724051214, 10072.899271007489, 10073.597056117966], "value": 6488.409923624816, "unit": "score"}], "name": "dromaeo_css", "value": 7019.254611350273, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 106.0, 111.0, 110.0, 111.0, 113.0, 111.0, 109.0, 109.0, 112.0], "value": 110.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 132.0, 149.0, 215.0, 128.0, 148.0, 159.0, 126.0, 181.0, 135.0], "value": 152.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 160.0, 160.0, 159.0, 160.0, 163.0, 161.0, 161.0, 164.0, 161.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 75.0, 145.0, 75.0, 75.0, 77.0, 75.0, 135.0, 80.0], "value": 88.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 155.0, 93.0, 92.0, 92.0, 122.0, 92.0, 91.0, 121.0], "value": 104.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 118.0, 116.0, 117.0, 116.0, 116.0, 116.0, 117.0, 116.0, 116.0], "value": 116.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 146.0, 145.0, 145.0, 146.0, 147.0, 145.0, 145.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 96.0, 96.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 57.0, 55.0, 56.0, 55.0, 56.0, 112.0, 54.0, 55.0], "value": 61.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 60.0, 59.0, 58.0, 59.0, 58.0, 58.0, 58.0, 59.0, 58.0], "value": 58.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 78.0, 78.0, 78.0, 79.0, 79.0, 81.0, 78.0, 83.0], "value": 79.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 124.0, 127.0, 130.0, 124.0, 138.0, 122.0, 131.0, 189.0, 126.0], "value": 133.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 165.0, 163.0, 164.0, 163.0, 167.0, 167.0, 164.0, 168.0, 163.0], "value": 165.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 64.0, 62.0, 63.0, 67.0, 66.0, 63.0, 66.0, 62.0], "value": 64.1, "unit": "score"}], "name": "kraken", "value": 1536.6999999999998, "alertThreshold": 2.0}]} 02:26:06 INFO - Return code: 0 02:26:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 02:26:06 ERROR - # TBPL SUCCESS # 02:26:06 INFO - Running post-action listener: _resource_record_post_action 02:26:06 INFO - ##### 02:26:06 INFO - ##### Finished run-tests step (success) 02:26:06 INFO - ##### 02:26:06 INFO - Running post-run listener: _resource_record_post_run 02:26:07 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 4096; Write bytes: 534380544; Read time: 4; Write time: 971632 02:26:07 INFO - TinderboxPrint: CPU usage
13.1% 02:26:07 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 4 02:26:07 INFO - TinderboxPrint: I/O write bytes / time
534,380,544 / 971,632 02:26:07 INFO - TinderboxPrint: CPU idle
8,293.3 (86.9%) 02:26:07 INFO - TinderboxPrint: CPU user
1,199.9 (12.6%) 02:26:07 INFO - TinderboxPrint: Swap in / out
0 / 0 02:26:07 INFO - install - Wall time: 17s; CPU: 16.0%; Read bytes: 0; Write bytes: 244867072; Read time: 0; Write time: 543096 02:26:07 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 4096; Write bytes: 289513472; Read time: 4; Write time: 428536 02:26:07 INFO - Running post-run listener: _upload_blobber_files 02:26:07 INFO - Blob upload gear active. 02:26:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:26:07 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 02:26:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:26:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:26:08 INFO - (blobuploader) - INFO - Open directory for files ... 02:26:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:26:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:26:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:26:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:26:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:26:08 INFO - (blobuploader) - INFO - Done attempting. 02:26:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:26:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:26:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:26:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:26:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:26:09 INFO - (blobuploader) - INFO - Done attempting. 02:26:09 INFO - (blobuploader) - INFO - Iteration through files over. 02:26:09 INFO - Return code: 0 02:26:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:26:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:26:09 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/90ccb0d099adb1a85d69cfa895111ffeaadca5bdf1acbf706b36eef6de0bc7b46f53160efd1ea4309d37d662cbe8f9be516dc9a161ee93d515d17dea2818a21c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2b146b62eddea8edd3fd1fb60aa80fa275bfef8ec48ef7ea977182136bb23d1b13fbbdbd1a69d73fda43eb6cf42e61b3da662b264f455b4ecae10c323857e77c"} 02:26:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:26:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:26:09 INFO - Contents: 02:26:09 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/90ccb0d099adb1a85d69cfa895111ffeaadca5bdf1acbf706b36eef6de0bc7b46f53160efd1ea4309d37d662cbe8f9be516dc9a161ee93d515d17dea2818a21c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2b146b62eddea8edd3fd1fb60aa80fa275bfef8ec48ef7ea977182136bb23d1b13fbbdbd1a69d73fda43eb6cf42e61b3da662b264f455b4ecae10c323857e77c"} 02:26:09 INFO - Running post-run listener: copy_logs_to_upload_dir 02:26:09 INFO - Copying logs to upload dir... 02:26:09 INFO - mkdir: /builds/slave/test/build/upload/logs 02:26:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.989152 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-05-13 02:26:10.004226) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 02:26:10.007977) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1463129991.309154-329193671 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/90ccb0d099adb1a85d69cfa895111ffeaadca5bdf1acbf706b36eef6de0bc7b46f53160efd1ea4309d37d662cbe8f9be516dc9a161ee93d515d17dea2818a21c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2b146b62eddea8edd3fd1fb60aa80fa275bfef8ec48ef7ea977182136bb23d1b13fbbdbd1a69d73fda43eb6cf42e61b3da662b264f455b4ecae10c323857e77c"} build_url:https://queue.taskcluster.net/v1/task/AnmEEez0SgWyvykSnIQOxA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011245 build_url: 'https://queue.taskcluster.net/v1/task/AnmEEez0SgWyvykSnIQOxA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/90ccb0d099adb1a85d69cfa895111ffeaadca5bdf1acbf706b36eef6de0bc7b46f53160efd1ea4309d37d662cbe8f9be516dc9a161ee93d515d17dea2818a21c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2b146b62eddea8edd3fd1fb60aa80fa275bfef8ec48ef7ea977182136bb23d1b13fbbdbd1a69d73fda43eb6cf42e61b3da662b264f455b4ecae10c323857e77c"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 02:26:10.033275) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 02:26:10.033650) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1463129991.309154-329193671 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004433 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 02:26:10.116525) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 02:26:10.116902) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 02:26:10.117212) ========= ========= Total master_lag: 0.18 =========