builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-006 starttime: 1471627490.13 results: success (0) buildid: 20160819053002 builduid: ba5cf8c20a394a26a887d61f25c4b676 revision: 5a3b356bd3fbac6745f671c756dc799ad5aa94bc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-19 10:24:50.127587) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-19 10:24:50.128158) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-19 10:24:50.128432) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-19 10:24:50.143317) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 10:24:50.143573) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1471626819.587734-1904139076 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-19 10:24:50-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.3M=0s 2016-08-19 10:24:50 (46.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.096779 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 10:24:50.254622) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 10:24:50.254902) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1471626819.587734-1904139076 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019203 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 10:24:50.308614) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 10:24:50.308925) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 5a3b356bd3fbac6745f671c756dc799ad5aa94bc --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 5a3b356bd3fbac6745f671c756dc799ad5aa94bc --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=e31afd3c0f0de4e86ebe4d94000001b5-1471626819.587734-1904139076 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-19 10:24:50,818 truncating revision to first 12 chars 2016-08-19 10:24:50,818 Setting DEBUG logging. 2016-08-19 10:24:50,818 attempt 1/10 2016-08-19 10:24:50,818 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/5a3b356bd3fb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-19 10:24:51,123 unpacking tar archive at: fx-team-5a3b356bd3fb/testing/mozharness/ program finished with exit code 0 elapsedTime=0.661398 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 10:24:50.984883) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-19 10:24:50.985216) ========= script_repo_revision: 5a3b356bd3fbac6745f671c756dc799ad5aa94bc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-19 10:24:50.985596) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-19 10:24:50.985869) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-19 10:24:50.996037) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-08-19 10:24:50.996301) ========= /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=e31afd3c0f0de4e86ebe4d94000001b5-1471626819.587734-1904139076 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:24:51 INFO - MultiFileLogger online at 20160819 10:24:51 in /builds/slave/test 10:24:51 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 10:24:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:24:51 INFO - {'append_to_log': False, 10:24:51 INFO - 'base_work_dir': '/builds/slave/test', 10:24:51 INFO - 'blob_upload_branch': 'Fx-Team', 10:24:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:24:51 INFO - 'branch': 'Fx-Team', 10:24:51 INFO - 'buildbot_json_path': 'buildprops.json', 10:24:51 INFO - 'config_files': ('talos/linux_config.py',), 10:24:51 INFO - 'default_actions': ('clobber', 10:24:51 INFO - 'read-buildbot-config', 10:24:51 INFO - 'download-and-extract', 10:24:51 INFO - 'populate-webroot', 10:24:51 INFO - 'create-virtualenv', 10:24:51 INFO - 'install', 10:24:51 INFO - 'run-tests'), 10:24:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:24:51 INFO - 'download_minidump_stackwalk': True, 10:24:51 INFO - 'download_symbols': 'ondemand', 10:24:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:24:51 INFO - 'tooltool.py': '/tools/tooltool.py', 10:24:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:24:51 INFO - '/tools/misc-python/virtualenv.py')}, 10:24:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:24:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:24:51 INFO - 'installer_path': 'installer.exe', 10:24:51 INFO - 'log_level': 'info', 10:24:51 INFO - 'log_name': 'talos', 10:24:51 INFO - 'log_to_console': True, 10:24:51 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:24:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:24:51 INFO - 'opt_config_files': (), 10:24:51 INFO - 'pip_index': False, 10:24:51 INFO - 'sps_profile': False, 10:24:51 INFO - 'sps_profile_interval': 0, 10:24:51 INFO - 'suite': 'dromaeojs', 10:24:51 INFO - 'system_bits': '32', 10:24:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:24:51 INFO - 'title': 'talos-linux64-ix-006', 10:24:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:24:51 INFO - 'use_talos_json': True, 10:24:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:24:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:24:51 INFO - 'work_dir': 'build'} 10:24:51 INFO - [mozharness: 2016-08-19 17:24:51.601964Z] Running clobber step. 10:24:51 INFO - Running pre-action listener: _resource_record_pre_action 10:24:51 INFO - Running main action method: clobber 10:24:51 INFO - rmtree: /builds/slave/test/build 10:24:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:24:52 INFO - Running post-action listener: _resource_record_post_action 10:24:52 INFO - [mozharness: 2016-08-19 17:24:52.060088Z] Finished clobber step (success) 10:24:52 INFO - [mozharness: 2016-08-19 17:24:52.060189Z] Running read-buildbot-config step. 10:24:52 INFO - Running pre-action listener: _resource_record_pre_action 10:24:52 INFO - Running main action method: read_buildbot_config 10:24:52 INFO - Using buildbot properties: 10:24:52 INFO - { 10:24:52 INFO - "project": "", 10:24:52 INFO - "product": "firefox", 10:24:52 INFO - "script_repo_revision": "production", 10:24:52 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 10:24:52 INFO - "repository": "", 10:24:52 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 10:24:52 INFO - "buildid": "20160819053002", 10:24:52 INFO - "pgo_build": "True", 10:24:52 INFO - "basedir": "/builds/slave/test", 10:24:52 INFO - "buildnumber": 996, 10:24:52 INFO - "slavename": "talos-linux64-ix-006", 10:24:52 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 10:24:52 INFO - "platform": "ubuntu64_hw", 10:24:52 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 10:24:52 INFO - "branch": "fx-team", 10:24:52 INFO - "repo_path": "integration/fx-team", 10:24:52 INFO - "revision": "5a3b356bd3fbac6745f671c756dc799ad5aa94bc", 10:24:52 INFO - "stage_platform": "linux64-pgo", 10:24:52 INFO - "builduid": "ba5cf8c20a394a26a887d61f25c4b676", 10:24:52 INFO - "slavebuilddir": "test" 10:24:52 INFO - } 10:24:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/QVrWDpp5SLO3Qw-UwVhPqw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 10:24:52 INFO - Running post-action listener: _resource_record_post_action 10:24:52 INFO - [mozharness: 2016-08-19 17:24:52.062617Z] Finished read-buildbot-config step (success) 10:24:52 INFO - [mozharness: 2016-08-19 17:24:52.062705Z] Running download-and-extract step. 10:24:52 INFO - Running pre-action listener: _resource_record_pre_action 10:24:52 INFO - Running main action method: download_and_extract 10:24:52 INFO - mkdir: /builds/slave/test/build/tests 10:24:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:24:52 INFO - trying https://queue.taskcluster.net/v1/task/QVrWDpp5SLO3Qw-UwVhPqw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 10:24:52 INFO - Downloading https://queue.taskcluster.net/v1/task/QVrWDpp5SLO3Qw-UwVhPqw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 10:24:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QVrWDpp5SLO3Qw-UwVhPqw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 10:24:52 INFO - Downloaded 1361 bytes. 10:24:52 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 10:24:52 INFO - Using the following test package requirements: 10:24:52 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 10:24:52 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 10:24:52 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 10:24:52 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 10:24:52 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 10:24:52 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 10:24:52 INFO - u'jsshell-linux-x86_64.zip'], 10:24:52 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 10:24:52 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 10:24:52 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 10:24:52 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 10:24:52 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 10:24:52 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 10:24:52 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 10:24:52 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 10:24:52 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 10:24:52 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 10:24:52 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 10:24:52 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 10:24:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:24:52 INFO - trying https://queue.taskcluster.net/v1/task/QVrWDpp5SLO3Qw-UwVhPqw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 10:24:52 INFO - Downloading https://queue.taskcluster.net/v1/task/QVrWDpp5SLO3Qw-UwVhPqw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 10:24:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QVrWDpp5SLO3Qw-UwVhPqw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 10:24:53 INFO - Downloaded 21906216 bytes. 10:24:53 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 10:24:57 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 10:24:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:24:57 INFO - trying https://queue.taskcluster.net/v1/task/QVrWDpp5SLO3Qw-UwVhPqw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 10:24:57 INFO - Downloading https://queue.taskcluster.net/v1/task/QVrWDpp5SLO3Qw-UwVhPqw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 10:24:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QVrWDpp5SLO3Qw-UwVhPqw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 10:24:58 INFO - Downloaded 21906216 bytes. 10:24:58 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 10:25:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:25:03 INFO - trying https://queue.taskcluster.net/v1/task/QVrWDpp5SLO3Qw-UwVhPqw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 10:25:03 INFO - Downloading https://queue.taskcluster.net/v1/task/QVrWDpp5SLO3Qw-UwVhPqw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 10:25:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QVrWDpp5SLO3Qw-UwVhPqw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 10:25:05 INFO - Downloaded 14413981 bytes. 10:25:05 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 10:25:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:25:05 INFO - trying https://queue.taskcluster.net/v1/task/QVrWDpp5SLO3Qw-UwVhPqw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 10:25:05 INFO - Downloading https://queue.taskcluster.net/v1/task/QVrWDpp5SLO3Qw-UwVhPqw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 10:25:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QVrWDpp5SLO3Qw-UwVhPqw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 10:25:07 INFO - Downloaded 59605909 bytes. 10:25:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QVrWDpp5SLO3Qw-UwVhPqw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 10:25:07 INFO - mkdir: /builds/slave/test/properties 10:25:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:25:07 INFO - Writing to file /builds/slave/test/properties/build_url 10:25:07 INFO - Contents: 10:25:07 INFO - build_url:https://queue.taskcluster.net/v1/task/QVrWDpp5SLO3Qw-UwVhPqw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 10:25:08 INFO - Running post-action listener: _resource_record_post_action 10:25:08 INFO - Running post-action listener: set_extra_try_arguments 10:25:08 INFO - [mozharness: 2016-08-19 17:25:08.619703Z] Finished download-and-extract step (success) 10:25:08 INFO - [mozharness: 2016-08-19 17:25:08.619928Z] Running populate-webroot step. 10:25:08 INFO - Running pre-action listener: _resource_record_pre_action 10:25:08 INFO - Running main action method: populate_webroot 10:25:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:25:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:25:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:25:08 INFO - '--tppagecycles', 10:25:08 INFO - '7'], 10:25:08 INFO - 'tests': ['tsvgm']}}, 10:25:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:25:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:25:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:25:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:25:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:25:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:25:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:25:08 INFO - 'talos_options': ['--disable-e10s'], 10:25:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:25:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:25:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:25:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:25:08 INFO - 'talos_options': ['--disable-e10s'], 10:25:08 INFO - 'tests': ['damp', 'tps']}, 10:25:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:25:08 INFO - 'tests': ['damp', 'tps']}, 10:25:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:25:08 INFO - 'tests': ['dromaeo_dom']}, 10:25:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:25:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:25:08 INFO - 'tests': ['basic_compositor_video']}, 10:25:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:25:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:25:08 INFO - 'tests': ['a11yr', 10:25:08 INFO - 'ts_paint', 10:25:08 INFO - 'tpaint', 10:25:08 INFO - 'sessionrestore', 10:25:08 INFO - 'sessionrestore_no_auto_restore', 10:25:08 INFO - 'tabpaint']}, 10:25:08 INFO - 'other-e10s': {'tests': ['a11yr', 10:25:08 INFO - 'ts_paint', 10:25:08 INFO - 'tpaint', 10:25:08 INFO - 'sessionrestore', 10:25:08 INFO - 'sessionrestore_no_auto_restore', 10:25:08 INFO - 'tabpaint']}, 10:25:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:25:08 INFO - 'ts_paint', 10:25:08 INFO - 'tpaint', 10:25:08 INFO - 'sessionrestore', 10:25:08 INFO - 'sessionrestore_no_auto_restore', 10:25:08 INFO - 'tabpaint']}, 10:25:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:25:08 INFO - 'ts_paint', 10:25:08 INFO - 'tpaint', 10:25:08 INFO - 'sessionrestore', 10:25:08 INFO - 'sessionrestore_no_auto_restore', 10:25:08 INFO - 'tabpaint']}, 10:25:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:25:08 INFO - 'tests': ['a11yr', 10:25:08 INFO - 'ts_paint', 10:25:08 INFO - 'tpaint', 10:25:08 INFO - 'sessionrestore', 10:25:08 INFO - 'sessionrestore_no_auto_restore', 10:25:08 INFO - 'tabpaint']}, 10:25:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:25:08 INFO - 'tests': ['a11yr', 10:25:08 INFO - 'ts_paint', 10:25:08 INFO - 'tpaint', 10:25:08 INFO - 'sessionrestore', 10:25:08 INFO - 'sessionrestore_no_auto_restore', 10:25:08 INFO - 'tabpaint']}, 10:25:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:25:08 INFO - 'tests': ['tsvgx', 10:25:08 INFO - 'tsvgr_opacity', 10:25:08 INFO - 'tart', 10:25:08 INFO - 'tscrollx', 10:25:08 INFO - 'cart']}, 10:25:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:25:08 INFO - 'tsvgr_opacity', 10:25:08 INFO - 'tart', 10:25:08 INFO - 'tscrollx', 10:25:08 INFO - 'cart']}, 10:25:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:25:08 INFO - 'talos_options': ['--disable-e10s'], 10:25:08 INFO - 'tests': ['tp5o']}, 10:25:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:25:08 INFO - 'tests': ['tp5o']}, 10:25:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:25:08 INFO - 'talos_options': ['--disable-e10s', 10:25:08 INFO - '--xperf_path', 10:25:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:25:08 INFO - 'tests': ['tp5n']}, 10:25:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:25:08 INFO - 'talos_options': ['--xperf_path', 10:25:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:25:08 INFO - 'tests': ['tp5n']}}, 10:25:08 INFO - 'talos.zip': {'path': '', 10:25:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:25:08 INFO - Running post-action listener: _resource_record_post_action 10:25:08 INFO - [mozharness: 2016-08-19 17:25:08.639259Z] Finished populate-webroot step (success) 10:25:08 INFO - [mozharness: 2016-08-19 17:25:08.639346Z] Running create-virtualenv step. 10:25:08 INFO - Running pre-action listener: _resource_record_pre_action 10:25:08 INFO - Running main action method: create_virtualenv 10:25:08 INFO - Creating virtualenv /builds/slave/test/build/venv 10:25:08 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 10:25:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:25:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:25:08 INFO - Using real prefix '/usr' 10:25:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:25:09 INFO - Installing distribute.............................................................................................................................................................................................done. 10:25:11 INFO - Installing pip.................done. 10:25:11 INFO - Return code: 0 10:25:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:25: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:25:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:25:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:25: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:25:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:25:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:25:11 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 0x7fa0b19f3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16928d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1721760>, '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 0x1722660>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1722af0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1471626819.587734-1904139076', '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 10:25:11 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 10:25:11 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 10:25:11 INFO - Using env: {'DISPLAY': ':0', 10:25:11 INFO - 'HOME': '/home/cltbld', 10:25:11 INFO - 'LANG': 'en_US.UTF-8', 10:25:11 INFO - 'LANGUAGE': 'en_US:en', 10:25:11 INFO - 'LOGNAME': 'cltbld', 10:25:11 INFO - 'MAIL': '/var/mail/cltbld', 10:25:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:25:11 INFO - 'MOZ_NO_REMOTE': '1', 10:25:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:25:11 INFO - 'NO_EM_RESTART': '1', 10:25:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:25:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:25:11 INFO - 'PWD': '/builds/slave/test', 10:25:11 INFO - 'SHELL': '/bin/bash', 10:25:11 INFO - 'SHLVL': '1', 10:25:11 INFO - 'TERM': 'linux', 10:25:11 INFO - 'TMOUT': '86400', 10:25:11 INFO - 'USER': 'cltbld', 10:25:11 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1471626819.587734-1904139076', 10:25:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:25:11 INFO - '_': '/tools/buildbot/bin/python'} 10:25:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:25:11 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 10:25:11 INFO - Cleaning up... 10:25:11 INFO - Return code: 0 10:25:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:25: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:25:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:25:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:25: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:25:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:25:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:25:11 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>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0b19f3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16928d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1721760>, '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 0x1722660>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1722af0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1471626819.587734-1904139076', '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 10:25:11 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>=3.1.1'] in /builds/slave/test/build 10:25:11 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>=3.1.1 10:25:11 INFO - Using env: {'DISPLAY': ':0', 10:25:11 INFO - 'HOME': '/home/cltbld', 10:25:11 INFO - 'LANG': 'en_US.UTF-8', 10:25:11 INFO - 'LANGUAGE': 'en_US:en', 10:25:11 INFO - 'LOGNAME': 'cltbld', 10:25:11 INFO - 'MAIL': '/var/mail/cltbld', 10:25:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:25:11 INFO - 'MOZ_NO_REMOTE': '1', 10:25:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:25:11 INFO - 'NO_EM_RESTART': '1', 10:25:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:25:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:25:11 INFO - 'PWD': '/builds/slave/test', 10:25:11 INFO - 'SHELL': '/bin/bash', 10:25:11 INFO - 'SHLVL': '1', 10:25:11 INFO - 'TERM': 'linux', 10:25:11 INFO - 'TMOUT': '86400', 10:25:11 INFO - 'USER': 'cltbld', 10:25:11 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1471626819.587734-1904139076', 10:25:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:25:11 INFO - '_': '/tools/buildbot/bin/python'} 10:25:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:25:11 INFO - Downloading/unpacking psutil>=3.1.1 10:25:11 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 10:25:11 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 10:25:11 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 10:25:11 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 10:25:11 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 10:25:11 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 10:25:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:25:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:25:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:25:13 INFO - Installing collected packages: psutil 10:25:13 INFO - Running setup.py install for psutil 10:25:13 INFO - building 'psutil._psutil_linux' extension 10:25:13 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 10:25:13 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 10:25:13 INFO - building 'psutil._psutil_posix' extension 10:25:13 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 10:25:14 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 10:25:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:25:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:25:14 INFO - Successfully installed psutil 10:25:14 INFO - Cleaning up... 10:25:14 INFO - Return code: 0 10:25:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:25:14 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:25:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:25:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:25:14 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:25:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:25:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:25:14 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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0b19f3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16928d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1721760>, '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 0x1722660>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1722af0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1471626819.587734-1904139076', '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 10:25:14 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.3'] in /builds/slave/test/build 10:25:14 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.3 10:25:14 INFO - Using env: {'DISPLAY': ':0', 10:25:14 INFO - 'HOME': '/home/cltbld', 10:25:14 INFO - 'LANG': 'en_US.UTF-8', 10:25:14 INFO - 'LANGUAGE': 'en_US:en', 10:25:14 INFO - 'LOGNAME': 'cltbld', 10:25:14 INFO - 'MAIL': '/var/mail/cltbld', 10:25:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:25:14 INFO - 'MOZ_NO_REMOTE': '1', 10:25:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:25:14 INFO - 'NO_EM_RESTART': '1', 10:25:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:25:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:25:14 INFO - 'PWD': '/builds/slave/test', 10:25:14 INFO - 'SHELL': '/bin/bash', 10:25:14 INFO - 'SHLVL': '1', 10:25:14 INFO - 'TERM': 'linux', 10:25:14 INFO - 'TMOUT': '86400', 10:25:14 INFO - 'USER': 'cltbld', 10:25:14 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1471626819.587734-1904139076', 10:25:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:25:14 INFO - '_': '/tools/buildbot/bin/python'} 10:25:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:25:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:25:14 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 10:25:14 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 10:25:14 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 10:25:14 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 10:25:14 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 10:25:14 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 10:25:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:25:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:25:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:25:16 INFO - Installing collected packages: mozsystemmonitor 10:25:16 INFO - Running setup.py install for mozsystemmonitor 10:25:16 INFO - Successfully installed mozsystemmonitor 10:25:16 INFO - Cleaning up... 10:25:16 INFO - Return code: 0 10:25:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:25: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:25:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:25:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:25: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:25:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:25:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:25:16 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0b19f3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16928d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1721760>, '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 0x1722660>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1722af0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1471626819.587734-1904139076', '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 10:25:16 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==2.5.1'] in /builds/slave/test/build 10:25:16 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==2.5.1 10:25:16 INFO - Using env: {'DISPLAY': ':0', 10:25:16 INFO - 'HOME': '/home/cltbld', 10:25:16 INFO - 'LANG': 'en_US.UTF-8', 10:25:16 INFO - 'LANGUAGE': 'en_US:en', 10:25:16 INFO - 'LOGNAME': 'cltbld', 10:25:16 INFO - 'MAIL': '/var/mail/cltbld', 10:25:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:25:16 INFO - 'MOZ_NO_REMOTE': '1', 10:25:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:25:16 INFO - 'NO_EM_RESTART': '1', 10:25:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:25:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:25:16 INFO - 'PWD': '/builds/slave/test', 10:25:16 INFO - 'SHELL': '/bin/bash', 10:25:16 INFO - 'SHLVL': '1', 10:25:16 INFO - 'TERM': 'linux', 10:25:16 INFO - 'TMOUT': '86400', 10:25:16 INFO - 'USER': 'cltbld', 10:25:16 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1471626819.587734-1904139076', 10:25:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:25:16 INFO - '_': '/tools/buildbot/bin/python'} 10:25:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:25:16 INFO - Downloading/unpacking jsonschema==2.5.1 10:25:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:25:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:25:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:25:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:25:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:25:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:25:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:25:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:25:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:25:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:25:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:25:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:25:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:25:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:25:19 INFO - Downloading functools32-3.2.3-2.tar.gz 10:25:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:25:19 INFO - warning: no files found matching '*.txt' 10:25:19 INFO - no previously-included directories found matching 'build' 10:25:19 INFO - no previously-included directories found matching 'dist' 10:25:19 INFO - no previously-included directories found matching '.git*' 10:25:19 INFO - Installing collected packages: jsonschema, functools32 10:25:19 INFO - Running setup.py install for functools32 10:25:19 INFO - warning: no files found matching '*.txt' 10:25:19 INFO - no previously-included directories found matching 'build' 10:25:19 INFO - no previously-included directories found matching 'dist' 10:25:19 INFO - no previously-included directories found matching '.git*' 10:25:19 INFO - Successfully installed jsonschema functools32 10:25:19 INFO - Cleaning up... 10:25:19 INFO - Return code: 0 10:25:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:25:19 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:25:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:25:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:25:19 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:25:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:25:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:25:19 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0b19f3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16928d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1721760>, '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 0x1722660>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1722af0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1471626819.587734-1904139076', '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 10:25:19 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:25:19 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 functools32==3.2.3-2 10:25:19 INFO - Using env: {'DISPLAY': ':0', 10:25:19 INFO - 'HOME': '/home/cltbld', 10:25:19 INFO - 'LANG': 'en_US.UTF-8', 10:25:19 INFO - 'LANGUAGE': 'en_US:en', 10:25:19 INFO - 'LOGNAME': 'cltbld', 10:25:19 INFO - 'MAIL': '/var/mail/cltbld', 10:25:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:25:19 INFO - 'MOZ_NO_REMOTE': '1', 10:25:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:25:19 INFO - 'NO_EM_RESTART': '1', 10:25:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:25:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:25:19 INFO - 'PWD': '/builds/slave/test', 10:25:19 INFO - 'SHELL': '/bin/bash', 10:25:19 INFO - 'SHLVL': '1', 10:25:19 INFO - 'TERM': 'linux', 10:25:19 INFO - 'TMOUT': '86400', 10:25:19 INFO - 'USER': 'cltbld', 10:25:19 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1471626819.587734-1904139076', 10:25:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:25:19 INFO - '_': '/tools/buildbot/bin/python'} 10:25:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:25:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:25:19 INFO - Cleaning up... 10:25:19 INFO - Return code: 0 10:25:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:25:19 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:25:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:25:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:25:19 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:25:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:25:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:25:19 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 0x7fa0b19f3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16928d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1721760>, '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 0x1722660>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1722af0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1471626819.587734-1904139076', '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 10:25:19 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 10:25:19 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 10:25:19 INFO - Using env: {'DISPLAY': ':0', 10:25:19 INFO - 'HOME': '/home/cltbld', 10:25:19 INFO - 'LANG': 'en_US.UTF-8', 10:25:19 INFO - 'LANGUAGE': 'en_US:en', 10:25:19 INFO - 'LOGNAME': 'cltbld', 10:25:19 INFO - 'MAIL': '/var/mail/cltbld', 10:25:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:25:19 INFO - 'MOZ_NO_REMOTE': '1', 10:25:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:25:19 INFO - 'NO_EM_RESTART': '1', 10:25:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:25:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:25:19 INFO - 'PWD': '/builds/slave/test', 10:25:19 INFO - 'SHELL': '/bin/bash', 10:25:19 INFO - 'SHLVL': '1', 10:25:19 INFO - 'TERM': 'linux', 10:25:19 INFO - 'TMOUT': '86400', 10:25:19 INFO - 'USER': 'cltbld', 10:25:19 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1471626819.587734-1904139076', 10:25:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:25:19 INFO - '_': '/tools/buildbot/bin/python'} 10:25:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:25:19 INFO - Downloading/unpacking blobuploader==1.2.4 10:25:19 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 10:25:19 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 10:25:19 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 10:25:19 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 10:25:19 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 10:25:19 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 10:25:21 INFO - Downloading blobuploader-1.2.4.tar.gz 10:25:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:25:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:25: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 10:25: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 10:25: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 10:25: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 10:25: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 10:25: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 10:25:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:25:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:25:22 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 10:25:22 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 10:25:22 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 10:25:22 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 10:25:22 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 10:25:22 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 10:25:22 INFO - Downloading docopt-0.6.1.tar.gz 10:25:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:25:22 INFO - Installing collected packages: blobuploader, requests, docopt 10:25:22 INFO - Running setup.py install for blobuploader 10:25:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:25:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:25:22 INFO - Running setup.py install for requests 10:25:22 INFO - Running setup.py install for docopt 10:25:22 INFO - Successfully installed blobuploader requests docopt 10:25:22 INFO - Cleaning up... 10:25:22 INFO - Return code: 0 10:25:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:25:22 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:25:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:25:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:25:22 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:25:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:25:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:25:22 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 0x7fa0b19f3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16928d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1721760>, '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 0x1722660>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1722af0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1471626819.587734-1904139076', '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 10:25:22 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 10:25:22 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 10:25:22 INFO - Using env: {'DISPLAY': ':0', 10:25:22 INFO - 'HOME': '/home/cltbld', 10:25:22 INFO - 'LANG': 'en_US.UTF-8', 10:25:22 INFO - 'LANGUAGE': 'en_US:en', 10:25:22 INFO - 'LOGNAME': 'cltbld', 10:25:22 INFO - 'MAIL': '/var/mail/cltbld', 10:25:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:25:22 INFO - 'MOZ_NO_REMOTE': '1', 10:25:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:25:22 INFO - 'NO_EM_RESTART': '1', 10:25:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:25:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:25:22 INFO - 'PWD': '/builds/slave/test', 10:25:22 INFO - 'SHELL': '/bin/bash', 10:25:22 INFO - 'SHLVL': '1', 10:25:22 INFO - 'TERM': 'linux', 10:25:22 INFO - 'TMOUT': '86400', 10:25:22 INFO - 'USER': 'cltbld', 10:25:22 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1471626819.587734-1904139076', 10:25:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:25:22 INFO - '_': '/tools/buildbot/bin/python'} 10:25:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:25:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:25:23 INFO - Running setup.py (path:/tmp/pip-khvDtm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:25:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:25:23 INFO - Running setup.py (path:/tmp/pip-UZfDu2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:25:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:25:23 INFO - Running setup.py (path:/tmp/pip-8Vt01v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:25:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:25:23 INFO - Running setup.py (path:/tmp/pip-OgghiM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:25:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:25:23 INFO - Running setup.py (path:/tmp/pip-b0tiip-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:25:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:25:23 INFO - Running setup.py (path:/tmp/pip-ok6XCj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:25:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:25:23 INFO - Running setup.py (path:/tmp/pip-Oank3z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:25:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:25:23 INFO - Running setup.py (path:/tmp/pip-VBo6yB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:25:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:25:23 INFO - Running setup.py (path:/tmp/pip-2tO5ns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:25:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:25:23 INFO - Running setup.py (path:/tmp/pip-EecAU0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:25:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:25:23 INFO - Running setup.py (path:/tmp/pip-g6kruz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:25:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:25:23 INFO - Running setup.py (path:/tmp/pip-ehNdol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:25:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:25:24 INFO - Running setup.py (path:/tmp/pip-5P10fX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:25:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:25:24 INFO - Running setup.py (path:/tmp/pip-55wCZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:25:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:25:24 INFO - Running setup.py (path:/tmp/pip-gllDcr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:25:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:25:24 INFO - Running setup.py (path:/tmp/pip-vrrlsn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:25:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:25:24 INFO - Running setup.py (path:/tmp/pip-cvxg8o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:25:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:25:24 INFO - Running setup.py install for manifestparser 10:25:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:25:24 INFO - Running setup.py install for mozcrash 10:25:24 INFO - Running setup.py install for mozdebug 10:25:24 INFO - Running setup.py install for mozdevice 10:25:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:25:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:25:24 INFO - Running setup.py install for mozfile 10:25:24 INFO - Running setup.py install for mozhttpd 10:25:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:25:25 INFO - Running setup.py install for mozinfo 10:25:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:25:25 INFO - Running setup.py install for mozInstall 10:25:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:25:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:25:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:25:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:25:25 INFO - Running setup.py install for mozleak 10:25:25 INFO - Running setup.py install for mozlog 10:25:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:25:25 INFO - Running setup.py install for moznetwork 10:25:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:25:25 INFO - Running setup.py install for mozprocess 10:25:25 INFO - Running setup.py install for mozprofile 10:25:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:25:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:25:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:25:25 INFO - Running setup.py install for mozrunner 10:25:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:25:25 INFO - Running setup.py install for mozscreenshot 10:25:26 INFO - Running setup.py install for moztest 10:25:26 INFO - Running setup.py install for mozversion 10:25:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:25:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:25:26 INFO - Cleaning up... 10:25:26 INFO - Return code: 0 10:25:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:25: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:25:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:25:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:25: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:25:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:25:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:25:26 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 0x7fa0b19f3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16928d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1721760>, '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 0x1722660>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1722af0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1471626819.587734-1904139076', '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 10:25:26 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 10:25:26 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 10:25:26 INFO - Using env: {'DISPLAY': ':0', 10:25:26 INFO - 'HOME': '/home/cltbld', 10:25:26 INFO - 'LANG': 'en_US.UTF-8', 10:25:26 INFO - 'LANGUAGE': 'en_US:en', 10:25:26 INFO - 'LOGNAME': 'cltbld', 10:25:26 INFO - 'MAIL': '/var/mail/cltbld', 10:25:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:25:26 INFO - 'MOZ_NO_REMOTE': '1', 10:25:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:25:26 INFO - 'NO_EM_RESTART': '1', 10:25:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:25:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:25:26 INFO - 'PWD': '/builds/slave/test', 10:25:26 INFO - 'SHELL': '/bin/bash', 10:25:26 INFO - 'SHLVL': '1', 10:25:26 INFO - 'TERM': 'linux', 10:25:26 INFO - 'TMOUT': '86400', 10:25:26 INFO - 'USER': 'cltbld', 10:25:26 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1471626819.587734-1904139076', 10:25:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:25:26 INFO - '_': '/tools/buildbot/bin/python'} 10:25:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:25:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:25:26 INFO - Running setup.py (path:/tmp/pip-RDoczv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:25:26 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)) 10:25:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:25:26 INFO - Running setup.py (path:/tmp/pip-tO5yt1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:25:26 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)) 10:25:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:25:26 INFO - Running setup.py (path:/tmp/pip-XFSgpz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:25:26 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)) 10:25:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:25:26 INFO - Running setup.py (path:/tmp/pip-0Ox2hW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:25:26 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)) 10:25:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:25:26 INFO - Running setup.py (path:/tmp/pip-8y7dZT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:25:26 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)) 10:25:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:25:26 INFO - Running setup.py (path:/tmp/pip-L5LbC8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:25:26 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)) 10:25:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:25:26 INFO - Running setup.py (path:/tmp/pip-7LOx1Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:25:26 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)) 10:25:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:25:26 INFO - Running setup.py (path:/tmp/pip-2V3gC9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:25:27 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)) 10:25:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:25:27 INFO - Running setup.py (path:/tmp/pip-YFAqdZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:25:27 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)) 10:25:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:25:27 INFO - Running setup.py (path:/tmp/pip-umZzEN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:25:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 10:25:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:25:27 INFO - Running setup.py (path:/tmp/pip-tXUpXR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:25:27 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)) 10:25:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:25:27 INFO - Running setup.py (path:/tmp/pip-XUQ7UO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:25:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 10:25:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:25:27 INFO - Running setup.py (path:/tmp/pip-DpkGnq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:25:27 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)) 10:25:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:25:27 INFO - Running setup.py (path:/tmp/pip-yTxEEd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:25:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 10:25:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:25:27 INFO - Running setup.py (path:/tmp/pip-1O1dJ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:25:27 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)) 10:25:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:25:27 INFO - Running setup.py (path:/tmp/pip-2V3EUS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:25:27 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)) 10:25:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:25:27 INFO - Running setup.py (path:/tmp/pip-d3M6qy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:25:27 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)) 10:25:27 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)) 10:25:27 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)) 10:25:27 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)) 10:25:27 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)) 10:25:27 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)) 10:25:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:25:27 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 10:25:27 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 10:25:27 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 10:25:27 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 10:25:27 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 10:25:27 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 10:25:29 INFO - Downloading blessings-1.6.tar.gz 10:25:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:25:29 INFO - Installing collected packages: blessings 10:25:29 INFO - Running setup.py install for blessings 10:25:29 INFO - Successfully installed blessings 10:25:29 INFO - Cleaning up... 10:25:29 INFO - Return code: 0 10:25:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:25:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:25:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:25:30 INFO - Reading from file tmpfile_stdout 10:25:30 INFO - Current package versions: 10:25:30 INFO - argparse == 1.2.1 10:25:30 INFO - blessings == 1.6 10:25:30 INFO - blobuploader == 1.2.4 10:25:30 INFO - docopt == 0.6.1 10:25:30 INFO - functools32 == 3.2.3-2 10:25:30 INFO - jsonschema == 2.5.1 10:25:30 INFO - manifestparser == 1.1 10:25:30 INFO - mozInstall == 1.12 10:25:30 INFO - mozcrash == 0.17 10:25:30 INFO - mozdebug == 0.1 10:25:30 INFO - mozdevice == 0.48 10:25:30 INFO - mozfile == 1.2 10:25:30 INFO - mozhttpd == 0.7 10:25:30 INFO - mozinfo == 0.9 10:25:30 INFO - mozleak == 0.1 10:25:30 INFO - mozlog == 3.3 10:25:30 INFO - moznetwork == 0.27 10:25:30 INFO - mozprocess == 0.23 10:25:30 INFO - mozprofile == 0.28 10:25:30 INFO - mozrunner == 6.12 10:25:30 INFO - mozscreenshot == 0.1 10:25:30 INFO - mozsystemmonitor == 0.3 10:25:30 INFO - moztest == 0.7 10:25:30 INFO - mozversion == 1.4 10:25:30 INFO - psutil == 3.1.1 10:25:30 INFO - requests == 1.2.3 10:25:30 INFO - wsgiref == 0.1.2 10:25:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:25:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:25:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:25:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:25:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:25:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:25:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:25:30 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 0x7fa0b19f3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16928d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1721760>, '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 0x1722660>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1722af0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1471626819.587734-1904139076', '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 10:25:30 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 10:25:30 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 10:25:30 INFO - Using env: {'DISPLAY': ':0', 10:25:30 INFO - 'HOME': '/home/cltbld', 10:25:30 INFO - 'LANG': 'en_US.UTF-8', 10:25:30 INFO - 'LANGUAGE': 'en_US:en', 10:25:30 INFO - 'LOGNAME': 'cltbld', 10:25:30 INFO - 'MAIL': '/var/mail/cltbld', 10:25:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:25:30 INFO - 'MOZ_NO_REMOTE': '1', 10:25:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:25:30 INFO - 'NO_EM_RESTART': '1', 10:25:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:25:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:25:30 INFO - 'PWD': '/builds/slave/test', 10:25:30 INFO - 'SHELL': '/bin/bash', 10:25:30 INFO - 'SHLVL': '1', 10:25:30 INFO - 'TERM': 'linux', 10:25:30 INFO - 'TMOUT': '86400', 10:25:30 INFO - 'USER': 'cltbld', 10:25:30 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1471626819.587734-1904139076', 10:25:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:25:30 INFO - '_': '/tools/buildbot/bin/python'} 10:25:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:25:30 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)) 10:25:30 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)) 10:25:30 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)) 10:25:30 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)) 10:25:30 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)) 10:25:30 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)) 10:25:30 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)) 10:25:30 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)) 10:25:30 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)) 10:25:30 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)) 10:25:30 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)) 10:25:30 INFO - Cleaning up... 10:25:30 INFO - Return code: 0 10:25:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:25:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:25:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:25:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:25:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:25:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:25:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:25:30 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 0x7fa0b19f3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16928d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1721760>, '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 0x1722660>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1722af0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1471626819.587734-1904139076', '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 10:25:30 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 10:25:30 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 10:25:30 INFO - Using env: {'DISPLAY': ':0', 10:25:30 INFO - 'HOME': '/home/cltbld', 10:25:30 INFO - 'LANG': 'en_US.UTF-8', 10:25:30 INFO - 'LANGUAGE': 'en_US:en', 10:25:30 INFO - 'LOGNAME': 'cltbld', 10:25:30 INFO - 'MAIL': '/var/mail/cltbld', 10:25:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:25:30 INFO - 'MOZ_NO_REMOTE': '1', 10:25:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:25:30 INFO - 'NO_EM_RESTART': '1', 10:25:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:25:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:25:30 INFO - 'PWD': '/builds/slave/test', 10:25:30 INFO - 'SHELL': '/bin/bash', 10:25:30 INFO - 'SHLVL': '1', 10:25:30 INFO - 'TERM': 'linux', 10:25:30 INFO - 'TMOUT': '86400', 10:25:30 INFO - 'USER': 'cltbld', 10:25:30 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1471626819.587734-1904139076', 10:25:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:25:30 INFO - '_': '/tools/buildbot/bin/python'} 10:25:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:25:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:25:30 INFO - Cleaning up... 10:25:30 INFO - Return code: 0 10:25:30 INFO - Running post-action listener: _resource_record_post_action 10:25:30 INFO - Running post-action listener: _start_resource_monitoring 10:25:30 INFO - Starting resource monitoring. 10:25:30 INFO - [mozharness: 2016-08-19 17:25:30.522509Z] Finished create-virtualenv step (success) 10:25:30 INFO - [mozharness: 2016-08-19 17:25:30.522970Z] Running install step. 10:25:30 INFO - Running pre-action listener: _resource_record_pre_action 10:25:30 INFO - Running main action method: install 10:25:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:25:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:25:30 INFO - Reading from file tmpfile_stdout 10:25:30 INFO - Detecting whether we're running mozinstall >=1.0... 10:25:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:25:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:25:30 INFO - Reading from file tmpfile_stdout 10:25:30 INFO - Output received: 10:25:30 INFO - Usage: mozinstall [options] installer 10:25:30 INFO - Options: 10:25:30 INFO - -h, --help show this help message and exit 10:25:30 INFO - -d DEST, --destination=DEST 10:25:30 INFO - Directory to install application into. [default: 10:25:30 INFO - "/builds/slave/test"] 10:25:30 INFO - --app=APP Application being installed. [default: firefox] 10:25:30 INFO - mkdir: /builds/slave/test/build/application 10:25:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 10:25:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 10:25:47 INFO - Reading from file tmpfile_stdout 10:25:47 INFO - Output received: 10:25:47 INFO - /builds/slave/test/build/application/firefox/firefox 10:25:47 INFO - Running post-action listener: _resource_record_post_action 10:25:47 INFO - [mozharness: 2016-08-19 17:25:47.778124Z] Finished install step (success) 10:25:47 INFO - [mozharness: 2016-08-19 17:25:47.778373Z] Running run-tests step. 10:25:47 INFO - Running pre-action listener: _resource_record_pre_action 10:25:47 INFO - Running main action method: run_tests 10:25:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:25:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:25:47 INFO - Python 2.7.3 10:25:47 INFO - Return code: 0 10:25:47 INFO - grabbing minidump binary from tooltool 10:25:47 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:25:47 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 0x1721760>, '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 0x1722660>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1722af0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:25:47 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 10:25:47 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 10:25:47 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 10:25:47 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:25:47 INFO - Return code: 0 10:25:47 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 10:25:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:25:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:25:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 10:25:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:25:47 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/QVrWDpp5SLO3Qw-UwVhPqw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--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 10:25:47 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/QVrWDpp5SLO3Qw-UwVhPqw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-006 --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 10:25:47 INFO - Using env: {'DISPLAY': ':0', 10:25:47 INFO - 'HOME': '/home/cltbld', 10:25:47 INFO - 'LANG': 'en_US.UTF-8', 10:25:47 INFO - 'LANGUAGE': 'en_US:en', 10:25:47 INFO - 'LOGNAME': 'cltbld', 10:25:47 INFO - 'MAIL': '/var/mail/cltbld', 10:25:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:25:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:25:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:25:47 INFO - 'MOZ_NO_REMOTE': '1', 10:25:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:25:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:25:47 INFO - 'NO_EM_RESTART': '1', 10:25:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:25:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:25:47 INFO - 'PWD': '/builds/slave/test', 10:25:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:25:47 INFO - 'SHELL': '/bin/bash', 10:25:47 INFO - 'SHLVL': '1', 10:25:47 INFO - 'TERM': 'linux', 10:25:47 INFO - 'TMOUT': '86400', 10:25:47 INFO - 'USER': 'cltbld', 10:25:47 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1471626819.587734-1904139076', 10:25:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:25:47 INFO - '_': '/tools/buildbot/bin/python'} 10:25:47 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/QVrWDpp5SLO3Qw-UwVhPqw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--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 10:25:48 INFO - mozversion application_buildid: 20160819053002 10:25:48 INFO - mozversion application_changeset: 5a3b356bd3fbac6745f671c756dc799ad5aa94bc 10:25:48 INFO - mozversion application_display_name: Nightly 10:25:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:25:48 INFO - mozversion application_name: Firefox 10:25:48 INFO - mozversion application_remotingname: firefox 10:25:48 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 10:25:48 INFO - mozversion application_vendor: Mozilla 10:25:48 INFO - mozversion application_version: 51.0a1 10:25:48 INFO - mozversion platform_buildid: 20160819053002 10:25:48 INFO - mozversion platform_changeset: 5a3b356bd3fbac6745f671c756dc799ad5aa94bc 10:25:48 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 10:25:48 INFO - mozversion platform_version: 51.0a1 10:25:48 INFO - using testdate: 1471627548 10:25:48 INFO - actual date: 1471627548 10:25:48 INFO - starting webserver on 'localhost:45721' 10:25:48 INFO - SUITE-START | Running 2 tests 10:25:48 INFO - TEST-START | dromaeo_css 10:25:48 INFO - operating with platform_type : linux_ 10:25:48 INFO - Initialising browser for dromaeo_css test... 10:25:48 INFO - TEST-INFO | started process 16739 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpa4FCoy/profile http://localhost:45721/getInfo.html) 10:25:52 INFO - PROCESS | 16739 | __metrics Screen width/height:1600/1200 10:25:52 INFO - PROCESS | 16739 | colorDepth:24 10:25:52 INFO - PROCESS | 16739 | Browser inner width/height: 1024/697 10:25:52 INFO - PROCESS | 16739 | __metrics 10:25:52 INFO - PROCESS | 16739 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:25:52 INFO - PROCESS | 16739 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:25:53 INFO - PROCESS | 16739 | console.error: PushService: 10:25:53 INFO - PROCESS | 16739 | stateChangeProcessEnqueue: Error transitioning state 10:25:53 INFO - PROCESS | 16739 | UnknownError 10:25:53 INFO - TEST-INFO | 16739: exit 0 10:25:53 INFO - Browser initialized. 10:25:53 INFO - Running cycle 1/1 for dromaeo_css test... 10:25:53 INFO - TEST-INFO | started process 16820 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpa4FCoy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:26:05 INFO - PROCESS | 16820 | RSS: Main: 155467776 10:26:05 INFO - PROCESS | 16820 | 10:26:56 INFO - PROCESS | 16820 | 1471627616991 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] 10:29:06 INFO - PROCESS | 16820 | Cycle 1(1): loaded http://localhost:45721/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45721/tests/dromaeo/cssquery-ext.html) 10:29:07 INFO - PROCESS | 16820 | RSS: Main: 271630336 10:29:07 INFO - PROCESS | 16820 | 10:32:09 INFO - PROCESS | 16820 | Cycle 1(1): loaded http://localhost:45721/tests/dromaeo/cssquery-ext.html (next: http://localhost:45721/tests/dromaeo/cssquery-jquery.html) 10:32:10 INFO - PROCESS | 16820 | RSS: Main: 242376704 10:32:10 INFO - PROCESS | 16820 | 10:35:12 INFO - PROCESS | 16820 | Cycle 1(1): loaded http://localhost:45721/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45721/tests/dromaeo/cssquery-mootools.html) 10:35:12 INFO - PROCESS | 16820 | RSS: Main: 215429120 10:35:12 INFO - PROCESS | 16820 | 10:38:15 INFO - PROCESS | 16820 | Cycle 1(1): loaded http://localhost:45721/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45721/tests/dromaeo/cssquery-prototype.html) 10:38:15 INFO - PROCESS | 16820 | RSS: Main: 185085952 10:38:15 INFO - PROCESS | 16820 | 10:41:18 INFO - PROCESS | 16820 | Cycle 1(1): loaded http://localhost:45721/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45721/tests/dromaeo/cssquery-yui.html) 10:41:18 INFO - PROCESS | 16820 | RSS: Main: 185491456 10:41:18 INFO - PROCESS | 16820 | 10:44:21 INFO - PROCESS | 16820 | Cycle 1(1): loaded http://localhost:45721/tests/dromaeo/cssquery-yui.html (next: http://localhost:45721/tests/dromaeo/cssquery-dojo.html) 10:44:21 INFO - PROCESS | 16820 | RSS: Main: 277327872 10:44:21 INFO - PROCESS | 16820 | 10:44:21 INFO - PROCESS | 16820 | __start_tp_report 10:44:21 INFO - PROCESS | 16820 | _x_x_mozilla_page_load 10:44:21 INFO - PROCESS | 16820 | _x_x_mozilla_page_load_details 10:44:21 INFO - PROCESS | 16820 | |i|pagename|runs| 10:44:21 INFO - PROCESS | 16820 | |0;dojo.html;3046.9085927422175;3062.663107058225;3073.3392320651055;3082.722554970054;3090.3046814466747;5164.147915593932;5233.450487698794;5264.341957255343;5342.171963345682;5433.320834895638;5230.764615592298;5286.867828304295;5326.334208223972;5399.055165346068;5418.078926582481;4719.952800471994;4783.928241076386;4797.2324428091515;4804.567588916997;4957;4858.392700912386;4891.657583969123;4902.558769710726;4904.926426103593;4925.82759603414;4693.037927224927;4775.068861571995;4810.615150787964;4822.590079843218;4832.299316599091;4811.374521312242;4814.807407703687;4834.468208497063;4877.122118018755;4921.630877684188;4839.177339852234;4848.054629347279;4849.636277279218;4859.757012149378;4900.6569540132205;5122.154844450662;5200.323957885488;5261.947380526184;5280.683159010472;5343.973280133613;5655.830325090254;5657.688827114507;5658.094704847246;5705.77176912923;5716.485516303553;5731.799387021434;5741.024025915604;5749.0514065179195;5749.166370876242;5754.251947246873;5178.352705911761;5269.657472264291;5275.683458992473;5276.762545685453;5398.406175320712;5829.387914269026;5891.646501209942;5892.175095486636;5894.587378883437;5899.144624029492;5594.748236329375;5601.187827764951;5640.520555752807;5643.209950606918;5648.322201335866;5458.181272809111;5622.662640241599;5677;5679.716014199356;5770.19217309577;5699.5725320600295;5701.258836351248;5701.543876489871;5706.457886500776;5709.000924838137;5601.551875849922;5650.434956504317;5655.858603534863;5709.42905709434;5717.371089180187;4210.621044105984;4267.637250833652;4286.421333120051;4298.183345164409;4300.956990430056;3820.3505404081375;3836.558795738526;3849.7690138591774;3863.632954869283;3870.8838734838;5232.581393488512;5427.050266203478;5428.104362780176;5436.320459942507;5436.864078398072;5632.887342253132;5683.8010669626365;5702.48677619008;5743.425657434307;5783.826485205451;5380.569554435714;5384.6769193848495;5392.164214546751;5393.7572809223675;5399.70301633414;4801.855944321676;4955.132851750929;4961.2558116282125;4964.379452568429;5009;5852.531797456193;5911.704414779329;5920.2895652521975;5960.642361458326;6018;5739.167820666064;5764.221830052889;5767.0196066669605;5769.480746732815;5770.913436298543;5647.604667673223;5962.9105563415715;5967.731452084579;5979.611325264018;5985.311689155803;5590.664560126405;5597.776088956559;5609.411011843697;5663.9716801415725;5692.544596432358;5666.914996275142;5761.654300741969;5772.422757724194;5776.624519406225;5783.045797443543;5406.432324606017;5620.9156862647915;5665.688387138747;5670.8015219467125;5673.290838645169;5412.188171774265;5487.890242195213;5505.6696598204235;5560.110382338807;5579.6652200868075;5619.325680918152;5624.746886390204;5630.915536267041;5632.493075623213;5634.887302254014;5350.9732451337495;5661.235733175969;5702.971485142548;5707.315122185364;5715.771369145354;5279.313689220377;5434.130539113723;5498.890022199454;5502.257195278587;5579.302587176603 10:44:21 INFO - PROCESS | 16820 | |1;ext.html;584.2959234122883;615.4491729901738;622.2004723929751;632.7342516143219;643.964581947993;32562.348753024955;33245.833770831145;36027.81986090066;36136.81931590345;36143.09642258941;44354.33468497967;45618.08763824721;45715.31427028597;50232.24651630219;50325.74837125818;8918.866217006751;9044.8643270351;9047.321450891177;9058.592363343665;9060.818783624323;8902.243309318716;8984.955075224614;8990.550472476383;8992.280617550581;8993.460392376479;8991.865122023175;8992.640294388217;9014.2788576914;9015.909840901606;9017.77455563613;8949.239314658262;8982.146696063863;8997.820043599157;9004.234640055538;9009.054049324792;8980.730578082603;9077.137671921157;9083.909930808279;9084.50035248061;9086.77283067922;43944;44474.77762611166;44543.331850022274;44623.55376446259;44679.776601116784;3478.7564870459078;3598.442241452579;3649.4708267301357;3816.9809150954065;3818.6563209311294;32270.515942260885;32467.837660811543;32768;32896.671033289604;33092.83453582741;4141.378793181017;4235.1529694061;4249.277622804131;4295.849645262432;4316.935245971313;360945;361406;361941;362682;363159;58545.70727146422;58873.705631472425;59192.704036480405;59196;59295.51761205918;53769.46230537723;59558;60858.39141608616;60995.390046099856;61015.389846100974;17075;17181.57046073833;17181.82818171802;17191.82808171927;17194.1402929853;13328;13421.530246441327;13448.394822233024;13451.32743362828;13451.529196478272;15220.923895380452;16515.17424128813;16902.74645880313;16930.830691693172;17368.5657858552;6487.481001519867;6604.306547812506;6683.331666833278;6693.631850248185;6720.193576770721;983.8130755156641;991.375433476905;1073.909981368911;1106.435717783936;1134.989859025468;32659;32773.836130819196;32910.8354458231;33284.833575831966;33501.32997340088;1095.9945200273949;1108.5288752280248;1129.2377645089532;1160.1936654025371;1199.1006744941294;16235.350585976428;17480.91259543694;17580.770095699605;17634.619326391028;17756.20097095634;511556;515882;516447;517072;517246;59457.40542594605;59645.701771490865;59754.70122649446;59796;59987.70006149941;1223.6634925395588;1324.966875828112;1334.1061488802331;1350.9459621615026;1378.77250253707;31519.52720709237;33481.330373392884;34254.31491370209;34508;34869.825650871586;55823.162652561055;57593.136102959324;57732.4226757727;63687.363126368145;63693.36306636874;466016;472125;473267;473489;473802;44142.77928610337;44908;46070.7696461529;47628.2855757157;48236.27645585389;47460.28809567928;48725.02549949052;51866;52201.21698174455;52213;42257;42895.57104429041;43135.35296970485;44236.77881610571;44860.77569612131;43667.70313640398;45247;45327;47064.7646761764;47911;44825.77587112044;45069.774651127846;45139.32291015703;45192.77403612961;45223.547764521936;36724.63275367212;38605.806970964964;39332.60667393404;39418.2116357677;39432.6056739429;28990.710092899644;29339.85330073336;29867.719998209002;30012.3997520044;30126.2468438282 10:44:22 INFO - PROCESS | 16820 | |2;jquery.html;374.69836781390967;391.2370876790258;398.6710963455149;405.189620758483;421.300640936046;16295.18524073796;19284.421467355984;19360.03199840006;19520.609587808252;20620.793792062075;19288.132034058464;19413.611727765438;19792.10935507902;19869.801301986976;19941.30498907591;5326.774841786391;5544.223808666779;5713.085906255;5743.368229494752;5757.856053598652;5622.100463925773;5664.575156863231;5677.120046392795;5711.314642242936;5711.8000869969555;5448.482394172547;5613;5636.267285252911;5649.983003059448;5656.943430565703;5609.49514543691;5638.351589567212;5642.069058605325;5649.548036157097;5650.6892120933335;4880.341153944208;5043.873903152414;5080.8221712240265;5096.413912400084;5103.106956282636;24390.878045609836;24398.756012439826;24422;24696.753032469805;24703.876480617662;2395.35325462126;2403.0868270057267;2523.470071285032;2983.6568794588466;3164.4145833020966;10390.168786497063;10822.783544329148;10843.945780271128;10882.61910833117;10935.125189984783;1721.8106008339073;1769.4603146040452;1803.2426380920044;1810.5111619862564;1840.5858681796622;80205.59897200477;80385;80977;92186;92396;11082.168837337233;11289.435528223556;11507.309561426342;11518.596849110241;11693.123015773852;15744.842551574568;15872.127033013065;15949.44176953801;16007.27967241477;16017.279222434785;8437.57812109392;8611.526366049928;8633.784155396039;8824.647014119491;8835.823283534293;7973.322267607203;8244.340452763765;8334.708285209988;8545.572721364031;8563.743087707378;4612.953870461252;4648.814047438064;4879.853604391874;4904.828331008398;5039.0929632666475;1978.6438441080456;2034.2269937423684;2049.1700861151257;2070.9378718638463;2074.1703318672653;463.24722326219893;875.3741075131185;919.9448033118034;1003.3428104591504;1011.8111219317273;11848.88151118495;12470.625881223577;12779.552715654907;12783.424745886457;13260.204387736767;478.54060102301486;901.7069452427987;1080.3679847289425;1096.413418820943;1108.2131686502512;10443.373397596017;11009.846848810184;11038.779224415628;11220.943895280634;11309.71725706864;498092;503414;508811;516322;520081;93449.5327523358;95856.52071739596;96455;97866;103627.41961286157;1013.7262939006426;1056.5456853553046;1061.1139698351808;1075.1398880895254;1086.6739978006567;17089.29107089281;17395.564131146475;17828.19773110239;17894.5526361842;17998.910005450412;40191.19617607573;41532;41798.58201417947;42252.78873605612;43429.13141737211;56636.716816417305;56886.14670779859;57074.714626426605;58531.4146858543;61779.6911015442;8570.957145214234;8787.736367908972;8805.339599530189;8936.28509719208;8972.68595599177;9584.520773961134;13133.60599182026;14196.148231106166;14282.571522853916;14311.355988980313;12051.51793928233;12163.695907602027;12184;12247.448864801283;12512.311822849466;11711.472983715583;11913.344766037604;12049.698757531132;12122.575709850113;12197.573084941985;12109.33398663082;12145.210561313486;12257.522339520881;12446.377681115728;12886.935565322488;8844.380893337404;8983.595738191667;9012.819743605221;9100.817983640423;9105.453672779655;12009.39953002329;12160.635180944586;12167.33079680626;12244.326562039174;12327.075469340014 10:44:22 INFO - PROCESS | 16820 | |3;mootools.html;1338.4177882621054;1477.0473044886053;1524.7026829768192;1636.2064398766604;1756.4203812741796;8567.828643427136;8972.102789721024;8999.280057595393;9215.861762073557;9292.163705266525;8995.235404990568;9146.451212927212;9152.405093668924;9153;9158.58786354614;2022.130483891926;2262.6492893601517;2332.3469428559947;2360.5160942006846;2369.9999999999914;2404.326788499214;2433.0146290752273;2433.111914151331;2436.8659723715186;2438.37821355555;2310.7111611048617;2318.40880575453;2323.4423738302853;2328.3247858121126;2364.645303204516;2347.624380099176;2370.95258094838;2372.3357459911253;2378.048780487801;2430.3195105370523;2403.327068420845;2409.4699166183414;2422.4307287787356;2422.6608274841537;2424.090965887792;9342.2526197904;9395;9404.764880877965;9409.905900940972;9415.05849415053;5275.973620131875;5294.788208471657;5328.866778330534;5426.972865135688;5438.265834112383;3283;3380.323935212967;3461.16931936336;3466.687998080185;3512.578490581121;1127.8590176227972;1159.287038471334;1162.482695200636;1163.4764356039816;1171.6719318590808;12940.676483087998;12970.286634235114;13037.608871733863;13057.869421305666;13098.148620339647;4375.453068366454;4600.95399046012;4733.597644200213;4757.119932812441;4760.33355330254;10056;10081;10087.596496140219;10088;10095.697129086138;5406.783728650888;5419.3225846769155;5419.593530485229;5430.13117901134;5434.239206511092;4242.5121111072;4811.446683631427;4881.4630390657;5059.671121377099;5061.620378471629;3661.707063434919;3762.247550489913;3810.2760475510117;3836.616338366197;3850.1711686948474;1185.4013723069795;1187.3825610682397;1192.2488832035936;1195.9581414650445;1213.5873802907054;961.9422834629945;980.26969907418;992.151710287708;993.0962823830424;998.8651532043226;6106.786262480792;6138.78514252008;6184.165137706352;6186.752529898844;6296.905546416808;485.7158562241058;486.9561739443467;496.45390070921695;498.5388515623034;500.65704992080543;4227.492700875914;4287.249731297011;4459.843905463358;4574.062317224961;4592.448906131285;10877.728056798485;11235.606753763743;11240.831387529193;11299.887001130048;11310.773784524428;10904.236703430843;11180.944095279472;11199.832002519968;11200.83198752003;11382;8118.2693557580105;8289.502629842229;8295.917040829636;8305.46014509055;8305.667773289122;6128.66292353907;6280.780172693934;6336.493080553452;6356.491480681442;6565.573237739532;10628.8937110631;10650;10776.191785615953;10834.404107773833;10843.078338341014;10190.592376305161;11072.169587280825;11076.667699969013;11088.722781930517;11095.5561777528;6968.965155174192;7861.803454913673;8139.959300203461;8181.713640022571;8286.627101780556;9022.864657030019;9205.217556507503;9206;9206.539673016456;9207.40151890125;7956.562389068657;8093.433459657767;8123.3501319893085;8281.54451505173;8441.83116337682;7907.604619768874;7985.8402831944195;8071.313938315309;8104.594770261581;8195.549244791593;6931.4108300795;7113.324234197743;7120.537165084253;7125.893111603434;7263.636818159177;5166.328377311076;5284.471552844685;5367.409584945731;5501.752421140978;5526.226328313959;5340.359156901041;5369;5375.19372094189;5410.053240682786;5480.095784195722 10:44:22 INFO - PROCESS | 16820 | |4;prototype.html;332.1629493675937;333.54970789434265;340.984655690494;349.44438343034585;349.5682831702845;5233.84298471046;5256.526912577867;5279;5312.724946012958;5327.121025030875;5236;5255.789768409269;5268.604854635908;5270.604704647148;5280.627036970386;4074.5110586729634;4136.482939632546;4142.958570414287;4188.706790524665;4190.455240818697;4136.544980052192;4177.979110104445;4183.4979802423595;4183.644390226835;4189.560096189902;4134.689898257628;4145.378193271018;4147.4815648044;4148.336266197409;4163.458750362449;3909.7263191576603;3984.824476779328;4104.363823607346;4115.34154535273;4157.708960372775;3663.6519530644546;3670.596234414212;3673.493867514333;3675.467057276707;3691;5113.718745468997;5147.253648220987;5160.638755287131;5162.97418512905;5187.714675692835;4459.175052615245;4496.235639941186;4715.127701375257;4727.409073865767;4741.762911854428;5443.89697872213;5446.101393270105;5456.590755693339;5460.535854452376;5487.75305111271;4042.8382864685673;4043.160362035296;4048.3725022621247;4048.4534587830562;4055.3511438169753;5376.494581517181;5684.687342196177;5710.229119068914;5715.513966368732;5718.055201571194;4031.1937612477036;4044;4045.4943132108488;4045.858394956221;4050.736702114412;4083.4487344208746;4089.407035979766;4090.570490098556;4104.856330028435;4105.281575724296;4436;4641.725554478348;4661.976690116596;4663.183942809995;4668.626509879202;5463.3717122530625;5497.5601951843755;5508.807191748269;5512.80705175317;5516;1396.9522857856607;1408.9929550352183;1409.5418988828671;1414.6251243420502;1415.4055296775173;849.46908182386;888.2272422992038;900.7703035725848;904.4754042655244;918.35155895167;4712.764361781898;4720.268358404453;4726.692764970266;4726.740029298353;4743.335932969387;4953.2168419369;4961.759560109973;4963.284879364618;4976.576990955809;4988.5260900214425;4704.9059018819435;4726.361941137902;4733.621310295168;4748.240281555004;4996.225585034325;2955.7635389168813;2987.193457766434;2990.7308342249303;2999.9350230668233;3010.247438140465;5791.305043394736;5794.478496935296;5821.767129314865;5835.299764028344;5843.211166492554;5543.223948647193;5752.490980501891;5754.568407369464;5755.395683453177;5779.768809247667;5815.185873977562;5826.65040097587;5845.444682755132;5857.179994800731;5871.530277577869;5420.83737487876;5639.943600563942;5643.661380317194;5644.322681278273;5650.208970744017;5607.775688972395;5612.522935550361;5613.522850557748;5634.4365563443325;5638.295213098363;5841.795537156179;5861.882762344814;5871.770526210653;5883.646981181143;5884.6469211847425;3927.803609819555;4048.4534587831445;4059.5737447568763;4079.4294196733877;4093.3450647896193;5598.608097433304;5601.859953501195;5615.578831587729;5616.3260408749575;5642.210090587403;5487.368952570506;5498.075673514398;5525.143602741581;5540.806071787468;5553.361363443094;5253.264542964064;5453.345598528202;5481.342238931354;5506.421825708403;5529.474699903502;5477.780888764564;5491.615586908878;5507.862303442446;5508.953298873202;5514.172874068921;5259.89480210386;5260.395054568774;5488.17677348401;5541.916871246854;5549.417311182429;5391.436483419921;5415.40430552631;5415.891682166413;5415.918761218506;5429 10:44:22 INFO - PROCESS | 16820 | |5;yui.html;703.7888633409978;725.9128904531453;736.7973807203015;737.365865355794;745.3664385272516;13874.861251387483;14724.484643037496;14750.926245368759;14797.556073317817;14850.1832399218;15410.845891541081;15537.378504859793;15560.766588501181;15566.844331556682;15578.53264402067;660.616842231507;789.6762327445748;793.5833687314163;811.679386642276;813.0649752784316;796.1202870827739;797.2585495489193;798.1858504325585;798.3014861995754;798.480987358219;867.089555966236;867.444835305405;868.1405408645427;868.2229404682805;869.009329364525;702.732961474639;840.3319361107934;855.7304449098514;859.4113032572708;865.35098675993;624.4473640827855;624.6938999890067;624.9031400132986;625.0780099353462;625.2559454249429;12335.629931102081;12420.503179872794;12426.254424734454;12431.12982091254;12437.067219958539;12833.486660533568;13084.084114112016;13379.86620133796;13461.528846490424;13796.793048104286;13175.86824131756;13201.339933003312;13286.26925519096;13451.125676830974;13617.59147225575;4474.812433011793;4521.706089104197;4618.191816432144;4647.837325693585;4679.953200467986;85223.57388213082;85495.57252213762;86156.13843861544;87409;87835.56082219612;11610;11648.592299269654;11791;11819;11918.821217681741;22181.556368872854;22260.22089226869;22280.554388912133;22317.330480085624;22325.665115023286;8713.695020674373;8773.254273386707;8821.382503224713;8985.640574377083;9123.36136470454;4735.976320118378;4790.688605240648;4896.926546101811;5013.824516141917;5097.286379906816;3272.672732726708;3388.3900897838625;3394.1514621344663;3397.779144355609;3407.557017587698;549.409384911214;607.4047433515092;620.1968450856152;631.43486579512;634.1882390540115;666.1839246922578;696.5507247825125;697.6267696782197;703.7820852765769;716.802879208222;9309.72070837876;10210.336328138646;10274.332168409179;10326.225533084898;10447;683.3235097253684;694.989575156373;710.1726488640676;713.9500234983501;723.6815801047522;4905.950940490621;4969.254611808257;4976.0794253063295;4989.725565093882;5004.274380214849;113927;124638;126902;127363;129099.35450322877;100239.99999999854;101993;102210.48894755478;103257;106499;92281.5385923066;93487.53256233675;94086;94873;95570.5221473902;9131.680391186277;9303.488308143116;9402.576884040098;9486.573104210465;9680.128788409076;55083.44916550783;56849.715751422635;57208.42791572031;57819.71090144522;57907;65968.34031659622;75900;83312;84056;84501;11792.823107653221;11887.643370698894;13071.803922941353;13194.934025329812;13780;13320.600381988555;13505.662358441117;13539.79690304666;14087.577372678836;14679;12719.55481558141;12737.55418560346;12785.16896401731;13035.348232588522;13583.117097388638;14558.41766329335;14705;14833.92583037121;14961.625959351104;15267.77098343548;14086.506972255922;14225.00424970242;14307.21310327942;14583.489577865148;15501.844981550492;11694.70763230926;11694.771924588438;11718.824217636913;11812;12865.549705760253;11877.524899003944;12268.263904165779;12362.690932726753;12652.17760845542;12657 10:44:22 INFO - PROCESS | 16820 | __end_tp_report 10:44:22 INFO - PROCESS | 16820 | __start_cc_report 10:44:22 INFO - PROCESS | 16820 | _x_x_mozilla_cycle_collect,1057 10:44:22 INFO - PROCESS | 16820 | __end_cc_report 10:44:22 INFO - PROCESS | 16820 | __startTimestamp1471628661993__endTimestamp 10:44:22 INFO - PROCESS | 16820 | 10:44:22 INFO - PROCESS | 16820 | ------- Summary: start ------- 10:44:22 INFO - PROCESS | 16820 | Number of tests: 6 10:44:22 INFO - PROCESS | 16820 | 10:44:22 INFO - PROCESS | 16820 | [#0] dojo.html Cycles:165 Average:5294.89 Median:5498.89 stddev:617.77 (11.2%) stddev-sans-first:593.96 10:44:22 INFO - PROCESS | 16820 | Values: 3046.9 3062.7 3073.3 3082.7 3090.3 5164.1 5233.5 5264.3 5342.2 5433.3 5230.8 5286.9 5326.3 5399.1 5418.1 4720.0 4783.9 4797.2 4804.6 4957.0 4858.4 4891.7 4902.6 4904.9 4925.8 4693.0 4775.1 4810.6 4822.6 4832.3 4811.4 4814.8 4834.5 4877.1 4921.6 4839.2 4848.1 4849.6 4859.8 4900.7 5122.2 5200.3 5261.9 5280.7 5344.0 5655.8 5657.7 5658.1 5705.8 5716.5 5731.8 5741.0 5749.1 5749.2 5754.3 5178.4 5269.7 5275.7 5276.8 5398.4 5829.4 5891.6 5892.2 5894.6 5899.1 5594.7 5601.2 5640.5 5643.2 5648.3 5458.2 5622.7 5677.0 5679.7 5770.2 5699.6 5701.3 5701.5 5706.5 5709.0 5601.6 5650.4 5655.9 5709.4 5717.4 4210.6 4267.6 4286.4 4298.2 4301.0 3820.4 3836.6 3849.8 3863.6 3870.9 5232.6 5427.1 5428.1 5436.3 5436.9 5632.9 5683.8 5702.5 5743.4 5783.8 5380.6 5384.7 5392.2 5393.8 5399.7 4801.9 4955.1 4961.3 4964.4 5009.0 5852.5 5911.7 5920.3 5960.6 6018.0 5739.2 5764.2 5767.0 5769.5 5770.9 5647.6 5962.9 5967.7 5979.6 5985.3 5590.7 5597.8 5609.4 5664.0 5692.5 5666.9 5761.7 5772.4 5776.6 5783.0 5406.4 5620.9 5665.7 5670.8 5673.3 5412.2 5487.9 5505.7 5560.1 5579.7 5619.3 5624.7 5630.9 5632.5 5634.9 5351.0 5661.2 5703.0 5707.3 5715.8 5279.3 5434.1 5498.9 5502.3 5579.3 10:44:22 INFO - PROCESS | 16820 | 10:44:22 INFO - PROCESS | 16820 | [#1] ext.html Cycles:180 Average:62244.81 Median:44843.28 stddev:120233.69 (268.1%) stddev-sans-first:120481.84 10:44:22 INFO - PROCESS | 16820 | Values: 584.3 615.4 622.2 632.7 644.0 32562.3 33245.8 36027.8 36136.8 36143.1 44354.3 45618.1 45715.3 50232.2 50325.7 8918.9 9044.9 9047.3 9058.6 9060.8 8902.2 8985.0 8990.6 8992.3 8993.5 8991.9 8992.6 9014.3 9015.9 9017.8 8949.2 8982.1 8997.8 9004.2 9009.1 8980.7 9077.1 9083.9 9084.5 9086.8 43944.0 44474.8 44543.3 44623.6 44679.8 3478.8 3598.4 3649.5 3817.0 3818.7 32270.5 32467.8 32768.0 32896.7 33092.8 4141.4 4235.2 4249.3 4295.8 4316.9 360945.0 361406.0 361941.0 362682.0 363159.0 58545.7 58873.7 59192.7 59196.0 59295.5 53769.5 59558.0 60858.4 60995.4 61015.4 17075.0 17181.6 17181.8 17191.8 17194.1 13328.0 13421.5 13448.4 13451.3 13451.5 15220.9 16515.2 16902.7 16930.8 17368.6 6487.5 6604.3 6683.3 6693.6 6720.2 983.8 991.4 1073.9 1106.4 1135.0 32659.0 32773.8 32910.8 33284.8 33501.3 1096.0 1108.5 1129.2 1160.2 1199.1 16235.4 17480.9 17580.8 17634.6 17756.2 511556.0 515882.0 516447.0 517072.0 517246.0 59457.4 59645.7 59754.7 59796.0 59987.7 1223.7 1325.0 1334.1 1350.9 1378.8 31519.5 33481.3 34254.3 34508.0 34869.8 55823.2 57593.1 57732.4 63687.4 63693.4 466016.0 472125.0 473267.0 473489.0 473802.0 44142.8 44908.0 46070.8 47628.3 48236.3 47460.3 48725.0 51866.0 52201.2 52213.0 42257.0 42895.6 43135.4 44236.8 44860.8 43667.7 45247.0 45327.0 47064.8 47911.0 44825.8 45069.8 45139.3 45192.8 45223.5 36724.6 38605.8 39332.6 39418.2 39432.6 28990.7 29339.9 29867.7 30012.4 30126.2 10:44:22 INFO - PROCESS | 16820 | 10:44:22 INFO - PROCESS | 16820 | [#2] jquery.html Cycles:180 Average:29819.48 Median:24700.31 stddev:84089.32 (340.4%) stddev-sans-first:84296.15 10:44:22 INFO - PROCESS | 16820 | Values: 374.7 391.2 398.7 405.2 421.3 16295.2 19284.4 19360.0 19520.6 20620.8 19288.1 19413.6 19792.1 19869.8 19941.3 5326.8 5544.2 5713.1 5743.4 5757.9 5622.1 5664.6 5677.1 5711.3 5711.8 5448.5 5613.0 5636.3 5650.0 5656.9 5609.5 5638.4 5642.1 5649.5 5650.7 4880.3 5043.9 5080.8 5096.4 5103.1 24390.9 24398.8 24422.0 24696.8 24703.9 2395.4 2403.1 2523.5 2983.7 3164.4 10390.2 10822.8 10843.9 10882.6 10935.1 1721.8 1769.5 1803.2 1810.5 1840.6 80205.6 80385.0 80977.0 92186.0 92396.0 11082.2 11289.4 11507.3 11518.6 11693.1 15744.8 15872.1 15949.4 16007.3 16017.3 8437.6 8611.5 8633.8 8824.6 8835.8 7973.3 8244.3 8334.7 8545.6 8563.7 4613.0 4648.8 4879.9 4904.8 5039.1 1978.6 2034.2 2049.2 2070.9 2074.2 463.2 875.4 919.9 1003.3 1011.8 11848.9 12470.6 12779.6 12783.4 13260.2 478.5 901.7 1080.4 1096.4 1108.2 10443.4 11009.8 11038.8 11220.9 11309.7 498092.0 503414.0 508811.0 516322.0 520081.0 93449.5 95856.5 96455.0 97866.0 103627.4 1013.7 1056.5 1061.1 1075.1 1086.7 17089.3 17395.6 17828.2 17894.6 17998.9 40191.2 41532.0 41798.6 42252.8 43429.1 56636.7 56886.1 57074.7 58531.4 61779.7 8571.0 8787.7 8805.3 8936.3 8972.7 9584.5 13133.6 14196.1 14282.6 14311.4 12051.5 12163.7 12184.0 12247.4 12512.3 11711.5 11913.3 12049.7 12122.6 12197.6 12109.3 12145.2 12257.5 12446.4 12886.9 8844.4 8983.6 9012.8 9100.8 9105.5 12009.4 12160.6 12167.3 12244.3 12327.1 10:44:22 INFO - PROCESS | 16820 | 10:44:22 INFO - PROCESS | 16820 | [#3] mootools.html Cycles:180 Average:5990.41 Median:4785.89 stddev:3478.46 (72.7%) stddev-sans-first:3470.65 10:44:22 INFO - PROCESS | 16820 | Values: 1338.4 1477.0 1524.7 1636.2 1756.4 8567.8 8972.1 8999.3 9215.9 9292.2 8995.2 9146.5 9152.4 9153.0 9158.6 2022.1 2262.6 2332.3 2360.5 2370.0 2404.3 2433.0 2433.1 2436.9 2438.4 2310.7 2318.4 2323.4 2328.3 2364.6 2347.6 2371.0 2372.3 2378.0 2430.3 2403.3 2409.5 2422.4 2422.7 2424.1 9342.3 9395.0 9404.8 9409.9 9415.1 5276.0 5294.8 5328.9 5427.0 5438.3 3283.0 3380.3 3461.2 3466.7 3512.6 1127.9 1159.3 1162.5 1163.5 1171.7 12940.7 12970.3 13037.6 13057.9 13098.1 4375.5 4601.0 4733.6 4757.1 4760.3 10056.0 10081.0 10087.6 10088.0 10095.7 5406.8 5419.3 5419.6 5430.1 5434.2 4242.5 4811.4 4881.5 5059.7 5061.6 3661.7 3762.2 3810.3 3836.6 3850.2 1185.4 1187.4 1192.2 1196.0 1213.6 961.9 980.3 992.2 993.1 998.9 6106.8 6138.8 6184.2 6186.8 6296.9 485.7 487.0 496.5 498.5 500.7 4227.5 4287.2 4459.8 4574.1 4592.4 10877.7 11235.6 11240.8 11299.9 11310.8 10904.2 11180.9 11199.8 11200.8 11382.0 8118.3 8289.5 8295.9 8305.5 8305.7 6128.7 6280.8 6336.5 6356.5 6565.6 10628.9 10650.0 10776.2 10834.4 10843.1 10190.6 11072.2 11076.7 11088.7 11095.6 6969.0 7861.8 8140.0 8181.7 8286.6 9022.9 9205.2 9206.0 9206.5 9207.4 7956.6 8093.4 8123.4 8281.5 8441.8 7907.6 7985.8 8071.3 8104.6 8195.5 6931.4 7113.3 7120.5 7125.9 7263.6 5166.3 5284.5 5367.4 5501.8 5526.2 5340.4 5369.0 5375.2 5410.1 5480.1 10:44:22 INFO - PROCESS | 16820 | 10:44:22 INFO - PROCESS | 16820 | [#4] prototype.html Cycles:180 Average:4595.98 Median:5234.92 stddev:1341.19 (25.6%) stddev-sans-first:1306.22 10:44:22 INFO - PROCESS | 16820 | Values: 332.2 333.5 341.0 349.4 349.6 5233.8 5256.5 5279.0 5312.7 5327.1 5236.0 5255.8 5268.6 5270.6 5280.6 4074.5 4136.5 4143.0 4188.7 4190.5 4136.5 4178.0 4183.5 4183.6 4189.6 4134.7 4145.4 4147.5 4148.3 4163.5 3909.7 3984.8 4104.4 4115.3 4157.7 3663.7 3670.6 3673.5 3675.5 3691.0 5113.7 5147.3 5160.6 5163.0 5187.7 4459.2 4496.2 4715.1 4727.4 4741.8 5443.9 5446.1 5456.6 5460.5 5487.8 4042.8 4043.2 4048.4 4048.5 4055.4 5376.5 5684.7 5710.2 5715.5 5718.1 4031.2 4044.0 4045.5 4045.9 4050.7 4083.4 4089.4 4090.6 4104.9 4105.3 4436.0 4641.7 4662.0 4663.2 4668.6 5463.4 5497.6 5508.8 5512.8 5516.0 1397.0 1409.0 1409.5 1414.6 1415.4 849.5 888.2 900.8 904.5 918.4 4712.8 4720.3 4726.7 4726.7 4743.3 4953.2 4961.8 4963.3 4976.6 4988.5 4704.9 4726.4 4733.6 4748.2 4996.2 2955.8 2987.2 2990.7 2999.9 3010.2 5791.3 5794.5 5821.8 5835.3 5843.2 5543.2 5752.5 5754.6 5755.4 5779.8 5815.2 5826.7 5845.4 5857.2 5871.5 5420.8 5639.9 5643.7 5644.3 5650.2 5607.8 5612.5 5613.5 5634.4 5638.3 5841.8 5861.9 5871.8 5883.6 5884.6 3927.8 4048.5 4059.6 4079.4 4093.3 5598.6 5601.9 5615.6 5616.3 5642.2 5487.4 5498.1 5525.1 5540.8 5553.4 5253.3 5453.3 5481.3 5506.4 5529.5 5477.8 5491.6 5507.9 5509.0 5514.2 5259.9 5260.4 5488.2 5541.9 5549.4 5391.4 5415.4 5415.9 5415.9 5429.0 10:44:22 INFO - PROCESS | 16820 | 10:44:22 INFO - PROCESS | 16820 | [#5] yui.html Cycles:180 Average:22022.04 Median:4569.95 stddev:32427.92 (709.6%) stddev-sans-first:32479.38 10:44:22 INFO - PROCESS | 16820 | Values: 703.8 725.9 736.8 737.4 745.4 13874.9 14724.5 14750.9 14797.6 14850.2 15410.8 15537.4 15560.8 15566.8 15578.5 660.6 789.7 793.6 811.7 813.1 796.1 797.3 798.2 798.3 798.5 867.1 867.4 868.1 868.2 869.0 702.7 840.3 855.7 859.4 865.4 624.4 624.7 624.9 625.1 625.3 12335.6 12420.5 12426.3 12431.1 12437.1 12833.5 13084.1 13379.9 13461.5 13796.8 13175.9 13201.3 13286.3 13451.1 13617.6 4474.8 4521.7 4618.2 4647.8 4680.0 85223.6 85495.6 86156.1 87409.0 87835.6 11610.0 11648.6 11791.0 11819.0 11918.8 22181.6 22260.2 22280.6 22317.3 22325.7 8713.7 8773.3 8821.4 8985.6 9123.4 4736.0 4790.7 4896.9 5013.8 5097.3 3272.7 3388.4 3394.2 3397.8 3407.6 549.4 607.4 620.2 631.4 634.2 666.2 696.6 697.6 703.8 716.8 9309.7 10210.3 10274.3 10326.2 10447.0 683.3 695.0 710.2 714.0 723.7 4906.0 4969.3 4976.1 4989.7 5004.3 113927.0 124638.0 126902.0 127363.0 129099.4 100240.0 101993.0 102210.5 103257.0 106499.0 92281.5 93487.5 94086.0 94873.0 95570.5 9131.7 9303.5 9402.6 9486.6 9680.1 55083.4 56849.7 57208.4 57819.7 57907.0 65968.3 75900.0 83312.0 84056.0 84501.0 11792.8 11887.6 13071.8 13194.9 13780.0 13320.6 13505.7 13539.8 14087.6 14679.0 12719.6 12737.6 12785.2 13035.3 13583.1 14558.4 14705.0 14833.9 14961.6 15267.8 14086.5 14225.0 14307.2 14583.5 15501.8 11694.7 11694.8 11718.8 11812.0 12865.5 11877.5 12268.3 12362.7 12652.2 12657.0 10:44:22 INFO - PROCESS | 16820 | -------- Summary: end -------- 10:44:22 INFO - PROCESS | 16820 | 10:44:22 INFO - TEST-INFO | 16820: exit 0 10:44:22 INFO - TEST-OK | dromaeo_css | took 1114731ms 10:44:22 INFO - TEST-START | kraken 10:44:22 INFO - operating with platform_type : linux_ 10:44:22 INFO - Initialising browser for kraken test... 10:44:22 INFO - TEST-INFO | started process 17127 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIYpsrA/profile http://localhost:45721/getInfo.html) 10:44:26 INFO - PROCESS | 17127 | __metrics Screen width/height:1600/1200 10:44:26 INFO - PROCESS | 17127 | colorDepth:24 10:44:26 INFO - PROCESS | 17127 | Browser inner width/height: 1024/697 10:44:26 INFO - PROCESS | 17127 | __metrics 10:44:27 INFO - PROCESS | 17127 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:44:27 INFO - PROCESS | 17127 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:44:27 INFO - PROCESS | 17127 | console.error: PushService: 10:44:27 INFO - PROCESS | 17127 | stateChangeProcessEnqueue: Error transitioning state 10:44:27 INFO - PROCESS | 17127 | UnknownError 10:44:28 INFO - TEST-INFO | 17127: exit 0 10:44:28 INFO - Browser initialized. 10:44:28 INFO - Running cycle 1/1 for kraken test... 10:44:28 INFO - TEST-INFO | started process 17211 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIYpsrA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:44:39 INFO - PROCESS | 17211 | RSS: Main: 155295744 10:44:39 INFO - PROCESS | 17211 | 10:44:42 INFO - PROCESS | 17211 | Cycle 1(1): loaded http://localhost:45721/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45721/tests/kraken/driver.html?testName=audio-beat-detection) 10:44:43 INFO - PROCESS | 17211 | RSS: Main: 203051008 10:44:43 INFO - PROCESS | 17211 | 10:44:45 INFO - PROCESS | 17211 | Cycle 1(1): loaded http://localhost:45721/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45721/tests/kraken/driver.html?testName=audio-dft) 10:44:46 INFO - PROCESS | 17211 | RSS: Main: 194633728 10:44:46 INFO - PROCESS | 17211 | 10:44:49 INFO - PROCESS | 17211 | Cycle 1(1): loaded http://localhost:45721/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45721/tests/kraken/driver.html?testName=audio-fft) 10:44:50 INFO - PROCESS | 17211 | RSS: Main: 275263488 10:44:50 INFO - PROCESS | 17211 | 10:44:52 INFO - PROCESS | 17211 | Cycle 1(1): loaded http://localhost:45721/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45721/tests/kraken/driver.html?testName=audio-oscillator) 10:44:52 INFO - PROCESS | 17211 | RSS: Main: 185888768 10:44:52 INFO - PROCESS | 17211 | 10:44:55 INFO - PROCESS | 17211 | Cycle 1(1): loaded http://localhost:45721/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45721/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:44:55 INFO - PROCESS | 17211 | RSS: Main: 190816256 10:44:55 INFO - PROCESS | 17211 | 10:44:58 INFO - PROCESS | 17211 | Cycle 1(1): loaded http://localhost:45721/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45721/tests/kraken/driver.html?testName=imaging-darkroom) 10:44:59 INFO - PROCESS | 17211 | RSS: Main: 225509376 10:44:59 INFO - PROCESS | 17211 | 10:45:02 INFO - PROCESS | 17211 | Cycle 1(1): loaded http://localhost:45721/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45721/tests/kraken/driver.html?testName=imaging-desaturate) 10:45:03 INFO - PROCESS | 17211 | RSS: Main: 226521088 10:45:03 INFO - PROCESS | 17211 | 10:45:05 INFO - PROCESS | 17211 | Cycle 1(1): loaded http://localhost:45721/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45721/tests/kraken/driver.html?testName=json-parse-financial) 10:45:06 INFO - PROCESS | 17211 | RSS: Main: 226263040 10:45:06 INFO - PROCESS | 17211 | 10:45:08 INFO - PROCESS | 17211 | Cycle 1(1): loaded http://localhost:45721/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45721/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:45:08 INFO - PROCESS | 17211 | RSS: Main: 188510208 10:45:08 INFO - PROCESS | 17211 | 10:45:10 INFO - PROCESS | 17211 | Cycle 1(1): loaded http://localhost:45721/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45721/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:45:11 INFO - PROCESS | 17211 | RSS: Main: 195604480 10:45:11 INFO - PROCESS | 17211 | 10:45:13 INFO - PROCESS | 17211 | Cycle 1(1): loaded http://localhost:45721/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45721/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:45:13 INFO - PROCESS | 17211 | RSS: Main: 192905216 10:45:13 INFO - PROCESS | 17211 | 10:45:16 INFO - PROCESS | 17211 | Cycle 1(1): loaded http://localhost:45721/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45721/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:45:16 INFO - PROCESS | 17211 | RSS: Main: 188166144 10:45:16 INFO - PROCESS | 17211 | 10:45:19 INFO - PROCESS | 17211 | Cycle 1(1): loaded http://localhost:45721/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45721/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:45:20 INFO - PROCESS | 17211 | RSS: Main: 192098304 10:45:20 INFO - PROCESS | 17211 | 10:45:21 INFO - PROCESS | 17211 | Cycle 1(1): loaded http://localhost:45721/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45721/tests/kraken/driver.html?testName=ai-astar) 10:45:22 INFO - PROCESS | 17211 | RSS: Main: 241631232 10:45:22 INFO - PROCESS | 17211 | 10:45:22 INFO - PROCESS | 17211 | __start_tp_report 10:45:22 INFO - PROCESS | 17211 | _x_x_mozilla_page_load 10:45:22 INFO - PROCESS | 17211 | _x_x_mozilla_page_load_details 10:45:22 INFO - PROCESS | 17211 | |i|pagename|runs| 10:45:22 INFO - PROCESS | 17211 | |0;ai-astar;105;113;115;114;109;114;113;112;112;112 10:45:22 INFO - PROCESS | 17211 | |1;audio-beat-detection;148;116;126;175;119;121;143;125;159;117 10:45:22 INFO - PROCESS | 17211 | |2;audio-dft;160;161;159;161;161;160;162;162;161;160 10:45:22 INFO - PROCESS | 17211 | |3;audio-fft;75;74;75;117;75;76;75;75;101;74 10:45:22 INFO - PROCESS | 17211 | |4;audio-oscillator;84;85;137;85;84;85;119;84;85;124 10:45:22 INFO - PROCESS | 17211 | |5;imaging-gaussian-blur;117;117;116;117;117;118;117;117;117;117 10:45:22 INFO - PROCESS | 17211 | |6;imaging-darkroom;147;147;145;147;145;146;146;146;146;145 10:45:22 INFO - PROCESS | 17211 | |7;imaging-desaturate;95;96;95;95;95;95;96;95;95;95 10:45:22 INFO - PROCESS | 17211 | |8;json-parse-financial;51;52;52;51;49;53;51;50;53;50 10:45:22 INFO - PROCESS | 17211 | |9;json-stringify-tinderbox;62;63;62;63;62;61;62;61;62;63 10:45:22 INFO - PROCESS | 17211 | |10;stanford-crypto-aes;80;78;78;78;79;78;78;78;78;78 10:45:22 INFO - PROCESS | 17211 | |11;stanford-crypto-ccm;124;123;120;129;121;119;122;121;140;125 10:45:22 INFO - PROCESS | 17211 | |12;stanford-crypto-pbkdf2;162;161;159;160;162;162;164;166;161;162 10:45:22 INFO - PROCESS | 17211 | |13;stanford-crypto-sha256-iterative;65;64;64;63;65;64;63;65;62;67 10:45:22 INFO - PROCESS | 17211 | __end_tp_report 10:45:22 INFO - PROCESS | 17211 | __start_cc_report 10:45:22 INFO - PROCESS | 17211 | _x_x_mozilla_cycle_collect,1629 10:45:22 INFO - PROCESS | 17211 | __end_cc_report 10:45:22 INFO - PROCESS | 17211 | __startTimestamp1471628722044__endTimestamp 10:45:22 INFO - PROCESS | 17211 | 10:45:22 INFO - PROCESS | 17211 | ------- Summary: start ------- 10:45:22 INFO - PROCESS | 17211 | Number of tests: 14 10:45:22 INFO - PROCESS | 17211 | 10:45:22 INFO - PROCESS | 17211 | [#0] ai-astar Cycles:10 Average:111.90 Median:113.00 stddev:2.92 (2.6%) stddev-sans-first:1.73 10:45:22 INFO - PROCESS | 17211 | Values: 105.0 113.0 115.0 114.0 109.0 114.0 113.0 112.0 112.0 112.0 10:45:22 INFO - PROCESS | 17211 | 10:45:22 INFO - PROCESS | 17211 | [#1] audio-beat-detection Cycles:10 Average:134.90 Median:134.50 stddev:20.35 (15.1%) stddev-sans-first:21.02 10:45:22 INFO - PROCESS | 17211 | Values: 148.0 116.0 126.0 175.0 119.0 121.0 143.0 125.0 159.0 117.0 10:45:22 INFO - PROCESS | 17211 | 10:45:22 INFO - PROCESS | 17211 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.95 (0.6%) stddev-sans-first:0.97 10:45:22 INFO - PROCESS | 17211 | Values: 160.0 161.0 159.0 161.0 161.0 160.0 162.0 162.0 161.0 160.0 10:45:22 INFO - PROCESS | 17211 | 10:45:22 INFO - PROCESS | 17211 | [#3] audio-fft Cycles:10 Average:81.70 Median:75.00 stddev:14.89 (19.8%) stddev-sans-first:15.59 10:45:22 INFO - PROCESS | 17211 | Values: 75.0 74.0 75.0 117.0 75.0 76.0 75.0 75.0 101.0 74.0 10:45:22 INFO - PROCESS | 17211 | 10:45:22 INFO - PROCESS | 17211 | [#4] audio-oscillator Cycles:10 Average:97.20 Median:84.50 stddev:20.80 (24.6%) stddev-sans-first:21.51 10:45:22 INFO - PROCESS | 17211 | Values: 84.0 85.0 137.0 85.0 84.0 85.0 119.0 84.0 85.0 124.0 10:45:22 INFO - PROCESS | 17211 | 10:45:22 INFO - PROCESS | 17211 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 10:45:22 INFO - PROCESS | 17211 | Values: 117.0 117.0 116.0 117.0 117.0 118.0 117.0 117.0 117.0 117.0 10:45:22 INFO - PROCESS | 17211 | 10:45:22 INFO - PROCESS | 17211 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.82 (0.6%) stddev-sans-first:0.78 10:45:22 INFO - PROCESS | 17211 | Values: 147.0 147.0 145.0 147.0 145.0 146.0 146.0 146.0 146.0 145.0 10:45:22 INFO - PROCESS | 17211 | 10:45:22 INFO - PROCESS | 17211 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 10:45:22 INFO - PROCESS | 17211 | Values: 95.0 96.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 10:45:22 INFO - PROCESS | 17211 | 10:45:22 INFO - PROCESS | 17211 | [#8] json-parse-financial Cycles:10 Average:51.20 Median:51.50 stddev:1.32 (2.6%) stddev-sans-first:1.39 10:45:22 INFO - PROCESS | 17211 | Values: 51.0 52.0 52.0 51.0 49.0 53.0 51.0 50.0 53.0 50.0 10:45:22 INFO - PROCESS | 17211 | 10:45:22 INFO - PROCESS | 17211 | [#9] json-stringify-tinderbox Cycles:10 Average:62.10 Median:62.00 stddev:0.74 (1.2%) stddev-sans-first:0.78 10:45:22 INFO - PROCESS | 17211 | Values: 62.0 63.0 62.0 63.0 62.0 61.0 62.0 61.0 62.0 63.0 10:45:22 INFO - PROCESS | 17211 | 10:45:22 INFO - PROCESS | 17211 | [#10] stanford-crypto-aes Cycles:10 Average:78.30 Median:78.00 stddev:0.67 (0.9%) stddev-sans-first:0.33 10:45:22 INFO - PROCESS | 17211 | Values: 80.0 78.0 78.0 78.0 79.0 78.0 78.0 78.0 78.0 78.0 10:45:22 INFO - PROCESS | 17211 | 10:45:22 INFO - PROCESS | 17211 | [#11] stanford-crypto-ccm Cycles:10 Average:124.40 Median:123.50 stddev:6.19 (5.0%) stddev-sans-first:6.56 10:45:22 INFO - PROCESS | 17211 | Values: 124.0 123.0 120.0 129.0 121.0 119.0 122.0 121.0 140.0 125.0 10:45:22 INFO - PROCESS | 17211 | 10:45:22 INFO - PROCESS | 17211 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.90 Median:162.00 stddev:1.97 (1.2%) stddev-sans-first:2.09 10:45:22 INFO - PROCESS | 17211 | Values: 162.0 161.0 159.0 160.0 162.0 162.0 164.0 166.0 161.0 162.0 10:45:22 INFO - PROCESS | 17211 | 10:45:22 INFO - PROCESS | 17211 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.20 Median:64.50 stddev:1.40 (2.2%) stddev-sans-first:1.45 10:45:22 INFO - PROCESS | 17211 | Values: 65.0 64.0 64.0 63.0 65.0 64.0 63.0 65.0 62.0 67.0 10:45:22 INFO - PROCESS | 17211 | -------- Summary: end -------- 10:45:22 INFO - PROCESS | 17211 | 10:45:23 INFO - TEST-INFO | 17211: exit 0 10:45:23 INFO - TEST-OK | kraken | took 60304ms 10:45:23 INFO - SUITE-END | took 1175s 10:45:23 INFO - Completed test suite (00:19:35) 10:45:23 INFO - javascript benchmark 10:45:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3046.9085927422175, 3062.663107058225, 3073.3392320651055, 3082.722554970054, 3090.3046814466747, 5164.147915593932, 5233.450487698794, 5264.341957255343, 5342.171963345682, 5433.320834895638, 5230.764615592298, 5286.867828304295, 5326.334208223972, 5399.055165346068, 5418.078926582481, 4719.952800471994, 4783.928241076386, 4797.2324428091515, 4804.567588916997, 4957.0, 4858.392700912386, 4891.657583969123, 4902.558769710726, 4904.926426103593, 4925.82759603414, 4693.037927224927, 4775.068861571995, 4810.615150787964, 4822.590079843218, 4832.299316599091, 4811.374521312242, 4814.807407703687, 4834.468208497063, 4877.122118018755, 4921.630877684188, 4839.177339852234, 4848.054629347279, 4849.636277279218, 4859.757012149378, 4900.6569540132205, 5122.154844450662, 5200.323957885488, 5261.947380526184, 5280.683159010472, 5343.973280133613, 5655.830325090254, 5657.688827114507, 5658.094704847246, 5705.77176912923, 5716.485516303553, 5731.799387021434, 5741.024025915604, 5749.0514065179195, 5749.166370876242, 5754.251947246873, 5178.352705911761, 5269.657472264291, 5275.683458992473, 5276.762545685453, 5398.406175320712, 5829.387914269026, 5891.646501209942, 5892.175095486636, 5894.587378883437, 5899.144624029492, 5594.748236329375, 5601.187827764951, 5640.520555752807, 5643.209950606918, 5648.322201335866, 5458.181272809111, 5622.662640241599, 5677.0, 5679.716014199356, 5770.19217309577, 5699.5725320600295, 5701.258836351248, 5701.543876489871, 5706.457886500776, 5709.000924838137, 5601.551875849922, 5650.434956504317, 5655.858603534863, 5709.42905709434, 5717.371089180187, 4210.621044105984, 4267.637250833652, 4286.421333120051, 4298.183345164409, 4300.956990430056, 3820.3505404081375, 3836.558795738526, 3849.7690138591774, 3863.632954869283, 3870.8838734838, 5232.581393488512, 5427.050266203478, 5428.104362780176, 5436.320459942507, 5436.864078398072, 5632.887342253132, 5683.8010669626365, 5702.48677619008, 5743.425657434307, 5783.826485205451, 5380.569554435714, 5384.6769193848495, 5392.164214546751, 5393.7572809223675, 5399.70301633414, 4801.855944321676, 4955.132851750929, 4961.2558116282125, 4964.379452568429, 5009.0, 5852.531797456193, 5911.704414779329, 5920.2895652521975, 5960.642361458326, 6018.0, 5739.167820666064, 5764.221830052889, 5767.0196066669605, 5769.480746732815, 5770.913436298543, 5647.604667673223, 5962.9105563415715, 5967.731452084579, 5979.611325264018, 5985.311689155803, 5590.664560126405, 5597.776088956559, 5609.411011843697, 5663.9716801415725, 5692.544596432358, 5666.914996275142, 5761.654300741969, 5772.422757724194, 5776.624519406225, 5783.045797443543, 5406.432324606017, 5620.9156862647915, 5665.688387138747, 5670.8015219467125, 5673.290838645169, 5412.188171774265, 5487.890242195213, 5505.6696598204235, 5560.110382338807, 5579.6652200868075, 5619.325680918152, 5624.746886390204, 5630.915536267041, 5632.493075623213, 5634.887302254014, 5350.9732451337495, 5661.235733175969, 5702.971485142548, 5707.315122185364, 5715.771369145354, 5279.313689220377, 5434.130539113723, 5498.890022199454, 5502.257195278587, 5579.302587176603], "value": 5252.240555114695, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [584.2959234122883, 615.4491729901738, 622.2004723929751, 632.7342516143219, 643.964581947993, 32562.348753024955, 33245.833770831145, 36027.81986090066, 36136.81931590345, 36143.09642258941, 44354.33468497967, 45618.08763824721, 45715.31427028597, 50232.24651630219, 50325.74837125818, 8918.866217006751, 9044.8643270351, 9047.321450891177, 9058.592363343665, 9060.818783624323, 8902.243309318716, 8984.955075224614, 8990.550472476383, 8992.280617550581, 8993.460392376479, 8991.865122023175, 8992.640294388217, 9014.2788576914, 9015.909840901606, 9017.77455563613, 8949.239314658262, 8982.146696063863, 8997.820043599157, 9004.234640055538, 9009.054049324792, 8980.730578082603, 9077.137671921157, 9083.909930808279, 9084.50035248061, 9086.77283067922, 43944.0, 44474.77762611166, 44543.331850022274, 44623.55376446259, 44679.776601116784, 3478.7564870459078, 3598.442241452579, 3649.4708267301357, 3816.9809150954065, 3818.6563209311294, 32270.515942260885, 32467.837660811543, 32768.0, 32896.671033289604, 33092.83453582741, 4141.378793181017, 4235.1529694061, 4249.277622804131, 4295.849645262432, 4316.935245971313, 360945.0, 361406.0, 361941.0, 362682.0, 363159.0, 58545.70727146422, 58873.705631472425, 59192.704036480405, 59196.0, 59295.51761205918, 53769.46230537723, 59558.0, 60858.39141608616, 60995.390046099856, 61015.389846100974, 17075.0, 17181.57046073833, 17181.82818171802, 17191.82808171927, 17194.1402929853, 13328.0, 13421.530246441327, 13448.394822233024, 13451.32743362828, 13451.529196478272, 15220.923895380452, 16515.17424128813, 16902.74645880313, 16930.830691693172, 17368.5657858552, 6487.481001519867, 6604.306547812506, 6683.331666833278, 6693.631850248185, 6720.193576770721, 983.8130755156641, 991.375433476905, 1073.909981368911, 1106.435717783936, 1134.989859025468, 32659.0, 32773.836130819196, 32910.8354458231, 33284.833575831966, 33501.32997340088, 1095.9945200273949, 1108.5288752280248, 1129.2377645089532, 1160.1936654025371, 1199.1006744941294, 16235.350585976428, 17480.91259543694, 17580.770095699605, 17634.619326391028, 17756.20097095634, 511556.0, 515882.0, 516447.0, 517072.0, 517246.0, 59457.40542594605, 59645.701771490865, 59754.70122649446, 59796.0, 59987.70006149941, 1223.6634925395588, 1324.966875828112, 1334.1061488802331, 1350.9459621615026, 1378.77250253707, 31519.52720709237, 33481.330373392884, 34254.31491370209, 34508.0, 34869.825650871586, 55823.162652561055, 57593.136102959324, 57732.4226757727, 63687.363126368145, 63693.36306636874, 466016.0, 472125.0, 473267.0, 473489.0, 473802.0, 44142.77928610337, 44908.0, 46070.7696461529, 47628.2855757157, 48236.27645585389, 47460.28809567928, 48725.02549949052, 51866.0, 52201.21698174455, 52213.0, 42257.0, 42895.57104429041, 43135.35296970485, 44236.77881610571, 44860.77569612131, 43667.70313640398, 45247.0, 45327.0, 47064.7646761764, 47911.0, 44825.77587112044, 45069.774651127846, 45139.32291015703, 45192.77403612961, 45223.547764521936, 36724.63275367212, 38605.806970964964, 39332.60667393404, 39418.2116357677, 39432.6056739429, 28990.710092899644, 29339.85330073336, 29867.719998209002, 30012.3997520044, 30126.2468438282], "value": 20805.251473584234, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [374.69836781390967, 391.2370876790258, 398.6710963455149, 405.189620758483, 421.300640936046, 16295.18524073796, 19284.421467355984, 19360.03199840006, 19520.609587808252, 20620.793792062075, 19288.132034058464, 19413.611727765438, 19792.10935507902, 19869.801301986976, 19941.30498907591, 5326.774841786391, 5544.223808666779, 5713.085906255, 5743.368229494752, 5757.856053598652, 5622.100463925773, 5664.575156863231, 5677.120046392795, 5711.314642242936, 5711.8000869969555, 5448.482394172547, 5613.0, 5636.267285252911, 5649.983003059448, 5656.943430565703, 5609.49514543691, 5638.351589567212, 5642.069058605325, 5649.548036157097, 5650.6892120933335, 4880.341153944208, 5043.873903152414, 5080.8221712240265, 5096.413912400084, 5103.106956282636, 24390.878045609836, 24398.756012439826, 24422.0, 24696.753032469805, 24703.876480617662, 2395.35325462126, 2403.0868270057267, 2523.470071285032, 2983.6568794588466, 3164.4145833020966, 10390.168786497063, 10822.783544329148, 10843.945780271128, 10882.61910833117, 10935.125189984783, 1721.8106008339073, 1769.4603146040452, 1803.2426380920044, 1810.5111619862564, 1840.5858681796622, 80205.59897200477, 80385.0, 80977.0, 92186.0, 92396.0, 11082.168837337233, 11289.435528223556, 11507.309561426342, 11518.596849110241, 11693.123015773852, 15744.842551574568, 15872.127033013065, 15949.44176953801, 16007.27967241477, 16017.279222434785, 8437.57812109392, 8611.526366049928, 8633.784155396039, 8824.647014119491, 8835.823283534293, 7973.322267607203, 8244.340452763765, 8334.708285209988, 8545.572721364031, 8563.743087707378, 4612.953870461252, 4648.814047438064, 4879.853604391874, 4904.828331008398, 5039.0929632666475, 1978.6438441080456, 2034.2269937423684, 2049.1700861151257, 2070.9378718638463, 2074.1703318672653, 463.24722326219893, 875.3741075131185, 919.9448033118034, 1003.3428104591504, 1011.8111219317273, 11848.88151118495, 12470.625881223577, 12779.552715654907, 12783.424745886457, 13260.204387736767, 478.54060102301486, 901.7069452427987, 1080.3679847289425, 1096.413418820943, 1108.2131686502512, 10443.373397596017, 11009.846848810184, 11038.779224415628, 11220.943895280634, 11309.71725706864, 498092.0, 503414.0, 508811.0, 516322.0, 520081.0, 93449.5327523358, 95856.52071739596, 96455.0, 97866.0, 103627.41961286157, 1013.7262939006426, 1056.5456853553046, 1061.1139698351808, 1075.1398880895254, 1086.6739978006567, 17089.29107089281, 17395.564131146475, 17828.19773110239, 17894.5526361842, 17998.910005450412, 40191.19617607573, 41532.0, 41798.58201417947, 42252.78873605612, 43429.13141737211, 56636.716816417305, 56886.14670779859, 57074.714626426605, 58531.4146858543, 61779.6911015442, 8570.957145214234, 8787.736367908972, 8805.339599530189, 8936.28509719208, 8972.68595599177, 9584.520773961134, 13133.60599182026, 14196.148231106166, 14282.571522853916, 14311.355988980313, 12051.51793928233, 12163.695907602027, 12184.0, 12247.448864801283, 12512.311822849466, 11711.472983715583, 11913.344766037604, 12049.698757531132, 12122.575709850113, 12197.573084941985, 12109.33398663082, 12145.210561313486, 12257.522339520881, 12446.377681115728, 12886.935565322488, 8844.380893337404, 8983.595738191667, 9012.819743605221, 9100.817983640423, 9105.453672779655, 12009.39953002329, 12160.635180944586, 12167.33079680626, 12244.326562039174, 12327.075469340014], "value": 9253.802628301804, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1338.4177882621054, 1477.0473044886053, 1524.7026829768192, 1636.2064398766604, 1756.4203812741796, 8567.828643427136, 8972.102789721024, 8999.280057595393, 9215.861762073557, 9292.163705266525, 8995.235404990568, 9146.451212927212, 9152.405093668924, 9153.0, 9158.58786354614, 2022.130483891926, 2262.6492893601517, 2332.3469428559947, 2360.5160942006846, 2369.9999999999914, 2404.326788499214, 2433.0146290752273, 2433.111914151331, 2436.8659723715186, 2438.37821355555, 2310.7111611048617, 2318.40880575453, 2323.4423738302853, 2328.3247858121126, 2364.645303204516, 2347.624380099176, 2370.95258094838, 2372.3357459911253, 2378.048780487801, 2430.3195105370523, 2403.327068420845, 2409.4699166183414, 2422.4307287787356, 2422.6608274841537, 2424.090965887792, 9342.2526197904, 9395.0, 9404.764880877965, 9409.905900940972, 9415.05849415053, 5275.973620131875, 5294.788208471657, 5328.866778330534, 5426.972865135688, 5438.265834112383, 3283.0, 3380.323935212967, 3461.16931936336, 3466.687998080185, 3512.578490581121, 1127.8590176227972, 1159.287038471334, 1162.482695200636, 1163.4764356039816, 1171.6719318590808, 12940.676483087998, 12970.286634235114, 13037.608871733863, 13057.869421305666, 13098.148620339647, 4375.453068366454, 4600.95399046012, 4733.597644200213, 4757.119932812441, 4760.33355330254, 10056.0, 10081.0, 10087.596496140219, 10088.0, 10095.697129086138, 5406.783728650888, 5419.3225846769155, 5419.593530485229, 5430.13117901134, 5434.239206511092, 4242.5121111072, 4811.446683631427, 4881.4630390657, 5059.671121377099, 5061.620378471629, 3661.707063434919, 3762.247550489913, 3810.2760475510117, 3836.616338366197, 3850.1711686948474, 1185.4013723069795, 1187.3825610682397, 1192.2488832035936, 1195.9581414650445, 1213.5873802907054, 961.9422834629945, 980.26969907418, 992.151710287708, 993.0962823830424, 998.8651532043226, 6106.786262480792, 6138.78514252008, 6184.165137706352, 6186.752529898844, 6296.905546416808, 485.7158562241058, 486.9561739443467, 496.45390070921695, 498.5388515623034, 500.65704992080543, 4227.492700875914, 4287.249731297011, 4459.843905463358, 4574.062317224961, 4592.448906131285, 10877.728056798485, 11235.606753763743, 11240.831387529193, 11299.887001130048, 11310.773784524428, 10904.236703430843, 11180.944095279472, 11199.832002519968, 11200.83198752003, 11382.0, 8118.2693557580105, 8289.502629842229, 8295.917040829636, 8305.46014509055, 8305.667773289122, 6128.66292353907, 6280.780172693934, 6336.493080553452, 6356.491480681442, 6565.573237739532, 10628.8937110631, 10650.0, 10776.191785615953, 10834.404107773833, 10843.078338341014, 10190.592376305161, 11072.169587280825, 11076.667699969013, 11088.722781930517, 11095.5561777528, 6968.965155174192, 7861.803454913673, 8139.959300203461, 8181.713640022571, 8286.627101780556, 9022.864657030019, 9205.217556507503, 9206.0, 9206.539673016456, 9207.40151890125, 7956.562389068657, 8093.433459657767, 8123.3501319893085, 8281.54451505173, 8441.83116337682, 7907.604619768874, 7985.8402831944195, 8071.313938315309, 8104.594770261581, 8195.549244791593, 6931.4108300795, 7113.324234197743, 7120.537165084253, 7125.893111603434, 7263.636818159177, 5166.328377311076, 5284.471552844685, 5367.409584945731, 5501.752421140978, 5526.226328313959, 5340.359156901041, 5369.0, 5375.19372094189, 5410.053240682786, 5480.095784195722], "value": 4680.589068480804, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.1629493675937, 333.54970789434265, 340.984655690494, 349.44438343034585, 349.5682831702845, 5233.84298471046, 5256.526912577867, 5279.0, 5312.724946012958, 5327.121025030875, 5236.0, 5255.789768409269, 5268.604854635908, 5270.604704647148, 5280.627036970386, 4074.5110586729634, 4136.482939632546, 4142.958570414287, 4188.706790524665, 4190.455240818697, 4136.544980052192, 4177.979110104445, 4183.4979802423595, 4183.644390226835, 4189.560096189902, 4134.689898257628, 4145.378193271018, 4147.4815648044, 4148.336266197409, 4163.458750362449, 3909.7263191576603, 3984.824476779328, 4104.363823607346, 4115.34154535273, 4157.708960372775, 3663.6519530644546, 3670.596234414212, 3673.493867514333, 3675.467057276707, 3691.0, 5113.718745468997, 5147.253648220987, 5160.638755287131, 5162.97418512905, 5187.714675692835, 4459.175052615245, 4496.235639941186, 4715.127701375257, 4727.409073865767, 4741.762911854428, 5443.89697872213, 5446.101393270105, 5456.590755693339, 5460.535854452376, 5487.75305111271, 4042.8382864685673, 4043.160362035296, 4048.3725022621247, 4048.4534587830562, 4055.3511438169753, 5376.494581517181, 5684.687342196177, 5710.229119068914, 5715.513966368732, 5718.055201571194, 4031.1937612477036, 4044.0, 4045.4943132108488, 4045.858394956221, 4050.736702114412, 4083.4487344208746, 4089.407035979766, 4090.570490098556, 4104.856330028435, 4105.281575724296, 4436.0, 4641.725554478348, 4661.976690116596, 4663.183942809995, 4668.626509879202, 5463.3717122530625, 5497.5601951843755, 5508.807191748269, 5512.80705175317, 5516.0, 1396.9522857856607, 1408.9929550352183, 1409.5418988828671, 1414.6251243420502, 1415.4055296775173, 849.46908182386, 888.2272422992038, 900.7703035725848, 904.4754042655244, 918.35155895167, 4712.764361781898, 4720.268358404453, 4726.692764970266, 4726.740029298353, 4743.335932969387, 4953.2168419369, 4961.759560109973, 4963.284879364618, 4976.576990955809, 4988.5260900214425, 4704.9059018819435, 4726.361941137902, 4733.621310295168, 4748.240281555004, 4996.225585034325, 2955.7635389168813, 2987.193457766434, 2990.7308342249303, 2999.9350230668233, 3010.247438140465, 5791.305043394736, 5794.478496935296, 5821.767129314865, 5835.299764028344, 5843.211166492554, 5543.223948647193, 5752.490980501891, 5754.568407369464, 5755.395683453177, 5779.768809247667, 5815.185873977562, 5826.65040097587, 5845.444682755132, 5857.179994800731, 5871.530277577869, 5420.83737487876, 5639.943600563942, 5643.661380317194, 5644.322681278273, 5650.208970744017, 5607.775688972395, 5612.522935550361, 5613.522850557748, 5634.4365563443325, 5638.295213098363, 5841.795537156179, 5861.882762344814, 5871.770526210653, 5883.646981181143, 5884.6469211847425, 3927.803609819555, 4048.4534587831445, 4059.5737447568763, 4079.4294196733877, 4093.3450647896193, 5598.608097433304, 5601.859953501195, 5615.578831587729, 5616.3260408749575, 5642.210090587403, 5487.368952570506, 5498.075673514398, 5525.143602741581, 5540.806071787468, 5553.361363443094, 5253.264542964064, 5453.345598528202, 5481.342238931354, 5506.421825708403, 5529.474699903502, 5477.780888764564, 5491.615586908878, 5507.862303442446, 5508.953298873202, 5514.172874068921, 5259.89480210386, 5260.395054568774, 5488.17677348401, 5541.916871246854, 5549.417311182429, 5391.436483419921, 5415.40430552631, 5415.891682166413, 5415.918761218506, 5429.0], "value": 4170.35311526607, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [703.7888633409978, 725.9128904531453, 736.7973807203015, 737.365865355794, 745.3664385272516, 13874.861251387483, 14724.484643037496, 14750.926245368759, 14797.556073317817, 14850.1832399218, 15410.845891541081, 15537.378504859793, 15560.766588501181, 15566.844331556682, 15578.53264402067, 660.616842231507, 789.6762327445748, 793.5833687314163, 811.679386642276, 813.0649752784316, 796.1202870827739, 797.2585495489193, 798.1858504325585, 798.3014861995754, 798.480987358219, 867.089555966236, 867.444835305405, 868.1405408645427, 868.2229404682805, 869.009329364525, 702.732961474639, 840.3319361107934, 855.7304449098514, 859.4113032572708, 865.35098675993, 624.4473640827855, 624.6938999890067, 624.9031400132986, 625.0780099353462, 625.2559454249429, 12335.629931102081, 12420.503179872794, 12426.254424734454, 12431.12982091254, 12437.067219958539, 12833.486660533568, 13084.084114112016, 13379.86620133796, 13461.528846490424, 13796.793048104286, 13175.86824131756, 13201.339933003312, 13286.26925519096, 13451.125676830974, 13617.59147225575, 4474.812433011793, 4521.706089104197, 4618.191816432144, 4647.837325693585, 4679.953200467986, 85223.57388213082, 85495.57252213762, 86156.13843861544, 87409.0, 87835.56082219612, 11610.0, 11648.592299269654, 11791.0, 11819.0, 11918.821217681741, 22181.556368872854, 22260.22089226869, 22280.554388912133, 22317.330480085624, 22325.665115023286, 8713.695020674373, 8773.254273386707, 8821.382503224713, 8985.640574377083, 9123.36136470454, 4735.976320118378, 4790.688605240648, 4896.926546101811, 5013.824516141917, 5097.286379906816, 3272.672732726708, 3388.3900897838625, 3394.1514621344663, 3397.779144355609, 3407.557017587698, 549.409384911214, 607.4047433515092, 620.1968450856152, 631.43486579512, 634.1882390540115, 666.1839246922578, 696.5507247825125, 697.6267696782197, 703.7820852765769, 716.802879208222, 9309.72070837876, 10210.336328138646, 10274.332168409179, 10326.225533084898, 10447.0, 683.3235097253684, 694.989575156373, 710.1726488640676, 713.9500234983501, 723.6815801047522, 4905.950940490621, 4969.254611808257, 4976.0794253063295, 4989.725565093882, 5004.274380214849, 113927.0, 124638.0, 126902.0, 127363.0, 129099.35450322877, 100239.99999999854, 101993.0, 102210.48894755478, 103257.0, 106499.0, 92281.5385923066, 93487.53256233675, 94086.0, 94873.0, 95570.5221473902, 9131.680391186277, 9303.488308143116, 9402.576884040098, 9486.573104210465, 9680.128788409076, 55083.44916550783, 56849.715751422635, 57208.42791572031, 57819.71090144522, 57907.0, 65968.34031659622, 75900.0, 83312.0, 84056.0, 84501.0, 11792.823107653221, 11887.643370698894, 13071.803922941353, 13194.934025329812, 13780.0, 13320.600381988555, 13505.662358441117, 13539.79690304666, 14087.577372678836, 14679.0, 12719.55481558141, 12737.55418560346, 12785.16896401731, 13035.348232588522, 13583.117097388638, 14558.41766329335, 14705.0, 14833.92583037121, 14961.625959351104, 15267.77098343548, 14086.506972255922, 14225.00424970242, 14307.21310327942, 14583.489577865148, 15501.844981550492, 11694.70763230926, 11694.771924588438, 11718.824217636913, 11812.0, 12865.549705760253, 11877.524899003944, 12268.263904165779, 12362.690932726753, 12652.17760845542, 12657.0], "value": 7711.36840392035, "unit": "score"}], "name": "dromaeo_css", "value": 7307.1544576393135, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 113.0, 115.0, 114.0, 109.0, 114.0, 113.0, 112.0, 112.0, 112.0], "value": 111.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 116.0, 126.0, 175.0, 119.0, 121.0, 143.0, 125.0, 159.0, 117.0], "value": 134.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 159.0, 161.0, 161.0, 160.0, 162.0, 162.0, 161.0, 160.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 75.0, 117.0, 75.0, 76.0, 75.0, 75.0, 101.0, 74.0], "value": 81.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 137.0, 85.0, 84.0, 85.0, 119.0, 84.0, 85.0, 124.0], "value": 97.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 145.0, 147.0, 145.0, 146.0, 146.0, 146.0, 146.0, 145.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 52.0, 52.0, 51.0, 49.0, 53.0, 51.0, 50.0, 53.0, 50.0], "value": 51.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 62.0, 63.0, 62.0, 61.0, 62.0, 61.0, 62.0, 63.0], "value": 62.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 78.0, 78.0, 78.0, 79.0, 78.0, 78.0, 78.0, 78.0, 78.0], "value": 78.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 123.0, 120.0, 129.0, 121.0, 119.0, 122.0, 121.0, 140.0, 125.0], "value": 124.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 159.0, 160.0, 162.0, 162.0, 164.0, 166.0, 161.0, 162.0], "value": 161.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 64.0, 63.0, 65.0, 64.0, 63.0, 65.0, 62.0, 67.0], "value": 64.2, "unit": "score"}], "name": "kraken", "value": 1486.7000000000003, "alertThreshold": 2.0}]} 10:45:23 INFO - Return code: 0 10:45:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:45:23 INFO - # TBPL SUCCESS # 10:45:23 INFO - Running post-action listener: _resource_record_post_action 10:45:23 INFO - [mozharness: 2016-08-19 17:45:23.360804Z] Finished run-tests step (success) 10:45:23 INFO - Running post-run listener: _resource_record_post_run 10:45:23 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 12288; Write bytes: 421244928; Read time: 244; Write time: 290272 10:45:23 INFO - TinderboxPrint: CPU usage
13.0% 10:45:23 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 244 10:45:23 INFO - TinderboxPrint: I/O write bytes / time
421,244,928 / 290,272 10:45:23 INFO - TinderboxPrint: CPU idle
8,278.3 (87.0%) 10:45:23 INFO - TinderboxPrint: CPU user
1,189.4 (12.5%) 10:45:23 INFO - TinderboxPrint: Swap in / out
0 / 0 10:45:23 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 118255616; Read time: 128; Write time: 88912 10:45:23 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 4096; Write bytes: 291409920; Read time: 76; Write time: 199308 10:45:23 INFO - Running post-run listener: _upload_blobber_files 10:45:23 INFO - Blob upload gear active. 10:45:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:45:23 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 10:45:23 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'] 10:45:23 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 10:45:24 INFO - (blobuploader) - INFO - Open directory for files ... 10:45:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:45:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:45:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:45:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:45:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:45:28 INFO - (blobuploader) - INFO - Done attempting. 10:45:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:45:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:45:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:45:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:45:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:45:29 INFO - (blobuploader) - INFO - Done attempting. 10:45:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:45:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:45:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:45:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:45:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:45:30 INFO - (blobuploader) - INFO - Done attempting. 10:45:30 INFO - (blobuploader) - INFO - Iteration through files over. 10:45:30 INFO - Return code: 0 10:45:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:45:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:45:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2dc0579bc280fbca7cfe92b17793e55dbe48a8fe23eb76f6fb7a4b2216387db62d5362d35367314f6a05745d8cc9412326616a6492acad63442b7f5a24ae0e72", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/40d7d7fe3b1bb1893f55315b067c924f40d8fb21509dd96e863fe27316c0fc48630717cce1f954f53c29c55ce87ea30836d5381ca89947b133458c0abc9c7e48", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8ad77d58d0a02d041ba9c8ec474f5915d95dceea7e264cbcc1b4091f369d8bb1e290581f881262fe58122e0d760f75982f2fb5a9cb143dbe60736a85db2910ce"} 10:45:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:45:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:45:30 INFO - Contents: 10:45:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2dc0579bc280fbca7cfe92b17793e55dbe48a8fe23eb76f6fb7a4b2216387db62d5362d35367314f6a05745d8cc9412326616a6492acad63442b7f5a24ae0e72", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/40d7d7fe3b1bb1893f55315b067c924f40d8fb21509dd96e863fe27316c0fc48630717cce1f954f53c29c55ce87ea30836d5381ca89947b133458c0abc9c7e48", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8ad77d58d0a02d041ba9c8ec474f5915d95dceea7e264cbcc1b4091f369d8bb1e290581f881262fe58122e0d760f75982f2fb5a9cb143dbe60736a85db2910ce"} 10:45:30 INFO - Running post-run listener: copy_logs_to_upload_dir 10:45:30 INFO - Copying logs to upload dir... 10:45:30 INFO - mkdir: /builds/slave/test/build/upload/logs 10:45:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.926380 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-08-19 10:45:29.933059) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-19 10:45:29.936390) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1471626819.587734-1904139076 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/2dc0579bc280fbca7cfe92b17793e55dbe48a8fe23eb76f6fb7a4b2216387db62d5362d35367314f6a05745d8cc9412326616a6492acad63442b7f5a24ae0e72", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/40d7d7fe3b1bb1893f55315b067c924f40d8fb21509dd96e863fe27316c0fc48630717cce1f954f53c29c55ce87ea30836d5381ca89947b133458c0abc9c7e48", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8ad77d58d0a02d041ba9c8ec474f5915d95dceea7e264cbcc1b4091f369d8bb1e290581f881262fe58122e0d760f75982f2fb5a9cb143dbe60736a85db2910ce"} build_url:https://queue.taskcluster.net/v1/task/QVrWDpp5SLO3Qw-UwVhPqw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011212 build_url: 'https://queue.taskcluster.net/v1/task/QVrWDpp5SLO3Qw-UwVhPqw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2dc0579bc280fbca7cfe92b17793e55dbe48a8fe23eb76f6fb7a4b2216387db62d5362d35367314f6a05745d8cc9412326616a6492acad63442b7f5a24ae0e72", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/40d7d7fe3b1bb1893f55315b067c924f40d8fb21509dd96e863fe27316c0fc48630717cce1f954f53c29c55ce87ea30836d5381ca89947b133458c0abc9c7e48", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8ad77d58d0a02d041ba9c8ec474f5915d95dceea7e264cbcc1b4091f369d8bb1e290581f881262fe58122e0d760f75982f2fb5a9cb143dbe60736a85db2910ce"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-19 10:45:29.965560) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 10:45:29.966315) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1471626819.587734-1904139076 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004464 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 10:45:30.047727) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-19 10:45:30.048016) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-19 10:45:30.048263) ========= ========= Total master_lag: 0.17 =========