builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-040 starttime: 1463067875.07 results: success (0) buildid: 20160512053006 builduid: 6b2d49f9d5b94a34aa058938e529741e revision: 092d7ffc5eeed5c0096117fff18638bbda0ebf6e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 08:44:35.070036) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 08:44:35.070493) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 08:44:35.070777) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 08:44:35.086761) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 08:44:35.087021) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1463067874.762792-725412232 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-12 08:44:35-- 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.9M=0s 2016-05-12 08:44:35 (37.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.081874 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 08:44:35.182192) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 08:44:35.182510) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1463067874.762792-725412232 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014858 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 08:44:35.232730) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-12 08:44:35.233075) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 092d7ffc5eeed5c0096117fff18638bbda0ebf6e --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 092d7ffc5eeed5c0096117fff18638bbda0ebf6e --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=9e0dfe3369c33a1556e63f89000001d8-1463067874.762792-725412232 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-12 08:44:35,615 truncating revision to first 12 chars 2016-05-12 08:44:35,615 Setting DEBUG logging. 2016-05-12 08:44:35,615 attempt 1/10 2016-05-12 08:44:35,615 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/092d7ffc5eee?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-12 08:44:36,445 unpacking tar archive at: fx-team-092d7ffc5eee/testing/mozharness/ program finished with exit code 0 elapsedTime=1.123242 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-12 08:44:36.368803) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 08:44:36.369089) ========= script_repo_revision: 092d7ffc5eeed5c0096117fff18638bbda0ebf6e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 08:44:36.369475) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 08:44:36.369758) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 08:44:36.379179) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2016-05-12 08:44:36.379644) ========= /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=9e0dfe3369c33a1556e63f89000001d8-1463067874.762792-725412232 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:44:36 INFO - MultiFileLogger online at 20160512 08:44:36 in /builds/slave/test 08:44:36 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 08:44:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:44:36 INFO - {'append_to_log': False, 08:44:36 INFO - 'base_work_dir': '/builds/slave/test', 08:44:36 INFO - 'blob_upload_branch': 'Fx-Team', 08:44:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:44:36 INFO - 'branch': 'Fx-Team', 08:44:36 INFO - 'buildbot_json_path': 'buildprops.json', 08:44:36 INFO - 'config_files': ('talos/linux_config.py',), 08:44:36 INFO - 'default_actions': ('clobber', 08:44:36 INFO - 'read-buildbot-config', 08:44:36 INFO - 'download-and-extract', 08:44:36 INFO - 'populate-webroot', 08:44:36 INFO - 'create-virtualenv', 08:44:36 INFO - 'install', 08:44:36 INFO - 'run-tests'), 08:44:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:44:36 INFO - 'download_minidump_stackwalk': True, 08:44:36 INFO - 'download_symbols': 'ondemand', 08:44:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:44:36 INFO - 'tooltool.py': '/tools/tooltool.py', 08:44:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:44:36 INFO - '/tools/misc-python/virtualenv.py')}, 08:44:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:44:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:44:36 INFO - 'installer_path': 'installer.exe', 08:44:36 INFO - 'log_level': 'info', 08:44:36 INFO - 'log_name': 'talos', 08:44:36 INFO - 'log_to_console': True, 08:44:36 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:44:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:44:36 INFO - 'opt_config_files': (), 08:44:36 INFO - 'pip_index': False, 08:44:36 INFO - 'sps_profile': False, 08:44:36 INFO - 'sps_profile_interval': 0, 08:44:36 INFO - 'suite': 'dromaeojs', 08:44:36 INFO - 'system_bits': '32', 08:44:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:44:36 INFO - 'title': 'talos-linux64-ix-040', 08:44:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:44:36 INFO - 'use_talos_json': True, 08:44:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:44:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:44:36 INFO - 'work_dir': 'build'} 08:44:36 INFO - ##### 08:44:36 INFO - ##### Running clobber step. 08:44:36 INFO - ##### 08:44:36 INFO - Running pre-action listener: _resource_record_pre_action 08:44:36 INFO - Running main action method: clobber 08:44:36 INFO - rmtree: /builds/slave/test/build 08:44:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:44:37 INFO - Running post-action listener: _resource_record_post_action 08:44:37 INFO - ##### 08:44:37 INFO - ##### Finished clobber step (success) 08:44:37 INFO - ##### 08:44:37 INFO - ##### 08:44:37 INFO - ##### Running read-buildbot-config step. 08:44:37 INFO - ##### 08:44:37 INFO - Running pre-action listener: _resource_record_pre_action 08:44:37 INFO - Running main action method: read_buildbot_config 08:44:37 INFO - Using buildbot properties: 08:44:37 INFO - { 08:44:37 INFO - "project": "", 08:44:37 INFO - "product": "firefox", 08:44:37 INFO - "script_repo_revision": "production", 08:44:37 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 08:44:37 INFO - "repository": "", 08:44:37 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 08:44:37 INFO - "buildid": "20160512053006", 08:44:37 INFO - "pgo_build": "True", 08:44:37 INFO - "basedir": "/builds/slave/test", 08:44:37 INFO - "buildnumber": 1148, 08:44:37 INFO - "slavename": "talos-linux64-ix-040", 08:44:37 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 08:44:37 INFO - "platform": "ubuntu64_hw", 08:44:37 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 08:44:37 INFO - "branch": "fx-team", 08:44:37 INFO - "repo_path": "integration/fx-team", 08:44:37 INFO - "revision": "092d7ffc5eeed5c0096117fff18638bbda0ebf6e", 08:44:37 INFO - "stage_platform": "linux64-pgo", 08:44:37 INFO - "builduid": "6b2d49f9d5b94a34aa058938e529741e", 08:44:37 INFO - "slavebuilddir": "test" 08:44:37 INFO - } 08:44:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/NZAgrqTqQrW3Ge8-1JnRcg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 08:44:37 INFO - Running post-action listener: _resource_record_post_action 08:44:37 INFO - ##### 08:44:37 INFO - ##### Finished read-buildbot-config step (success) 08:44:37 INFO - ##### 08:44:37 INFO - ##### 08:44:37 INFO - ##### Running download-and-extract step. 08:44:37 INFO - ##### 08:44:37 INFO - Running pre-action listener: _resource_record_pre_action 08:44:37 INFO - Running main action method: download_and_extract 08:44:37 INFO - mkdir: /builds/slave/test/build/tests 08:44:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:44:37 INFO - https://queue.taskcluster.net/v1/task/NZAgrqTqQrW3Ge8-1JnRcg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 08:44:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NZAgrqTqQrW3Ge8-1JnRcg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 08:44:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NZAgrqTqQrW3Ge8-1JnRcg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 08:44:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NZAgrqTqQrW3Ge8-1JnRcg/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 08:44:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NZAgrqTqQrW3Ge8-1JnRcg/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 08:44:38 INFO - Downloaded 1361 bytes. 08:44:38 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 08:44:38 INFO - Using the following test package requirements: 08:44:38 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 08:44:38 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:44:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:44:38 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:44:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 08:44:38 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:44:38 INFO - u'jsshell-linux-x86_64.zip'], 08:44:38 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:44:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:44:38 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 08:44:38 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:44:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:44:38 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:44:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:44:38 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:44:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:44:38 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:44:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:44:38 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:44:38 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')]} 08:44:38 INFO - https://queue.taskcluster.net/v1/task/NZAgrqTqQrW3Ge8-1JnRcg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:44:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NZAgrqTqQrW3Ge8-1JnRcg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 08:44:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NZAgrqTqQrW3Ge8-1JnRcg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 08:44:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NZAgrqTqQrW3Ge8-1JnRcg/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 08:44:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NZAgrqTqQrW3Ge8-1JnRcg/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 08:44:39 INFO - Downloaded 23902869 bytes. 08:44:39 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'] 08:44:39 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 08:44:40 INFO - Return code: 0 08:44:40 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 08:44:40 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')]} 08:44:40 INFO - https://queue.taskcluster.net/v1/task/NZAgrqTqQrW3Ge8-1JnRcg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:44:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NZAgrqTqQrW3Ge8-1JnRcg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 08:44:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NZAgrqTqQrW3Ge8-1JnRcg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 08:44:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NZAgrqTqQrW3Ge8-1JnRcg/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 08:44:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NZAgrqTqQrW3Ge8-1JnRcg/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 08:44:42 INFO - Downloaded 23902869 bytes. 08:44:42 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'] 08:44:42 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 08:44:43 INFO - Return code: 0 08:44: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')]} 08:44:43 INFO - https://queue.taskcluster.net/v1/task/NZAgrqTqQrW3Ge8-1JnRcg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 08:44:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NZAgrqTqQrW3Ge8-1JnRcg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 08:44:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NZAgrqTqQrW3Ge8-1JnRcg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 08:44:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NZAgrqTqQrW3Ge8-1JnRcg/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 08:44:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NZAgrqTqQrW3Ge8-1JnRcg/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 08:44:44 INFO - Downloaded 10840239 bytes. 08:44:44 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'] 08:44:44 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 08:44:44 INFO - Return code: 0 08:44: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')]} 08:44:44 INFO - https://queue.taskcluster.net/v1/task/NZAgrqTqQrW3Ge8-1JnRcg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 08:44:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NZAgrqTqQrW3Ge8-1JnRcg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 08:44:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NZAgrqTqQrW3Ge8-1JnRcg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 08:44:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NZAgrqTqQrW3Ge8-1JnRcg/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 08:44:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NZAgrqTqQrW3Ge8-1JnRcg/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 08:44:45 INFO - Downloaded 58336046 bytes. 08:44:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NZAgrqTqQrW3Ge8-1JnRcg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 08:44:45 INFO - mkdir: /builds/slave/test/properties 08:44:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:44:45 INFO - Writing to file /builds/slave/test/properties/build_url 08:44:45 INFO - Contents: 08:44:45 INFO - build_url:https://queue.taskcluster.net/v1/task/NZAgrqTqQrW3Ge8-1JnRcg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 08:44:46 INFO - Running post-action listener: _resource_record_post_action 08:44:46 INFO - Running post-action listener: set_extra_try_arguments 08:44:46 INFO - ##### 08:44:46 INFO - ##### Finished download-and-extract step (success) 08:44:46 INFO - ##### 08:44:46 INFO - ##### 08:44:46 INFO - ##### Running populate-webroot step. 08:44:46 INFO - ##### 08:44:46 INFO - Running pre-action listener: _resource_record_pre_action 08:44:46 INFO - Running main action method: populate_webroot 08:44:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:44:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:44:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:44:46 INFO - '--tppagecycles', 08:44:46 INFO - '7'], 08:44:46 INFO - 'tests': ['tsvgm']}}, 08:44:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:44:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:44:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:44:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:44:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:44:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:44:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:44:46 INFO - 'talos_options': ['--disable-e10s'], 08:44:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:44:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:44:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:44:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:44:46 INFO - 'talos_options': ['--disable-e10s'], 08:44:46 INFO - 'tests': ['damp', 'tps']}, 08:44:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:44:46 INFO - 'tests': ['damp', 'tps']}, 08:44:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:44:46 INFO - 'tests': ['dromaeo_dom']}, 08:44:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:44:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:44:46 INFO - 'tests': ['a11yr', 08:44:46 INFO - 'ts_paint', 08:44:46 INFO - 'tpaint', 08:44:46 INFO - 'sessionrestore', 08:44:46 INFO - 'sessionrestore_no_auto_restore', 08:44:46 INFO - 'tabpaint']}, 08:44:46 INFO - 'other-e10s': {'tests': ['a11yr', 08:44:46 INFO - 'ts_paint', 08:44:46 INFO - 'tpaint', 08:44:46 INFO - 'sessionrestore', 08:44:46 INFO - 'sessionrestore_no_auto_restore', 08:44:46 INFO - 'tabpaint']}, 08:44:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:44:46 INFO - 'ts_paint', 08:44:46 INFO - 'tpaint', 08:44:46 INFO - 'sessionrestore', 08:44:46 INFO - 'sessionrestore_no_auto_restore', 08:44:46 INFO - 'tabpaint']}, 08:44:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:44:46 INFO - 'ts_paint', 08:44:46 INFO - 'tpaint', 08:44:46 INFO - 'sessionrestore', 08:44:46 INFO - 'sessionrestore_no_auto_restore', 08:44:46 INFO - 'tabpaint']}, 08:44:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:44:46 INFO - 'tests': ['a11yr', 08:44:46 INFO - 'ts_paint', 08:44:46 INFO - 'tpaint', 08:44:46 INFO - 'sessionrestore', 08:44:46 INFO - 'sessionrestore_no_auto_restore', 08:44:46 INFO - 'tabpaint']}, 08:44:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:44:46 INFO - 'tests': ['a11yr', 08:44:46 INFO - 'ts_paint', 08:44:46 INFO - 'tpaint', 08:44:46 INFO - 'sessionrestore', 08:44:46 INFO - 'sessionrestore_no_auto_restore', 08:44:46 INFO - 'tabpaint']}, 08:44:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:44:46 INFO - 'tests': ['tsvgx', 08:44:46 INFO - 'tsvgr_opacity', 08:44:46 INFO - 'tart', 08:44:46 INFO - 'tscrollx', 08:44:46 INFO - 'cart']}, 08:44:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:44:46 INFO - 'tsvgr_opacity', 08:44:46 INFO - 'tart', 08:44:46 INFO - 'tscrollx', 08:44:46 INFO - 'cart']}, 08:44:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:44:46 INFO - 'talos_options': ['--disable-e10s'], 08:44:46 INFO - 'tests': ['tp5o']}, 08:44:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:44:46 INFO - 'tests': ['tp5o']}, 08:44:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:44:46 INFO - 'talos_options': ['--disable-e10s', 08:44:46 INFO - '--xperf_path', 08:44:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:44:46 INFO - 'tests': ['tp5n']}, 08:44:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:44:46 INFO - 'talos_options': ['--xperf_path', 08:44:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:44:46 INFO - 'tests': ['tp5n']}}, 08:44:46 INFO - 'talos.zip': {'path': '', 08:44:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:44:46 INFO - Running post-action listener: _resource_record_post_action 08:44:46 INFO - ##### 08:44:46 INFO - ##### Finished populate-webroot step (success) 08:44:46 INFO - ##### 08:44:46 INFO - ##### 08:44:46 INFO - ##### Running create-virtualenv step. 08:44:46 INFO - ##### 08:44:46 INFO - Running pre-action listener: _resource_record_pre_action 08:44:46 INFO - Running main action method: create_virtualenv 08:44:46 INFO - Creating virtualenv /builds/slave/test/build/venv 08:44: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 08:44:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:44:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:44:47 INFO - Using real prefix '/usr' 08:44:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:44:47 INFO - Installing distribute.............................................................................................................................................................................................done. 08:44:49 INFO - Installing pip.................done. 08:44:49 INFO - Return code: 0 08:44:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:44: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')]} 08:44:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44: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')]} 08:44:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f42512a7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2849770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d89c0>, '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 0x28d98c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28d9d50>, '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': '9e0dfe3369c33a1556e63f89000001d8-1463067874.762792-725412232', '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 08:44: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', 'pip>=1.5'] in /builds/slave/test/build 08:44: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 pip>=1.5 08:44:49 INFO - Using env: {'DISPLAY': ':0', 08:44:49 INFO - 'HOME': '/home/cltbld', 08:44:49 INFO - 'LANG': 'en_US.UTF-8', 08:44:49 INFO - 'LANGUAGE': 'en_US:en', 08:44:49 INFO - 'LOGNAME': 'cltbld', 08:44:49 INFO - 'MAIL': '/var/mail/cltbld', 08:44:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:49 INFO - 'MOZ_NO_REMOTE': '1', 08:44:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:44:49 INFO - 'NO_EM_RESTART': '1', 08:44:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:44:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:49 INFO - 'PWD': '/builds/slave/test', 08:44:49 INFO - 'SHELL': '/bin/bash', 08:44:49 INFO - 'SHLVL': '1', 08:44:49 INFO - 'TERM': 'linux', 08:44:49 INFO - 'TMOUT': '86400', 08:44:49 INFO - 'USER': 'cltbld', 08:44:49 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1463067874.762792-725412232', 08:44:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:49 INFO - '_': '/tools/buildbot/bin/python'} 08:44:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44: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 08:44:49 INFO - Cleaning up... 08:44:49 INFO - Return code: 0 08:44:49 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 08:44: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')]} 08:44:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44: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')]} 08:44:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44: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 0x7f42512a7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2849770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d89c0>, '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 0x28d98c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28d9d50>, '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': '9e0dfe3369c33a1556e63f89000001d8-1463067874.762792-725412232', '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 08:44: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 08:44: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 08:44:49 INFO - Using env: {'DISPLAY': ':0', 08:44:49 INFO - 'HOME': '/home/cltbld', 08:44:49 INFO - 'LANG': 'en_US.UTF-8', 08:44:49 INFO - 'LANGUAGE': 'en_US:en', 08:44:49 INFO - 'LOGNAME': 'cltbld', 08:44:49 INFO - 'MAIL': '/var/mail/cltbld', 08:44:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:49 INFO - 'MOZ_NO_REMOTE': '1', 08:44:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:44:49 INFO - 'NO_EM_RESTART': '1', 08:44:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:44:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:49 INFO - 'PWD': '/builds/slave/test', 08:44:49 INFO - 'SHELL': '/bin/bash', 08:44:49 INFO - 'SHLVL': '1', 08:44:49 INFO - 'TERM': 'linux', 08:44:49 INFO - 'TMOUT': '86400', 08:44:49 INFO - 'USER': 'cltbld', 08:44:49 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1463067874.762792-725412232', 08:44:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:49 INFO - '_': '/tools/buildbot/bin/python'} 08:44:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:49 INFO - Downloading/unpacking psutil>=0.7.1 08:44: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 08:44: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 08:44: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 08:44: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 08:44: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 08:44: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 08:44:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:44:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:44:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:44:51 INFO - Installing collected packages: psutil 08:44:51 INFO - Running setup.py install for psutil 08:44:51 INFO - building 'psutil._psutil_linux' extension 08:44: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 08:44:52 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 08:44:52 INFO - building 'psutil._psutil_posix' extension 08:44:52 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 08:44:52 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 08:44:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:44:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:44:52 INFO - Successfully installed psutil 08:44:52 INFO - Cleaning up... 08:44:52 INFO - Return code: 0 08:44:52 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 08:44:52 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')]} 08:44:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:52 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')]} 08:44:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:52 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 0x7f42512a7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2849770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d89c0>, '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 0x28d98c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28d9d50>, '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': '9e0dfe3369c33a1556e63f89000001d8-1463067874.762792-725412232', '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 08:44:52 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 08:44:52 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 08:44:52 INFO - Using env: {'DISPLAY': ':0', 08:44:52 INFO - 'HOME': '/home/cltbld', 08:44:52 INFO - 'LANG': 'en_US.UTF-8', 08:44:52 INFO - 'LANGUAGE': 'en_US:en', 08:44:52 INFO - 'LOGNAME': 'cltbld', 08:44:52 INFO - 'MAIL': '/var/mail/cltbld', 08:44:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:52 INFO - 'MOZ_NO_REMOTE': '1', 08:44:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:44:52 INFO - 'NO_EM_RESTART': '1', 08:44:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:44:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:52 INFO - 'PWD': '/builds/slave/test', 08:44:52 INFO - 'SHELL': '/bin/bash', 08:44:52 INFO - 'SHLVL': '1', 08:44:52 INFO - 'TERM': 'linux', 08:44:52 INFO - 'TMOUT': '86400', 08:44:52 INFO - 'USER': 'cltbld', 08:44:52 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1463067874.762792-725412232', 08:44:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:52 INFO - '_': '/tools/buildbot/bin/python'} 08:44:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:52 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 08:44: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 08:44: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 08:44: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 08:44: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 08:44: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 08:44: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 08:44:54 INFO - Downloading mozsystemmonitor-0.0.tar.gz 08:44:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:44:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 08:44:54 INFO - Installing collected packages: mozsystemmonitor 08:44:54 INFO - Running setup.py install for mozsystemmonitor 08:44:54 INFO - Successfully installed mozsystemmonitor 08:44:54 INFO - Cleaning up... 08:44:54 INFO - Return code: 0 08:44:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:44: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')]} 08:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44: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')]} 08:44:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44: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 0x7f42512a7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2849770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d89c0>, '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 0x28d98c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28d9d50>, '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': '9e0dfe3369c33a1556e63f89000001d8-1463067874.762792-725412232', '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 08:44: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 08:44: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 08:44:54 INFO - Using env: {'DISPLAY': ':0', 08:44:54 INFO - 'HOME': '/home/cltbld', 08:44:54 INFO - 'LANG': 'en_US.UTF-8', 08:44:54 INFO - 'LANGUAGE': 'en_US:en', 08:44:54 INFO - 'LOGNAME': 'cltbld', 08:44:54 INFO - 'MAIL': '/var/mail/cltbld', 08:44:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:54 INFO - 'MOZ_NO_REMOTE': '1', 08:44:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:44:54 INFO - 'NO_EM_RESTART': '1', 08:44:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:44:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:54 INFO - 'PWD': '/builds/slave/test', 08:44:54 INFO - 'SHELL': '/bin/bash', 08:44:54 INFO - 'SHLVL': '1', 08:44:54 INFO - 'TERM': 'linux', 08:44:54 INFO - 'TMOUT': '86400', 08:44:54 INFO - 'USER': 'cltbld', 08:44:54 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1463067874.762792-725412232', 08:44:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:54 INFO - '_': '/tools/buildbot/bin/python'} 08:44:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:54 INFO - Downloading/unpacking blobuploader==1.2.4 08:44: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 08:44: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 08:44: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 08:44: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 08:44: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 08:44: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 08:44:56 INFO - Downloading blobuploader-1.2.4.tar.gz 08:44:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:44:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:44: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 08:44: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 08:44: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 08:44: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 08:44: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 08:44: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 08:44:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:44:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:44:57 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 08:44:57 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 08:44:57 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 08:44:57 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 08:44:57 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 08:44:57 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 08:44:57 INFO - Downloading docopt-0.6.1.tar.gz 08:44:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:44:57 INFO - Installing collected packages: blobuploader, requests, docopt 08:44:57 INFO - Running setup.py install for blobuploader 08:44:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:44:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:44:57 INFO - Running setup.py install for requests 08:44:58 INFO - Running setup.py install for docopt 08:44:58 INFO - Successfully installed blobuploader requests docopt 08:44:58 INFO - Cleaning up... 08:44:58 INFO - Return code: 0 08:44:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:44:58 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')]} 08:44:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:58 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')]} 08:44:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:58 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 0x7f42512a7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2849770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d89c0>, '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 0x28d98c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28d9d50>, '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': '9e0dfe3369c33a1556e63f89000001d8-1463067874.762792-725412232', '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 08:44:58 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 08:44:58 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 08:44:58 INFO - Using env: {'DISPLAY': ':0', 08:44:58 INFO - 'HOME': '/home/cltbld', 08:44:58 INFO - 'LANG': 'en_US.UTF-8', 08:44:58 INFO - 'LANGUAGE': 'en_US:en', 08:44:58 INFO - 'LOGNAME': 'cltbld', 08:44:58 INFO - 'MAIL': '/var/mail/cltbld', 08:44:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:58 INFO - 'MOZ_NO_REMOTE': '1', 08:44:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:44:58 INFO - 'NO_EM_RESTART': '1', 08:44:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:44:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:58 INFO - 'PWD': '/builds/slave/test', 08:44:58 INFO - 'SHELL': '/bin/bash', 08:44:58 INFO - 'SHLVL': '1', 08:44:58 INFO - 'TERM': 'linux', 08:44:58 INFO - 'TMOUT': '86400', 08:44:58 INFO - 'USER': 'cltbld', 08:44:58 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1463067874.762792-725412232', 08:44:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:58 INFO - '_': '/tools/buildbot/bin/python'} 08:44:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:44:58 INFO - Running setup.py (path:/tmp/pip-lhjPl7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:44:58 INFO - Running setup.py (path:/tmp/pip-sV29fa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:44:58 INFO - Running setup.py (path:/tmp/pip-MNe2IJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:44:58 INFO - Running setup.py (path:/tmp/pip-pKrGKX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:44:58 INFO - Running setup.py (path:/tmp/pip-ydfdnl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:44:58 INFO - Running setup.py (path:/tmp/pip-RBwdGM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:44:58 INFO - Running setup.py (path:/tmp/pip-L9Gt0w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:44:58 INFO - Running setup.py (path:/tmp/pip-WjWQf6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:44:58 INFO - Running setup.py (path:/tmp/pip-C5r5XR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:44:59 INFO - Running setup.py (path:/tmp/pip-kw7Ahb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:44:59 INFO - Running setup.py (path:/tmp/pip-R9BcGx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:44:59 INFO - Running setup.py (path:/tmp/pip-4vL8dp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:44:59 INFO - Running setup.py (path:/tmp/pip-DwH3c9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:44:59 INFO - Running setup.py (path:/tmp/pip-bL88CO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:44:59 INFO - Running setup.py (path:/tmp/pip-PTZiQN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:44:59 INFO - Running setup.py (path:/tmp/pip-wKDpfE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:44:59 INFO - Running setup.py (path:/tmp/pip-QrXP5k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:44:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:44:59 INFO - Running setup.py install for manifestparser 08:44:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:44:59 INFO - Running setup.py install for mozcrash 08:44:59 INFO - Running setup.py install for mozdebug 08:44:59 INFO - Running setup.py install for mozdevice 08:45:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:45:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:45:00 INFO - Running setup.py install for mozfile 08:45:00 INFO - Running setup.py install for mozhttpd 08:45:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:45:00 INFO - Running setup.py install for mozinfo 08:45:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:45:00 INFO - Running setup.py install for mozInstall 08:45:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:45:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:45:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:45:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:45:00 INFO - Running setup.py install for mozleak 08:45:00 INFO - Running setup.py install for mozlog 08:45:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:45:00 INFO - Running setup.py install for moznetwork 08:45:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:45:00 INFO - Running setup.py install for mozprocess 08:45:00 INFO - Running setup.py install for mozprofile 08:45:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:45:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:45:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:45:00 INFO - Running setup.py install for mozrunner 08:45:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:45:01 INFO - Running setup.py install for mozscreenshot 08:45:01 INFO - Running setup.py install for moztest 08:45:01 INFO - Running setup.py install for mozversion 08:45:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:45:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:45:01 INFO - Cleaning up... 08:45:01 INFO - Return code: 0 08:45:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:45:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:45:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:45:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:01 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 0x7f42512a7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2849770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d89c0>, '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 0x28d98c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28d9d50>, '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': '9e0dfe3369c33a1556e63f89000001d8-1463067874.762792-725412232', '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 08:45:01 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 08:45:01 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 08:45:01 INFO - Using env: {'DISPLAY': ':0', 08:45:01 INFO - 'HOME': '/home/cltbld', 08:45:01 INFO - 'LANG': 'en_US.UTF-8', 08:45:01 INFO - 'LANGUAGE': 'en_US:en', 08:45:01 INFO - 'LOGNAME': 'cltbld', 08:45:01 INFO - 'MAIL': '/var/mail/cltbld', 08:45:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:01 INFO - 'MOZ_NO_REMOTE': '1', 08:45:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:45:01 INFO - 'NO_EM_RESTART': '1', 08:45:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:45:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:01 INFO - 'PWD': '/builds/slave/test', 08:45:01 INFO - 'SHELL': '/bin/bash', 08:45:01 INFO - 'SHLVL': '1', 08:45:01 INFO - 'TERM': 'linux', 08:45:01 INFO - 'TMOUT': '86400', 08:45:01 INFO - 'USER': 'cltbld', 08:45:01 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1463067874.762792-725412232', 08:45:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:01 INFO - '_': '/tools/buildbot/bin/python'} 08:45:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:45:01 INFO - Running setup.py (path:/tmp/pip-rMkZyv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:45: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)) 08:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:45:01 INFO - Running setup.py (path:/tmp/pip-oJDQjt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:45: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)) 08:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:45:01 INFO - Running setup.py (path:/tmp/pip-ZX_dR_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:45: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)) 08:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:45:01 INFO - Running setup.py (path:/tmp/pip-GIEvEF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:45: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)) 08:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:45:01 INFO - Running setup.py (path:/tmp/pip-BowUYD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:45: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)) 08:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:45:01 INFO - Running setup.py (path:/tmp/pip-JZIr9d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:45:02 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)) 08:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:45:02 INFO - Running setup.py (path:/tmp/pip-6wP7gX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:45:02 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)) 08:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:45:02 INFO - Running setup.py (path:/tmp/pip-Va1Owo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:45:02 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)) 08:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:45:02 INFO - Running setup.py (path:/tmp/pip-FDuFAY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:45:02 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)) 08:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:45:02 INFO - Running setup.py (path:/tmp/pip-aZLtTd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:45:02 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)) 08:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:45:02 INFO - Running setup.py (path:/tmp/pip-SeB1wf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:45:02 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)) 08:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:45:02 INFO - Running setup.py (path:/tmp/pip-3jFjXR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:45:02 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)) 08:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:45:02 INFO - Running setup.py (path:/tmp/pip-syzhBC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:45:02 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)) 08:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:45:02 INFO - Running setup.py (path:/tmp/pip-a1HqTQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:45:02 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)) 08:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:45:02 INFO - Running setup.py (path:/tmp/pip-tsdmL0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:45: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)) 08:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:45:02 INFO - Running setup.py (path:/tmp/pip-whu79f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:45: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)) 08:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:45:02 INFO - Running setup.py (path:/tmp/pip-ZZNuIL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:45: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)) 08:45: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)) 08:45: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)) 08:45: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)) 08:45: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)) 08:45: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)) 08:45:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:45: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 08:45: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 08:45: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 08:45: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 08:45: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 08:45: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 08:45:04 INFO - Downloading blessings-1.6.tar.gz 08:45:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:45:04 INFO - Installing collected packages: blessings 08:45:04 INFO - Running setup.py install for blessings 08:45:04 INFO - Successfully installed blessings 08:45:04 INFO - Cleaning up... 08:45:04 INFO - Return code: 0 08:45:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:45:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:45:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:45:05 INFO - Reading from file tmpfile_stdout 08:45:05 INFO - Current package versions: 08:45:05 INFO - argparse == 1.2.1 08:45:05 INFO - blessings == 1.6 08:45:05 INFO - blobuploader == 1.2.4 08:45:05 INFO - docopt == 0.6.1 08:45:05 INFO - manifestparser == 1.1 08:45:05 INFO - mozInstall == 1.12 08:45:05 INFO - mozcrash == 0.17 08:45:05 INFO - mozdebug == 0.1 08:45:05 INFO - mozdevice == 0.48 08:45:05 INFO - mozfile == 1.2 08:45:05 INFO - mozhttpd == 0.7 08:45:05 INFO - mozinfo == 0.9 08:45:05 INFO - mozleak == 0.1 08:45:05 INFO - mozlog == 3.2 08:45:05 INFO - moznetwork == 0.27 08:45:05 INFO - mozprocess == 0.22 08:45:05 INFO - mozprofile == 0.28 08:45:05 INFO - mozrunner == 6.11 08:45:05 INFO - mozscreenshot == 0.1 08:45:05 INFO - mozsystemmonitor == 0.0 08:45:05 INFO - moztest == 0.7 08:45:05 INFO - mozversion == 1.4 08:45:05 INFO - psutil == 3.1.1 08:45:05 INFO - requests == 1.2.3 08:45:05 INFO - wsgiref == 0.1.2 08:45:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:45:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:45:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:45:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:05 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 0x7f42512a7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2849770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d89c0>, '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 0x28d98c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28d9d50>, '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': '9e0dfe3369c33a1556e63f89000001d8-1463067874.762792-725412232', '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 08:45:05 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 08:45:05 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 08:45:05 INFO - Using env: {'DISPLAY': ':0', 08:45:05 INFO - 'HOME': '/home/cltbld', 08:45:05 INFO - 'LANG': 'en_US.UTF-8', 08:45:05 INFO - 'LANGUAGE': 'en_US:en', 08:45:05 INFO - 'LOGNAME': 'cltbld', 08:45:05 INFO - 'MAIL': '/var/mail/cltbld', 08:45:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:05 INFO - 'MOZ_NO_REMOTE': '1', 08:45:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:45:05 INFO - 'NO_EM_RESTART': '1', 08:45:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:45:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:05 INFO - 'PWD': '/builds/slave/test', 08:45:05 INFO - 'SHELL': '/bin/bash', 08:45:05 INFO - 'SHLVL': '1', 08:45:05 INFO - 'TERM': 'linux', 08:45:05 INFO - 'TMOUT': '86400', 08:45:05 INFO - 'USER': 'cltbld', 08:45:05 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1463067874.762792-725412232', 08:45:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:05 INFO - '_': '/tools/buildbot/bin/python'} 08:45:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:05 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)) 08:45:05 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)) 08:45:05 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)) 08:45:05 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)) 08:45:05 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)) 08:45:05 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)) 08:45:05 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)) 08:45:05 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)) 08:45:05 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)) 08:45:05 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)) 08:45:05 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)) 08:45:05 INFO - Cleaning up... 08:45:05 INFO - Return code: 0 08:45:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:45:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:45:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:45:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:05 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 0x7f42512a7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2849770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d89c0>, '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 0x28d98c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28d9d50>, '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': '9e0dfe3369c33a1556e63f89000001d8-1463067874.762792-725412232', '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 08:45:05 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 08:45:05 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 08:45:05 INFO - Using env: {'DISPLAY': ':0', 08:45:05 INFO - 'HOME': '/home/cltbld', 08:45:05 INFO - 'LANG': 'en_US.UTF-8', 08:45:05 INFO - 'LANGUAGE': 'en_US:en', 08:45:05 INFO - 'LOGNAME': 'cltbld', 08:45:05 INFO - 'MAIL': '/var/mail/cltbld', 08:45:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:05 INFO - 'MOZ_NO_REMOTE': '1', 08:45:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:45:05 INFO - 'NO_EM_RESTART': '1', 08:45:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:45:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:05 INFO - 'PWD': '/builds/slave/test', 08:45:05 INFO - 'SHELL': '/bin/bash', 08:45:05 INFO - 'SHLVL': '1', 08:45:05 INFO - 'TERM': 'linux', 08:45:05 INFO - 'TMOUT': '86400', 08:45:05 INFO - 'USER': 'cltbld', 08:45:05 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1463067874.762792-725412232', 08:45:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:05 INFO - '_': '/tools/buildbot/bin/python'} 08:45:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:05 INFO - Downloading/unpacking jsonschema 08:45:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:45:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:45:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:45:07 INFO - Downloading/unpacking functools32 (from jsonschema) 08:45: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 08:45: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 08:45: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 08:45: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 08:45: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 08:45: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 08:45:07 INFO - Downloading functools32-3.2.3-2.tar.gz 08:45:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:45:07 INFO - warning: no files found matching '*.txt' 08:45:07 INFO - no previously-included directories found matching 'build' 08:45:07 INFO - no previously-included directories found matching 'dist' 08:45:07 INFO - no previously-included directories found matching '.git*' 08:45:07 INFO - Installing collected packages: jsonschema, functools32 08:45:07 INFO - Running setup.py install for functools32 08:45:07 INFO - warning: no files found matching '*.txt' 08:45:07 INFO - no previously-included directories found matching 'build' 08:45:07 INFO - no previously-included directories found matching 'dist' 08:45:07 INFO - no previously-included directories found matching '.git*' 08:45:07 INFO - Successfully installed jsonschema functools32 08:45:07 INFO - Cleaning up... 08:45:07 INFO - Return code: 0 08:45:07 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 08:45: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')]} 08:45:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45: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')]} 08:45:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45: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 0x7f42512a7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2849770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28d89c0>, '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 0x28d98c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28d9d50>, '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': '9e0dfe3369c33a1556e63f89000001d8-1463067874.762792-725412232', '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 08:45: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 08:45: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 08:45:07 INFO - Using env: {'DISPLAY': ':0', 08:45:07 INFO - 'HOME': '/home/cltbld', 08:45:07 INFO - 'LANG': 'en_US.UTF-8', 08:45:07 INFO - 'LANGUAGE': 'en_US:en', 08:45:07 INFO - 'LOGNAME': 'cltbld', 08:45:07 INFO - 'MAIL': '/var/mail/cltbld', 08:45:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:07 INFO - 'MOZ_NO_REMOTE': '1', 08:45:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:45:07 INFO - 'NO_EM_RESTART': '1', 08:45:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:45:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:07 INFO - 'PWD': '/builds/slave/test', 08:45:07 INFO - 'SHELL': '/bin/bash', 08:45:07 INFO - 'SHLVL': '1', 08:45:07 INFO - 'TERM': 'linux', 08:45:07 INFO - 'TMOUT': '86400', 08:45:07 INFO - 'USER': 'cltbld', 08:45:07 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1463067874.762792-725412232', 08:45:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:07 INFO - '_': '/tools/buildbot/bin/python'} 08:45:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:08 INFO - Downloading/unpacking flake8 08:45:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:45:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:45:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:09 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:45:10 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:45:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:45:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:45:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:10 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:45:10 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:45:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:45:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:45:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:10 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:45:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:45:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:45:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 08:45:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:45:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:45:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:45:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:45:10 INFO - no previously-included directories found matching 'docs/_build' 08:45:10 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:45:10 INFO - Running setup.py install for pep8 08:45:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:45:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:45:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:45:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:45:10 INFO - no previously-included directories found matching 'docs/_build' 08:45:10 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 08:45:10 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:45:10 INFO - Cleaning up... 08:45:10 INFO - Return code: 0 08:45:10 INFO - Running post-action listener: _resource_record_post_action 08:45:10 INFO - Running post-action listener: _start_resource_monitoring 08:45:10 INFO - Starting resource monitoring. 08:45:11 INFO - ##### 08:45:11 INFO - ##### Finished create-virtualenv step (success) 08:45:11 INFO - ##### 08:45:11 INFO - ##### 08:45:11 INFO - ##### Running install step. 08:45:11 INFO - ##### 08:45:11 INFO - Running pre-action listener: _resource_record_pre_action 08:45:11 INFO - Running main action method: install 08:45:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:45:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:45:11 INFO - Reading from file tmpfile_stdout 08:45:11 INFO - Detecting whether we're running mozinstall >=1.0... 08:45:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:45:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:45:11 INFO - Reading from file tmpfile_stdout 08:45:11 INFO - Output received: 08:45:11 INFO - Usage: mozinstall [options] installer 08:45:11 INFO - Options: 08:45:11 INFO - -h, --help show this help message and exit 08:45:11 INFO - -d DEST, --destination=DEST 08:45:11 INFO - Directory to install application into. [default: 08:45:11 INFO - "/builds/slave/test"] 08:45:11 INFO - --app=APP Application being installed. [default: firefox] 08:45:11 INFO - mkdir: /builds/slave/test/build/application 08:45:11 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'] 08:45:11 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 08:45:27 INFO - Reading from file tmpfile_stdout 08:45:27 INFO - Output received: 08:45:27 INFO - /builds/slave/test/build/application/firefox/firefox 08:45:27 INFO - Running post-action listener: _resource_record_post_action 08:45:27 INFO - ##### 08:45:27 INFO - ##### Finished install step (success) 08:45:27 INFO - ##### 08:45:27 INFO - ##### 08:45:27 INFO - ##### Running run-tests step. 08:45:27 INFO - ##### 08:45:27 INFO - Running pre-action listener: _resource_record_pre_action 08:45:27 INFO - Running main action method: run_tests 08:45:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:45:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:45:27 INFO - Python 2.7.3 08:45:27 INFO - Return code: 0 08:45:27 INFO - grabbing minidump binary from tooltool 08:45: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')]} 08:45: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 0x28d89c0>, '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 0x28d98c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28d9d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:45: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 08:45: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 08:45: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 08:45:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:45:27 INFO - Return code: 0 08:45:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:45:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:45:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:45:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:45:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:45:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 08:45:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 08:45:28 INFO - Return code: 0 08:45: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/NZAgrqTqQrW3Ge8-1JnRcg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--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 08:45: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/NZAgrqTqQrW3Ge8-1JnRcg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-040 --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 08:45:28 INFO - Using env: {'DISPLAY': ':0', 08:45:28 INFO - 'HOME': '/home/cltbld', 08:45:28 INFO - 'LANG': 'en_US.UTF-8', 08:45:28 INFO - 'LANGUAGE': 'en_US:en', 08:45:28 INFO - 'LOGNAME': 'cltbld', 08:45:28 INFO - 'MAIL': '/var/mail/cltbld', 08:45:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:45:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:45:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:28 INFO - 'MOZ_NO_REMOTE': '1', 08:45:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:45:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:45:28 INFO - 'NO_EM_RESTART': '1', 08:45:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:45:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:28 INFO - 'PWD': '/builds/slave/test', 08:45:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:45:28 INFO - 'SHELL': '/bin/bash', 08:45:28 INFO - 'SHLVL': '1', 08:45:28 INFO - 'TERM': 'linux', 08:45:28 INFO - 'TMOUT': '86400', 08:45:28 INFO - 'USER': 'cltbld', 08:45:28 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1463067874.762792-725412232', 08:45:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:28 INFO - '_': '/tools/buildbot/bin/python'} 08:45: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/NZAgrqTqQrW3Ge8-1JnRcg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--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 08:45:29 INFO - mozversion application_buildid: 20160512053006 08:45:29 INFO - mozversion application_changeset: 092d7ffc5eeed5c0096117fff18638bbda0ebf6e 08:45:29 INFO - mozversion application_display_name: Nightly 08:45:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:45:29 INFO - mozversion application_name: Firefox 08:45:29 INFO - mozversion application_remotingname: firefox 08:45:29 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 08:45:29 INFO - mozversion application_vendor: Mozilla 08:45:29 INFO - mozversion application_version: 49.0a1 08:45:29 INFO - mozversion platform_buildid: 20160512053006 08:45:29 INFO - mozversion platform_changeset: 092d7ffc5eeed5c0096117fff18638bbda0ebf6e 08:45:29 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 08:45:29 INFO - mozversion platform_version: 49.0a1 08:45:29 INFO - using testdate: 1463067929 08:45:29 INFO - actual date: 1463067929 08:45:29 INFO - starting webserver on 'localhost:47194' 08:45:29 INFO - SUITE-START | Running 2 tests 08:45:29 INFO - TEST-START | dromaeo_css 08:45:29 INFO - operating with platform_type : linux_ 08:45:29 INFO - Initialising browser for dromaeo_css test... 08:45:29 INFO - TEST-INFO | started process 7064 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9JGQHt/profile http://localhost:47194/getInfo.html) 08:45:33 INFO - PROCESS | 7064 | __metrics Screen width/height:1600/1200 08:45:33 INFO - PROCESS | 7064 | colorDepth:24 08:45:33 INFO - PROCESS | 7064 | Browser inner width/height: 1024/697 08:45:33 INFO - PROCESS | 7064 | __metrics 08:45:34 ERROR - PROCESS | 7064 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:45:35 INFO - TEST-INFO | 7064: exit 0 08:45:35 INFO - Browser initialized. 08:45:35 INFO - Running cycle 1/1 for dromaeo_css test... 08:45:35 INFO - TEST-INFO | started process 7148 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9JGQHt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:45:46 INFO - PROCESS | 7148 | RSS: Main: 152809472 08:45:46 INFO - PROCESS | 7148 | 08:46:38 INFO - PROCESS | 7148 | 1463067998159 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] 08:48:45 INFO - PROCESS | 7148 | Cycle 1(1): loaded http://localhost:47194/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47194/tests/dromaeo/cssquery-ext.html) 08:48:47 INFO - PROCESS | 7148 | RSS: Main: 238403584 08:48:47 INFO - PROCESS | 7148 | 08:51:49 INFO - PROCESS | 7148 | Cycle 1(1): loaded http://localhost:47194/tests/dromaeo/cssquery-ext.html (next: http://localhost:47194/tests/dromaeo/cssquery-jquery.html) 08:51:50 INFO - PROCESS | 7148 | RSS: Main: 236933120 08:51:50 INFO - PROCESS | 7148 | 08:54:51 INFO - PROCESS | 7148 | Cycle 1(1): loaded http://localhost:47194/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47194/tests/dromaeo/cssquery-mootools.html) 08:54:52 INFO - PROCESS | 7148 | RSS: Main: 241385472 08:54:52 INFO - PROCESS | 7148 | 08:57:54 INFO - PROCESS | 7148 | Cycle 1(1): loaded http://localhost:47194/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47194/tests/dromaeo/cssquery-prototype.html) 08:57:55 INFO - PROCESS | 7148 | RSS: Main: 229769216 08:57:55 INFO - PROCESS | 7148 | 09:00:57 INFO - PROCESS | 7148 | Cycle 1(1): loaded http://localhost:47194/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47194/tests/dromaeo/cssquery-yui.html) 09:00:58 INFO - PROCESS | 7148 | RSS: Main: 221790208 09:00:58 INFO - PROCESS | 7148 | 09:04:00 INFO - PROCESS | 7148 | Cycle 1(1): loaded http://localhost:47194/tests/dromaeo/cssquery-yui.html (next: http://localhost:47194/tests/dromaeo/cssquery-dojo.html) 09:04:01 INFO - PROCESS | 7148 | RSS: Main: 265777152 09:04:01 INFO - PROCESS | 7148 | 09:04:01 INFO - PROCESS | 7148 | __start_tp_report 09:04:01 INFO - PROCESS | 7148 | _x_x_mozilla_page_load 09:04:01 INFO - PROCESS | 7148 | _x_x_mozilla_page_load_details 09:04:01 INFO - PROCESS | 7148 | |i|pagename|runs| 09:04:01 INFO - PROCESS | 7148 | |0;dojo.html;3115.8909438169667;3166.240102375429;3209.069369882734;3212.4538828399163;3258.1203075169706;5268.92096618551;5325.387580428252;5364.624476286662;5397.730113494319;5464.754086066132;5242.68543887367;5292.285541451903;5296.443873393296;5298.867528311795;5314.441983591715;4769.666123371374;4879.6828206166665;4881.170201065827;4888.902221955559;5115.335006449175;5020.322256495363;5025.824096156635;5045.34410526631;5057.721825299607;5096.923546146811;4818.036392721452;4823.13183626947;4845.53967373099;4880.682755620891;4882;4890.633202509826;4893.902121957541;4895.632827537914;4906.877328066791;4940.728259945701;4827.85516434504;4881.1213981483315;4894.290327902434;4899.632527560448;4951.009798040406;5249.396319423277;5261.684298942076;5295.735213239323;5317.54800841925;5389.892202155935;5816.243888294494;5822.155787410802;5873.383294754075;5873.530117590582;5877.85305367365;5671.2060311556415;5887.175795388649;5918.704064796743;5928.318243402021;5944.078667806453;5414.160805075219;5415.675059496442;5447;5508.862278443071;5524.392316845144;6007.729652165663;6012.068129439944;6012.759489620366;6014.428629280212;6017.969910150421;5578.41426650204;5602.1036634138345;5731.910936922055;5776.046952252916;5777.62445441045;5745.2818397700285;5799.420057994167;5802.883942321215;5806.5645076618575;5851;5728.656280623092;5762.078067509178;5770.221020162225;5772.567057470623;5784.942150578525;5593.74828132735;5635.887282254332;5691.231683722644;5742.138679198154;5790.044642634002;4366.170427618742;4381.123775244963;4383.364412160219;4418.072204836956;4420.027593929331;3901.51231096113;3920.8235629396745;3921.156951255494;3935.0752573145287;3945;5386.084365657847;5394.32570928634;5435.7825686972865;5450.8909821803345;5481.808136715196;5753.51095156908;5762.510186634099;5784.450477204743;5787.768489260467;5820.912686309709;5449.6730196188355;5456.372517160498;5458.181272809111;5460;5469.835904922858;4873.6832105913;4901.926471102936;4902.2646603009125;4909.386326709161;4993.251012348177;5931.347551769302;5933.169356290123;5937.614055086406;5942.138389933435;5945.494632956161;5659.717014149359;5681.318241811009;5682.119271512922;5686.289213848269;5735.713214339183;5782.219400381063;5932;6033.939660603513;6048.274207095177;6136.20229370179;5762.2221000164445;5789.652620842763;5814.273215848019;5818.069108942549;5852.712403271274;5855.00464920956;5889.469947704727;5901.645901245939;5904.822855314347;5911.1428842816695;5575.609707320448;5633.0987042073075;5642.85892852682;5676.971615141898;5735.512481439125;5680.375158732453;5683.232763576864;5683.261176047087;5687.118496633028;5691.14632805066;5589.049861523632;5596.160575913483;5609.074502707171;5610.214569960291;5610.438956104357;5562.749676264497;5616.943830561642;5643.520300774316;5658.462446067617;5749.568782341425;5553.139263414178;5554.222408862682;5556.805511807067;5597.188407680782;5603.159526071122 09:04:01 INFO - PROCESS | 7148 | |1;ext.html;606.6208619612742;642.4442856928753;643.3309358267401;649.9285078641349;656.970436330365;30785.23036924078;43404.78297608512;44450.77774611131;44495.555044449546;44525.554744452544;45479.317810232875;45586;45701.31448028274;45707.31439028417;45822.54177458224;8706.695265665687;8846.646134154658;8850.203481686647;8851.33614978876;8857.247133993618;8739.388242823004;8832.072632373605;8832.8675069874;8832.8675069874;8833.425827321236;8849.734507964771;8889.511076890769;8901.910980890172;8904.777380565505;8906.332025098143;8805.42764720291;8831;8845;8851.77870553235;8851.911480885205;8753.080926502753;8802.64789408423;8803.559822008874;8813;8824.161704638049;42811.7859410701;42892.785536072435;43223.135537289396;43268.350974735404;43305.783471082446;3419.1793969447153;3512.1395258161915;3526.1713497328105;3554.964450355489;3585.731070169748;31357.37285254282;32286.354272914647;32458.18854528632;32549.6745032549;32985;4389.122175564899;4389.385486031958;4451.688381813275;4459.576340247671;4544.159330523864;310674;313305;314615;314766;315544.42227788945;50958;55547;55959.72020139873;55969.440305597236;56038.43961560414;58626;59945.70027149836;60088;65790.67104664446;65803.34196657973;17313.480595582154;17375.826241737675;17489.562760930825;17505.912470437564;17535.473935781945;11638.883611163948;13438.46246150143;13498.392572334269;13554.93222533881;13557;15248.085114893142;15417.306221220071;15708.764368534481;15767.921160394124;15957.281922313523;6128.506751151801;6288.748450061947;6289.213848268966;6387.552871298964;6521.804345869631;977.7213494154132;1039.2205845615788;1053.2206167436043;1099.70308016835;1172.1736175996073;32313.030609081765;32382;32493;32612;32774.836125819216;1132.6998345438465;1209.6492017314881;1210.8183772434206;1238.120934136773;1253.3839871969433;16974.49076527706;17119.315227390765;17186.968781872878;17383.91308043452;17641.558961026076;465195;472971;476011;671401;672393;53380.466195338326;54215.186772198445;54221.457785422426;54264.45735542673;54335.72832135893;1263.5893334666087;1377.5660666890099;1390.7218556288783;1403.7403080430154;1404.059280282193;30576.541351879296;32086;32190.791348206058;32519.349613007133;32619.510707339883;46996.53003470058;47434.288485672056;47612;47628.7618561905;47720.522794772995;336835;337153;337508;337819;337955;24510.63234051455;26081.347966300993;27170;27314;28490.287742806595;42548;43390;43729.12541749084;43932.560674392844;44020.33969490396;36038.27923441569;36847.63152368442;37212;37536.249275014896;37848.81075594604;36459.45310820393;36952.63047369492;37304.62695373011;37365.4395184067;37671.24657506889;36934;37682.8115859419;38005;38683.22633547369;38836.41745373878;31999;32952.525267531055;33514.32971340511;34034.14914627055;34074.659253407146;25815.90209174911;25968.740312597387;26139.869300653376;28038.43923121567;29257.561136583394 09:04:01 INFO - PROCESS | 7148 | |2;jquery.html;403.45130622353605;405.21388506297785;411.7179731883658;417.7723140888216;423.12202180475504;18091.457256282316;19719.211231550722;19759.901200493994;19974.700379494283;20664.38006859793;19106;19282.807171928278;19548.9172515423;19782;19826.70259946102;5512.503874651291;5548.140038294071;5832.591718579701;5838.41615838417;5923.229980102581;5780;5802.941970580282;5803.21656576361;5806.622569532988;5806.65160090396;5751.252337196159;5834.766609335621;5843.766249350021;5863.941360586382;5885.176075349455;5781.13283007548;5803.245578074844;5824.213731146305;5967.254093238345;6438.935610643881;5664.660120392789;5761.654300741927;5785.884282314372;5808.448197421239;5808.564357673149;13501.662458438519;13755.38100785467;14011.71976560463;14035.298235088205;14058.226797526131;2136.920461287634;2754.504189245935;3009.3078591923763;3010.698930106994;3056.969430305691;10208.591656333738;10434.373937563694;10484;10501.737456563571;10565.3132546385;1866.309465497761;1914.6183699870605;1925.7111433284979;1964.9214031438726;1983.9206431742714;71984;77861;78420.60789696072;83674.58162709208;83984.58007709983;10985.285956412808;11545.769084618429;11567.305961642329;11657.475413606408;11920.46357913896;15903;16024.19879006045;16035.438759643357;16040.43858464948;16050;7958.442908996315;8060.395470339748;8062;8196.959015204886;8306.958465207756;7804.882926756103;7905.825897878014;8060.435769496079;8143.633536490872;8191.508509489449;4688.859334219979;4783.115123702126;4815.566599006036;4852.369192005087;4881.316615673809;1971.280482623832;1984.4542750743371;2009.8191162795126;2043.427840204745;2062.1957436599737;916.2029034739686;957.3155194036298;984.6602921991902;1007.8740157480315;1060.596973150198;18664.81335186631;19202.711959320623;19369.90315048444;19681.11434985429;19695.11371988264;995.2436148527172;1011.5397494140149;1019.7042857571221;1047.041956609709;1067.882532921378;8965.641374344954;10919.454027298603;10973.670789876316;11011.724706882393;11087.72280692973;485056;489516;509545;518180;518332;92358;92537.53731231435;94050.52974735083;94086.52956735172;94916.52541737247;996.1781530237583;1027.3579013116803;1035.11497669493;1039.4802598700649;1051.2588625019496;16666.333346665997;16735.832641673915;17009.659806803546;17119.400820971205;17674.469765907044;39067;39365.06985429979;40265.57078721397;40870.59129408668;41042.58957410507;52596.73701631468;53492.46507535031;56395.43604563902;56623.71688141533;57932.4206757927;8964.372493925363;9000.414973026733;9004.81990360176;9052.547372631474;9207.58565864551;10230.846537302095;13331.200127992352;13539.729205415639;14074.929625351808;14127.364268607731;7995.640196191302;11612.419379031184;12261.386930653252;12573.74852502963;12596.055295853032;11210.383428911162;11979.221350612183;12225.572104976283;12304.384780760747;12503.06227032958;12514.249145051326;12665.87334126647;12686.809697854724;12741.745165096832;12806.67983300425;7622.313991740548;7925.28672419462;7965.880511792444;8279.254867061753;8450.408471406943;11735.471903764523;12480.188787728768;12657.556985505464;12728.109032368016;12856.228626282453 09:04:01 INFO - PROCESS | 7148 | |3;mootools.html;1369.5343583181716;1487.5165571189364;1521.0493441599;1606.5180445866238;1720.3290716620513;8323.83352332953;8705.521196334206;8709.825803483927;8949.86575201371;8955.596998135083;8645.178708022744;8830.82338353234;8836.204741573258;8848.955755221215;8862.822743545124;2031.1266155553105;2245.5508898220423;2333.8366314358;2367.254314890808;2397.30478161333;2354.788069073783;2403.819713521484;2406.4826062396583;2406.879656017201;2407.253751337091;2109.8734075955417;2150.4838838678684;2153.8276937844935;2154.34292540775;2156.8166705829944;2007.4579863436863;2265.2751119641803;2337.9181728639587;2345.917892873741;2356.611159158737;2261.5929132756096;2285.5543169082034;2290.7823756743082;2291.6218823894037;2294.3231746634783;8624.741257762276;8649.308055355556;8661.826763464758;8707.956460217722;8724;5088.516590923857;5249.763760630781;5251.08106081438;5255.737213139366;5290.39160496544;3448.637893021247;3488.89533314001;3588.6052534221217;3590.4793804898404;3630.6187850275605;1082.431723345242;1170.449888552379;1219.4024927785322;1236.6042866282794;1243.8569564500108;11625.76748465026;11844.881551184464;11846.703832404173;11870.584529541511;11886.227395219283;4436.911261774811;4554.27131658933;4584.88537786558;4601.585857272861;4630.837920672828;10107.545160467796;10126.848097278547;10131.64539241123;10140.13808826258;10149.847752283722;4648.2330415481065;5203.8178663746585;5226.660267082628;5237.921431178535;5239.659422137549;4737.38414006177;4839.927401088987;4845.345878306383;4866.8053277868485;4875.878103047452;3926.5484469285825;3981.3828856527284;4049.9595004049584;4070.8575199867864;4087.816048277835;1246.9501219951283;1282.9857124314735;1289.6711338608782;1295.8509771376223;1305.6083175047447;1019.7450637340664;1033.8759348878182;1050.5745173204864;1052.884182739898;1053.1995683280609;5903.704814759245;5908.970455147697;6047.425494578008;6063.939360606338;6150.231221097363;447.7604481602392;453.1865301783307;459.7517340636086;461.7460396781824;470.83991442909576;4006.098627808778;4016.779077150785;4066.755994640331;4109.815058322382;4124.938125928114;10425.895741042492;10867.891321086847;10888.945555272332;10951;11066.944665276622;10773.51519181623;10832.295900766425;10900;10930.726731831608;10947.288426252268;7943.880841787378;8041.718539851077;8176.223258790405;8249.670013199524;8290.37822163328;6029.698515074141;6044.90932636002;6103.084537319438;6151.507879369729;6154.261488621394;9949.353292035994;9960.103590676585;10063.194944404577;10081.445520496443;10084.54619542137;10092.848607271044;10127.341722787995;10140.949295253477;10162.491875406053;10195.490225488844;7173.928260717326;7429.294217049372;7611.124720657195;7803.063632364153;7884.487508311941;8592;8619.1811777881;8633.093525180017;8645.697400590949;8688;7639.350655194371;7839;7843.803904902423;7911.762647120596;7970.721024764105;7657.34912532441;7728.149903510722;7755.883661744965;7834.294913457816;7843.372530197685;7072.893906591508;7076.009358689685;7101.502894797314;7103.431725461847;7144;4796.25658023007;4848.0788650156355;4933.728644924564;4981.128302547112;5009.0482808266315;5121.103806833864;5193.584513239008;5265.131253343156;5268.86827829292;5328.387235467971 09:04:01 INFO - PROCESS | 7148 | |4;prototype.html;316.3451655073997;323.9643639199688;324.6493786710352;341.6600482519893;342.4554957617388;5197.298364720761;5278.5513231375335;5284.762185701643;5315.973420132904;5333.653312534682;4943.62922780792;5093.286939828417;5175.637705360617;5199.298094757207;5234.528892399683;4151.688623353246;4171.165766846628;4189.539150693437;4216.957830421695;4226.809793559281;4147.221361687676;4158.251514727348;4168.291390463613;4186.455760751098;4191.895202619929;3956.030772460751;4012.7993600320015;4088.5707000764937;4095.5699651536456;4175.413342929698;4107.363358679409;4115.444415003996;4122.464079669654;4143.7720925349095;4148.543660197391;3681.1533347329996;3682.429223470366;3684.428913518382;3688.944665830015;3710.8515659373593;4937.481564435754;5007.198848184273;5013.323201367805;5054.418741844698;5069.138246498104;4646.767661616939;4681;4685.1800934836265;4733.526647335273;4742.596879265267;5369.5167434931045;5397.62216644835;5410.242566040757;5441.673499590037;5450.400455949843;3951.6641085507767;4035.3745169498775;4036.365272064789;4052.918941621151;4060.634542891154;5601.075822489284;5627.634203776783;5636.294578602294;5638.673182822155;5689.943100569024;4068.532118806316;4097.97951010243;4115.8971025724595;4120.690948178839;4151.211269858717;4005.178938317638;4121.216968775923;4127.628513433745;4186.539480657125;4202.285611446062;4657.46439159501;4658.114958157939;4662.207424737802;4693.671442998957;4705.952940470552;5460.672359658433;5506.229127922094;5522.420145884705;5525.751341189656;5547.916781248285;1341.9530316439073;1353.8240028796195;1408.8591140886035;1438.6762978329791;1453.5639308207496;930.2000279759402;945.2438049560325;946.2146418472652;959.9088086631759;964.1756298364797;4659.949622166236;4669.649776266726;4726.740029298353;4742.549457801503;4747.240441529339;4874.8537543873035;4952.919773695056;4954.9752251238515;4955.578775804025;4997.475265097112;4433.800478978454;4633.675642704979;4649.535396350139;4683.765811709401;4699.697148397329;2624.842509449439;2628.6976997645515;2642.8899862057983;2657.86710664466;2661.3745769744096;4987.900241995139;5094.796208151706;5121.359830021247;5137.357830271216;5140.588752899759;4967.40391153064;4973.676711013773;4996.800127994839;4996.850094497171;5355.892882142335;5892.970535147382;5908.5568582357355;5915.911261331084;5939.613925094942;5958.404159584007;5543.334799824047;5702.346319567445;5717.828465146053;5725.370209276893;5742.684152371659;5502.028904391584;5594.13290939905;5623.706547494017;5626.831195064155;5637.9718101409235;5824.679642619697;5835.4748072673665;5847.502962248086;5855;5857.414258574279;4039.8384064638262;4069.165821006746;4083.5712250214488;4089.3661482470266;4102.323116685713;5399.487048730364;5525.364583072998;5721.914171287517;5727.513161381329;5739.986507083814;5406.243125962447;5480.671159730429;5486.807961721321;5487.835364939058;5519.448055194448;5497.147942068986;5497.587680924026;5518.475744804237;5522.282103326542;5532.446755324436;5267.868303292448;5415.620906536662;5431;5431.076716958048;5434.972825135849;5501.174823776466;5536.640118392292;5541.861453463695;5548.722563871709;5553.611946862888;5405.432429594994;5424;5464.426235245242;5475.72621368922;5478.890422191614 09:04:01 INFO - PROCESS | 7148 | |5;yui.html;820.6635279535392;836.4688422851491;850.2857599616322;863.4948555095269;885.420049867337;13699.178049317048;14728.18994955277;14736.04215725977;14763.483278085268;14776.335064922065;13937.930310348434;13956.51152209673;13969.79045314321;13971.79042314366;13979.161250324987;691.374306252842;804.1757198871175;813.5891374855689;816.7263966571206;818.9140140285274;808.7695006923028;817.1338381315794;819.3813670678659;819.8196396792696;820.4134044158426;866.4974314897376;867.8871746672924;868.0754995929346;868.2316150207049;868.4398562926908;722.4906440959111;848.9405741598091;864.6843901975765;869.6651789061194;870.2428886868402;638.2532437048618;638.3552611862053;639.1148259660357;639.9552031357807;640.7693230437039;12998.675033124247;13012.21926684402;13059.477620895248;13088;13121.015923805657;12694.42875070624;13111;13367.264800435973;13435.798463023062;13585.86414135856;10938.507767150497;11106.666799996014;11271.549138034468;12079.396030198455;12457.128001039931;4512.323151527265;4569.451665800092;4584.1519318925775;4604.240300350438;4709.552592503706;49453.50546494561;49653.50346496525;50107.749461252824;50125.749371253274;50340.24489632695;10207.795844083224;10524.526396312185;10795.02844743961;10965.396903170242;11335.829962550568;18936.95673893507;20750.273740419012;20753.273635422687;20772.896135519226;20784.79215207859;7788.532688038623;8392.908921840122;8413.411061225655;8508.574571271412;8515.446495977742;4388.210122178039;4457.888552786207;4752.477227504972;4857.878553036132;4929.457759646436;3364.192593777476;3444.741644376682;3460.619331873492;3464.497647841049;3468.6011108722314;666.2604508995012;668.9966550167218;675.4933799650262;677.4140368581142;680.8025672554903;734.1043926409772;744.7616762635904;751.7444069016451;752.8419032003231;782.2255966593145;8927.151920567536;8938.731838044869;8964.551772411354;9050.094990500897;9108.225800806873;597.7967491052976;617.6232498176109;660.425429876011;664.2095905871997;702.9789106326818;4349.28236840924;4432.4237849080055;4466.173757854807;4476.46282446102;4538.88652783683;53686.7315663427;56326;57005.14492282619;57947.13079303814;58125.12812307819;50735.23897141472;52961.2055819163;53120.46879531155;54030.729846350514;55475.44524554784;47899;47934.28098578524;47975.76012119917;48392.758036210296;49146;8477.364197685074;8633.95683021581;8710.564471776512;8741.475511469333;8812.250958668328;31825.204369890937;32463.837680811444;32593.34813303768;32945.34109317848;33088.66911330856;39345.40981885217;43085.3537196936;43372.783136084116;43412.56587434085;43735.56264437315;11363.715907102389;11394.658160255205;11502.42487875586;11686.94156529212;11975.76048479043;11375.658730238107;11657;12019.459124339252;12345.691357716129;12657.050721195763;11270.380129092979;11811.468483918072;11877.168598198388;11891;11929.82105268439;11137.721556960816;11443.485043173243;11586;11595.478203481032;11945.462454189408;11228.157888158257;11501.654950351503;11608.419579020847;11866.82199766987;12051.035917126434;9847.803043938939;9910.30627856072;9966.900330996597;10029.398236105857;10256;9800;9817.656382026595;9862.112409883144;9931.255155863484;9960.651377201762 09:04:01 INFO - PROCESS | 7148 | __end_tp_report 09:04:01 INFO - PROCESS | 7148 | __start_cc_report 09:04:01 INFO - PROCESS | 7148 | _x_x_mozilla_cycle_collect,999 09:04:01 INFO - PROCESS | 7148 | __end_cc_report 09:04:01 INFO - PROCESS | 7148 | __startTimestamp1463069041225__endTimestamp 09:04:01 INFO - PROCESS | 7148 | 09:04:01 INFO - PROCESS | 7148 | ------- Summary: start ------- 09:04:01 INFO - PROCESS | 7148 | Number of tests: 6 09:04:01 INFO - PROCESS | 7148 | 09:04:01 INFO - PROCESS | 7148 | [#0] dojo.html Cycles:165 Average:5371.98 Median:5593.75 stddev:614.08 (11.0%) stddev-sans-first:589.90 09:04:01 INFO - PROCESS | 7148 | Values: 3115.9 3166.2 3209.1 3212.5 3258.1 5268.9 5325.4 5364.6 5397.7 5464.8 5242.7 5292.3 5296.4 5298.9 5314.4 4769.7 4879.7 4881.2 4888.9 5115.3 5020.3 5025.8 5045.3 5057.7 5096.9 4818.0 4823.1 4845.5 4880.7 4882.0 4890.6 4893.9 4895.6 4906.9 4940.7 4827.9 4881.1 4894.3 4899.6 4951.0 5249.4 5261.7 5295.7 5317.5 5389.9 5816.2 5822.2 5873.4 5873.5 5877.9 5671.2 5887.2 5918.7 5928.3 5944.1 5414.2 5415.7 5447.0 5508.9 5524.4 6007.7 6012.1 6012.8 6014.4 6018.0 5578.4 5602.1 5731.9 5776.0 5777.6 5745.3 5799.4 5802.9 5806.6 5851.0 5728.7 5762.1 5770.2 5772.6 5784.9 5593.7 5635.9 5691.2 5742.1 5790.0 4366.2 4381.1 4383.4 4418.1 4420.0 3901.5 3920.8 3921.2 3935.1 3945.0 5386.1 5394.3 5435.8 5450.9 5481.8 5753.5 5762.5 5784.5 5787.8 5820.9 5449.7 5456.4 5458.2 5460.0 5469.8 4873.7 4901.9 4902.3 4909.4 4993.3 5931.3 5933.2 5937.6 5942.1 5945.5 5659.7 5681.3 5682.1 5686.3 5735.7 5782.2 5932.0 6033.9 6048.3 6136.2 5762.2 5789.7 5814.3 5818.1 5852.7 5855.0 5889.5 5901.6 5904.8 5911.1 5575.6 5633.1 5642.9 5677.0 5735.5 5680.4 5683.2 5683.3 5687.1 5691.1 5589.0 5596.2 5609.1 5610.2 5610.4 5562.7 5616.9 5643.5 5658.5 5749.6 5553.1 5554.2 5556.8 5597.2 5603.2 09:04:01 INFO - PROCESS | 7148 | 09:04:01 INFO - PROCESS | 7148 | [#1] ext.html Cycles:180 Average:56042.82 Median:37765.81 stddev:111391.57 (295.0%) stddev-sans-first:111626.29 09:04:01 INFO - PROCESS | 7148 | Values: 606.6 642.4 643.3 649.9 657.0 30785.2 43404.8 44450.8 44495.6 44525.6 45479.3 45586.0 45701.3 45707.3 45822.5 8706.7 8846.6 8850.2 8851.3 8857.2 8739.4 8832.1 8832.9 8832.9 8833.4 8849.7 8889.5 8901.9 8904.8 8906.3 8805.4 8831.0 8845.0 8851.8 8851.9 8753.1 8802.6 8803.6 8813.0 8824.2 42811.8 42892.8 43223.1 43268.4 43305.8 3419.2 3512.1 3526.2 3555.0 3585.7 31357.4 32286.4 32458.2 32549.7 32985.0 4389.1 4389.4 4451.7 4459.6 4544.2 310674.0 313305.0 314615.0 314766.0 315544.4 50958.0 55547.0 55959.7 55969.4 56038.4 58626.0 59945.7 60088.0 65790.7 65803.3 17313.5 17375.8 17489.6 17505.9 17535.5 11638.9 13438.5 13498.4 13554.9 13557.0 15248.1 15417.3 15708.8 15767.9 15957.3 6128.5 6288.7 6289.2 6387.6 6521.8 977.7 1039.2 1053.2 1099.7 1172.2 32313.0 32382.0 32493.0 32612.0 32774.8 1132.7 1209.6 1210.8 1238.1 1253.4 16974.5 17119.3 17187.0 17383.9 17641.6 465195.0 472971.0 476011.0 671401.0 672393.0 53380.5 54215.2 54221.5 54264.5 54335.7 1263.6 1377.6 1390.7 1403.7 1404.1 30576.5 32086.0 32190.8 32519.3 32619.5 46996.5 47434.3 47612.0 47628.8 47720.5 336835.0 337153.0 337508.0 337819.0 337955.0 24510.6 26081.3 27170.0 27314.0 28490.3 42548.0 43390.0 43729.1 43932.6 44020.3 36038.3 36847.6 37212.0 37536.2 37848.8 36459.5 36952.6 37304.6 37365.4 37671.2 36934.0 37682.8 38005.0 38683.2 38836.4 31999.0 32952.5 33514.3 34034.1 34074.7 25815.9 25968.7 26139.9 28038.4 29257.6 09:04:01 INFO - PROCESS | 7148 | 09:04:01 INFO - PROCESS | 7148 | [#2] jquery.html Cycles:180 Average:29150.27 Median:3010.00 stddev:83059.42 (2759.4%) stddev-sans-first:83264.38 09:04:01 INFO - PROCESS | 7148 | Values: 403.5 405.2 411.7 417.8 423.1 18091.5 19719.2 19759.9 19974.7 20664.4 19106.0 19282.8 19548.9 19782.0 19826.7 5512.5 5548.1 5832.6 5838.4 5923.2 5780.0 5802.9 5803.2 5806.6 5806.7 5751.3 5834.8 5843.8 5863.9 5885.2 5781.1 5803.2 5824.2 5967.3 6438.9 5664.7 5761.7 5785.9 5808.4 5808.6 13501.7 13755.4 14011.7 14035.3 14058.2 2136.9 2754.5 3009.3 3010.7 3057.0 10208.6 10434.4 10484.0 10501.7 10565.3 1866.3 1914.6 1925.7 1964.9 1983.9 71984.0 77861.0 78420.6 83674.6 83984.6 10985.3 11545.8 11567.3 11657.5 11920.5 15903.0 16024.2 16035.4 16040.4 16050.0 7958.4 8060.4 8062.0 8197.0 8307.0 7804.9 7905.8 8060.4 8143.6 8191.5 4688.9 4783.1 4815.6 4852.4 4881.3 1971.3 1984.5 2009.8 2043.4 2062.2 916.2 957.3 984.7 1007.9 1060.6 18664.8 19202.7 19369.9 19681.1 19695.1 995.2 1011.5 1019.7 1047.0 1067.9 8965.6 10919.5 10973.7 11011.7 11087.7 485056.0 489516.0 509545.0 518180.0 518332.0 92358.0 92537.5 94050.5 94086.5 94916.5 996.2 1027.4 1035.1 1039.5 1051.3 16666.3 16735.8 17009.7 17119.4 17674.5 39067.0 39365.1 40265.6 40870.6 41042.6 52596.7 53492.5 56395.4 56623.7 57932.4 8964.4 9000.4 9004.8 9052.5 9207.6 10230.8 13331.2 13539.7 14074.9 14127.4 7995.6 11612.4 12261.4 12573.7 12596.1 11210.4 11979.2 12225.6 12304.4 12503.1 12514.2 12665.9 12686.8 12741.7 12806.7 7622.3 7925.3 7965.9 8279.3 8450.4 11735.5 12480.2 12657.6 12728.1 12856.2 09:04:01 INFO - PROCESS | 7148 | 09:04:01 INFO - PROCESS | 7148 | [#3] mootools.html Cycles:180 Average:5767.04 Median:2503.73 stddev:3276.77 (130.9%) stddev-sans-first:3269.30 09:04:01 INFO - PROCESS | 7148 | Values: 1369.5 1487.5 1521.0 1606.5 1720.3 8323.8 8705.5 8709.8 8949.9 8955.6 8645.2 8830.8 8836.2 8849.0 8862.8 2031.1 2245.6 2333.8 2367.3 2397.3 2354.8 2403.8 2406.5 2406.9 2407.3 2109.9 2150.5 2153.8 2154.3 2156.8 2007.5 2265.3 2337.9 2345.9 2356.6 2261.6 2285.6 2290.8 2291.6 2294.3 8624.7 8649.3 8661.8 8708.0 8724.0 5088.5 5249.8 5251.1 5255.7 5290.4 3448.6 3488.9 3588.6 3590.5 3630.6 1082.4 1170.4 1219.4 1236.6 1243.9 11625.8 11844.9 11846.7 11870.6 11886.2 4436.9 4554.3 4584.9 4601.6 4630.8 10107.5 10126.8 10131.6 10140.1 10149.8 4648.2 5203.8 5226.7 5237.9 5239.7 4737.4 4839.9 4845.3 4866.8 4875.9 3926.5 3981.4 4050.0 4070.9 4087.8 1247.0 1283.0 1289.7 1295.9 1305.6 1019.7 1033.9 1050.6 1052.9 1053.2 5903.7 5909.0 6047.4 6063.9 6150.2 447.8 453.2 459.8 461.7 470.8 4006.1 4016.8 4066.8 4109.8 4124.9 10425.9 10867.9 10888.9 10951.0 11066.9 10773.5 10832.3 10900.0 10930.7 10947.3 7943.9 8041.7 8176.2 8249.7 8290.4 6029.7 6044.9 6103.1 6151.5 6154.3 9949.4 9960.1 10063.2 10081.4 10084.5 10092.8 10127.3 10140.9 10162.5 10195.5 7173.9 7429.3 7611.1 7803.1 7884.5 8592.0 8619.2 8633.1 8645.7 8688.0 7639.4 7839.0 7843.8 7911.8 7970.7 7657.3 7728.1 7755.9 7834.3 7843.4 7072.9 7076.0 7101.5 7103.4 7144.0 4796.3 4848.1 4933.7 4981.1 5009.0 5121.1 5193.6 5265.1 5268.9 5328.4 09:04:01 INFO - PROCESS | 7148 | 09:04:01 INFO - PROCESS | 7148 | [#4] prototype.html Cycles:180 Average:4548.66 Median:4997.16 stddev:1325.02 (26.5%) stddev-sans-first:1290.09 09:04:01 INFO - PROCESS | 7148 | Values: 316.3 324.0 324.6 341.7 342.5 5197.3 5278.6 5284.8 5316.0 5333.7 4943.6 5093.3 5175.6 5199.3 5234.5 4151.7 4171.2 4189.5 4217.0 4226.8 4147.2 4158.3 4168.3 4186.5 4191.9 3956.0 4012.8 4088.6 4095.6 4175.4 4107.4 4115.4 4122.5 4143.8 4148.5 3681.2 3682.4 3684.4 3688.9 3710.9 4937.5 5007.2 5013.3 5054.4 5069.1 4646.8 4681.0 4685.2 4733.5 4742.6 5369.5 5397.6 5410.2 5441.7 5450.4 3951.7 4035.4 4036.4 4052.9 4060.6 5601.1 5627.6 5636.3 5638.7 5689.9 4068.5 4098.0 4115.9 4120.7 4151.2 4005.2 4121.2 4127.6 4186.5 4202.3 4657.5 4658.1 4662.2 4693.7 4706.0 5460.7 5506.2 5522.4 5525.8 5547.9 1342.0 1353.8 1408.9 1438.7 1453.6 930.2 945.2 946.2 959.9 964.2 4659.9 4669.6 4726.7 4742.5 4747.2 4874.9 4952.9 4955.0 4955.6 4997.5 4433.8 4633.7 4649.5 4683.8 4699.7 2624.8 2628.7 2642.9 2657.9 2661.4 4987.9 5094.8 5121.4 5137.4 5140.6 4967.4 4973.7 4996.8 4996.9 5355.9 5893.0 5908.6 5915.9 5939.6 5958.4 5543.3 5702.3 5717.8 5725.4 5742.7 5502.0 5594.1 5623.7 5626.8 5638.0 5824.7 5835.5 5847.5 5855.0 5857.4 4039.8 4069.2 4083.6 4089.4 4102.3 5399.5 5525.4 5721.9 5727.5 5740.0 5406.2 5480.7 5486.8 5487.8 5519.4 5497.1 5497.6 5518.5 5522.3 5532.4 5267.9 5415.6 5431.0 5431.1 5435.0 5501.2 5536.6 5541.9 5548.7 5553.6 5405.4 5424.0 5464.4 5475.7 5478.9 09:04:01 INFO - PROCESS | 7148 | 09:04:01 INFO - PROCESS | 7148 | [#5] yui.html Cycles:180 Average:14145.98 Median:47916.64 stddev:15991.28 (33.4%) stddev-sans-first:16004.83 09:04:01 INFO - PROCESS | 7148 | Values: 820.7 836.5 850.3 863.5 885.4 13699.2 14728.2 14736.0 14763.5 14776.3 13937.9 13956.5 13969.8 13971.8 13979.2 691.4 804.2 813.6 816.7 818.9 808.8 817.1 819.4 819.8 820.4 866.5 867.9 868.1 868.2 868.4 722.5 848.9 864.7 869.7 870.2 638.3 638.4 639.1 640.0 640.8 12998.7 13012.2 13059.5 13088.0 13121.0 12694.4 13111.0 13367.3 13435.8 13585.9 10938.5 11106.7 11271.5 12079.4 12457.1 4512.3 4569.5 4584.2 4604.2 4709.6 49453.5 49653.5 50107.7 50125.7 50340.2 10207.8 10524.5 10795.0 10965.4 11335.8 18937.0 20750.3 20753.3 20772.9 20784.8 7788.5 8392.9 8413.4 8508.6 8515.4 4388.2 4457.9 4752.5 4857.9 4929.5 3364.2 3444.7 3460.6 3464.5 3468.6 666.3 669.0 675.5 677.4 680.8 734.1 744.8 751.7 752.8 782.2 8927.2 8938.7 8964.6 9050.1 9108.2 597.8 617.6 660.4 664.2 703.0 4349.3 4432.4 4466.2 4476.5 4538.9 53686.7 56326.0 57005.1 57947.1 58125.1 50735.2 52961.2 53120.5 54030.7 55475.4 47899.0 47934.3 47975.8 48392.8 49146.0 8477.4 8634.0 8710.6 8741.5 8812.3 31825.2 32463.8 32593.3 32945.3 33088.7 39345.4 43085.4 43372.8 43412.6 43735.6 11363.7 11394.7 11502.4 11686.9 11975.8 11375.7 11657.0 12019.5 12345.7 12657.1 11270.4 11811.5 11877.2 11891.0 11929.8 11137.7 11443.5 11586.0 11595.5 11945.5 11228.2 11501.7 11608.4 11866.8 12051.0 9847.8 9910.3 9966.9 10029.4 10256.0 9800.0 9817.7 9862.1 9931.3 9960.7 09:04:01 INFO - PROCESS | 7148 | -------- Summary: end -------- 09:04:01 INFO - PROCESS | 7148 | 09:04:01 INFO - TEST-INFO | 7148: exit 0 09:04:01 INFO - TEST-OK | dromaeo_css | took 1112874ms 09:04:01 INFO - TEST-START | kraken 09:04:01 INFO - operating with platform_type : linux_ 09:04:01 INFO - Initialising browser for kraken test... 09:04:01 INFO - TEST-INFO | started process 7330 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXz14wN/profile http://localhost:47194/getInfo.html) 09:04:05 INFO - PROCESS | 7330 | __metrics Screen width/height:1600/1200 09:04:05 INFO - PROCESS | 7330 | colorDepth:24 09:04:05 INFO - PROCESS | 7330 | Browser inner width/height: 1024/697 09:04:05 INFO - PROCESS | 7330 | __metrics 09:04:06 ERROR - PROCESS | 7330 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:04:07 INFO - TEST-INFO | 7330: exit 0 09:04:07 INFO - Browser initialized. 09:04:07 INFO - Running cycle 1/1 for kraken test... 09:04:07 INFO - TEST-INFO | started process 7410 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXz14wN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:04:19 INFO - PROCESS | 7410 | RSS: Main: 151752704 09:04:19 INFO - PROCESS | 7410 | 09:04:21 INFO - PROCESS | 7410 | Cycle 1(1): loaded http://localhost:47194/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47194/tests/kraken/driver.html?testName=audio-beat-detection) 09:04:22 INFO - PROCESS | 7410 | RSS: Main: 208445440 09:04:22 INFO - PROCESS | 7410 | 09:04:25 INFO - PROCESS | 7410 | Cycle 1(1): loaded http://localhost:47194/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47194/tests/kraken/driver.html?testName=audio-dft) 09:04:26 INFO - PROCESS | 7410 | RSS: Main: 197324800 09:04:26 INFO - PROCESS | 7410 | 09:04:29 INFO - PROCESS | 7410 | Cycle 1(1): loaded http://localhost:47194/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47194/tests/kraken/driver.html?testName=audio-fft) 09:04:30 INFO - PROCESS | 7410 | RSS: Main: 265527296 09:04:30 INFO - PROCESS | 7410 | 09:04:32 INFO - PROCESS | 7410 | Cycle 1(1): loaded http://localhost:47194/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47194/tests/kraken/driver.html?testName=audio-oscillator) 09:04:32 INFO - PROCESS | 7410 | RSS: Main: 173879296 09:04:32 INFO - PROCESS | 7410 | 09:04:35 INFO - PROCESS | 7410 | Cycle 1(1): loaded http://localhost:47194/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47194/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:04:35 INFO - PROCESS | 7410 | RSS: Main: 177467392 09:04:35 INFO - PROCESS | 7410 | 09:04:38 INFO - PROCESS | 7410 | Cycle 1(1): loaded http://localhost:47194/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47194/tests/kraken/driver.html?testName=imaging-darkroom) 09:04:39 INFO - PROCESS | 7410 | RSS: Main: 214134784 09:04:39 INFO - PROCESS | 7410 | 09:04:43 INFO - PROCESS | 7410 | Cycle 1(1): loaded http://localhost:47194/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47194/tests/kraken/driver.html?testName=imaging-desaturate) 09:04:43 INFO - PROCESS | 7410 | RSS: Main: 211824640 09:04:43 INFO - PROCESS | 7410 | 09:04:46 INFO - PROCESS | 7410 | Cycle 1(1): loaded http://localhost:47194/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47194/tests/kraken/driver.html?testName=json-parse-financial) 09:04:47 INFO - PROCESS | 7410 | RSS: Main: 213454848 09:04:47 INFO - PROCESS | 7410 | 09:04:48 INFO - PROCESS | 7410 | Cycle 1(1): loaded http://localhost:47194/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47194/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:04:49 INFO - PROCESS | 7410 | RSS: Main: 202338304 09:04:49 INFO - PROCESS | 7410 | 09:04:51 INFO - PROCESS | 7410 | Cycle 1(1): loaded http://localhost:47194/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47194/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:04:51 INFO - PROCESS | 7410 | RSS: Main: 207564800 09:04:51 INFO - PROCESS | 7410 | 09:04:53 INFO - PROCESS | 7410 | Cycle 1(1): loaded http://localhost:47194/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47194/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:04:54 INFO - PROCESS | 7410 | RSS: Main: 217513984 09:04:54 INFO - PROCESS | 7410 | 09:04:57 INFO - PROCESS | 7410 | Cycle 1(1): loaded http://localhost:47194/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47194/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:04:57 INFO - PROCESS | 7410 | RSS: Main: 220737536 09:04:57 INFO - PROCESS | 7410 | 09:05:00 INFO - PROCESS | 7410 | Cycle 1(1): loaded http://localhost:47194/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47194/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:05:01 INFO - PROCESS | 7410 | RSS: Main: 218812416 09:05:01 INFO - PROCESS | 7410 | 09:05:03 INFO - PROCESS | 7410 | Cycle 1(1): loaded http://localhost:47194/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47194/tests/kraken/driver.html?testName=ai-astar) 09:05:03 INFO - PROCESS | 7410 | RSS: Main: 263983104 09:05:03 INFO - PROCESS | 7410 | 09:05:03 INFO - PROCESS | 7410 | __start_tp_report 09:05:03 INFO - PROCESS | 7410 | _x_x_mozilla_page_load 09:05:03 INFO - PROCESS | 7410 | _x_x_mozilla_page_load_details 09:05:03 INFO - PROCESS | 7410 | |i|pagename|runs| 09:05:03 INFO - PROCESS | 7410 | |0;ai-astar;110;105;112;109;110;110;109;109;110;109 09:05:03 INFO - PROCESS | 7410 | |1;audio-beat-detection;153;125;159;208;132;149;162;134;192;126 09:05:03 INFO - PROCESS | 7410 | |2;audio-dft;161;162;161;163;161;162;161;162;163;162 09:05:03 INFO - PROCESS | 7410 | |3;audio-fft;75;76;75;155;76;75;77;75;133;76 09:05:03 INFO - PROCESS | 7410 | |4;audio-oscillator;92;93;147;92;92;92;126;93;91;128 09:05:03 INFO - PROCESS | 7410 | |5;imaging-gaussian-blur;116;116;117;117;117;117;117;117;117;116 09:05:03 INFO - PROCESS | 7410 | |6;imaging-darkroom;146;145;145;145;145;145;145;145;146;145 09:05:03 INFO - PROCESS | 7410 | |7;imaging-desaturate;94;95;95;95;94;95;95;96;95;95 09:05:03 INFO - PROCESS | 7410 | |8;json-parse-financial;58;58;59;58;58;59;59;121;56;56 09:05:03 INFO - PROCESS | 7410 | |9;json-stringify-tinderbox;60;59;58;59;60;59;59;59;59;59 09:05:03 INFO - PROCESS | 7410 | |10;stanford-crypto-aes;80;79;79;80;77;79;79;79;80;80 09:05:03 INFO - PROCESS | 7410 | |11;stanford-crypto-ccm;132;125;124;124;123;130;123;122;240;128 09:05:03 INFO - PROCESS | 7410 | |12;stanford-crypto-pbkdf2;170;172;171;171;172;174;168;173;169;169 09:05:03 INFO - PROCESS | 7410 | |13;stanford-crypto-sha256-iterative;65;69;69;66;65;66;65;65;64;64 09:05:03 INFO - PROCESS | 7410 | __end_tp_report 09:05:03 INFO - PROCESS | 7410 | __start_cc_report 09:05:03 INFO - PROCESS | 7410 | _x_x_mozilla_cycle_collect,1825 09:05:03 INFO - PROCESS | 7410 | __end_cc_report 09:05:03 INFO - PROCESS | 7410 | __startTimestamp1463069103430__endTimestamp 09:05:03 INFO - PROCESS | 7410 | 09:05:03 INFO - PROCESS | 7410 | ------- Summary: start ------- 09:05:03 INFO - PROCESS | 7410 | Number of tests: 14 09:05:03 INFO - PROCESS | 7410 | 09:05:03 INFO - PROCESS | 7410 | [#0] ai-astar Cycles:10 Average:109.30 Median:110.00 stddev:1.77 (1.6%) stddev-sans-first:1.86 09:05:03 INFO - PROCESS | 7410 | Values: 110.0 105.0 112.0 109.0 110.0 110.0 109.0 109.0 110.0 109.0 09:05:03 INFO - PROCESS | 7410 | 09:05:03 INFO - PROCESS | 7410 | [#1] audio-beat-detection Cycles:10 Average:154.00 Median:156.00 stddev:27.86 (17.9%) stddev-sans-first:29.54 09:05:03 INFO - PROCESS | 7410 | Values: 153.0 125.0 159.0 208.0 132.0 149.0 162.0 134.0 192.0 126.0 09:05:03 INFO - PROCESS | 7410 | 09:05:03 INFO - PROCESS | 7410 | [#2] audio-dft Cycles:10 Average:161.80 Median:162.00 stddev:0.79 (0.5%) stddev-sans-first:0.78 09:05:03 INFO - PROCESS | 7410 | Values: 161.0 162.0 161.0 163.0 161.0 162.0 161.0 162.0 163.0 162.0 09:05:03 INFO - PROCESS | 7410 | 09:05:03 INFO - PROCESS | 7410 | [#3] audio-fft Cycles:10 Average:89.30 Median:75.50 stddev:29.30 (38.8%) stddev-sans-first:30.62 09:05:03 INFO - PROCESS | 7410 | Values: 75.0 76.0 75.0 155.0 76.0 75.0 77.0 75.0 133.0 76.0 09:05:03 INFO - PROCESS | 7410 | 09:05:03 INFO - PROCESS | 7410 | [#4] audio-oscillator Cycles:10 Average:104.60 Median:92.00 stddev:20.80 (22.6%) stddev-sans-first:21.55 09:05:03 INFO - PROCESS | 7410 | Values: 92.0 93.0 147.0 92.0 92.0 92.0 126.0 93.0 91.0 128.0 09:05:03 INFO - PROCESS | 7410 | 09:05:03 INFO - PROCESS | 7410 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 09:05:03 INFO - PROCESS | 7410 | Values: 116.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 09:05:03 INFO - PROCESS | 7410 | 09:05:03 INFO - PROCESS | 7410 | [#6] imaging-darkroom Cycles:10 Average:145.20 Median:145.00 stddev:0.42 (0.3%) stddev-sans-first:0.33 09:05:03 INFO - PROCESS | 7410 | Values: 146.0 145.0 145.0 145.0 145.0 145.0 145.0 145.0 146.0 145.0 09:05:03 INFO - PROCESS | 7410 | 09:05:03 INFO - PROCESS | 7410 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 09:05:03 INFO - PROCESS | 7410 | Values: 94.0 95.0 95.0 95.0 94.0 95.0 95.0 96.0 95.0 95.0 09:05:03 INFO - PROCESS | 7410 | 09:05:03 INFO - PROCESS | 7410 | [#8] json-parse-financial Cycles:10 Average:64.20 Median:58.00 stddev:19.99 (34.5%) stddev-sans-first:21.07 09:05:03 INFO - PROCESS | 7410 | Values: 58.0 58.0 59.0 58.0 58.0 59.0 59.0 121.0 56.0 56.0 09:05:03 INFO - PROCESS | 7410 | 09:05:03 INFO - PROCESS | 7410 | [#9] json-stringify-tinderbox Cycles:10 Average:59.10 Median:59.00 stddev:0.57 (1.0%) stddev-sans-first:0.50 09:05:03 INFO - PROCESS | 7410 | Values: 60.0 59.0 58.0 59.0 60.0 59.0 59.0 59.0 59.0 59.0 09:05:03 INFO - PROCESS | 7410 | 09:05:03 INFO - PROCESS | 7410 | [#10] stanford-crypto-aes Cycles:10 Average:79.20 Median:79.50 stddev:0.92 (1.2%) stddev-sans-first:0.93 09:05:03 INFO - PROCESS | 7410 | Values: 80.0 79.0 79.0 80.0 77.0 79.0 79.0 79.0 80.0 80.0 09:05:03 INFO - PROCESS | 7410 | 09:05:03 INFO - PROCESS | 7410 | [#11] stanford-crypto-ccm Cycles:10 Average:137.10 Median:126.50 stddev:36.31 (28.7%) stddev-sans-first:38.46 09:05:03 INFO - PROCESS | 7410 | Values: 132.0 125.0 124.0 124.0 123.0 130.0 123.0 122.0 240.0 128.0 09:05:03 INFO - PROCESS | 7410 | 09:05:03 INFO - PROCESS | 7410 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.90 Median:171.50 stddev:1.91 (1.1%) stddev-sans-first:2.00 09:05:03 INFO - PROCESS | 7410 | Values: 170.0 172.0 171.0 171.0 172.0 174.0 168.0 173.0 169.0 169.0 09:05:03 INFO - PROCESS | 7410 | 09:05:03 INFO - PROCESS | 7410 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:65.50 stddev:1.81 (2.8%) stddev-sans-first:1.90 09:05:03 INFO - PROCESS | 7410 | Values: 65.0 69.0 69.0 66.0 65.0 66.0 65.0 65.0 64.0 64.0 09:05:03 INFO - PROCESS | 7410 | -------- Summary: end -------- 09:05:03 INFO - PROCESS | 7410 | 09:05:04 INFO - TEST-INFO | 7410: exit 0 09:05:04 INFO - TEST-OK | kraken | took 62479ms 09:05:04 INFO - SUITE-END | took 1175s 09:05:04 INFO - Completed test suite (00:19:35) 09:05:04 INFO - javascript benchmark 09:05:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3115.8909438169667, 3166.240102375429, 3209.069369882734, 3212.4538828399163, 3258.1203075169706, 5268.92096618551, 5325.387580428252, 5364.624476286662, 5397.730113494319, 5464.754086066132, 5242.68543887367, 5292.285541451903, 5296.443873393296, 5298.867528311795, 5314.441983591715, 4769.666123371374, 4879.6828206166665, 4881.170201065827, 4888.902221955559, 5115.335006449175, 5020.322256495363, 5025.824096156635, 5045.34410526631, 5057.721825299607, 5096.923546146811, 4818.036392721452, 4823.13183626947, 4845.53967373099, 4880.682755620891, 4882.0, 4890.633202509826, 4893.902121957541, 4895.632827537914, 4906.877328066791, 4940.728259945701, 4827.85516434504, 4881.1213981483315, 4894.290327902434, 4899.632527560448, 4951.009798040406, 5249.396319423277, 5261.684298942076, 5295.735213239323, 5317.54800841925, 5389.892202155935, 5816.243888294494, 5822.155787410802, 5873.383294754075, 5873.530117590582, 5877.85305367365, 5671.2060311556415, 5887.175795388649, 5918.704064796743, 5928.318243402021, 5944.078667806453, 5414.160805075219, 5415.675059496442, 5447.0, 5508.862278443071, 5524.392316845144, 6007.729652165663, 6012.068129439944, 6012.759489620366, 6014.428629280212, 6017.969910150421, 5578.41426650204, 5602.1036634138345, 5731.910936922055, 5776.046952252916, 5777.62445441045, 5745.2818397700285, 5799.420057994167, 5802.883942321215, 5806.5645076618575, 5851.0, 5728.656280623092, 5762.078067509178, 5770.221020162225, 5772.567057470623, 5784.942150578525, 5593.74828132735, 5635.887282254332, 5691.231683722644, 5742.138679198154, 5790.044642634002, 4366.170427618742, 4381.123775244963, 4383.364412160219, 4418.072204836956, 4420.027593929331, 3901.51231096113, 3920.8235629396745, 3921.156951255494, 3935.0752573145287, 3945.0, 5386.084365657847, 5394.32570928634, 5435.7825686972865, 5450.8909821803345, 5481.808136715196, 5753.51095156908, 5762.510186634099, 5784.450477204743, 5787.768489260467, 5820.912686309709, 5449.6730196188355, 5456.372517160498, 5458.181272809111, 5460.0, 5469.835904922858, 4873.6832105913, 4901.926471102936, 4902.2646603009125, 4909.386326709161, 4993.251012348177, 5931.347551769302, 5933.169356290123, 5937.614055086406, 5942.138389933435, 5945.494632956161, 5659.717014149359, 5681.318241811009, 5682.119271512922, 5686.289213848269, 5735.713214339183, 5782.219400381063, 5932.0, 6033.939660603513, 6048.274207095177, 6136.20229370179, 5762.2221000164445, 5789.652620842763, 5814.273215848019, 5818.069108942549, 5852.712403271274, 5855.00464920956, 5889.469947704727, 5901.645901245939, 5904.822855314347, 5911.1428842816695, 5575.609707320448, 5633.0987042073075, 5642.85892852682, 5676.971615141898, 5735.512481439125, 5680.375158732453, 5683.232763576864, 5683.261176047087, 5687.118496633028, 5691.14632805066, 5589.049861523632, 5596.160575913483, 5609.074502707171, 5610.214569960291, 5610.438956104357, 5562.749676264497, 5616.943830561642, 5643.520300774316, 5658.462446067617, 5749.568782341425, 5553.139263414178, 5554.222408862682, 5556.805511807067, 5597.188407680782, 5603.159526071122], "value": 5330.782232931923, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [606.6208619612742, 642.4442856928753, 643.3309358267401, 649.9285078641349, 656.970436330365, 30785.23036924078, 43404.78297608512, 44450.77774611131, 44495.555044449546, 44525.554744452544, 45479.317810232875, 45586.0, 45701.31448028274, 45707.31439028417, 45822.54177458224, 8706.695265665687, 8846.646134154658, 8850.203481686647, 8851.33614978876, 8857.247133993618, 8739.388242823004, 8832.072632373605, 8832.8675069874, 8832.8675069874, 8833.425827321236, 8849.734507964771, 8889.511076890769, 8901.910980890172, 8904.777380565505, 8906.332025098143, 8805.42764720291, 8831.0, 8845.0, 8851.77870553235, 8851.911480885205, 8753.080926502753, 8802.64789408423, 8803.559822008874, 8813.0, 8824.161704638049, 42811.7859410701, 42892.785536072435, 43223.135537289396, 43268.350974735404, 43305.783471082446, 3419.1793969447153, 3512.1395258161915, 3526.1713497328105, 3554.964450355489, 3585.731070169748, 31357.37285254282, 32286.354272914647, 32458.18854528632, 32549.6745032549, 32985.0, 4389.122175564899, 4389.385486031958, 4451.688381813275, 4459.576340247671, 4544.159330523864, 310674.0, 313305.0, 314615.0, 314766.0, 315544.42227788945, 50958.0, 55547.0, 55959.72020139873, 55969.440305597236, 56038.43961560414, 58626.0, 59945.70027149836, 60088.0, 65790.67104664446, 65803.34196657973, 17313.480595582154, 17375.826241737675, 17489.562760930825, 17505.912470437564, 17535.473935781945, 11638.883611163948, 13438.46246150143, 13498.392572334269, 13554.93222533881, 13557.0, 15248.085114893142, 15417.306221220071, 15708.764368534481, 15767.921160394124, 15957.281922313523, 6128.506751151801, 6288.748450061947, 6289.213848268966, 6387.552871298964, 6521.804345869631, 977.7213494154132, 1039.2205845615788, 1053.2206167436043, 1099.70308016835, 1172.1736175996073, 32313.030609081765, 32382.0, 32493.0, 32612.0, 32774.836125819216, 1132.6998345438465, 1209.6492017314881, 1210.8183772434206, 1238.120934136773, 1253.3839871969433, 16974.49076527706, 17119.315227390765, 17186.968781872878, 17383.91308043452, 17641.558961026076, 465195.0, 472971.0, 476011.0, 671401.0, 672393.0, 53380.466195338326, 54215.186772198445, 54221.457785422426, 54264.45735542673, 54335.72832135893, 1263.5893334666087, 1377.5660666890099, 1390.7218556288783, 1403.7403080430154, 1404.059280282193, 30576.541351879296, 32086.0, 32190.791348206058, 32519.349613007133, 32619.510707339883, 46996.53003470058, 47434.288485672056, 47612.0, 47628.7618561905, 47720.522794772995, 336835.0, 337153.0, 337508.0, 337819.0, 337955.0, 24510.63234051455, 26081.347966300993, 27170.0, 27314.0, 28490.287742806595, 42548.0, 43390.0, 43729.12541749084, 43932.560674392844, 44020.33969490396, 36038.27923441569, 36847.63152368442, 37212.0, 37536.249275014896, 37848.81075594604, 36459.45310820393, 36952.63047369492, 37304.62695373011, 37365.4395184067, 37671.24657506889, 36934.0, 37682.8115859419, 38005.0, 38683.22633547369, 38836.41745373878, 31999.0, 32952.525267531055, 33514.32971340511, 34034.14914627055, 34074.659253407146, 25815.90209174911, 25968.740312597387, 26139.869300653376, 28038.43923121567, 29257.561136583394], "value": 19522.12821879173, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [403.45130622353605, 405.21388506297785, 411.7179731883658, 417.7723140888216, 423.12202180475504, 18091.457256282316, 19719.211231550722, 19759.901200493994, 19974.700379494283, 20664.38006859793, 19106.0, 19282.807171928278, 19548.9172515423, 19782.0, 19826.70259946102, 5512.503874651291, 5548.140038294071, 5832.591718579701, 5838.41615838417, 5923.229980102581, 5780.0, 5802.941970580282, 5803.21656576361, 5806.622569532988, 5806.65160090396, 5751.252337196159, 5834.766609335621, 5843.766249350021, 5863.941360586382, 5885.176075349455, 5781.13283007548, 5803.245578074844, 5824.213731146305, 5967.254093238345, 6438.935610643881, 5664.660120392789, 5761.654300741927, 5785.884282314372, 5808.448197421239, 5808.564357673149, 13501.662458438519, 13755.38100785467, 14011.71976560463, 14035.298235088205, 14058.226797526131, 2136.920461287634, 2754.504189245935, 3009.3078591923763, 3010.698930106994, 3056.969430305691, 10208.591656333738, 10434.373937563694, 10484.0, 10501.737456563571, 10565.3132546385, 1866.309465497761, 1914.6183699870605, 1925.7111433284979, 1964.9214031438726, 1983.9206431742714, 71984.0, 77861.0, 78420.60789696072, 83674.58162709208, 83984.58007709983, 10985.285956412808, 11545.769084618429, 11567.305961642329, 11657.475413606408, 11920.46357913896, 15903.0, 16024.19879006045, 16035.438759643357, 16040.43858464948, 16050.0, 7958.442908996315, 8060.395470339748, 8062.0, 8196.959015204886, 8306.958465207756, 7804.882926756103, 7905.825897878014, 8060.435769496079, 8143.633536490872, 8191.508509489449, 4688.859334219979, 4783.115123702126, 4815.566599006036, 4852.369192005087, 4881.316615673809, 1971.280482623832, 1984.4542750743371, 2009.8191162795126, 2043.427840204745, 2062.1957436599737, 916.2029034739686, 957.3155194036298, 984.6602921991902, 1007.8740157480315, 1060.596973150198, 18664.81335186631, 19202.711959320623, 19369.90315048444, 19681.11434985429, 19695.11371988264, 995.2436148527172, 1011.5397494140149, 1019.7042857571221, 1047.041956609709, 1067.882532921378, 8965.641374344954, 10919.454027298603, 10973.670789876316, 11011.724706882393, 11087.72280692973, 485056.0, 489516.0, 509545.0, 518180.0, 518332.0, 92358.0, 92537.53731231435, 94050.52974735083, 94086.52956735172, 94916.52541737247, 996.1781530237583, 1027.3579013116803, 1035.11497669493, 1039.4802598700649, 1051.2588625019496, 16666.333346665997, 16735.832641673915, 17009.659806803546, 17119.400820971205, 17674.469765907044, 39067.0, 39365.06985429979, 40265.57078721397, 40870.59129408668, 41042.58957410507, 52596.73701631468, 53492.46507535031, 56395.43604563902, 56623.71688141533, 57932.4206757927, 8964.372493925363, 9000.414973026733, 9004.81990360176, 9052.547372631474, 9207.58565864551, 10230.846537302095, 13331.200127992352, 13539.729205415639, 14074.929625351808, 14127.364268607731, 7995.640196191302, 11612.419379031184, 12261.386930653252, 12573.74852502963, 12596.055295853032, 11210.383428911162, 11979.221350612183, 12225.572104976283, 12304.384780760747, 12503.06227032958, 12514.249145051326, 12665.87334126647, 12686.809697854724, 12741.745165096832, 12806.67983300425, 7622.313991740548, 7925.28672419462, 7965.880511792444, 8279.254867061753, 8450.408471406943, 11735.471903764523, 12480.188787728768, 12657.556985505464, 12728.109032368016, 12856.228626282453], "value": 9249.350342667378, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1369.5343583181716, 1487.5165571189364, 1521.0493441599, 1606.5180445866238, 1720.3290716620513, 8323.83352332953, 8705.521196334206, 8709.825803483927, 8949.86575201371, 8955.596998135083, 8645.178708022744, 8830.82338353234, 8836.204741573258, 8848.955755221215, 8862.822743545124, 2031.1266155553105, 2245.5508898220423, 2333.8366314358, 2367.254314890808, 2397.30478161333, 2354.788069073783, 2403.819713521484, 2406.4826062396583, 2406.879656017201, 2407.253751337091, 2109.8734075955417, 2150.4838838678684, 2153.8276937844935, 2154.34292540775, 2156.8166705829944, 2007.4579863436863, 2265.2751119641803, 2337.9181728639587, 2345.917892873741, 2356.611159158737, 2261.5929132756096, 2285.5543169082034, 2290.7823756743082, 2291.6218823894037, 2294.3231746634783, 8624.741257762276, 8649.308055355556, 8661.826763464758, 8707.956460217722, 8724.0, 5088.516590923857, 5249.763760630781, 5251.08106081438, 5255.737213139366, 5290.39160496544, 3448.637893021247, 3488.89533314001, 3588.6052534221217, 3590.4793804898404, 3630.6187850275605, 1082.431723345242, 1170.449888552379, 1219.4024927785322, 1236.6042866282794, 1243.8569564500108, 11625.76748465026, 11844.881551184464, 11846.703832404173, 11870.584529541511, 11886.227395219283, 4436.911261774811, 4554.27131658933, 4584.88537786558, 4601.585857272861, 4630.837920672828, 10107.545160467796, 10126.848097278547, 10131.64539241123, 10140.13808826258, 10149.847752283722, 4648.2330415481065, 5203.8178663746585, 5226.660267082628, 5237.921431178535, 5239.659422137549, 4737.38414006177, 4839.927401088987, 4845.345878306383, 4866.8053277868485, 4875.878103047452, 3926.5484469285825, 3981.3828856527284, 4049.9595004049584, 4070.8575199867864, 4087.816048277835, 1246.9501219951283, 1282.9857124314735, 1289.6711338608782, 1295.8509771376223, 1305.6083175047447, 1019.7450637340664, 1033.8759348878182, 1050.5745173204864, 1052.884182739898, 1053.1995683280609, 5903.704814759245, 5908.970455147697, 6047.425494578008, 6063.939360606338, 6150.231221097363, 447.7604481602392, 453.1865301783307, 459.7517340636086, 461.7460396781824, 470.83991442909576, 4006.098627808778, 4016.779077150785, 4066.755994640331, 4109.815058322382, 4124.938125928114, 10425.895741042492, 10867.891321086847, 10888.945555272332, 10951.0, 11066.944665276622, 10773.51519181623, 10832.295900766425, 10900.0, 10930.726731831608, 10947.288426252268, 7943.880841787378, 8041.718539851077, 8176.223258790405, 8249.670013199524, 8290.37822163328, 6029.698515074141, 6044.90932636002, 6103.084537319438, 6151.507879369729, 6154.261488621394, 9949.353292035994, 9960.103590676585, 10063.194944404577, 10081.445520496443, 10084.54619542137, 10092.848607271044, 10127.341722787995, 10140.949295253477, 10162.491875406053, 10195.490225488844, 7173.928260717326, 7429.294217049372, 7611.124720657195, 7803.063632364153, 7884.487508311941, 8592.0, 8619.1811777881, 8633.093525180017, 8645.697400590949, 8688.0, 7639.350655194371, 7839.0, 7843.803904902423, 7911.762647120596, 7970.721024764105, 7657.34912532441, 7728.149903510722, 7755.883661744965, 7834.294913457816, 7843.372530197685, 7072.893906591508, 7076.009358689685, 7101.502894797314, 7103.431725461847, 7144.0, 4796.25658023007, 4848.0788650156355, 4933.728644924564, 4981.128302547112, 5009.0482808266315, 5121.103806833864, 5193.584513239008, 5265.131253343156, 5268.86827829292, 5328.387235467971], "value": 4549.390949672446, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.3451655073997, 323.9643639199688, 324.6493786710352, 341.6600482519893, 342.4554957617388, 5197.298364720761, 5278.5513231375335, 5284.762185701643, 5315.973420132904, 5333.653312534682, 4943.62922780792, 5093.286939828417, 5175.637705360617, 5199.298094757207, 5234.528892399683, 4151.688623353246, 4171.165766846628, 4189.539150693437, 4216.957830421695, 4226.809793559281, 4147.221361687676, 4158.251514727348, 4168.291390463613, 4186.455760751098, 4191.895202619929, 3956.030772460751, 4012.7993600320015, 4088.5707000764937, 4095.5699651536456, 4175.413342929698, 4107.363358679409, 4115.444415003996, 4122.464079669654, 4143.7720925349095, 4148.543660197391, 3681.1533347329996, 3682.429223470366, 3684.428913518382, 3688.944665830015, 3710.8515659373593, 4937.481564435754, 5007.198848184273, 5013.323201367805, 5054.418741844698, 5069.138246498104, 4646.767661616939, 4681.0, 4685.1800934836265, 4733.526647335273, 4742.596879265267, 5369.5167434931045, 5397.62216644835, 5410.242566040757, 5441.673499590037, 5450.400455949843, 3951.6641085507767, 4035.3745169498775, 4036.365272064789, 4052.918941621151, 4060.634542891154, 5601.075822489284, 5627.634203776783, 5636.294578602294, 5638.673182822155, 5689.943100569024, 4068.532118806316, 4097.97951010243, 4115.8971025724595, 4120.690948178839, 4151.211269858717, 4005.178938317638, 4121.216968775923, 4127.628513433745, 4186.539480657125, 4202.285611446062, 4657.46439159501, 4658.114958157939, 4662.207424737802, 4693.671442998957, 4705.952940470552, 5460.672359658433, 5506.229127922094, 5522.420145884705, 5525.751341189656, 5547.916781248285, 1341.9530316439073, 1353.8240028796195, 1408.8591140886035, 1438.6762978329791, 1453.5639308207496, 930.2000279759402, 945.2438049560325, 946.2146418472652, 959.9088086631759, 964.1756298364797, 4659.949622166236, 4669.649776266726, 4726.740029298353, 4742.549457801503, 4747.240441529339, 4874.8537543873035, 4952.919773695056, 4954.9752251238515, 4955.578775804025, 4997.475265097112, 4433.800478978454, 4633.675642704979, 4649.535396350139, 4683.765811709401, 4699.697148397329, 2624.842509449439, 2628.6976997645515, 2642.8899862057983, 2657.86710664466, 2661.3745769744096, 4987.900241995139, 5094.796208151706, 5121.359830021247, 5137.357830271216, 5140.588752899759, 4967.40391153064, 4973.676711013773, 4996.800127994839, 4996.850094497171, 5355.892882142335, 5892.970535147382, 5908.5568582357355, 5915.911261331084, 5939.613925094942, 5958.404159584007, 5543.334799824047, 5702.346319567445, 5717.828465146053, 5725.370209276893, 5742.684152371659, 5502.028904391584, 5594.13290939905, 5623.706547494017, 5626.831195064155, 5637.9718101409235, 5824.679642619697, 5835.4748072673665, 5847.502962248086, 5855.0, 5857.414258574279, 4039.8384064638262, 4069.165821006746, 4083.5712250214488, 4089.3661482470266, 4102.323116685713, 5399.487048730364, 5525.364583072998, 5721.914171287517, 5727.513161381329, 5739.986507083814, 5406.243125962447, 5480.671159730429, 5486.807961721321, 5487.835364939058, 5519.448055194448, 5497.147942068986, 5497.587680924026, 5518.475744804237, 5522.282103326542, 5532.446755324436, 5267.868303292448, 5415.620906536662, 5431.0, 5431.076716958048, 5434.972825135849, 5501.174823776466, 5536.640118392292, 5541.861453463695, 5548.722563871709, 5553.611946862888, 5405.432429594994, 5424.0, 5464.426235245242, 5475.72621368922, 5478.890422191614], "value": 4129.468457247629, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [820.6635279535392, 836.4688422851491, 850.2857599616322, 863.4948555095269, 885.420049867337, 13699.178049317048, 14728.18994955277, 14736.04215725977, 14763.483278085268, 14776.335064922065, 13937.930310348434, 13956.51152209673, 13969.79045314321, 13971.79042314366, 13979.161250324987, 691.374306252842, 804.1757198871175, 813.5891374855689, 816.7263966571206, 818.9140140285274, 808.7695006923028, 817.1338381315794, 819.3813670678659, 819.8196396792696, 820.4134044158426, 866.4974314897376, 867.8871746672924, 868.0754995929346, 868.2316150207049, 868.4398562926908, 722.4906440959111, 848.9405741598091, 864.6843901975765, 869.6651789061194, 870.2428886868402, 638.2532437048618, 638.3552611862053, 639.1148259660357, 639.9552031357807, 640.7693230437039, 12998.675033124247, 13012.21926684402, 13059.477620895248, 13088.0, 13121.015923805657, 12694.42875070624, 13111.0, 13367.264800435973, 13435.798463023062, 13585.86414135856, 10938.507767150497, 11106.666799996014, 11271.549138034468, 12079.396030198455, 12457.128001039931, 4512.323151527265, 4569.451665800092, 4584.1519318925775, 4604.240300350438, 4709.552592503706, 49453.50546494561, 49653.50346496525, 50107.749461252824, 50125.749371253274, 50340.24489632695, 10207.795844083224, 10524.526396312185, 10795.02844743961, 10965.396903170242, 11335.829962550568, 18936.95673893507, 20750.273740419012, 20753.273635422687, 20772.896135519226, 20784.79215207859, 7788.532688038623, 8392.908921840122, 8413.411061225655, 8508.574571271412, 8515.446495977742, 4388.210122178039, 4457.888552786207, 4752.477227504972, 4857.878553036132, 4929.457759646436, 3364.192593777476, 3444.741644376682, 3460.619331873492, 3464.497647841049, 3468.6011108722314, 666.2604508995012, 668.9966550167218, 675.4933799650262, 677.4140368581142, 680.8025672554903, 734.1043926409772, 744.7616762635904, 751.7444069016451, 752.8419032003231, 782.2255966593145, 8927.151920567536, 8938.731838044869, 8964.551772411354, 9050.094990500897, 9108.225800806873, 597.7967491052976, 617.6232498176109, 660.425429876011, 664.2095905871997, 702.9789106326818, 4349.28236840924, 4432.4237849080055, 4466.173757854807, 4476.46282446102, 4538.88652783683, 53686.7315663427, 56326.0, 57005.14492282619, 57947.13079303814, 58125.12812307819, 50735.23897141472, 52961.2055819163, 53120.46879531155, 54030.729846350514, 55475.44524554784, 47899.0, 47934.28098578524, 47975.76012119917, 48392.758036210296, 49146.0, 8477.364197685074, 8633.95683021581, 8710.564471776512, 8741.475511469333, 8812.250958668328, 31825.204369890937, 32463.837680811444, 32593.34813303768, 32945.34109317848, 33088.66911330856, 39345.40981885217, 43085.3537196936, 43372.783136084116, 43412.56587434085, 43735.56264437315, 11363.715907102389, 11394.658160255205, 11502.42487875586, 11686.94156529212, 11975.76048479043, 11375.658730238107, 11657.0, 12019.459124339252, 12345.691357716129, 12657.050721195763, 11270.380129092979, 11811.468483918072, 11877.168598198388, 11891.0, 11929.82105268439, 11137.721556960816, 11443.485043173243, 11586.0, 11595.478203481032, 11945.462454189408, 11228.157888158257, 11501.654950351503, 11608.419579020847, 11866.82199766987, 12051.035917126434, 9847.803043938939, 9910.30627856072, 9966.900330996597, 10029.398236105857, 10256.0, 9800.0, 9817.656382026595, 9862.112409883144, 9931.255155863484, 9960.651377201762], "value": 6618.759325251256, "unit": "score"}], "name": "dromaeo_css", "value": 7020.20578500286, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 105.0, 112.0, 109.0, 110.0, 110.0, 109.0, 109.0, 110.0, 109.0], "value": 109.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 125.0, 159.0, 208.0, 132.0, 149.0, 162.0, 134.0, 192.0, 126.0], "value": 154.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 161.0, 163.0, 161.0, 162.0, 161.0, 162.0, 163.0, 162.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 155.0, 76.0, 75.0, 77.0, 75.0, 133.0, 76.0], "value": 89.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 147.0, 92.0, 92.0, 92.0, 126.0, 93.0, 91.0, 128.0], "value": 104.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 146.0, 145.0], "value": 145.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 96.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 59.0, 58.0, 58.0, 59.0, 59.0, 121.0, 56.0, 56.0], "value": 64.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 58.0, 59.0, 60.0, 59.0, 59.0, 59.0, 59.0, 59.0], "value": 59.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 79.0, 80.0, 77.0, 79.0, 79.0, 79.0, 80.0, 80.0], "value": 79.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 125.0, 124.0, 124.0, 123.0, 130.0, 123.0, 122.0, 240.0, 128.0], "value": 137.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 172.0, 171.0, 171.0, 172.0, 174.0, 168.0, 173.0, 169.0, 169.0], "value": 170.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 69.0, 69.0, 66.0, 65.0, 66.0, 65.0, 65.0, 64.0, 64.0], "value": 65.8, "unit": "score"}], "name": "kraken", "value": 1552.1, "alertThreshold": 2.0}]} 09:05:04 INFO - Return code: 0 09:05:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:05:04 ERROR - # TBPL SUCCESS # 09:05:04 INFO - Running post-action listener: _resource_record_post_action 09:05:04 INFO - ##### 09:05:04 INFO - ##### Finished run-tests step (success) 09:05:04 INFO - ##### 09:05:04 INFO - Running post-run listener: _resource_record_post_run 09:05:05 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 8192; Write bytes: 553799680; Read time: 284; Write time: 550856 09:05:05 INFO - TinderboxPrint: CPU usage
13.1% 09:05:05 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 284 09:05:05 INFO - TinderboxPrint: I/O write bytes / time
553,799,680 / 550,856 09:05:05 INFO - TinderboxPrint: CPU idle
8,272.7 (86.9%) 09:05:05 INFO - TinderboxPrint: CPU user
1,203.5 (12.6%) 09:05:05 INFO - TinderboxPrint: Swap in / out
0 / 0 09:05:05 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 4096; Write bytes: 259919872; Read time: 184; Write time: 312140 09:05:05 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 4096; Write bytes: 289992704; Read time: 100; Write time: 238052 09:05:05 INFO - Running post-run listener: _upload_blobber_files 09:05:05 INFO - Blob upload gear active. 09:05:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:05:05 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 09:05:05 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'] 09:05:05 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 09:05:06 INFO - (blobuploader) - INFO - Open directory for files ... 09:05:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:05:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:05:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:05:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:05:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:05:06 INFO - (blobuploader) - INFO - Done attempting. 09:05:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:05:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:05:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:05:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:05:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:05:07 INFO - (blobuploader) - INFO - Done attempting. 09:05:07 INFO - (blobuploader) - INFO - Iteration through files over. 09:05:07 INFO - Return code: 0 09:05:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:05:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:05:07 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/95c16b2e5cae56aaf4a3e8f8e0316626f2ec4519d3de9ab9d692bfdaa0420e0bd49eb4d5da6353743c8b0a0de845a188f259699d06b0b6eae87ce4c3e890eb78", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a8eec3017602703fa84fbaea33f40a476a20c295d97a0d9ddb79b6994a54dc6a210edc8f588401fb9978a89d745a4c7186f1e2a020f9beb822bfedd677fc2e28"} 09:05:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:05:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:05:07 INFO - Contents: 09:05:07 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/95c16b2e5cae56aaf4a3e8f8e0316626f2ec4519d3de9ab9d692bfdaa0420e0bd49eb4d5da6353743c8b0a0de845a188f259699d06b0b6eae87ce4c3e890eb78", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a8eec3017602703fa84fbaea33f40a476a20c295d97a0d9ddb79b6994a54dc6a210edc8f588401fb9978a89d745a4c7186f1e2a020f9beb822bfedd677fc2e28"} 09:05:07 INFO - Running post-run listener: copy_logs_to_upload_dir 09:05:07 INFO - Copying logs to upload dir... 09:05:07 INFO - mkdir: /builds/slave/test/build/upload/logs 09:05:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1231.084053 ========= master_lag: 20.47 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2016-05-12 09:05:27.932898) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 09:05:27.936393) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1463067874.762792-725412232 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/95c16b2e5cae56aaf4a3e8f8e0316626f2ec4519d3de9ab9d692bfdaa0420e0bd49eb4d5da6353743c8b0a0de845a188f259699d06b0b6eae87ce4c3e890eb78", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a8eec3017602703fa84fbaea33f40a476a20c295d97a0d9ddb79b6994a54dc6a210edc8f588401fb9978a89d745a4c7186f1e2a020f9beb822bfedd677fc2e28"} build_url:https://queue.taskcluster.net/v1/task/NZAgrqTqQrW3Ge8-1JnRcg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011345 build_url: 'https://queue.taskcluster.net/v1/task/NZAgrqTqQrW3Ge8-1JnRcg/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/95c16b2e5cae56aaf4a3e8f8e0316626f2ec4519d3de9ab9d692bfdaa0420e0bd49eb4d5da6353743c8b0a0de845a188f259699d06b0b6eae87ce4c3e890eb78", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a8eec3017602703fa84fbaea33f40a476a20c295d97a0d9ddb79b6994a54dc6a210edc8f588401fb9978a89d745a4c7186f1e2a020f9beb822bfedd677fc2e28"}' ========= master_lag: 0.10 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 09:05:28.052239) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 09:05:28.052612) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1463067874.762792-725412232 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004526 ========= master_lag: 0.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 09:05:28.068857) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 09:05:28.069204) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 09:05:28.069541) ========= ========= Total master_lag: 20.65 =========