builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-055 starttime: 1463067486.38 results: success (0) buildid: 20160512053006 builduid: 6b2d49f9d5b94a34aa058938e529741e revision: 092d7ffc5eeed5c0096117fff18638bbda0ebf6e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 08:38:06.381274) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 08:38:06.381715) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 08:38:06.381987) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 08:38:06.395175) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 08:38:06.395445) ========= 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=82e07478c745e68b1fe4678a000001bc-1463067485.956944-975307266 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-12 08:38:06-- 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% 22.8M=0.001s 2016-05-12 08:38:06 (22.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.133968 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 08:38:06.540858) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 08:38:06.541200) ========= 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=82e07478c745e68b1fe4678a000001bc-1463067485.956944-975307266 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019231 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 08:38:06.590540) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-12 08:38:06.590924) ========= 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=82e07478c745e68b1fe4678a000001bc-1463067485.956944-975307266 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-12 08:38:06,852 truncating revision to first 12 chars 2016-05-12 08:38:06,852 Setting DEBUG logging. 2016-05-12 08:38:06,852 attempt 1/10 2016-05-12 08:38:06,852 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:38:08,093 unpacking tar archive at: fx-team-092d7ffc5eee/testing/mozharness/ program finished with exit code 0 elapsedTime=1.541401 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-12 08:38:08.145400) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 08:38:08.145736) ========= script_repo_revision: 092d7ffc5eeed5c0096117fff18638bbda0ebf6e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 08:38:08.146112) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 08:38:08.146373) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 08:38:08.153779) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 31 secs) (at 2016-05-12 08:38:08.154081) ========= /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=82e07478c745e68b1fe4678a000001bc-1463067485.956944-975307266 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:38:08 INFO - MultiFileLogger online at 20160512 08:38:08 in /builds/slave/test 08:38:08 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:38:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:38:08 INFO - {'append_to_log': False, 08:38:08 INFO - 'base_work_dir': '/builds/slave/test', 08:38:08 INFO - 'blob_upload_branch': 'Fx-Team', 08:38:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:38:08 INFO - 'branch': 'Fx-Team', 08:38:08 INFO - 'buildbot_json_path': 'buildprops.json', 08:38:08 INFO - 'config_files': ('talos/linux_config.py',), 08:38:08 INFO - 'default_actions': ('clobber', 08:38:08 INFO - 'read-buildbot-config', 08:38:08 INFO - 'download-and-extract', 08:38:08 INFO - 'populate-webroot', 08:38:08 INFO - 'create-virtualenv', 08:38:08 INFO - 'install', 08:38:08 INFO - 'run-tests'), 08:38:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:38:08 INFO - 'download_minidump_stackwalk': True, 08:38:08 INFO - 'download_symbols': 'ondemand', 08:38:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:38:08 INFO - 'tooltool.py': '/tools/tooltool.py', 08:38:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:38:08 INFO - '/tools/misc-python/virtualenv.py')}, 08:38:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:38:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:38:08 INFO - 'installer_path': 'installer.exe', 08:38:08 INFO - 'log_level': 'info', 08:38:08 INFO - 'log_name': 'talos', 08:38:08 INFO - 'log_to_console': True, 08:38:08 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:38:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:38:08 INFO - 'opt_config_files': (), 08:38:08 INFO - 'pip_index': False, 08:38:08 INFO - 'sps_profile': False, 08:38:08 INFO - 'sps_profile_interval': 0, 08:38:08 INFO - 'suite': 'dromaeojs', 08:38:08 INFO - 'system_bits': '32', 08:38:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:38:08 INFO - 'title': 'talos-linux64-ix-055', 08:38:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:38:08 INFO - 'use_talos_json': True, 08:38:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:38:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:38:08 INFO - 'work_dir': 'build'} 08:38:08 INFO - ##### 08:38:08 INFO - ##### Running clobber step. 08:38:08 INFO - ##### 08:38:08 INFO - Running pre-action listener: _resource_record_pre_action 08:38:08 INFO - Running main action method: clobber 08:38:08 INFO - rmtree: /builds/slave/test/build 08:38:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:38:08 INFO - Running post-action listener: _resource_record_post_action 08:38:08 INFO - ##### 08:38:08 INFO - ##### Finished clobber step (success) 08:38:08 INFO - ##### 08:38:08 INFO - ##### 08:38:08 INFO - ##### Running read-buildbot-config step. 08:38:08 INFO - ##### 08:38:08 INFO - Running pre-action listener: _resource_record_pre_action 08:38:08 INFO - Running main action method: read_buildbot_config 08:38:08 INFO - Using buildbot properties: 08:38:08 INFO - { 08:38:08 INFO - "project": "", 08:38:08 INFO - "product": "firefox", 08:38:08 INFO - "script_repo_revision": "production", 08:38:08 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 08:38:08 INFO - "repository": "", 08:38:08 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 08:38:08 INFO - "buildid": "20160512053006", 08:38:08 INFO - "pgo_build": "True", 08:38:08 INFO - "basedir": "/builds/slave/test", 08:38:08 INFO - "buildnumber": 1147, 08:38:08 INFO - "slavename": "talos-linux64-ix-055", 08:38:08 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 08:38:08 INFO - "platform": "ubuntu64_hw", 08:38:08 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 08:38:08 INFO - "branch": "fx-team", 08:38:08 INFO - "repo_path": "integration/fx-team", 08:38:08 INFO - "revision": "092d7ffc5eeed5c0096117fff18638bbda0ebf6e", 08:38:08 INFO - "stage_platform": "linux64-pgo", 08:38:08 INFO - "builduid": "6b2d49f9d5b94a34aa058938e529741e", 08:38:08 INFO - "slavebuilddir": "test" 08:38:08 INFO - } 08:38:08 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:38:08 INFO - Running post-action listener: _resource_record_post_action 08:38:08 INFO - ##### 08:38:08 INFO - ##### Finished read-buildbot-config step (success) 08:38:08 INFO - ##### 08:38:08 INFO - ##### 08:38:08 INFO - ##### Running download-and-extract step. 08:38:08 INFO - ##### 08:38:08 INFO - Running pre-action listener: _resource_record_pre_action 08:38:08 INFO - Running main action method: download_and_extract 08:38:08 INFO - mkdir: /builds/slave/test/build/tests 08:38:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:38:08 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:38:08 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:38:08 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:38:08 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:38:08 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:38:09 INFO - Downloaded 1361 bytes. 08:38:09 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 08:38:09 INFO - Using the following test package requirements: 08:38:09 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 08:38:09 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:38:09 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:38:09 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:38:09 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 08:38:09 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:38:09 INFO - u'jsshell-linux-x86_64.zip'], 08:38:09 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:38:09 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:38:09 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 08:38:09 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:38:09 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:38:09 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:38:09 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:38:09 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:38:09 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:38:09 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:38:09 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:38:09 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:38:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:38:09 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:38:09 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:38:09 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:38:09 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:38:09 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:38:10 INFO - Downloaded 23902869 bytes. 08:38:10 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:38:10 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:38:11 INFO - Return code: 0 08:38:11 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:38:11 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:38:11 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:38:11 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:38:11 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:38:11 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:38:11 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:38:13 INFO - Downloaded 23902869 bytes. 08:38:13 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:38:13 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:38:15 INFO - Return code: 0 08:38:15 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:38:15 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:38:15 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:38:15 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:38:15 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:38:15 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:38:16 INFO - Downloaded 10840239 bytes. 08:38:16 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:38:16 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:38:16 INFO - Return code: 0 08:38:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:38:16 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:38:16 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:38:16 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:38:16 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:38:16 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:38:17 INFO - Downloaded 58336046 bytes. 08:38:17 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:38:17 INFO - mkdir: /builds/slave/test/properties 08:38:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:38:17 INFO - Writing to file /builds/slave/test/properties/build_url 08:38:17 INFO - Contents: 08:38:17 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:38:18 INFO - Running post-action listener: _resource_record_post_action 08:38:18 INFO - Running post-action listener: set_extra_try_arguments 08:38:18 INFO - ##### 08:38:18 INFO - ##### Finished download-and-extract step (success) 08:38:18 INFO - ##### 08:38:18 INFO - ##### 08:38:18 INFO - ##### Running populate-webroot step. 08:38:18 INFO - ##### 08:38:18 INFO - Running pre-action listener: _resource_record_pre_action 08:38:18 INFO - Running main action method: populate_webroot 08:38:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:38:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:38:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:38:18 INFO - '--tppagecycles', 08:38:18 INFO - '7'], 08:38:18 INFO - 'tests': ['tsvgm']}}, 08:38:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:38:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:38:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:38:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:38:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:38:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:38:18 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:38:18 INFO - 'talos_options': ['--disable-e10s'], 08:38:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:38:18 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:38:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:38:18 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:38:18 INFO - 'talos_options': ['--disable-e10s'], 08:38:18 INFO - 'tests': ['damp', 'tps']}, 08:38:18 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:38:18 INFO - 'tests': ['damp', 'tps']}, 08:38:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:38:18 INFO - 'tests': ['dromaeo_dom']}, 08:38:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:38:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:38:18 INFO - 'tests': ['a11yr', 08:38:18 INFO - 'ts_paint', 08:38:18 INFO - 'tpaint', 08:38:18 INFO - 'sessionrestore', 08:38:18 INFO - 'sessionrestore_no_auto_restore', 08:38:18 INFO - 'tabpaint']}, 08:38:18 INFO - 'other-e10s': {'tests': ['a11yr', 08:38:18 INFO - 'ts_paint', 08:38:18 INFO - 'tpaint', 08:38:18 INFO - 'sessionrestore', 08:38:18 INFO - 'sessionrestore_no_auto_restore', 08:38:18 INFO - 'tabpaint']}, 08:38:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:38:18 INFO - 'ts_paint', 08:38:18 INFO - 'tpaint', 08:38:18 INFO - 'sessionrestore', 08:38:18 INFO - 'sessionrestore_no_auto_restore', 08:38:18 INFO - 'tabpaint']}, 08:38:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:38:18 INFO - 'ts_paint', 08:38:18 INFO - 'tpaint', 08:38:18 INFO - 'sessionrestore', 08:38:18 INFO - 'sessionrestore_no_auto_restore', 08:38:18 INFO - 'tabpaint']}, 08:38:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:38:18 INFO - 'tests': ['a11yr', 08:38:18 INFO - 'ts_paint', 08:38:18 INFO - 'tpaint', 08:38:18 INFO - 'sessionrestore', 08:38:18 INFO - 'sessionrestore_no_auto_restore', 08:38:18 INFO - 'tabpaint']}, 08:38:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:38:18 INFO - 'tests': ['a11yr', 08:38:18 INFO - 'ts_paint', 08:38:18 INFO - 'tpaint', 08:38:18 INFO - 'sessionrestore', 08:38:18 INFO - 'sessionrestore_no_auto_restore', 08:38:18 INFO - 'tabpaint']}, 08:38:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:38:18 INFO - 'tests': ['tsvgx', 08:38:18 INFO - 'tsvgr_opacity', 08:38:18 INFO - 'tart', 08:38:18 INFO - 'tscrollx', 08:38:18 INFO - 'cart']}, 08:38:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:38:18 INFO - 'tsvgr_opacity', 08:38:18 INFO - 'tart', 08:38:18 INFO - 'tscrollx', 08:38:18 INFO - 'cart']}, 08:38:18 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:38:18 INFO - 'talos_options': ['--disable-e10s'], 08:38:18 INFO - 'tests': ['tp5o']}, 08:38:18 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:38:18 INFO - 'tests': ['tp5o']}, 08:38:18 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:38:18 INFO - 'talos_options': ['--disable-e10s', 08:38:18 INFO - '--xperf_path', 08:38:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:38:18 INFO - 'tests': ['tp5n']}, 08:38:18 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:38:18 INFO - 'talos_options': ['--xperf_path', 08:38:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:38:18 INFO - 'tests': ['tp5n']}}, 08:38:18 INFO - 'talos.zip': {'path': '', 08:38:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:38:18 INFO - Running post-action listener: _resource_record_post_action 08:38:18 INFO - ##### 08:38:18 INFO - ##### Finished populate-webroot step (success) 08:38:18 INFO - ##### 08:38:18 INFO - ##### 08:38:18 INFO - ##### Running create-virtualenv step. 08:38:18 INFO - ##### 08:38:18 INFO - Running pre-action listener: _resource_record_pre_action 08:38:18 INFO - Running main action method: create_virtualenv 08:38:18 INFO - Creating virtualenv /builds/slave/test/build/venv 08:38:18 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:38:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:38:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:38:18 INFO - Using real prefix '/usr' 08:38:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:38:19 INFO - Installing distribute.............................................................................................................................................................................................done. 08:38:20 INFO - Installing pip.................done. 08:38:21 INFO - Return code: 0 08:38:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:38:21 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:38:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:38:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:21 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:38:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:38:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:21 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 0x7fb5ea26cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf3f770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfce9c0>, '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 0xfcf8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfcfd50>, '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': '82e07478c745e68b1fe4678a000001bc-1463067485.956944-975307266', '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:38:21 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:38:21 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:38:21 INFO - Using env: {'DISPLAY': ':0', 08:38:21 INFO - 'HOME': '/home/cltbld', 08:38:21 INFO - 'LANG': 'en_US.UTF-8', 08:38:21 INFO - 'LANGUAGE': 'en_US:en', 08:38:21 INFO - 'LOGNAME': 'cltbld', 08:38:21 INFO - 'MAIL': '/var/mail/cltbld', 08:38:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:21 INFO - 'MOZ_NO_REMOTE': '1', 08:38:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:38:21 INFO - 'NO_EM_RESTART': '1', 08:38:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:38:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:38:21 INFO - 'PWD': '/builds/slave/test', 08:38:21 INFO - 'SHELL': '/bin/bash', 08:38:21 INFO - 'SHLVL': '1', 08:38:21 INFO - 'TERM': 'linux', 08:38:21 INFO - 'TMOUT': '86400', 08:38:21 INFO - 'USER': 'cltbld', 08:38:21 INFO - 'XDG_SESSION_COOKIE': '82e07478c745e68b1fe4678a000001bc-1463067485.956944-975307266', 08:38:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:21 INFO - '_': '/tools/buildbot/bin/python'} 08:38:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:38:21 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:38:21 INFO - Cleaning up... 08:38:21 INFO - Return code: 0 08:38:21 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 08:38:21 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:38:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:38:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:21 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:38:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:38:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:21 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 0x7fb5ea26cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf3f770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfce9c0>, '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 0xfcf8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfcfd50>, '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': '82e07478c745e68b1fe4678a000001bc-1463067485.956944-975307266', '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:38:21 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:38:21 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:38:21 INFO - Using env: {'DISPLAY': ':0', 08:38:21 INFO - 'HOME': '/home/cltbld', 08:38:21 INFO - 'LANG': 'en_US.UTF-8', 08:38:21 INFO - 'LANGUAGE': 'en_US:en', 08:38:21 INFO - 'LOGNAME': 'cltbld', 08:38:21 INFO - 'MAIL': '/var/mail/cltbld', 08:38:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:21 INFO - 'MOZ_NO_REMOTE': '1', 08:38:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:38:21 INFO - 'NO_EM_RESTART': '1', 08:38:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:38:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:38:21 INFO - 'PWD': '/builds/slave/test', 08:38:21 INFO - 'SHELL': '/bin/bash', 08:38:21 INFO - 'SHLVL': '1', 08:38:21 INFO - 'TERM': 'linux', 08:38:21 INFO - 'TMOUT': '86400', 08:38:21 INFO - 'USER': 'cltbld', 08:38:21 INFO - 'XDG_SESSION_COOKIE': '82e07478c745e68b1fe4678a000001bc-1463067485.956944-975307266', 08:38:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:21 INFO - '_': '/tools/buildbot/bin/python'} 08:38:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:38:21 INFO - Downloading/unpacking psutil>=0.7.1 08:38:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:38:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:38:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:38:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:38:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:38:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:38:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:38:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:38:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:38:23 INFO - Installing collected packages: psutil 08:38:23 INFO - Running setup.py install for psutil 08:38:23 INFO - building 'psutil._psutil_linux' extension 08:38:23 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:38:23 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:38:23 INFO - building 'psutil._psutil_posix' extension 08:38:23 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:38:23 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:38:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:38:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:38:23 INFO - Successfully installed psutil 08:38:23 INFO - Cleaning up... 08:38:23 INFO - Return code: 0 08:38:23 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 08:38:23 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:38:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:38:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:23 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:38:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:38:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:23 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 0x7fb5ea26cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf3f770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfce9c0>, '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 0xfcf8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfcfd50>, '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': '82e07478c745e68b1fe4678a000001bc-1463067485.956944-975307266', '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:38:23 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:38:23 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:38:23 INFO - Using env: {'DISPLAY': ':0', 08:38:23 INFO - 'HOME': '/home/cltbld', 08:38:23 INFO - 'LANG': 'en_US.UTF-8', 08:38:23 INFO - 'LANGUAGE': 'en_US:en', 08:38:23 INFO - 'LOGNAME': 'cltbld', 08:38:23 INFO - 'MAIL': '/var/mail/cltbld', 08:38:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:23 INFO - 'MOZ_NO_REMOTE': '1', 08:38:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:38:23 INFO - 'NO_EM_RESTART': '1', 08:38:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:38:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:38:23 INFO - 'PWD': '/builds/slave/test', 08:38:23 INFO - 'SHELL': '/bin/bash', 08:38:23 INFO - 'SHLVL': '1', 08:38:23 INFO - 'TERM': 'linux', 08:38:23 INFO - 'TMOUT': '86400', 08:38:23 INFO - 'USER': 'cltbld', 08:38:23 INFO - 'XDG_SESSION_COOKIE': '82e07478c745e68b1fe4678a000001bc-1463067485.956944-975307266', 08:38:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:23 INFO - '_': '/tools/buildbot/bin/python'} 08:38:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:38:24 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 08:38:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:38:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:38:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:38:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:38:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:38:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:38:26 INFO - Downloading mozsystemmonitor-0.0.tar.gz 08:38:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:38:26 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:38:26 INFO - Installing collected packages: mozsystemmonitor 08:38:26 INFO - Running setup.py install for mozsystemmonitor 08:38:26 INFO - Successfully installed mozsystemmonitor 08:38:26 INFO - Cleaning up... 08:38:26 INFO - Return code: 0 08:38:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:38:26 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:38:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:38:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:26 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:38:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:38:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:26 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 0x7fb5ea26cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf3f770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfce9c0>, '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 0xfcf8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfcfd50>, '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': '82e07478c745e68b1fe4678a000001bc-1463067485.956944-975307266', '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:38:26 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:38:26 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:38:26 INFO - Using env: {'DISPLAY': ':0', 08:38:26 INFO - 'HOME': '/home/cltbld', 08:38:26 INFO - 'LANG': 'en_US.UTF-8', 08:38:26 INFO - 'LANGUAGE': 'en_US:en', 08:38:26 INFO - 'LOGNAME': 'cltbld', 08:38:26 INFO - 'MAIL': '/var/mail/cltbld', 08:38:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:26 INFO - 'MOZ_NO_REMOTE': '1', 08:38:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:38:26 INFO - 'NO_EM_RESTART': '1', 08:38:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:38:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:38:26 INFO - 'PWD': '/builds/slave/test', 08:38:26 INFO - 'SHELL': '/bin/bash', 08:38:26 INFO - 'SHLVL': '1', 08:38:26 INFO - 'TERM': 'linux', 08:38:26 INFO - 'TMOUT': '86400', 08:38:26 INFO - 'USER': 'cltbld', 08:38:26 INFO - 'XDG_SESSION_COOKIE': '82e07478c745e68b1fe4678a000001bc-1463067485.956944-975307266', 08:38:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:26 INFO - '_': '/tools/buildbot/bin/python'} 08:38:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:38:26 INFO - Downloading/unpacking blobuploader==1.2.4 08:38:26 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:38:26 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:38:26 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:38:26 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:38:26 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:38:26 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:38:28 INFO - Downloading blobuploader-1.2.4.tar.gz 08:38:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:38:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:38:28 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:38:28 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:38:28 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:38:28 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:38:28 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:38:28 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:38:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:38:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:38:28 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:38:28 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:38:28 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:38:28 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:38:28 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:38:28 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:38:28 INFO - Downloading docopt-0.6.1.tar.gz 08:38:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:38:29 INFO - Installing collected packages: blobuploader, requests, docopt 08:38:29 INFO - Running setup.py install for blobuploader 08:38:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:38:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:38:29 INFO - Running setup.py install for requests 08:38:29 INFO - Running setup.py install for docopt 08:38:29 INFO - Successfully installed blobuploader requests docopt 08:38:29 INFO - Cleaning up... 08:38:29 INFO - Return code: 0 08:38:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:38:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:38:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:38:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:38:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:38:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:29 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 0x7fb5ea26cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf3f770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfce9c0>, '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 0xfcf8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfcfd50>, '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': '82e07478c745e68b1fe4678a000001bc-1463067485.956944-975307266', '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:38:29 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:38:29 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:38:29 INFO - Using env: {'DISPLAY': ':0', 08:38:29 INFO - 'HOME': '/home/cltbld', 08:38:29 INFO - 'LANG': 'en_US.UTF-8', 08:38:29 INFO - 'LANGUAGE': 'en_US:en', 08:38:29 INFO - 'LOGNAME': 'cltbld', 08:38:29 INFO - 'MAIL': '/var/mail/cltbld', 08:38:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:29 INFO - 'MOZ_NO_REMOTE': '1', 08:38:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:38:29 INFO - 'NO_EM_RESTART': '1', 08:38:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:38:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:38:29 INFO - 'PWD': '/builds/slave/test', 08:38:29 INFO - 'SHELL': '/bin/bash', 08:38:29 INFO - 'SHLVL': '1', 08:38:29 INFO - 'TERM': 'linux', 08:38:29 INFO - 'TMOUT': '86400', 08:38:29 INFO - 'USER': 'cltbld', 08:38:29 INFO - 'XDG_SESSION_COOKIE': '82e07478c745e68b1fe4678a000001bc-1463067485.956944-975307266', 08:38:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:29 INFO - '_': '/tools/buildbot/bin/python'} 08:38:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:38:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:38:29 INFO - Running setup.py (path:/tmp/pip-LRLzSH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:38:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:38:29 INFO - Running setup.py (path:/tmp/pip-ciYQay-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:38:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:38:30 INFO - Running setup.py (path:/tmp/pip-lbcdz2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:38:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:38:30 INFO - Running setup.py (path:/tmp/pip-8llYdy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:38:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:38:30 INFO - Running setup.py (path:/tmp/pip-z4y9Bz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:38:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:38:30 INFO - Running setup.py (path:/tmp/pip-Dy0hSh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:38:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:38:30 INFO - Running setup.py (path:/tmp/pip-rBEkVE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:38:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:38:30 INFO - Running setup.py (path:/tmp/pip-0fMxWg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:38:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:38:30 INFO - Running setup.py (path:/tmp/pip-ugWxGl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:38:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:38:30 INFO - Running setup.py (path:/tmp/pip-AUMaS9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:38:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:38:30 INFO - Running setup.py (path:/tmp/pip-UHedE4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:38:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:38:30 INFO - Running setup.py (path:/tmp/pip-MDJGed-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:38:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:38:30 INFO - Running setup.py (path:/tmp/pip-6NW8fB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:38:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:38:30 INFO - Running setup.py (path:/tmp/pip-EDh1V2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:38:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:38:30 INFO - Running setup.py (path:/tmp/pip-wInYPl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:38:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:38:30 INFO - Running setup.py (path:/tmp/pip-plrFJF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:38:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:38:31 INFO - Running setup.py (path:/tmp/pip-nlUFDz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:38:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:38:31 INFO - Running setup.py install for manifestparser 08:38:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:38:31 INFO - Running setup.py install for mozcrash 08:38:31 INFO - Running setup.py install for mozdebug 08:38:31 INFO - Running setup.py install for mozdevice 08:38:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:38:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:38:31 INFO - Running setup.py install for mozfile 08:38:31 INFO - Running setup.py install for mozhttpd 08:38:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:38:31 INFO - Running setup.py install for mozinfo 08:38:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:38:31 INFO - Running setup.py install for mozInstall 08:38:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:38:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:38:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:38:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:38:31 INFO - Running setup.py install for mozleak 08:38:32 INFO - Running setup.py install for mozlog 08:38:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:38:32 INFO - Running setup.py install for moznetwork 08:38:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:38:32 INFO - Running setup.py install for mozprocess 08:38:32 INFO - Running setup.py install for mozprofile 08:38:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:38:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:38:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:38:32 INFO - Running setup.py install for mozrunner 08:38:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:38:32 INFO - Running setup.py install for mozscreenshot 08:38:32 INFO - Running setup.py install for moztest 08:38:32 INFO - Running setup.py install for mozversion 08:38:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:38:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:38:32 INFO - Cleaning up... 08:38:32 INFO - Return code: 0 08:38:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:38:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:38:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:38:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:38:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:38:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:32 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 0x7fb5ea26cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf3f770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfce9c0>, '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 0xfcf8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfcfd50>, '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': '82e07478c745e68b1fe4678a000001bc-1463067485.956944-975307266', '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:38:32 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:38:32 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:38:32 INFO - Using env: {'DISPLAY': ':0', 08:38:32 INFO - 'HOME': '/home/cltbld', 08:38:32 INFO - 'LANG': 'en_US.UTF-8', 08:38:32 INFO - 'LANGUAGE': 'en_US:en', 08:38:32 INFO - 'LOGNAME': 'cltbld', 08:38:32 INFO - 'MAIL': '/var/mail/cltbld', 08:38:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:32 INFO - 'MOZ_NO_REMOTE': '1', 08:38:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:38:32 INFO - 'NO_EM_RESTART': '1', 08:38:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:38:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:38:32 INFO - 'PWD': '/builds/slave/test', 08:38:32 INFO - 'SHELL': '/bin/bash', 08:38:32 INFO - 'SHLVL': '1', 08:38:32 INFO - 'TERM': 'linux', 08:38:32 INFO - 'TMOUT': '86400', 08:38:32 INFO - 'USER': 'cltbld', 08:38:32 INFO - 'XDG_SESSION_COOKIE': '82e07478c745e68b1fe4678a000001bc-1463067485.956944-975307266', 08:38:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:32 INFO - '_': '/tools/buildbot/bin/python'} 08:38:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:38:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:38:33 INFO - Running setup.py (path:/tmp/pip-n7g2nY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:38:33 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:38:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:38:33 INFO - Running setup.py (path:/tmp/pip-HJiCOQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:38:33 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:38:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:38:33 INFO - Running setup.py (path:/tmp/pip-9RYSuO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:38:33 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:38:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:38:33 INFO - Running setup.py (path:/tmp/pip-1pSIxq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:38:33 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:38:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:38:33 INFO - Running setup.py (path:/tmp/pip-v4Aynx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:38:33 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:38:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:38:33 INFO - Running setup.py (path:/tmp/pip-EyZUaD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:38:33 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:38:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:38:33 INFO - Running setup.py (path:/tmp/pip-dEFwlU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:38:33 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:38:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:38:33 INFO - Running setup.py (path:/tmp/pip-7yplRZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:38:33 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:38:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:38:33 INFO - Running setup.py (path:/tmp/pip-eYbJGz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:38:33 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:38:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:38:33 INFO - Running setup.py (path:/tmp/pip-gavEOL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:38:33 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:38:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:38:33 INFO - Running setup.py (path:/tmp/pip-BTzxXt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:38:33 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:38:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:38:33 INFO - Running setup.py (path:/tmp/pip-aUCz3i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:38:33 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:38:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:38:33 INFO - Running setup.py (path:/tmp/pip-3NMBip-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:38:34 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:38:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:38:34 INFO - Running setup.py (path:/tmp/pip-n71tNO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:38:34 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:38:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:38:34 INFO - Running setup.py (path:/tmp/pip-RiGsC0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:38:34 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:38:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:38:34 INFO - Running setup.py (path:/tmp/pip-M7T8qq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:38:34 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:38:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:38:34 INFO - Running setup.py (path:/tmp/pip-0imxUI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:38:34 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:38:34 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:38:34 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:38:34 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:38:34 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:38:34 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:38:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:38:34 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:38:34 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:38:34 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:38:34 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:38:34 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:38:34 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:38:36 INFO - Downloading blessings-1.6.tar.gz 08:38:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:38:36 INFO - Installing collected packages: blessings 08:38:36 INFO - Running setup.py install for blessings 08:38:36 INFO - Successfully installed blessings 08:38:36 INFO - Cleaning up... 08:38:36 INFO - Return code: 0 08:38:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:38:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:38:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:38:36 INFO - Reading from file tmpfile_stdout 08:38:36 INFO - Current package versions: 08:38:36 INFO - argparse == 1.2.1 08:38:36 INFO - blessings == 1.6 08:38:36 INFO - blobuploader == 1.2.4 08:38:36 INFO - docopt == 0.6.1 08:38:36 INFO - manifestparser == 1.1 08:38:36 INFO - mozInstall == 1.12 08:38:36 INFO - mozcrash == 0.17 08:38:36 INFO - mozdebug == 0.1 08:38:36 INFO - mozdevice == 0.48 08:38:36 INFO - mozfile == 1.2 08:38:36 INFO - mozhttpd == 0.7 08:38:36 INFO - mozinfo == 0.9 08:38:36 INFO - mozleak == 0.1 08:38:36 INFO - mozlog == 3.2 08:38:36 INFO - moznetwork == 0.27 08:38:36 INFO - mozprocess == 0.22 08:38:36 INFO - mozprofile == 0.28 08:38:36 INFO - mozrunner == 6.11 08:38:36 INFO - mozscreenshot == 0.1 08:38:36 INFO - mozsystemmonitor == 0.0 08:38:36 INFO - moztest == 0.7 08:38:36 INFO - mozversion == 1.4 08:38:36 INFO - psutil == 3.1.1 08:38:36 INFO - requests == 1.2.3 08:38:36 INFO - wsgiref == 0.1.2 08:38:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:38:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:38:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:38:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:38:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:38:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:36 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 0x7fb5ea26cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf3f770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfce9c0>, '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 0xfcf8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfcfd50>, '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': '82e07478c745e68b1fe4678a000001bc-1463067485.956944-975307266', '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:38:36 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:38:36 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:38:36 INFO - Using env: {'DISPLAY': ':0', 08:38:36 INFO - 'HOME': '/home/cltbld', 08:38:36 INFO - 'LANG': 'en_US.UTF-8', 08:38:36 INFO - 'LANGUAGE': 'en_US:en', 08:38:36 INFO - 'LOGNAME': 'cltbld', 08:38:36 INFO - 'MAIL': '/var/mail/cltbld', 08:38:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:36 INFO - 'MOZ_NO_REMOTE': '1', 08:38:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:38:36 INFO - 'NO_EM_RESTART': '1', 08:38:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:38:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:38:36 INFO - 'PWD': '/builds/slave/test', 08:38:36 INFO - 'SHELL': '/bin/bash', 08:38:36 INFO - 'SHLVL': '1', 08:38:36 INFO - 'TERM': 'linux', 08:38:36 INFO - 'TMOUT': '86400', 08:38:36 INFO - 'USER': 'cltbld', 08:38:36 INFO - 'XDG_SESSION_COOKIE': '82e07478c745e68b1fe4678a000001bc-1463067485.956944-975307266', 08:38:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:36 INFO - '_': '/tools/buildbot/bin/python'} 08:38:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:38:36 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:38:36 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:38:36 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:38:36 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:38:36 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:38:36 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:38:36 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:38:36 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:38:36 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:38:36 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:38:36 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:38:36 INFO - Cleaning up... 08:38:36 INFO - Return code: 0 08:38:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:38:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:38:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:38:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:38:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:38:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:36 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 0x7fb5ea26cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf3f770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfce9c0>, '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 0xfcf8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfcfd50>, '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': '82e07478c745e68b1fe4678a000001bc-1463067485.956944-975307266', '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:38:36 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:38:36 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:38:36 INFO - Using env: {'DISPLAY': ':0', 08:38:36 INFO - 'HOME': '/home/cltbld', 08:38:36 INFO - 'LANG': 'en_US.UTF-8', 08:38:36 INFO - 'LANGUAGE': 'en_US:en', 08:38:36 INFO - 'LOGNAME': 'cltbld', 08:38:36 INFO - 'MAIL': '/var/mail/cltbld', 08:38:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:36 INFO - 'MOZ_NO_REMOTE': '1', 08:38:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:38:36 INFO - 'NO_EM_RESTART': '1', 08:38:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:38:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:38:36 INFO - 'PWD': '/builds/slave/test', 08:38:36 INFO - 'SHELL': '/bin/bash', 08:38:36 INFO - 'SHLVL': '1', 08:38:36 INFO - 'TERM': 'linux', 08:38:36 INFO - 'TMOUT': '86400', 08:38:36 INFO - 'USER': 'cltbld', 08:38:36 INFO - 'XDG_SESSION_COOKIE': '82e07478c745e68b1fe4678a000001bc-1463067485.956944-975307266', 08:38:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:36 INFO - '_': '/tools/buildbot/bin/python'} 08:38:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:38:37 INFO - Downloading/unpacking jsonschema 08:38:37 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:38:37 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:38:37 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:38:37 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:38:37 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:38:37 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:38:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:38:39 INFO - Downloading/unpacking functools32 (from jsonschema) 08:38:39 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:38:39 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:38:39 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:38:39 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:38:39 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:38:39 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:38:39 INFO - Downloading functools32-3.2.3-2.tar.gz 08:38:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:38:39 INFO - warning: no files found matching '*.txt' 08:38:39 INFO - no previously-included directories found matching 'build' 08:38:39 INFO - no previously-included directories found matching 'dist' 08:38:39 INFO - no previously-included directories found matching '.git*' 08:38:39 INFO - Installing collected packages: jsonschema, functools32 08:38:39 INFO - Running setup.py install for functools32 08:38:39 INFO - warning: no files found matching '*.txt' 08:38:39 INFO - no previously-included directories found matching 'build' 08:38:39 INFO - no previously-included directories found matching 'dist' 08:38:39 INFO - no previously-included directories found matching '.git*' 08:38:39 INFO - Successfully installed jsonschema functools32 08:38:39 INFO - Cleaning up... 08:38:39 INFO - Return code: 0 08:38:39 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 08:38:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:38:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:38:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:38:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:38:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:39 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 0x7fb5ea26cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf3f770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfce9c0>, '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 0xfcf8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfcfd50>, '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': '82e07478c745e68b1fe4678a000001bc-1463067485.956944-975307266', '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:38:39 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:38:39 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:38:39 INFO - Using env: {'DISPLAY': ':0', 08:38:39 INFO - 'HOME': '/home/cltbld', 08:38:39 INFO - 'LANG': 'en_US.UTF-8', 08:38:39 INFO - 'LANGUAGE': 'en_US:en', 08:38:39 INFO - 'LOGNAME': 'cltbld', 08:38:39 INFO - 'MAIL': '/var/mail/cltbld', 08:38:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:39 INFO - 'MOZ_NO_REMOTE': '1', 08:38:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:38:39 INFO - 'NO_EM_RESTART': '1', 08:38:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:38:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:38:39 INFO - 'PWD': '/builds/slave/test', 08:38:39 INFO - 'SHELL': '/bin/bash', 08:38:39 INFO - 'SHLVL': '1', 08:38:39 INFO - 'TERM': 'linux', 08:38:39 INFO - 'TMOUT': '86400', 08:38:39 INFO - 'USER': 'cltbld', 08:38:39 INFO - 'XDG_SESSION_COOKIE': '82e07478c745e68b1fe4678a000001bc-1463067485.956944-975307266', 08:38:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:39 INFO - '_': '/tools/buildbot/bin/python'} 08:38:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:38:39 INFO - Downloading/unpacking flake8 08:38:39 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:38:39 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:38:39 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:38:39 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:38:39 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:38:39 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:38:41 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:38:41 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:38:41 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:38:41 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:38:41 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:38:41 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:38:41 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:38:41 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:38:41 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:38:41 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:38:41 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:38:41 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:38:41 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:38:41 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:38:41 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:38:41 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:38:41 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:38:41 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:38:41 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:38:41 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:38:41 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:38:41 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:38:41 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:38:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 08:38:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:38:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:38:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:38:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:38:42 INFO - no previously-included directories found matching 'docs/_build' 08:38:42 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:38:42 INFO - Running setup.py install for pep8 08:38:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:38:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:38:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:38:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:38:42 INFO - no previously-included directories found matching 'docs/_build' 08:38:42 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 08:38:42 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:38:42 INFO - Cleaning up... 08:38:42 INFO - Return code: 0 08:38:42 INFO - Running post-action listener: _resource_record_post_action 08:38:42 INFO - Running post-action listener: _start_resource_monitoring 08:38:42 INFO - Starting resource monitoring. 08:38:42 INFO - ##### 08:38:42 INFO - ##### Finished create-virtualenv step (success) 08:38:42 INFO - ##### 08:38:42 INFO - ##### 08:38:42 INFO - ##### Running install step. 08:38:42 INFO - ##### 08:38:42 INFO - Running pre-action listener: _resource_record_pre_action 08:38:42 INFO - Running main action method: install 08:38:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:38:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:38:42 INFO - Reading from file tmpfile_stdout 08:38:42 INFO - Detecting whether we're running mozinstall >=1.0... 08:38:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:38:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:38:42 INFO - Reading from file tmpfile_stdout 08:38:42 INFO - Output received: 08:38:42 INFO - Usage: mozinstall [options] installer 08:38:42 INFO - Options: 08:38:42 INFO - -h, --help show this help message and exit 08:38:42 INFO - -d DEST, --destination=DEST 08:38:42 INFO - Directory to install application into. [default: 08:38:42 INFO - "/builds/slave/test"] 08:38:42 INFO - --app=APP Application being installed. [default: firefox] 08:38:42 INFO - mkdir: /builds/slave/test/build/application 08:38:42 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:38:42 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:38:59 INFO - Reading from file tmpfile_stdout 08:38:59 INFO - Output received: 08:38:59 INFO - /builds/slave/test/build/application/firefox/firefox 08:38:59 INFO - Running post-action listener: _resource_record_post_action 08:38:59 INFO - ##### 08:38:59 INFO - ##### Finished install step (success) 08:38:59 INFO - ##### 08:38:59 INFO - ##### 08:38:59 INFO - ##### Running run-tests step. 08:38:59 INFO - ##### 08:38:59 INFO - Running pre-action listener: _resource_record_pre_action 08:38:59 INFO - Running main action method: run_tests 08:38:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:38:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:38:59 INFO - Python 2.7.3 08:38:59 INFO - Return code: 0 08:38:59 INFO - grabbing minidump binary from tooltool 08:38:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:38:59 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 0xfce9c0>, '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 0xfcf8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfcfd50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:38:59 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:38:59 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:38:59 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:38:59 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:38:59 INFO - Return code: 0 08:38:59 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:38:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:38:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:38:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:38:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:38:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 08:38:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 08:39:00 INFO - Return code: 0 08:39:00 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-055', '--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:39:00 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-055 --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:39:00 INFO - Using env: {'DISPLAY': ':0', 08:39:00 INFO - 'HOME': '/home/cltbld', 08:39:00 INFO - 'LANG': 'en_US.UTF-8', 08:39:00 INFO - 'LANGUAGE': 'en_US:en', 08:39:00 INFO - 'LOGNAME': 'cltbld', 08:39:00 INFO - 'MAIL': '/var/mail/cltbld', 08:39:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:39:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:39:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:39:00 INFO - 'MOZ_NO_REMOTE': '1', 08:39:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:39:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:39:00 INFO - 'NO_EM_RESTART': '1', 08:39:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:39:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:39:00 INFO - 'PWD': '/builds/slave/test', 08:39:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:39:00 INFO - 'SHELL': '/bin/bash', 08:39:00 INFO - 'SHLVL': '1', 08:39:00 INFO - 'TERM': 'linux', 08:39:00 INFO - 'TMOUT': '86400', 08:39:00 INFO - 'USER': 'cltbld', 08:39:00 INFO - 'XDG_SESSION_COOKIE': '82e07478c745e68b1fe4678a000001bc-1463067485.956944-975307266', 08:39:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:39:00 INFO - '_': '/tools/buildbot/bin/python'} 08:39:00 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-055', '--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:39:00 INFO - mozversion application_buildid: 20160512053006 08:39:00 INFO - mozversion application_changeset: 092d7ffc5eeed5c0096117fff18638bbda0ebf6e 08:39:00 INFO - mozversion application_display_name: Nightly 08:39:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:39:00 INFO - mozversion application_name: Firefox 08:39:00 INFO - mozversion application_remotingname: firefox 08:39:00 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 08:39:00 INFO - mozversion application_vendor: Mozilla 08:39:00 INFO - mozversion application_version: 49.0a1 08:39:00 INFO - mozversion platform_buildid: 20160512053006 08:39:00 INFO - mozversion platform_changeset: 092d7ffc5eeed5c0096117fff18638bbda0ebf6e 08:39:00 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 08:39:00 INFO - mozversion platform_version: 49.0a1 08:39:00 INFO - using testdate: 1463067540 08:39:00 INFO - actual date: 1463067540 08:39:00 INFO - starting webserver on 'localhost:49448' 08:39:00 INFO - SUITE-START | Running 2 tests 08:39:00 INFO - TEST-START | dromaeo_css 08:39:00 INFO - operating with platform_type : linux_ 08:39:00 INFO - Initialising browser for dromaeo_css test... 08:39:00 INFO - TEST-INFO | started process 28206 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnlALS4/profile http://localhost:49448/getInfo.html) 08:39:04 INFO - PROCESS | 28206 | __metrics Screen width/height:1600/1200 08:39:04 INFO - PROCESS | 28206 | colorDepth:24 08:39:04 INFO - PROCESS | 28206 | Browser inner width/height: 1024/697 08:39:04 INFO - PROCESS | 28206 | __metrics 08:39:05 ERROR - PROCESS | 28206 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:39:06 INFO - TEST-INFO | 28206: exit 0 08:39:06 INFO - Browser initialized. 08:39:06 INFO - Running cycle 1/1 for dromaeo_css test... 08:39:06 INFO - TEST-INFO | started process 28286 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnlALS4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:39:18 INFO - PROCESS | 28286 | RSS: Main: 151666688 08:39:18 INFO - PROCESS | 28286 | 08:40:09 INFO - PROCESS | 28286 | 1463067609359 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:42:17 INFO - PROCESS | 28286 | Cycle 1(1): loaded http://localhost:49448/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49448/tests/dromaeo/cssquery-ext.html) 08:42:18 INFO - PROCESS | 28286 | RSS: Main: 251453440 08:42:18 INFO - PROCESS | 28286 | 08:45:21 INFO - PROCESS | 28286 | Cycle 1(1): loaded http://localhost:49448/tests/dromaeo/cssquery-ext.html (next: http://localhost:49448/tests/dromaeo/cssquery-jquery.html) 08:45:21 INFO - PROCESS | 28286 | RSS: Main: 238465024 08:45:21 INFO - PROCESS | 28286 | 08:48:23 INFO - PROCESS | 28286 | Cycle 1(1): loaded http://localhost:49448/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49448/tests/dromaeo/cssquery-mootools.html) 08:48:24 INFO - PROCESS | 28286 | RSS: Main: 232357888 08:48:24 INFO - PROCESS | 28286 | 08:51:26 INFO - PROCESS | 28286 | Cycle 1(1): loaded http://localhost:49448/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49448/tests/dromaeo/cssquery-prototype.html) 08:51:26 INFO - PROCESS | 28286 | RSS: Main: 230440960 08:51:26 INFO - PROCESS | 28286 | 08:54:29 INFO - PROCESS | 28286 | Cycle 1(1): loaded http://localhost:49448/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49448/tests/dromaeo/cssquery-yui.html) 08:54:30 INFO - PROCESS | 28286 | RSS: Main: 221032448 08:54:30 INFO - PROCESS | 28286 | 08:57:32 INFO - PROCESS | 28286 | Cycle 1(1): loaded http://localhost:49448/tests/dromaeo/cssquery-yui.html (next: http://localhost:49448/tests/dromaeo/cssquery-dojo.html) 08:57:33 INFO - PROCESS | 28286 | RSS: Main: 273723392 08:57:33 INFO - PROCESS | 28286 | 08:57:33 INFO - PROCESS | 28286 | __start_tp_report 08:57:33 INFO - PROCESS | 28286 | _x_x_mozilla_page_load 08:57:33 INFO - PROCESS | 28286 | _x_x_mozilla_page_load_details 08:57:33 INFO - PROCESS | 28286 | |i|pagename|runs| 08:57:33 INFO - PROCESS | 28286 | |0;dojo.html;3186.1078897908587;3193.6486986431487;3209.1977005748563;3231.434498962681;3263.820489873056;5314.388845282784;5319.20211968205;5392.299001129857;5437.238786569884;5475.151351540508;5464.453554644533;5501.367342755574;5532.502074813266;5542.057850165472;5555.444455554452;4795.2087905495555;4824.155772739774;4836.806527738886;4903.043906438246;4995.825146119886;4860.7326597037145;5111.69329840209;5113.028524580336;5121.820736274231;5152.768125434364;4836.87907802306;4872.9756351218375;4891.339669144655;4924.926126108111;4954.628402869781;4936.777844996984;4942.505749425065;4949.059678661043;4951.282064100686;4951.975240123812;4919.926201106986;4921.926171107436;4929.1374009548545;4936.086823937583;4942.036302920932;5279.392869819982;5300.0459917214885;5337.066013447631;5354.678719276856;5379.757910894019;5797.797077102324;5808.82573522795;5852.561057920673;5909.852253693692;5915.704214789286;5663.688497132656;5894.17481552583;5898.498627616657;5974.462298393122;5977.163197152402;5364.678119312854;5382.542483888874;5420.75606597708;5493.587980901449;5499.202615620793;5957.76168953237;5959.195508606369;5987.880242395215;5991.820245392645;5994.820155395345;5580;5659.066254068115;5730.255066841284;5769.88460230793;5781.103928891029;5784.971075144682;5831.3877042910735;5851.531877449794;5855.648661080349;5891.293044834647;5799.4780469757925;5812.709364531841;5825.533957283407;5827.562932779973;5828.417158284137;5640.5487560995025;5714.714264286769;5780.335261444903;5830.62100963436;5835.503982161564;4401.25178719612;4409.5149533551285;4414.028913638995;4449.332600109945;4449.9777501112285;3931.5871833457645;3953.3872249801325;3955.248502784462;3977.8806635800393;3986.740861844029;5311.521963023347;5359.464053594609;5400.621956463088;5466.316710411199;5538.972305138529;5775.797847075416;5794.333651630117;5810.941890581124;5811.79658711943;5860.238169037998;5437.374701909331;5484.122540393518;5488.313960754906;5500.147477141049;5502.862428439321;4860.246661767432;4962.280469331927;4990.226514890198;4996.025774973918;5008.6744361616375;6017.819465416044;6019.518438524995;6036.366181550961;6036.879262414815;6037.154798328237;5624.381318054929;5838.737256823369;5839.678817665069;5841.737121829613;5857.092150716646;5860.501857342174;5900.203472531325;5931.051031834886;5960.374160713062;5961.195238642727;5640.88718225625;5690.9430905690415;5691.516221121251;5691.886162276648;5712;5723.227364305765;5787.987102257172;5797.826065218051;5802.4197580241635;5834.03738383179;5697.658140511417;5740.167675687086;5755.309362876482;5782.913256301074;5807.0998995155815;5739.253896993364;5743.368229494668;5747.367789543064;5751.8562035949435;5753.568482363756;5385.515303622693;5530.115181570929;5594.776208951596;5649.209110724584;5662.546996240373;5692.231548740867;5806.390329015561;5809.94190058111;5826.883462330645;5828.970855145697;5612.915806262828;5640.943590564042;5647.322321321468;5651.15232715096;5656.943430565641 08:57:33 INFO - PROCESS | 28286 | |1;ext.html;606.2331151093867;628.8270725550475;635.2599221906473;647.9578827376221;664.6278084272808;32408.1897952551;35257.2948541029;35282;35308.29383412333;44363.77818110913;42666;46570.534294657045;46576.767116164454;46624.300635490494;46695.29957050647;8801.031886492498;8905.910940890606;8947;8957.059508751585;8960.8655870162;8800.383973121883;8898.37711360205;8899.555022248895;8901.910980890205;8905.821883562325;8924.196822285992;8939.463632182089;8940.910590894106;8949.507777072258;8953.418027828171;8881.600327985223;8907.287417006624;8928.33037522182;8933.508657023862;8939.865902011476;8787.077356877564;8843.027267000625;8844.73465796027;8859.689910853089;8861.379703420764;41929.58070419287;42164.78917605423;42277;42299.57700423018;42300.788496057634;3668.596454390015;3668.724845636588;3870.593587673282;3944.4872166618443;4262.403263543107;32260.193495162574;32451.350972980406;32557.348853023042;32919.83540082308;33903;4313;4414.205443020255;4437.933430998538;4442.666799990014;4460.464744230713;321019.39490302635;321496;322232;322432;322486;58684;59045.11432328518;59061.70469147713;59078.40921590729;59261.70369148126;36947.815260923526;37823;37840.43239351412;39867.2026559473;61517;17472.12639368052;17598.560035999202;17676.734848977274;17680.734788978174;17701.114944252735;13438.059335846398;13533.796993045113;13537.390817413043;13545.119567228296;13553.864461355457;19707.802921970884;19934.70097948532;20075.196992120153;20108.798912010985;20158.39524814258;7059.788206353817;7140.357367836816;7207.89188162178;7401.962990185122;7440.4791664584045;980.926430517714;1021.6781713760141;1107.0092267420614;1130.3952385473733;1140.7433327501246;32333.51499727459;32971.67028329686;33164.668353316156;33179.17052073718;33261.833690831874;1123.8370436286857;1139.8233273842402;1160.4197901049474;1170.7190274334098;1228.7419641875306;17189.656206875792;17370.131493425277;17458.563535911704;17545.210465529082;17571.12144392801;485234;485456;486680;488054;493063;57075;58819;59107.70446147742;59156.40843591595;59188.40811591915;1249.9125061245625;1327.9136856104321;1351.175782772508;1352.0670737191306;1388.4446221511275;31624.367512649158;33455.83272083624;33525.329493410485;33958.32083358368;34389.312213756086;48888.75555622199;49024.50975490199;49421.50578494169;49423.25865112098;49516.75241623913;330074;331312;331510;332359;332796;25865.224043278733;27612.30969225705;28458.146255612366;28473.440819897514;30243.243918902204;47941.76029119972;48888.75555622341;49012.509874900796;49944.50055499398;50148.247776284115;40574;41267.6668949756;41277.587224127376;41570;42682;41195;41588.16823663449;41773.791131045364;41825.37261941134;42755;41166.58833411628;41844.581554185286;41991.580084198766;42368.788156059025;42519.14961700687;35614.465783013795;36627;36704.265914681026;37359.81320093382;37472.437913430775;27913.860430697718;27968.86015569909;28224;28249.71750282553;28446.57330140091 08:57:33 INFO - PROCESS | 28286 | |2;jquery.html;387.9922401551967;396.96228858258456;404.3308324722585;406.2301937827816;414.77602094868774;18110.547236319082;19879.60240795185;19896;20089.196432142733;20853.47866303343;11641.708957276072;12166.574169904055;12180.878191218086;19091.899891011977;19579.804201957977;5191.714455704935;5311.389190243113;5338.67967921924;5349.063913815066;5370.731463426813;5234.659747116444;5327.813526526573;5329.973350133244;5354.491323324278;5365.517103460687;5326.840194794143;5332.440093790155;5338.91991620126;5339;5412.864678383033;5572.972135139318;5701.173329867188;5721.9427805721825;5737.627054241461;5761.078227483604;4937.703737775745;5083.898322033576;5141.640085194001;5142.948570514322;5149.7940082397035;13372.665683357896;13867.167969921837;13882.403523594714;14002.649933751636;14017;2251.17831991322;2327.487952650414;2352.788249057593;2445.8654773987423;2637.802164837645;9916.702498925044;10145.239107067;10233.437160956144;10253.743656408575;10658;1873.9531511712316;1886.7547218861598;1895.2608482691762;1928.7106933960017;2028.4016215216548;44719;53555.928881422544;54285.728571357286;80901.59549202275;84343.5782821088;10087.293889427816;11235;11307.886921130683;16528;17575.29698812033;32387;33145.50281745776;33244.1688957778;33469.33061338808;33606.66393336084;9854.753631159278;10081.19350451962;10117.089461948313;10281;10613.946930265454;8721.30229581632;8752.51861147643;8843.292536597057;8976.68581599654;8998.280137588978;8713.738587842376;8920.553972301359;8931.374803763803;8940.32947528938;9203.4938078405;3374.5073848566617;3389.8135602542097;3393.590550708975;3515.314513669861;3547.6452354764315;946.4321407155652;985.9901400985898;1006.3207335048813;1040.15747244732;1122.3602546548552;19085.141168647446;19239;19698.704519431933;19815;20015;1007.1036777268237;1018.6027449294781;1031.2214278219897;1063.9893601064045;1092.3882625729616;10705.518251678694;11195.4962026709;11229.438528073564;11313.943430282796;11366.545338186379;500441;508219;510602;511106.333619984;517974;94975.05024949662;96359.51820240854;96674.51662741641;97123.51438242763;97166;981.8183636027358;1006.5470538257813;1021.9489025548693;1024.2011231239644;1040.4745603470199;17242.568935776704;17441.912790436476;17624.735628965318;17823.376181833573;17939.192736327157;26146.86926565431;32796.508052378755;38991.51597566316;39264.803675981435;39530.60469395269;55356;57026.14460783009;57877.13184302155;57925.13112303235;58195.418045819;8771.684247362691;8805.867911981453;8855.778605534913;9057.04900985387;9125.087491250822;10981.890181098086;13410.061295709596;13742.175469471464;13820.654483637989;14084;11876.584319548774;11938.820917686067;12096.75806483883;12098.818517722417;12232.204906681038;12030.879691202976;12055.336956467478;12140.15018948665;12152.453139608562;12372.81440778407;12255;12351.69120771988;12380.938095309826;12556.246625202517;12568.183068100545;8567.74296771098;8942.06108358639;8976.86534701967;9053.139951704577;9145.862812057958;11479.196456247982;12210.511579536718;12403.62789116328;12660.11379203447;12717.8092328617 08:57:33 INFO - PROCESS | 28286 | |3;mootools.html;1365.6858922447843;1526.511516314779;1538.3462028637825;1552.5420001099674;1557.7507598784196;8479.745607631774;8882.24500917422;8912.688055918044;9158.084191580838;9178.770530736718;8889.733308000754;9082.046385129548;9097.681581144661;9107.043760405162;9120.087991200893;1914.33955285427;2341.882905854709;2388.8088952883772;2412.5416170927556;2416.154345978911;2425.9272221833285;2432.2338463383976;2436.0255897640995;2436.4639779248537;2437.8171637127284;2339.8830058497006;2367.621180611103;2378.5480758655885;2382.0471811275456;2388.474535602165;1998.8400927925804;2294.9541009179807;2384.511175209078;2386.9641955370685;2397.7841994220603;2149.48412381029;2183.748868880083;2188.1466228171025;2190.1786829938774;2196.5936301784063;9045.773855653595;9102.044285350075;9102.77243068922;9127.361084724072;9134.040925702839;5185.325907632021;5271.472852714736;5316.175992721134;5321.973390133063;5338.652987555796;3560.092176495003;3573.5175751273505;3625.456181572759;3625.5286812714444;3634.854605815764;1217.5677634439824;1221.2611370121037;1245.302630526908;1248.3259040118417;1279.2452453052745;12715.554955576603;12722.173058751152;12731.490740370282;12734.490620375174;12774.936125319407;4555.863324100283;4671.135839869635;4731.763411829464;4734.171519983989;4739.146953548395;10665.306755060896;10708.518116684612;10721.195910306758;10723.30298530593;10723.731906702395;4704.976475117671;5215.58275337972;5291.074062039128;5291.841244762663;5317.946820531823;4740.525947405232;4911.705297682079;4922.458529561745;4956.702597844141;4961;3913.745606535566;3931.3906344516645;3991.3613821788376;4017.6183262590594;4028.798560071985;1232.1744431231098;1236.863944966053;1248.2822377133184;1250.8248845161684;1252.9812052819216;906.5965645287915;988.3032462114193;1002.8946960569181;1007.3754272351189;1015.2740790334946;5798.6810725410505;5955.910661340084;6124.448799607968;6130;6231.566739649906;465.53911627488543;471.5402482579526;474.8551691733985;487.87559172410806;489.1269084683822;3978.0253837809923;4003.6997225208224;4023.75857448554;4128.318827393507;4128.422020917074;10967.561297548009;11228;11369.943150284362;11386.316820990767;11392.487338069806;11212.495437705324;11224.438778061065;11268.661940141645;11376.715582110348;11378.601748938912;7865.331446826969;8138.511689298661;8218.917810821935;8385.95807020961;8457.661693532313;6230.532710046855;6487.578307410004;6524.739010439529;6546.149000629887;6617.636030018395;10354.016368444985;10424.739381515219;10520.894791051991;10560.155187584822;10592.099671527698;10379.221558383;10528.842067369149;10663.89336106629;10711.785764284827;10813.24307298513;7314.634268286671;7855.3715702744785;8015.078265999251;8024.478408903403;8295.336373090018;8952.820943581222;8995.55022248898;9039.954800225956;9052.818943621223;9078.81842363136;8013;8186.959065204635;8206.343492520704;8279.95860020696;8325.750227493185;7679.500832445873;7858.5677787722225;7864.9606751965875;7974.960125199337;7990.640421181177;6697.665116744046;6911.619860907698;6969.860602788017;7020.087388639444;7035.648217589202;5210.765515551733;5301.602379821452;5361.169018802092;5361.303030605996;5451.454854514517;5367.919481207707;5437.293151890387;5491.945080549303;5566.304211973504;5661.150827375926 08:57:33 INFO - PROCESS | 28286 | |4;prototype.html;332.3779953825332;337.2883216413367;341.6139762068863;342.33074339665956;345.58874938822817;5321.175217841236;5347.844771584764;5382.38102618199;5392.56859451244;5444.428335024825;5365.436629153932;5369.731513424333;5394.1908713692965;5400.702961337125;5402.459754024605;4029.597040295969;4072.287349713803;4176.227397931377;4209.726367786084;4251.383549385338;4199.790010499478;4199.824049266211;4204.495460544739;4224.577542245766;4231.4076029355765;3987.1427643056736;4211.284081706102;4225.514065882432;4226.36604509323;4230.3865939438765;4081.3265811141127;4204.747715137086;4205.495340559122;4213.894652633709;4215.59951804578;3700.130469339703;3716.7769933804057;3731.003912906582;3733.4959780429567;3750.3498460677215;5148.433672296044;5155.484451554852;5160.612954028463;5160.741962901878;5173.870653233662;4502.684812063157;4735.4373056891145;4745.430548334188;4753.073150735608;4786.425628924551;5581.413951535112;5597.860053498654;5611;5621.578381621394;5638.718064096779;3997.600239976008;3999.4000899864964;4012.3981402789814;4017.3773065174946;4028.9194216115807;5578.972105139489;5773.595848290622;5784.652920824764;5808.2449281593545;5837.620554663933;4025.9331277211563;4067.9796601016806;4079.5104587449696;4080.734752241154;4105;4119.6910231732145;4145.66834653227;4150.522689890641;4187.455630768041;4190.706650534495;4552.044070745114;4744.363194697824;4744.95255047452;4752;4754.952450475451;5559;5570.498655121059;5593.692346920959;5596.921693068796;5610.214569960209;1428.8714015738633;1430.8426073131948;1433.6487560547523;1438.798603166347;1443.7401267771693;904.5974541329045;917.788908551037;926.7775733823834;957.5786653872274;988.3822610868208;5321.494458026481;5329.147336426153;5343.145096784496;5355.625106242525;5372.91940620891;5608.69152196625;5611.382747897728;5612.803551875746;5625.6905870176715;5629.155626656035;5130.79476820923;5247.924175544004;5299.152135658276;5319.218061949213;5363.463653634605;2956.8521573921216;3009.473342165112;3016.6575873736415;3018.4868572342752;3025.4705426550267;5654.434556544395;5777.162311464898;5859.94140058594;5866.472017518358;5869.677167755729;5844;5882.499987501024;5887.882242355129;5913.811889600789;5920.437558431942;5971.701414929323;5993.580449368589;6006.789762358297;6017.217761690952;6027.547933905063;5591.4688104629995;5749.396313387034;5789.189513468202;5791.218185544897;5841.737121829613;5647.265855438766;5662;5669.497583140554;5719.142128680731;5773.942260577341;5888.528917686661;5908.409159084057;5958.791442299499;5990;6060.6969651516365;4115.36211887158;4121.814518346621;4136.234796562646;4138.544760076329;4167.499900012018;5890.676012819336;5894;5898.705064746831;5899.823005309848;5901.229631110564;5533.058056812998;5543.916841247304;5646.520045796154;5717.742701578356;5731.254936858182;5580.944190558205;5618.2415373924;5635.098384258499;5638.661680299195;5647.6893770843;5328.813491527778;5387.784488620411;5521.668699878021;5537.723113844372;5543.861403464945;5635.549156067423;5652.6043176978865;5670.74481648335;5671.602987790815;5671.971640141773;5497.422770609189;5543.695096769717;5548.889022219614;5559.805406810742;5565.998120338378 08:57:33 INFO - PROCESS | 28286 | |5;yui.html;791.9841603167937;802.919708029197;844.3287586368837;869.9347548933831;880.1066917079165;13974.510892118766;14906.478273260438;14922.179280139588;14964.32660530276;14990.175540345277;13197.736045279089;13271.601851944433;13343;13343.599692009255;13382.464701411956;688.9827754306158;806.3428305930677;834.1366685480499;835.2274146414572;836.3894357119307;821.1706176761484;827.23480780278;828.9751307460785;833.2334252487695;833.8499070167368;724.0478770416885;886.2688282167238;888.4003297774009;888.5024386343669;888.7333799860048;854.2013217641525;871.6687658689689;875.3697337916722;875.3741075131248;875.8029443375253;644.4393377761331;644.6583310845259;645.0807599171162;645.3675398109879;645.3933302695451;14267.643308917257;14281.642958926006;14300.642483937881;14320.785188222186;14335.713285734331;13141;13534.390952407166;13571.45714171432;13605.795913061313;13615.455381784717;11765.529378824836;11955.581554645545;12060.758784824255;12125.514979400901;12210.084243681671;4548.977255113703;4598.770061496945;4651.279051746985;4704.835330763441;4761.095391875567;52680;52697;53074.20388694173;54180;54741;10782.245242833083;11254.324740515594;11308.83036754433;11616.128790340757;11652.359120248298;20082.297119600746;20332.69500957487;20348.389548313575;20374.388768336972;20375.8981205096;8165.63254653542;8346.290565302017;8520.914790852012;8573.885394898747;8715.564221788885;4736.905261894743;4788.880277993008;4793.1372352976105;4914.287428322873;5075.416327122428;3477.8956631301103;3569.8929032129076;3601.2617413430185;3617.2946275476556;3618.6743193112748;682.3074579302013;693.5041445366587;708.7235977968493;739.4158614694438;766.4519868294196;609.4484491535168;630.4137152448176;651.1535004494139;739.2865884421451;782.8238646304536;9143.954280228556;9577.616895324109;9623.278254130968;9669.806603868024;9684.56419461126;678.1218322272669;742.5210739073267;743.2456057102046;753.4273951796564;770.2143813310392;4322.848700295474;4443.7111587746695;4526.125910047143;4564.064366804863;4583.954160458419;59445.405545943984;60290;60757.392426076054;60757.69621151954;62957.37042629515;56803.14795278074;57312.713436432554;58206.417935820944;58210;58879.116813247834;49431.50568494341;50996.74501627468;51009.744951275;51121.233181502306;52256.21615675768;8670.523121228392;8939.374243802871;8939.865902011605;8984.191422771983;9014.233790127913;33499.16252093717;34125.488117677756;34811.08539030276;35173.12067198341;35291.117722057155;44067;45314.773426132655;45526.77236613796;45711.771441142584;46086.76956615329;11878.406079695807;11993.100517461051;11997.140443230965;12064.698382540506;12226.510939562319;12569.434375452944;12826.358682065673;13095.148815326973;13128;13623;11915.404229788302;12001.879981200076;12092.939535302266;12416.379181040731;12828.166169198972;12047.277163370225;12229.266244025386;12286.631401057983;12338.691532711755;12398.13213075076;11612.651620451063;11724.296542207494;11905.821412678644;11974.880251197377;12258.325792081521;10271.897281027093;10508.737281568023;10526.789464210826;10573.52419141156;10639.414832183995;10593;10594.47027648599;10609.151267898704;10707.250492465453;10710.250282480152 08:57:33 INFO - PROCESS | 28286 | __end_tp_report 08:57:33 INFO - PROCESS | 28286 | __start_cc_report 08:57:33 INFO - PROCESS | 28286 | _x_x_mozilla_cycle_collect,814 08:57:33 INFO - PROCESS | 28286 | __end_cc_report 08:57:33 INFO - PROCESS | 28286 | __startTimestamp1463068653063__endTimestamp 08:57:33 INFO - PROCESS | 28286 | 08:57:33 INFO - PROCESS | 28286 | ------- Summary: start ------- 08:57:33 INFO - PROCESS | 28286 | Number of tests: 6 08:57:33 INFO - PROCESS | 28286 | 08:57:33 INFO - PROCESS | 28286 | [#0] dojo.html Cycles:165 Average:5400.97 Median:5640.89 stddev:611.27 (10.8%) stddev-sans-first:587.93 08:57:33 INFO - PROCESS | 28286 | Values: 3186.1 3193.6 3209.2 3231.4 3263.8 5314.4 5319.2 5392.3 5437.2 5475.2 5464.5 5501.4 5532.5 5542.1 5555.4 4795.2 4824.2 4836.8 4903.0 4995.8 4860.7 5111.7 5113.0 5121.8 5152.8 4836.9 4873.0 4891.3 4924.9 4954.6 4936.8 4942.5 4949.1 4951.3 4952.0 4919.9 4921.9 4929.1 4936.1 4942.0 5279.4 5300.0 5337.1 5354.7 5379.8 5797.8 5808.8 5852.6 5909.9 5915.7 5663.7 5894.2 5898.5 5974.5 5977.2 5364.7 5382.5 5420.8 5493.6 5499.2 5957.8 5959.2 5987.9 5991.8 5994.8 5580.0 5659.1 5730.3 5769.9 5781.1 5785.0 5831.4 5851.5 5855.6 5891.3 5799.5 5812.7 5825.5 5827.6 5828.4 5640.5 5714.7 5780.3 5830.6 5835.5 4401.3 4409.5 4414.0 4449.3 4450.0 3931.6 3953.4 3955.2 3977.9 3986.7 5311.5 5359.5 5400.6 5466.3 5539.0 5775.8 5794.3 5810.9 5811.8 5860.2 5437.4 5484.1 5488.3 5500.1 5502.9 4860.2 4962.3 4990.2 4996.0 5008.7 6017.8 6019.5 6036.4 6036.9 6037.2 5624.4 5838.7 5839.7 5841.7 5857.1 5860.5 5900.2 5931.1 5960.4 5961.2 5640.9 5690.9 5691.5 5691.9 5712.0 5723.2 5788.0 5797.8 5802.4 5834.0 5697.7 5740.2 5755.3 5782.9 5807.1 5739.3 5743.4 5747.4 5751.9 5753.6 5385.5 5530.1 5594.8 5649.2 5662.5 5692.2 5806.4 5809.9 5826.9 5829.0 5612.9 5640.9 5647.3 5651.2 5656.9 08:57:33 INFO - PROCESS | 28286 | 08:57:33 INFO - PROCESS | 28286 | [#1] ext.html Cycles:180 Average:54862.88 Median:41423.79 stddev:102370.00 (247.1%) stddev-sans-first:102576.12 08:57:33 INFO - PROCESS | 28286 | Values: 606.2 628.8 635.3 648.0 664.6 32408.2 35257.3 35282.0 35308.3 44363.8 42666.0 46570.5 46576.8 46624.3 46695.3 8801.0 8905.9 8947.0 8957.1 8960.9 8800.4 8898.4 8899.6 8901.9 8905.8 8924.2 8939.5 8940.9 8949.5 8953.4 8881.6 8907.3 8928.3 8933.5 8939.9 8787.1 8843.0 8844.7 8859.7 8861.4 41929.6 42164.8 42277.0 42299.6 42300.8 3668.6 3668.7 3870.6 3944.5 4262.4 32260.2 32451.4 32557.3 32919.8 33903.0 4313.0 4414.2 4437.9 4442.7 4460.5 321019.4 321496.0 322232.0 322432.0 322486.0 58684.0 59045.1 59061.7 59078.4 59261.7 36947.8 37823.0 37840.4 39867.2 61517.0 17472.1 17598.6 17676.7 17680.7 17701.1 13438.1 13533.8 13537.4 13545.1 13553.9 19707.8 19934.7 20075.2 20108.8 20158.4 7059.8 7140.4 7207.9 7402.0 7440.5 980.9 1021.7 1107.0 1130.4 1140.7 32333.5 32971.7 33164.7 33179.2 33261.8 1123.8 1139.8 1160.4 1170.7 1228.7 17189.7 17370.1 17458.6 17545.2 17571.1 485234.0 485456.0 486680.0 488054.0 493063.0 57075.0 58819.0 59107.7 59156.4 59188.4 1249.9 1327.9 1351.2 1352.1 1388.4 31624.4 33455.8 33525.3 33958.3 34389.3 48888.8 49024.5 49421.5 49423.3 49516.8 330074.0 331312.0 331510.0 332359.0 332796.0 25865.2 27612.3 28458.1 28473.4 30243.2 47941.8 48888.8 49012.5 49944.5 50148.2 40574.0 41267.7 41277.6 41570.0 42682.0 41195.0 41588.2 41773.8 41825.4 42755.0 41166.6 41844.6 41991.6 42368.8 42519.1 35614.5 36627.0 36704.3 37359.8 37472.4 27913.9 27968.9 28224.0 28249.7 28446.6 08:57:33 INFO - PROCESS | 28286 | 08:57:33 INFO - PROCESS | 28286 | [#2] jquery.html Cycles:180 Average:29498.38 Median:17512.40 stddev:83736.21 (478.2%) stddev-sans-first:83942.59 08:57:33 INFO - PROCESS | 28286 | Values: 388.0 397.0 404.3 406.2 414.8 18110.5 19879.6 19896.0 20089.2 20853.5 11641.7 12166.6 12180.9 19091.9 19579.8 5191.7 5311.4 5338.7 5349.1 5370.7 5234.7 5327.8 5330.0 5354.5 5365.5 5326.8 5332.4 5338.9 5339.0 5412.9 5573.0 5701.2 5721.9 5737.6 5761.1 4937.7 5083.9 5141.6 5142.9 5149.8 13372.7 13867.2 13882.4 14002.6 14017.0 2251.2 2327.5 2352.8 2445.9 2637.8 9916.7 10145.2 10233.4 10253.7 10658.0 1874.0 1886.8 1895.3 1928.7 2028.4 44719.0 53555.9 54285.7 80901.6 84343.6 10087.3 11235.0 11307.9 16528.0 17575.3 32387.0 33145.5 33244.2 33469.3 33606.7 9854.8 10081.2 10117.1 10281.0 10613.9 8721.3 8752.5 8843.3 8976.7 8998.3 8713.7 8920.6 8931.4 8940.3 9203.5 3374.5 3389.8 3393.6 3515.3 3547.6 946.4 986.0 1006.3 1040.2 1122.4 19085.1 19239.0 19698.7 19815.0 20015.0 1007.1 1018.6 1031.2 1064.0 1092.4 10705.5 11195.5 11229.4 11313.9 11366.5 500441.0 508219.0 510602.0 511106.3 517974.0 94975.1 96359.5 96674.5 97123.5 97166.0 981.8 1006.5 1021.9 1024.2 1040.5 17242.6 17441.9 17624.7 17823.4 17939.2 26146.9 32796.5 38991.5 39264.8 39530.6 55356.0 57026.1 57877.1 57925.1 58195.4 8771.7 8805.9 8855.8 9057.0 9125.1 10981.9 13410.1 13742.2 13820.7 14084.0 11876.6 11938.8 12096.8 12098.8 12232.2 12030.9 12055.3 12140.2 12152.5 12372.8 12255.0 12351.7 12380.9 12556.2 12568.2 8567.7 8942.1 8976.9 9053.1 9145.9 11479.2 12210.5 12403.6 12660.1 12717.8 08:57:33 INFO - PROCESS | 28286 | 08:57:33 INFO - PROCESS | 28286 | [#3] mootools.html Cycles:180 Average:5955.87 Median:4688.06 stddev:3455.15 (73.7%) stddev-sans-first:3447.63 08:57:33 INFO - PROCESS | 28286 | Values: 1365.7 1526.5 1538.3 1552.5 1557.8 8479.7 8882.2 8912.7 9158.1 9178.8 8889.7 9082.0 9097.7 9107.0 9120.1 1914.3 2341.9 2388.8 2412.5 2416.2 2425.9 2432.2 2436.0 2436.5 2437.8 2339.9 2367.6 2378.5 2382.0 2388.5 1998.8 2295.0 2384.5 2387.0 2397.8 2149.5 2183.7 2188.1 2190.2 2196.6 9045.8 9102.0 9102.8 9127.4 9134.0 5185.3 5271.5 5316.2 5322.0 5338.7 3560.1 3573.5 3625.5 3625.5 3634.9 1217.6 1221.3 1245.3 1248.3 1279.2 12715.6 12722.2 12731.5 12734.5 12774.9 4555.9 4671.1 4731.8 4734.2 4739.1 10665.3 10708.5 10721.2 10723.3 10723.7 4705.0 5215.6 5291.1 5291.8 5317.9 4740.5 4911.7 4922.5 4956.7 4961.0 3913.7 3931.4 3991.4 4017.6 4028.8 1232.2 1236.9 1248.3 1250.8 1253.0 906.6 988.3 1002.9 1007.4 1015.3 5798.7 5955.9 6124.4 6130.0 6231.6 465.5 471.5 474.9 487.9 489.1 3978.0 4003.7 4023.8 4128.3 4128.4 10967.6 11228.0 11369.9 11386.3 11392.5 11212.5 11224.4 11268.7 11376.7 11378.6 7865.3 8138.5 8218.9 8386.0 8457.7 6230.5 6487.6 6524.7 6546.1 6617.6 10354.0 10424.7 10520.9 10560.2 10592.1 10379.2 10528.8 10663.9 10711.8 10813.2 7314.6 7855.4 8015.1 8024.5 8295.3 8952.8 8995.6 9040.0 9052.8 9078.8 8013.0 8187.0 8206.3 8280.0 8325.8 7679.5 7858.6 7865.0 7975.0 7990.6 6697.7 6911.6 6969.9 7020.1 7035.6 5210.8 5301.6 5361.2 5361.3 5451.5 5367.9 5437.3 5491.9 5566.3 5661.2 08:57:33 INFO - PROCESS | 28286 | 08:57:33 INFO - PROCESS | 28286 | [#4] prototype.html Cycles:180 Average:4722.19 Median:5385.08 stddev:1389.63 (25.8%) stddev-sans-first:1353.91 08:57:33 INFO - PROCESS | 28286 | Values: 332.4 337.3 341.6 342.3 345.6 5321.2 5347.8 5382.4 5392.6 5444.4 5365.4 5369.7 5394.2 5400.7 5402.5 4029.6 4072.3 4176.2 4209.7 4251.4 4199.8 4199.8 4204.5 4224.6 4231.4 3987.1 4211.3 4225.5 4226.4 4230.4 4081.3 4204.7 4205.5 4213.9 4215.6 3700.1 3716.8 3731.0 3733.5 3750.3 5148.4 5155.5 5160.6 5160.7 5173.9 4502.7 4735.4 4745.4 4753.1 4786.4 5581.4 5597.9 5611.0 5621.6 5638.7 3997.6 3999.4 4012.4 4017.4 4028.9 5579.0 5773.6 5784.7 5808.2 5837.6 4025.9 4068.0 4079.5 4080.7 4105.0 4119.7 4145.7 4150.5 4187.5 4190.7 4552.0 4744.4 4745.0 4752.0 4755.0 5559.0 5570.5 5593.7 5596.9 5610.2 1428.9 1430.8 1433.6 1438.8 1443.7 904.6 917.8 926.8 957.6 988.4 5321.5 5329.1 5343.1 5355.6 5372.9 5608.7 5611.4 5612.8 5625.7 5629.2 5130.8 5247.9 5299.2 5319.2 5363.5 2956.9 3009.5 3016.7 3018.5 3025.5 5654.4 5777.2 5859.9 5866.5 5869.7 5844.0 5882.5 5887.9 5913.8 5920.4 5971.7 5993.6 6006.8 6017.2 6027.5 5591.5 5749.4 5789.2 5791.2 5841.7 5647.3 5662.0 5669.5 5719.1 5773.9 5888.5 5908.4 5958.8 5990.0 6060.7 4115.4 4121.8 4136.2 4138.5 4167.5 5890.7 5894.0 5898.7 5899.8 5901.2 5533.1 5543.9 5646.5 5717.7 5731.3 5580.9 5618.2 5635.1 5638.7 5647.7 5328.8 5387.8 5521.7 5537.7 5543.9 5635.5 5652.6 5670.7 5671.6 5672.0 5497.4 5543.7 5548.9 5559.8 5566.0 08:57:33 INFO - PROCESS | 28286 | 08:57:33 INFO - PROCESS | 28286 | [#5] yui.html Cycles:180 Average:14908.03 Median:25342.77 stddev:17156.11 (67.7%) stddev-sans-first:17171.49 08:57:33 INFO - PROCESS | 28286 | Values: 792.0 802.9 844.3 869.9 880.1 13974.5 14906.5 14922.2 14964.3 14990.2 13197.7 13271.6 13343.0 13343.6 13382.5 689.0 806.3 834.1 835.2 836.4 821.2 827.2 829.0 833.2 833.8 724.0 886.3 888.4 888.5 888.7 854.2 871.7 875.4 875.4 875.8 644.4 644.7 645.1 645.4 645.4 14267.6 14281.6 14300.6 14320.8 14335.7 13141.0 13534.4 13571.5 13605.8 13615.5 11765.5 11955.6 12060.8 12125.5 12210.1 4549.0 4598.8 4651.3 4704.8 4761.1 52680.0 52697.0 53074.2 54180.0 54741.0 10782.2 11254.3 11308.8 11616.1 11652.4 20082.3 20332.7 20348.4 20374.4 20375.9 8165.6 8346.3 8520.9 8573.9 8715.6 4736.9 4788.9 4793.1 4914.3 5075.4 3477.9 3569.9 3601.3 3617.3 3618.7 682.3 693.5 708.7 739.4 766.5 609.4 630.4 651.2 739.3 782.8 9144.0 9577.6 9623.3 9669.8 9684.6 678.1 742.5 743.2 753.4 770.2 4322.8 4443.7 4526.1 4564.1 4584.0 59445.4 60290.0 60757.4 60757.7 62957.4 56803.1 57312.7 58206.4 58210.0 58879.1 49431.5 50996.7 51009.7 51121.2 52256.2 8670.5 8939.4 8939.9 8984.2 9014.2 33499.2 34125.5 34811.1 35173.1 35291.1 44067.0 45314.8 45526.8 45711.8 46086.8 11878.4 11993.1 11997.1 12064.7 12226.5 12569.4 12826.4 13095.1 13128.0 13623.0 11915.4 12001.9 12092.9 12416.4 12828.2 12047.3 12229.3 12286.6 12338.7 12398.1 11612.7 11724.3 11905.8 11974.9 12258.3 10271.9 10508.7 10526.8 10573.5 10639.4 10593.0 10594.5 10609.2 10707.3 10710.3 08:57:33 INFO - PROCESS | 28286 | -------- Summary: end -------- 08:57:33 INFO - PROCESS | 28286 | 08:57:33 INFO - TEST-INFO | 28286: exit 0 08:57:33 INFO - TEST-OK | dromaeo_css | took 1113154ms 08:57:33 INFO - TEST-START | kraken 08:57:33 INFO - operating with platform_type : linux_ 08:57:33 INFO - Initialising browser for kraken test... 08:57:33 INFO - TEST-INFO | started process 28468 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIdeYTy/profile http://localhost:49448/getInfo.html) 08:57:37 INFO - PROCESS | 28468 | __metrics Screen width/height:1600/1200 08:57:37 INFO - PROCESS | 28468 | colorDepth:24 08:57:37 INFO - PROCESS | 28468 | Browser inner width/height: 1024/697 08:57:37 INFO - PROCESS | 28468 | __metrics 08:57:38 ERROR - PROCESS | 28468 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:57:39 INFO - TEST-INFO | 28468: exit 0 08:57:39 INFO - Browser initialized. 08:57:39 INFO - Running cycle 1/1 for kraken test... 08:57:39 INFO - TEST-INFO | started process 28551 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIdeYTy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:57:51 INFO - PROCESS | 28551 | RSS: Main: 152444928 08:57:51 INFO - PROCESS | 28551 | 08:57:53 INFO - PROCESS | 28551 | Cycle 1(1): loaded http://localhost:49448/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49448/tests/kraken/driver.html?testName=audio-beat-detection) 08:57:54 INFO - PROCESS | 28551 | RSS: Main: 208801792 08:57:54 INFO - PROCESS | 28551 | 08:57:57 INFO - PROCESS | 28551 | Cycle 1(1): loaded http://localhost:49448/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49448/tests/kraken/driver.html?testName=audio-dft) 08:57:58 INFO - PROCESS | 28551 | RSS: Main: 196808704 08:57:58 INFO - PROCESS | 28551 | 08:58:01 INFO - PROCESS | 28551 | Cycle 1(1): loaded http://localhost:49448/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49448/tests/kraken/driver.html?testName=audio-fft) 08:58:01 INFO - PROCESS | 28551 | RSS: Main: 265474048 08:58:01 INFO - PROCESS | 28551 | 08:58:04 INFO - PROCESS | 28551 | Cycle 1(1): loaded http://localhost:49448/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49448/tests/kraken/driver.html?testName=audio-oscillator) 08:58:04 INFO - PROCESS | 28551 | RSS: Main: 174485504 08:58:04 INFO - PROCESS | 28551 | 08:58:06 INFO - PROCESS | 28551 | Cycle 1(1): loaded http://localhost:49448/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49448/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:58:07 INFO - PROCESS | 28551 | RSS: Main: 178528256 08:58:07 INFO - PROCESS | 28551 | 08:58:10 INFO - PROCESS | 28551 | Cycle 1(1): loaded http://localhost:49448/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49448/tests/kraken/driver.html?testName=imaging-darkroom) 08:58:11 INFO - PROCESS | 28551 | RSS: Main: 214712320 08:58:11 INFO - PROCESS | 28551 | 08:58:14 INFO - PROCESS | 28551 | Cycle 1(1): loaded http://localhost:49448/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49448/tests/kraken/driver.html?testName=imaging-desaturate) 08:58:15 INFO - PROCESS | 28551 | RSS: Main: 212865024 08:58:15 INFO - PROCESS | 28551 | 08:58:18 INFO - PROCESS | 28551 | Cycle 1(1): loaded http://localhost:49448/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49448/tests/kraken/driver.html?testName=json-parse-financial) 08:58:19 INFO - PROCESS | 28551 | RSS: Main: 213438464 08:58:19 INFO - PROCESS | 28551 | 08:58:20 INFO - PROCESS | 28551 | Cycle 1(1): loaded http://localhost:49448/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49448/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:58:21 INFO - PROCESS | 28551 | RSS: Main: 200413184 08:58:21 INFO - PROCESS | 28551 | 08:58:22 INFO - PROCESS | 28551 | Cycle 1(1): loaded http://localhost:49448/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49448/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:58:23 INFO - PROCESS | 28551 | RSS: Main: 205025280 08:58:23 INFO - PROCESS | 28551 | 08:58:25 INFO - PROCESS | 28551 | Cycle 1(1): loaded http://localhost:49448/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49448/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:58:26 INFO - PROCESS | 28551 | RSS: Main: 215240704 08:58:26 INFO - PROCESS | 28551 | 08:58:29 INFO - PROCESS | 28551 | Cycle 1(1): loaded http://localhost:49448/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49448/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:58:29 INFO - PROCESS | 28551 | RSS: Main: 220102656 08:58:29 INFO - PROCESS | 28551 | 08:58:32 INFO - PROCESS | 28551 | Cycle 1(1): loaded http://localhost:49448/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49448/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:58:33 INFO - PROCESS | 28551 | RSS: Main: 217354240 08:58:33 INFO - PROCESS | 28551 | 08:58:35 INFO - PROCESS | 28551 | Cycle 1(1): loaded http://localhost:49448/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49448/tests/kraken/driver.html?testName=ai-astar) 08:58:35 INFO - PROCESS | 28551 | RSS: Main: 263266304 08:58:35 INFO - PROCESS | 28551 | 08:58:35 INFO - PROCESS | 28551 | __start_tp_report 08:58:35 INFO - PROCESS | 28551 | _x_x_mozilla_page_load 08:58:35 INFO - PROCESS | 28551 | _x_x_mozilla_page_load_details 08:58:35 INFO - PROCESS | 28551 | |i|pagename|runs| 08:58:35 INFO - PROCESS | 28551 | |0;ai-astar;112;107;110;110;111;113;111;110;111;113 08:58:35 INFO - PROCESS | 28551 | |1;audio-beat-detection;157;125;150;217;126;176;163;126;183;127 08:58:35 INFO - PROCESS | 28551 | |2;audio-dft;162;161;163;161;161;161;162;161;163;162 08:58:35 INFO - PROCESS | 28551 | |3;audio-fft;74;76;75;146;76;74;76;74;142;75 08:58:35 INFO - PROCESS | 28551 | |4;audio-oscillator;91;93;154;93;93;92;125;91;91;127 08:58:35 INFO - PROCESS | 28551 | |5;imaging-gaussian-blur;117;117;116;117;117;117;116;116;117;117 08:58:35 INFO - PROCESS | 28551 | |6;imaging-darkroom;145;146;146;145;145;145;145;145;145;151 08:58:35 INFO - PROCESS | 28551 | |7;imaging-desaturate;95;95;94;94;94;94;95;95;95;95 08:58:35 INFO - PROCESS | 28551 | |8;json-parse-financial;57;59;59;58;58;58;58;110;55;56 08:58:35 INFO - PROCESS | 28551 | |9;json-stringify-tinderbox;59;59;58;60;59;59;59;59;65;59 08:58:35 INFO - PROCESS | 28551 | |10;stanford-crypto-aes;79;78;80;84;79;86;80;81;79;80 08:58:35 INFO - PROCESS | 28551 | |11;stanford-crypto-ccm;133;132;124;141;127;122;123;130;236;126 08:58:35 INFO - PROCESS | 28551 | |12;stanford-crypto-pbkdf2;172;168;170;168;168;170;173;178;168;166 08:58:35 INFO - PROCESS | 28551 | |13;stanford-crypto-sha256-iterative;65;65;66;65;69;72;66;65;63;63 08:58:35 INFO - PROCESS | 28551 | __end_tp_report 08:58:35 INFO - PROCESS | 28551 | __start_cc_report 08:58:35 INFO - PROCESS | 28551 | _x_x_mozilla_cycle_collect,1817 08:58:35 INFO - PROCESS | 28551 | __end_cc_report 08:58:35 INFO - PROCESS | 28551 | __startTimestamp1463068715293__endTimestamp 08:58:35 INFO - PROCESS | 28551 | 08:58:35 INFO - PROCESS | 28551 | ------- Summary: start ------- 08:58:35 INFO - PROCESS | 28551 | Number of tests: 14 08:58:35 INFO - PROCESS | 28551 | 08:58:35 INFO - PROCESS | 28551 | [#0] ai-astar Cycles:10 Average:110.80 Median:111.00 stddev:1.75 (1.6%) stddev-sans-first:1.80 08:58:35 INFO - PROCESS | 28551 | Values: 112.0 107.0 110.0 110.0 111.0 113.0 111.0 110.0 111.0 113.0 08:58:35 INFO - PROCESS | 28551 | 08:58:35 INFO - PROCESS | 28551 | [#1] audio-beat-detection Cycles:10 Average:155.00 Median:160.00 stddev:30.78 (19.2%) stddev-sans-first:32.64 08:58:35 INFO - PROCESS | 28551 | Values: 157.0 125.0 150.0 217.0 126.0 176.0 163.0 126.0 183.0 127.0 08:58:35 INFO - PROCESS | 28551 | 08:58:35 INFO - PROCESS | 28551 | [#2] audio-dft Cycles:10 Average:161.70 Median:162.00 stddev:0.82 (0.5%) stddev-sans-first:0.87 08:58:35 INFO - PROCESS | 28551 | Values: 162.0 161.0 163.0 161.0 161.0 161.0 162.0 161.0 163.0 162.0 08:58:35 INFO - PROCESS | 28551 | 08:58:35 INFO - PROCESS | 28551 | [#3] audio-fft Cycles:10 Average:88.80 Median:75.00 stddev:29.12 (38.8%) stddev-sans-first:30.39 08:58:35 INFO - PROCESS | 28551 | Values: 74.0 76.0 75.0 146.0 76.0 74.0 76.0 74.0 142.0 75.0 08:58:35 INFO - PROCESS | 28551 | 08:58:35 INFO - PROCESS | 28551 | [#4] audio-oscillator Cycles:10 Average:105.00 Median:91.50 stddev:22.30 (24.4%) stddev-sans-first:23.07 08:58:35 INFO - PROCESS | 28551 | Values: 91.0 93.0 154.0 93.0 93.0 92.0 125.0 91.0 91.0 127.0 08:58:35 INFO - PROCESS | 28551 | 08:58:35 INFO - PROCESS | 28551 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 08:58:35 INFO - PROCESS | 28551 | Values: 117.0 117.0 116.0 117.0 117.0 117.0 116.0 116.0 117.0 117.0 08:58:35 INFO - PROCESS | 28551 | 08:58:35 INFO - PROCESS | 28551 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:145.00 stddev:1.87 (1.3%) stddev-sans-first:1.96 08:58:35 INFO - PROCESS | 28551 | Values: 145.0 146.0 146.0 145.0 145.0 145.0 145.0 145.0 145.0 151.0 08:58:35 INFO - PROCESS | 28551 | 08:58:35 INFO - PROCESS | 28551 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 08:58:35 INFO - PROCESS | 28551 | Values: 95.0 95.0 94.0 94.0 94.0 94.0 95.0 95.0 95.0 95.0 08:58:35 INFO - PROCESS | 28551 | 08:58:35 INFO - PROCESS | 28551 | [#8] json-parse-financial Cycles:10 Average:62.80 Median:58.00 stddev:16.63 (28.7%) stddev-sans-first:17.51 08:58:35 INFO - PROCESS | 28551 | Values: 57.0 59.0 59.0 58.0 58.0 58.0 58.0 110.0 55.0 56.0 08:58:35 INFO - PROCESS | 28551 | 08:58:35 INFO - PROCESS | 28551 | [#9] json-stringify-tinderbox Cycles:10 Average:59.60 Median:59.00 stddev:1.96 (3.3%) stddev-sans-first:2.06 08:58:35 INFO - PROCESS | 28551 | Values: 59.0 59.0 58.0 60.0 59.0 59.0 59.0 59.0 65.0 59.0 08:58:35 INFO - PROCESS | 28551 | 08:58:35 INFO - PROCESS | 28551 | [#10] stanford-crypto-aes Cycles:10 Average:80.60 Median:80.00 stddev:2.50 (3.1%) stddev-sans-first:2.59 08:58:35 INFO - PROCESS | 28551 | Values: 79.0 78.0 80.0 84.0 79.0 86.0 80.0 81.0 79.0 80.0 08:58:35 INFO - PROCESS | 28551 | 08:58:35 INFO - PROCESS | 28551 | [#11] stanford-crypto-ccm Cycles:10 Average:139.40 Median:131.00 stddev:34.42 (26.3%) stddev-sans-first:36.43 08:58:35 INFO - PROCESS | 28551 | Values: 133.0 132.0 124.0 141.0 127.0 122.0 123.0 130.0 236.0 126.0 08:58:35 INFO - PROCESS | 28551 | 08:58:35 INFO - PROCESS | 28551 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.10 Median:170.00 stddev:3.48 (2.0%) stddev-sans-first:3.62 08:58:35 INFO - PROCESS | 28551 | Values: 172.0 168.0 170.0 168.0 168.0 170.0 173.0 178.0 168.0 166.0 08:58:35 INFO - PROCESS | 28551 | 08:58:35 INFO - PROCESS | 28551 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.90 Median:65.50 stddev:2.73 (4.2%) stddev-sans-first:2.87 08:58:35 INFO - PROCESS | 28551 | Values: 65.0 65.0 66.0 65.0 69.0 72.0 66.0 65.0 63.0 63.0 08:58:35 INFO - PROCESS | 28551 | -------- Summary: end -------- 08:58:35 INFO - PROCESS | 28551 | 08:58:36 INFO - TEST-INFO | 28551: exit 0 08:58:36 INFO - TEST-OK | kraken | took 62506ms 08:58:36 INFO - SUITE-END | took 1175s 08:58:36 INFO - Completed test suite (00:19:35) 08:58:36 INFO - javascript benchmark 08:58:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3186.1078897908587, 3193.6486986431487, 3209.1977005748563, 3231.434498962681, 3263.820489873056, 5314.388845282784, 5319.20211968205, 5392.299001129857, 5437.238786569884, 5475.151351540508, 5464.453554644533, 5501.367342755574, 5532.502074813266, 5542.057850165472, 5555.444455554452, 4795.2087905495555, 4824.155772739774, 4836.806527738886, 4903.043906438246, 4995.825146119886, 4860.7326597037145, 5111.69329840209, 5113.028524580336, 5121.820736274231, 5152.768125434364, 4836.87907802306, 4872.9756351218375, 4891.339669144655, 4924.926126108111, 4954.628402869781, 4936.777844996984, 4942.505749425065, 4949.059678661043, 4951.282064100686, 4951.975240123812, 4919.926201106986, 4921.926171107436, 4929.1374009548545, 4936.086823937583, 4942.036302920932, 5279.392869819982, 5300.0459917214885, 5337.066013447631, 5354.678719276856, 5379.757910894019, 5797.797077102324, 5808.82573522795, 5852.561057920673, 5909.852253693692, 5915.704214789286, 5663.688497132656, 5894.17481552583, 5898.498627616657, 5974.462298393122, 5977.163197152402, 5364.678119312854, 5382.542483888874, 5420.75606597708, 5493.587980901449, 5499.202615620793, 5957.76168953237, 5959.195508606369, 5987.880242395215, 5991.820245392645, 5994.820155395345, 5580.0, 5659.066254068115, 5730.255066841284, 5769.88460230793, 5781.103928891029, 5784.971075144682, 5831.3877042910735, 5851.531877449794, 5855.648661080349, 5891.293044834647, 5799.4780469757925, 5812.709364531841, 5825.533957283407, 5827.562932779973, 5828.417158284137, 5640.5487560995025, 5714.714264286769, 5780.335261444903, 5830.62100963436, 5835.503982161564, 4401.25178719612, 4409.5149533551285, 4414.028913638995, 4449.332600109945, 4449.9777501112285, 3931.5871833457645, 3953.3872249801325, 3955.248502784462, 3977.8806635800393, 3986.740861844029, 5311.521963023347, 5359.464053594609, 5400.621956463088, 5466.316710411199, 5538.972305138529, 5775.797847075416, 5794.333651630117, 5810.941890581124, 5811.79658711943, 5860.238169037998, 5437.374701909331, 5484.122540393518, 5488.313960754906, 5500.147477141049, 5502.862428439321, 4860.246661767432, 4962.280469331927, 4990.226514890198, 4996.025774973918, 5008.6744361616375, 6017.819465416044, 6019.518438524995, 6036.366181550961, 6036.879262414815, 6037.154798328237, 5624.381318054929, 5838.737256823369, 5839.678817665069, 5841.737121829613, 5857.092150716646, 5860.501857342174, 5900.203472531325, 5931.051031834886, 5960.374160713062, 5961.195238642727, 5640.88718225625, 5690.9430905690415, 5691.516221121251, 5691.886162276648, 5712.0, 5723.227364305765, 5787.987102257172, 5797.826065218051, 5802.4197580241635, 5834.03738383179, 5697.658140511417, 5740.167675687086, 5755.309362876482, 5782.913256301074, 5807.0998995155815, 5739.253896993364, 5743.368229494668, 5747.367789543064, 5751.8562035949435, 5753.568482363756, 5385.515303622693, 5530.115181570929, 5594.776208951596, 5649.209110724584, 5662.546996240373, 5692.231548740867, 5806.390329015561, 5809.94190058111, 5826.883462330645, 5828.970855145697, 5612.915806262828, 5640.943590564042, 5647.322321321468, 5651.15232715096, 5656.943430565641], "value": 5360.341525100112, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [606.2331151093867, 628.8270725550475, 635.2599221906473, 647.9578827376221, 664.6278084272808, 32408.1897952551, 35257.2948541029, 35282.0, 35308.29383412333, 44363.77818110913, 42666.0, 46570.534294657045, 46576.767116164454, 46624.300635490494, 46695.29957050647, 8801.031886492498, 8905.910940890606, 8947.0, 8957.059508751585, 8960.8655870162, 8800.383973121883, 8898.37711360205, 8899.555022248895, 8901.910980890205, 8905.821883562325, 8924.196822285992, 8939.463632182089, 8940.910590894106, 8949.507777072258, 8953.418027828171, 8881.600327985223, 8907.287417006624, 8928.33037522182, 8933.508657023862, 8939.865902011476, 8787.077356877564, 8843.027267000625, 8844.73465796027, 8859.689910853089, 8861.379703420764, 41929.58070419287, 42164.78917605423, 42277.0, 42299.57700423018, 42300.788496057634, 3668.596454390015, 3668.724845636588, 3870.593587673282, 3944.4872166618443, 4262.403263543107, 32260.193495162574, 32451.350972980406, 32557.348853023042, 32919.83540082308, 33903.0, 4313.0, 4414.205443020255, 4437.933430998538, 4442.666799990014, 4460.464744230713, 321019.39490302635, 321496.0, 322232.0, 322432.0, 322486.0, 58684.0, 59045.11432328518, 59061.70469147713, 59078.40921590729, 59261.70369148126, 36947.815260923526, 37823.0, 37840.43239351412, 39867.2026559473, 61517.0, 17472.12639368052, 17598.560035999202, 17676.734848977274, 17680.734788978174, 17701.114944252735, 13438.059335846398, 13533.796993045113, 13537.390817413043, 13545.119567228296, 13553.864461355457, 19707.802921970884, 19934.70097948532, 20075.196992120153, 20108.798912010985, 20158.39524814258, 7059.788206353817, 7140.357367836816, 7207.89188162178, 7401.962990185122, 7440.4791664584045, 980.926430517714, 1021.6781713760141, 1107.0092267420614, 1130.3952385473733, 1140.7433327501246, 32333.51499727459, 32971.67028329686, 33164.668353316156, 33179.17052073718, 33261.833690831874, 1123.8370436286857, 1139.8233273842402, 1160.4197901049474, 1170.7190274334098, 1228.7419641875306, 17189.656206875792, 17370.131493425277, 17458.563535911704, 17545.210465529082, 17571.12144392801, 485234.0, 485456.0, 486680.0, 488054.0, 493063.0, 57075.0, 58819.0, 59107.70446147742, 59156.40843591595, 59188.40811591915, 1249.9125061245625, 1327.9136856104321, 1351.175782772508, 1352.0670737191306, 1388.4446221511275, 31624.367512649158, 33455.83272083624, 33525.329493410485, 33958.32083358368, 34389.312213756086, 48888.75555622199, 49024.50975490199, 49421.50578494169, 49423.25865112098, 49516.75241623913, 330074.0, 331312.0, 331510.0, 332359.0, 332796.0, 25865.224043278733, 27612.30969225705, 28458.146255612366, 28473.440819897514, 30243.243918902204, 47941.76029119972, 48888.75555622341, 49012.509874900796, 49944.50055499398, 50148.247776284115, 40574.0, 41267.6668949756, 41277.587224127376, 41570.0, 42682.0, 41195.0, 41588.16823663449, 41773.791131045364, 41825.37261941134, 42755.0, 41166.58833411628, 41844.581554185286, 41991.580084198766, 42368.788156059025, 42519.14961700687, 35614.465783013795, 36627.0, 36704.265914681026, 37359.81320093382, 37472.437913430775, 27913.860430697718, 27968.86015569909, 28224.0, 28249.71750282553, 28446.57330140091], "value": 19945.370641913658, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.9922401551967, 396.96228858258456, 404.3308324722585, 406.2301937827816, 414.77602094868774, 18110.547236319082, 19879.60240795185, 19896.0, 20089.196432142733, 20853.47866303343, 11641.708957276072, 12166.574169904055, 12180.878191218086, 19091.899891011977, 19579.804201957977, 5191.714455704935, 5311.389190243113, 5338.67967921924, 5349.063913815066, 5370.731463426813, 5234.659747116444, 5327.813526526573, 5329.973350133244, 5354.491323324278, 5365.517103460687, 5326.840194794143, 5332.440093790155, 5338.91991620126, 5339.0, 5412.864678383033, 5572.972135139318, 5701.173329867188, 5721.9427805721825, 5737.627054241461, 5761.078227483604, 4937.703737775745, 5083.898322033576, 5141.640085194001, 5142.948570514322, 5149.7940082397035, 13372.665683357896, 13867.167969921837, 13882.403523594714, 14002.649933751636, 14017.0, 2251.17831991322, 2327.487952650414, 2352.788249057593, 2445.8654773987423, 2637.802164837645, 9916.702498925044, 10145.239107067, 10233.437160956144, 10253.743656408575, 10658.0, 1873.9531511712316, 1886.7547218861598, 1895.2608482691762, 1928.7106933960017, 2028.4016215216548, 44719.0, 53555.928881422544, 54285.728571357286, 80901.59549202275, 84343.5782821088, 10087.293889427816, 11235.0, 11307.886921130683, 16528.0, 17575.29698812033, 32387.0, 33145.50281745776, 33244.1688957778, 33469.33061338808, 33606.66393336084, 9854.753631159278, 10081.19350451962, 10117.089461948313, 10281.0, 10613.946930265454, 8721.30229581632, 8752.51861147643, 8843.292536597057, 8976.68581599654, 8998.280137588978, 8713.738587842376, 8920.553972301359, 8931.374803763803, 8940.32947528938, 9203.4938078405, 3374.5073848566617, 3389.8135602542097, 3393.590550708975, 3515.314513669861, 3547.6452354764315, 946.4321407155652, 985.9901400985898, 1006.3207335048813, 1040.15747244732, 1122.3602546548552, 19085.141168647446, 19239.0, 19698.704519431933, 19815.0, 20015.0, 1007.1036777268237, 1018.6027449294781, 1031.2214278219897, 1063.9893601064045, 1092.3882625729616, 10705.518251678694, 11195.4962026709, 11229.438528073564, 11313.943430282796, 11366.545338186379, 500441.0, 508219.0, 510602.0, 511106.333619984, 517974.0, 94975.05024949662, 96359.51820240854, 96674.51662741641, 97123.51438242763, 97166.0, 981.8183636027358, 1006.5470538257813, 1021.9489025548693, 1024.2011231239644, 1040.4745603470199, 17242.568935776704, 17441.912790436476, 17624.735628965318, 17823.376181833573, 17939.192736327157, 26146.86926565431, 32796.508052378755, 38991.51597566316, 39264.803675981435, 39530.60469395269, 55356.0, 57026.14460783009, 57877.13184302155, 57925.13112303235, 58195.418045819, 8771.684247362691, 8805.867911981453, 8855.778605534913, 9057.04900985387, 9125.087491250822, 10981.890181098086, 13410.061295709596, 13742.175469471464, 13820.654483637989, 14084.0, 11876.584319548774, 11938.820917686067, 12096.75806483883, 12098.818517722417, 12232.204906681038, 12030.879691202976, 12055.336956467478, 12140.15018948665, 12152.453139608562, 12372.81440778407, 12255.0, 12351.69120771988, 12380.938095309826, 12556.246625202517, 12568.183068100545, 8567.74296771098, 8942.06108358639, 8976.86534701967, 9053.139951704577, 9145.862812057958, 11479.196456247982, 12210.511579536718, 12403.62789116328, 12660.11379203447, 12717.8092328617], "value": 9633.758534902112, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1365.6858922447843, 1526.511516314779, 1538.3462028637825, 1552.5420001099674, 1557.7507598784196, 8479.745607631774, 8882.24500917422, 8912.688055918044, 9158.084191580838, 9178.770530736718, 8889.733308000754, 9082.046385129548, 9097.681581144661, 9107.043760405162, 9120.087991200893, 1914.33955285427, 2341.882905854709, 2388.8088952883772, 2412.5416170927556, 2416.154345978911, 2425.9272221833285, 2432.2338463383976, 2436.0255897640995, 2436.4639779248537, 2437.8171637127284, 2339.8830058497006, 2367.621180611103, 2378.5480758655885, 2382.0471811275456, 2388.474535602165, 1998.8400927925804, 2294.9541009179807, 2384.511175209078, 2386.9641955370685, 2397.7841994220603, 2149.48412381029, 2183.748868880083, 2188.1466228171025, 2190.1786829938774, 2196.5936301784063, 9045.773855653595, 9102.044285350075, 9102.77243068922, 9127.361084724072, 9134.040925702839, 5185.325907632021, 5271.472852714736, 5316.175992721134, 5321.973390133063, 5338.652987555796, 3560.092176495003, 3573.5175751273505, 3625.456181572759, 3625.5286812714444, 3634.854605815764, 1217.5677634439824, 1221.2611370121037, 1245.302630526908, 1248.3259040118417, 1279.2452453052745, 12715.554955576603, 12722.173058751152, 12731.490740370282, 12734.490620375174, 12774.936125319407, 4555.863324100283, 4671.135839869635, 4731.763411829464, 4734.171519983989, 4739.146953548395, 10665.306755060896, 10708.518116684612, 10721.195910306758, 10723.30298530593, 10723.731906702395, 4704.976475117671, 5215.58275337972, 5291.074062039128, 5291.841244762663, 5317.946820531823, 4740.525947405232, 4911.705297682079, 4922.458529561745, 4956.702597844141, 4961.0, 3913.745606535566, 3931.3906344516645, 3991.3613821788376, 4017.6183262590594, 4028.798560071985, 1232.1744431231098, 1236.863944966053, 1248.2822377133184, 1250.8248845161684, 1252.9812052819216, 906.5965645287915, 988.3032462114193, 1002.8946960569181, 1007.3754272351189, 1015.2740790334946, 5798.6810725410505, 5955.910661340084, 6124.448799607968, 6130.0, 6231.566739649906, 465.53911627488543, 471.5402482579526, 474.8551691733985, 487.87559172410806, 489.1269084683822, 3978.0253837809923, 4003.6997225208224, 4023.75857448554, 4128.318827393507, 4128.422020917074, 10967.561297548009, 11228.0, 11369.943150284362, 11386.316820990767, 11392.487338069806, 11212.495437705324, 11224.438778061065, 11268.661940141645, 11376.715582110348, 11378.601748938912, 7865.331446826969, 8138.511689298661, 8218.917810821935, 8385.95807020961, 8457.661693532313, 6230.532710046855, 6487.578307410004, 6524.739010439529, 6546.149000629887, 6617.636030018395, 10354.016368444985, 10424.739381515219, 10520.894791051991, 10560.155187584822, 10592.099671527698, 10379.221558383, 10528.842067369149, 10663.89336106629, 10711.785764284827, 10813.24307298513, 7314.634268286671, 7855.3715702744785, 8015.078265999251, 8024.478408903403, 8295.336373090018, 8952.820943581222, 8995.55022248898, 9039.954800225956, 9052.818943621223, 9078.81842363136, 8013.0, 8186.959065204635, 8206.343492520704, 8279.95860020696, 8325.750227493185, 7679.500832445873, 7858.5677787722225, 7864.9606751965875, 7974.960125199337, 7990.640421181177, 6697.665116744046, 6911.619860907698, 6969.860602788017, 7020.087388639444, 7035.648217589202, 5210.765515551733, 5301.602379821452, 5361.169018802092, 5361.303030605996, 5451.454854514517, 5367.919481207707, 5437.293151890387, 5491.945080549303, 5566.304211973504, 5661.150827375926], "value": 4658.334778746871, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.3779953825332, 337.2883216413367, 341.6139762068863, 342.33074339665956, 345.58874938822817, 5321.175217841236, 5347.844771584764, 5382.38102618199, 5392.56859451244, 5444.428335024825, 5365.436629153932, 5369.731513424333, 5394.1908713692965, 5400.702961337125, 5402.459754024605, 4029.597040295969, 4072.287349713803, 4176.227397931377, 4209.726367786084, 4251.383549385338, 4199.790010499478, 4199.824049266211, 4204.495460544739, 4224.577542245766, 4231.4076029355765, 3987.1427643056736, 4211.284081706102, 4225.514065882432, 4226.36604509323, 4230.3865939438765, 4081.3265811141127, 4204.747715137086, 4205.495340559122, 4213.894652633709, 4215.59951804578, 3700.130469339703, 3716.7769933804057, 3731.003912906582, 3733.4959780429567, 3750.3498460677215, 5148.433672296044, 5155.484451554852, 5160.612954028463, 5160.741962901878, 5173.870653233662, 4502.684812063157, 4735.4373056891145, 4745.430548334188, 4753.073150735608, 4786.425628924551, 5581.413951535112, 5597.860053498654, 5611.0, 5621.578381621394, 5638.718064096779, 3997.600239976008, 3999.4000899864964, 4012.3981402789814, 4017.3773065174946, 4028.9194216115807, 5578.972105139489, 5773.595848290622, 5784.652920824764, 5808.2449281593545, 5837.620554663933, 4025.9331277211563, 4067.9796601016806, 4079.5104587449696, 4080.734752241154, 4105.0, 4119.6910231732145, 4145.66834653227, 4150.522689890641, 4187.455630768041, 4190.706650534495, 4552.044070745114, 4744.363194697824, 4744.95255047452, 4752.0, 4754.952450475451, 5559.0, 5570.498655121059, 5593.692346920959, 5596.921693068796, 5610.214569960209, 1428.8714015738633, 1430.8426073131948, 1433.6487560547523, 1438.798603166347, 1443.7401267771693, 904.5974541329045, 917.788908551037, 926.7775733823834, 957.5786653872274, 988.3822610868208, 5321.494458026481, 5329.147336426153, 5343.145096784496, 5355.625106242525, 5372.91940620891, 5608.69152196625, 5611.382747897728, 5612.803551875746, 5625.6905870176715, 5629.155626656035, 5130.79476820923, 5247.924175544004, 5299.152135658276, 5319.218061949213, 5363.463653634605, 2956.8521573921216, 3009.473342165112, 3016.6575873736415, 3018.4868572342752, 3025.4705426550267, 5654.434556544395, 5777.162311464898, 5859.94140058594, 5866.472017518358, 5869.677167755729, 5844.0, 5882.499987501024, 5887.882242355129, 5913.811889600789, 5920.437558431942, 5971.701414929323, 5993.580449368589, 6006.789762358297, 6017.217761690952, 6027.547933905063, 5591.4688104629995, 5749.396313387034, 5789.189513468202, 5791.218185544897, 5841.737121829613, 5647.265855438766, 5662.0, 5669.497583140554, 5719.142128680731, 5773.942260577341, 5888.528917686661, 5908.409159084057, 5958.791442299499, 5990.0, 6060.6969651516365, 4115.36211887158, 4121.814518346621, 4136.234796562646, 4138.544760076329, 4167.499900012018, 5890.676012819336, 5894.0, 5898.705064746831, 5899.823005309848, 5901.229631110564, 5533.058056812998, 5543.916841247304, 5646.520045796154, 5717.742701578356, 5731.254936858182, 5580.944190558205, 5618.2415373924, 5635.098384258499, 5638.661680299195, 5647.6893770843, 5328.813491527778, 5387.784488620411, 5521.668699878021, 5537.723113844372, 5543.861403464945, 5635.549156067423, 5652.6043176978865, 5670.74481648335, 5671.602987790815, 5671.971640141773, 5497.422770609189, 5543.695096769717, 5548.889022219614, 5559.805406810742, 5565.998120338378], "value": 4279.186666325829, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [791.9841603167937, 802.919708029197, 844.3287586368837, 869.9347548933831, 880.1066917079165, 13974.510892118766, 14906.478273260438, 14922.179280139588, 14964.32660530276, 14990.175540345277, 13197.736045279089, 13271.601851944433, 13343.0, 13343.599692009255, 13382.464701411956, 688.9827754306158, 806.3428305930677, 834.1366685480499, 835.2274146414572, 836.3894357119307, 821.1706176761484, 827.23480780278, 828.9751307460785, 833.2334252487695, 833.8499070167368, 724.0478770416885, 886.2688282167238, 888.4003297774009, 888.5024386343669, 888.7333799860048, 854.2013217641525, 871.6687658689689, 875.3697337916722, 875.3741075131248, 875.8029443375253, 644.4393377761331, 644.6583310845259, 645.0807599171162, 645.3675398109879, 645.3933302695451, 14267.643308917257, 14281.642958926006, 14300.642483937881, 14320.785188222186, 14335.713285734331, 13141.0, 13534.390952407166, 13571.45714171432, 13605.795913061313, 13615.455381784717, 11765.529378824836, 11955.581554645545, 12060.758784824255, 12125.514979400901, 12210.084243681671, 4548.977255113703, 4598.770061496945, 4651.279051746985, 4704.835330763441, 4761.095391875567, 52680.0, 52697.0, 53074.20388694173, 54180.0, 54741.0, 10782.245242833083, 11254.324740515594, 11308.83036754433, 11616.128790340757, 11652.359120248298, 20082.297119600746, 20332.69500957487, 20348.389548313575, 20374.388768336972, 20375.8981205096, 8165.63254653542, 8346.290565302017, 8520.914790852012, 8573.885394898747, 8715.564221788885, 4736.905261894743, 4788.880277993008, 4793.1372352976105, 4914.287428322873, 5075.416327122428, 3477.8956631301103, 3569.8929032129076, 3601.2617413430185, 3617.2946275476556, 3618.6743193112748, 682.3074579302013, 693.5041445366587, 708.7235977968493, 739.4158614694438, 766.4519868294196, 609.4484491535168, 630.4137152448176, 651.1535004494139, 739.2865884421451, 782.8238646304536, 9143.954280228556, 9577.616895324109, 9623.278254130968, 9669.806603868024, 9684.56419461126, 678.1218322272669, 742.5210739073267, 743.2456057102046, 753.4273951796564, 770.2143813310392, 4322.848700295474, 4443.7111587746695, 4526.125910047143, 4564.064366804863, 4583.954160458419, 59445.405545943984, 60290.0, 60757.392426076054, 60757.69621151954, 62957.37042629515, 56803.14795278074, 57312.713436432554, 58206.417935820944, 58210.0, 58879.116813247834, 49431.50568494341, 50996.74501627468, 51009.744951275, 51121.233181502306, 52256.21615675768, 8670.523121228392, 8939.374243802871, 8939.865902011605, 8984.191422771983, 9014.233790127913, 33499.16252093717, 34125.488117677756, 34811.08539030276, 35173.12067198341, 35291.117722057155, 44067.0, 45314.773426132655, 45526.77236613796, 45711.771441142584, 46086.76956615329, 11878.406079695807, 11993.100517461051, 11997.140443230965, 12064.698382540506, 12226.510939562319, 12569.434375452944, 12826.358682065673, 13095.148815326973, 13128.0, 13623.0, 11915.404229788302, 12001.879981200076, 12092.939535302266, 12416.379181040731, 12828.166169198972, 12047.277163370225, 12229.266244025386, 12286.631401057983, 12338.691532711755, 12398.13213075076, 11612.651620451063, 11724.296542207494, 11905.821412678644, 11974.880251197377, 12258.325792081521, 10271.897281027093, 10508.737281568023, 10526.789464210826, 10573.52419141156, 10639.414832183995, 10593.0, 10594.47027648599, 10609.151267898704, 10707.250492465453, 10710.250282480152], "value": 6856.267662026421, "unit": "score"}], "name": "dromaeo_css", "value": 7212.609912378025, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 107.0, 110.0, 110.0, 111.0, 113.0, 111.0, 110.0, 111.0, 113.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 125.0, 150.0, 217.0, 126.0, 176.0, 163.0, 126.0, 183.0, 127.0], "value": 155.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 163.0, 161.0, 161.0, 161.0, 162.0, 161.0, 163.0, 162.0], "value": 161.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 75.0, 146.0, 76.0, 74.0, 76.0, 74.0, 142.0, 75.0], "value": 88.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 93.0, 154.0, 93.0, 93.0, 92.0, 125.0, 91.0, 91.0, 127.0], "value": 105.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 151.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 94.0, 94.0, 94.0, 95.0, 95.0, 95.0, 95.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 59.0, 59.0, 58.0, 58.0, 58.0, 58.0, 110.0, 55.0, 56.0], "value": 62.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 58.0, 60.0, 59.0, 59.0, 59.0, 59.0, 65.0, 59.0], "value": 59.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 80.0, 84.0, 79.0, 86.0, 80.0, 81.0, 79.0, 80.0], "value": 80.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 132.0, 124.0, 141.0, 127.0, 122.0, 123.0, 130.0, 236.0, 126.0], "value": 139.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 168.0, 170.0, 168.0, 168.0, 170.0, 173.0, 178.0, 168.0, 166.0], "value": 170.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 66.0, 65.0, 69.0, 72.0, 66.0, 65.0, 63.0, 63.0], "value": 65.9, "unit": "score"}], "name": "kraken", "value": 1556.8, "alertThreshold": 2.0}]} 08:58:36 INFO - Return code: 0 08:58:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 08:58:36 ERROR - # TBPL SUCCESS # 08:58:36 INFO - Running post-action listener: _resource_record_post_action 08:58:36 INFO - ##### 08:58:36 INFO - ##### Finished run-tests step (success) 08:58:36 INFO - ##### 08:58:36 INFO - Running post-run listener: _resource_record_post_run 08:58:37 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 544624640; Read time: 20; Write time: 525136 08:58:37 INFO - TinderboxPrint: CPU usage
13.1% 08:58:37 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 08:58:37 INFO - TinderboxPrint: I/O write bytes / time
544,624,640 / 525,136 08:58:37 INFO - TinderboxPrint: CPU idle
8,276.6 (86.9%) 08:58:37 INFO - TinderboxPrint: CPU user
1,200.4 (12.6%) 08:58:37 INFO - TinderboxPrint: Swap in / out
0 / 0 08:58:37 INFO - install - Wall time: 17s; CPU: 15.0%; Read bytes: 0; Write bytes: 254906368; Read time: 0; Write time: 315932 08:58:37 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 4096; Write bytes: 287498240; Read time: 20; Write time: 209100 08:58:37 INFO - Running post-run listener: _upload_blobber_files 08:58:37 INFO - Blob upload gear active. 08:58:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:58:37 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 08:58:37 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'] 08:58:37 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 08:58:38 INFO - (blobuploader) - INFO - Open directory for files ... 08:58:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:58:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:58:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:58:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:58:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:58:39 INFO - (blobuploader) - INFO - Done attempting. 08:58:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:58:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:58:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:58:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:58:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:58:39 INFO - (blobuploader) - INFO - Done attempting. 08:58:39 INFO - (blobuploader) - INFO - Iteration through files over. 08:58:39 INFO - Return code: 0 08:58:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:58:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:58:39 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c60f068d935436268401afcffd67d22921f61e6b042a96b9556ab847ceeaf9f1b37367da01b8e4eb061e19d2ceb96208014beee836c6852c6fa6ad666a7e8d5e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5795327f4245a0850c49714bc91312285c634b8c838cf3f8ae9819d5a46f681eb675bdfae21449f436c5ae2db7f857309eff3048e083a385f60bf70a766e964d"} 08:58:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:58:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:58:39 INFO - Contents: 08:58:39 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c60f068d935436268401afcffd67d22921f61e6b042a96b9556ab847ceeaf9f1b37367da01b8e4eb061e19d2ceb96208014beee836c6852c6fa6ad666a7e8d5e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5795327f4245a0850c49714bc91312285c634b8c838cf3f8ae9819d5a46f681eb675bdfae21449f436c5ae2db7f857309eff3048e083a385f60bf70a766e964d"} 08:58:39 INFO - Running post-run listener: copy_logs_to_upload_dir 08:58:39 INFO - Copying logs to upload dir... 08:58:39 INFO - mkdir: /builds/slave/test/build/upload/logs 08:58:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1231.523192 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 31 secs) (at 2016-05-12 08:58:39.687943) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 08:58:39.691176) ========= 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=82e07478c745e68b1fe4678a000001bc-1463067485.956944-975307266 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/c60f068d935436268401afcffd67d22921f61e6b042a96b9556ab847ceeaf9f1b37367da01b8e4eb061e19d2ceb96208014beee836c6852c6fa6ad666a7e8d5e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5795327f4245a0850c49714bc91312285c634b8c838cf3f8ae9819d5a46f681eb675bdfae21449f436c5ae2db7f857309eff3048e083a385f60bf70a766e964d"} 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.011297 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/c60f068d935436268401afcffd67d22921f61e6b042a96b9556ab847ceeaf9f1b37367da01b8e4eb061e19d2ceb96208014beee836c6852c6fa6ad666a7e8d5e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5795327f4245a0850c49714bc91312285c634b8c838cf3f8ae9819d5a46f681eb675bdfae21449f436c5ae2db7f857309eff3048e083a385f60bf70a766e964d"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 08:58:39.716573) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 08:58:39.716934) ========= 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=82e07478c745e68b1fe4678a000001bc-1463067485.956944-975307266 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004447 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 08:58:39.798748) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 08:58:39.799062) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 08:58:39.799390) ========= ========= Total master_lag: 0.16 =========