builder: autoland_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-024 starttime: 1471731410.98 results: success (0) buildid: 20160820123003 builduid: eefac6dc2873487280c53bcdbdcc1e0e revision: 3f3459b7c54ac0225037edc6665113603b85e15c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-20 15:16:50.980382) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-20 15:16:50.980813) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-20 15:16:50.981115) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-20 15:16:51.064240) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-20 15:16:51.064495) ========= 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=41a7e32711b8e08598a63237000001d6-1471729468.226553-397249985 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-20 15:16:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 69.3M=0s 2016-08-20 15:16:51 (69.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.115904 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-20 15:16:51.197669) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-20 15:16:51.198029) ========= 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=41a7e32711b8e08598a63237000001d6-1471729468.226553-397249985 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019753 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-20 15:16:51.247481) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-20 15:16:51.247830) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 3f3459b7c54ac0225037edc6665113603b85e15c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 3f3459b7c54ac0225037edc6665113603b85e15c --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=41a7e32711b8e08598a63237000001d6-1471729468.226553-397249985 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-20 15:16:51,760 truncating revision to first 12 chars 2016-08-20 15:16:51,761 Setting DEBUG logging. 2016-08-20 15:16:51,761 attempt 1/10 2016-08-20 15:16:51,761 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/3f3459b7c54a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-20 15:16:52,082 unpacking tar archive at: autoland-3f3459b7c54a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.665155 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-20 15:16:51.926382) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-20 15:16:51.926682) ========= script_repo_revision: 3f3459b7c54ac0225037edc6665113603b85e15c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-20 15:16:51.927082) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-20 15:16:51.927366) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-20 15:16:51.949134) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-08-20 15:16:51.949394) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] 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=41a7e32711b8e08598a63237000001d6-1471729468.226553-397249985 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:16:52 INFO - MultiFileLogger online at 20160820 15:16:52 in /builds/slave/test 15:16:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 15:16:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:16:52 INFO - {'append_to_log': False, 15:16:52 INFO - 'base_work_dir': '/builds/slave/test', 15:16:52 INFO - 'blob_upload_branch': 'Autoland', 15:16:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:16:52 INFO - 'branch': 'Autoland', 15:16:52 INFO - 'buildbot_json_path': 'buildprops.json', 15:16:52 INFO - 'config_files': ('talos/linux_config.py',), 15:16:52 INFO - 'default_actions': ('clobber', 15:16:52 INFO - 'read-buildbot-config', 15:16:52 INFO - 'download-and-extract', 15:16:52 INFO - 'populate-webroot', 15:16:52 INFO - 'create-virtualenv', 15:16:52 INFO - 'install', 15:16:52 INFO - 'run-tests'), 15:16:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:16:52 INFO - 'download_minidump_stackwalk': True, 15:16:52 INFO - 'download_symbols': 'ondemand', 15:16:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:16:52 INFO - 'tooltool.py': '/tools/tooltool.py', 15:16:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:16:52 INFO - '/tools/misc-python/virtualenv.py')}, 15:16:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:16:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:16:52 INFO - 'installer_path': 'installer.exe', 15:16:52 INFO - 'log_level': 'info', 15:16:52 INFO - 'log_name': 'talos', 15:16:52 INFO - 'log_to_console': True, 15:16:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:16:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:16:52 INFO - 'opt_config_files': (), 15:16:52 INFO - 'pip_index': False, 15:16:52 INFO - 'sps_profile': False, 15:16:52 INFO - 'sps_profile_interval': 0, 15:16:52 INFO - 'suite': 'dromaeojs', 15:16:52 INFO - 'system_bits': '32', 15:16:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:16:52 INFO - 'title': 'talos-linux64-ix-024', 15:16:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:16:52 INFO - 'use_talos_json': True, 15:16:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:16:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:16:52 INFO - 'work_dir': 'build'} 15:16:52 INFO - [mozharness: 2016-08-20 22:16:52.555882Z] Running clobber step. 15:16:52 INFO - Running pre-action listener: _resource_record_pre_action 15:16:52 INFO - Running main action method: clobber 15:16:52 INFO - rmtree: /builds/slave/test/build 15:16:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:16:53 INFO - Running post-action listener: _resource_record_post_action 15:16:53 INFO - [mozharness: 2016-08-20 22:16:53.013887Z] Finished clobber step (success) 15:16:53 INFO - [mozharness: 2016-08-20 22:16:53.013987Z] Running read-buildbot-config step. 15:16:53 INFO - Running pre-action listener: _resource_record_pre_action 15:16:53 INFO - Running main action method: read_buildbot_config 15:16:53 INFO - Using buildbot properties: 15:16:53 INFO - { 15:16:53 INFO - "project": "", 15:16:53 INFO - "product": "firefox", 15:16:53 INFO - "script_repo_revision": "production", 15:16:53 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 15:16:53 INFO - "repository": "", 15:16:53 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland pgo talos dromaeojs", 15:16:53 INFO - "buildid": "20160820123003", 15:16:53 INFO - "pgo_build": "True", 15:16:53 INFO - "basedir": "/builds/slave/test", 15:16:53 INFO - "buildnumber": 142, 15:16:53 INFO - "slavename": "talos-linux64-ix-024", 15:16:53 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:16:53 INFO - "platform": "ubuntu64_hw", 15:16:53 INFO - "scheduler": "tests-autoland-linux64-pgo-talos", 15:16:53 INFO - "branch": "autoland", 15:16:53 INFO - "repo_path": "integration/autoland", 15:16:53 INFO - "revision": "3f3459b7c54ac0225037edc6665113603b85e15c", 15:16:53 INFO - "stage_platform": "linux64-pgo", 15:16:53 INFO - "builduid": "eefac6dc2873487280c53bcdbdcc1e0e", 15:16:53 INFO - "slavebuilddir": "test" 15:16:53 INFO - } 15:16:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/RdHYxEDuQwC1aLag9uq6yA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 15:16:53 INFO - Running post-action listener: _resource_record_post_action 15:16:53 INFO - [mozharness: 2016-08-20 22:16:53.016388Z] Finished read-buildbot-config step (success) 15:16:53 INFO - [mozharness: 2016-08-20 22:16:53.016476Z] Running download-and-extract step. 15:16:53 INFO - Running pre-action listener: _resource_record_pre_action 15:16:53 INFO - Running main action method: download_and_extract 15:16:53 INFO - mkdir: /builds/slave/test/build/tests 15:16:53 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')]} 15:16:53 INFO - trying https://queue.taskcluster.net/v1/task/RdHYxEDuQwC1aLag9uq6yA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 15:16:53 INFO - Downloading https://queue.taskcluster.net/v1/task/RdHYxEDuQwC1aLag9uq6yA/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 15:16:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RdHYxEDuQwC1aLag9uq6yA/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 15:16:53 INFO - Downloaded 1361 bytes. 15:16:53 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 15:16:53 INFO - Using the following test package requirements: 15:16:53 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 15:16:53 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:16:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:16:53 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:16:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:16:53 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:16:53 INFO - u'jsshell-linux-x86_64.zip'], 15:16:53 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:16:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:16:53 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 15:16:53 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:16:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:16:53 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:16:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:16:53 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:16:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:16:53 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:16:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:16:53 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:16:53 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')]} 15:16:53 INFO - trying https://queue.taskcluster.net/v1/task/RdHYxEDuQwC1aLag9uq6yA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 15:16:53 INFO - Downloading https://queue.taskcluster.net/v1/task/RdHYxEDuQwC1aLag9uq6yA/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 15:16:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RdHYxEDuQwC1aLag9uq6yA/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 15:16:54 INFO - Downloaded 21919096 bytes. 15:16:54 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 15:16:58 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 15:16:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:16:58 INFO - trying https://queue.taskcluster.net/v1/task/RdHYxEDuQwC1aLag9uq6yA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 15:16:58 INFO - Downloading https://queue.taskcluster.net/v1/task/RdHYxEDuQwC1aLag9uq6yA/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 15:16:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RdHYxEDuQwC1aLag9uq6yA/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 15:16:59 INFO - Downloaded 21919096 bytes. 15:16:59 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 15:17:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:17:04 INFO - trying https://queue.taskcluster.net/v1/task/RdHYxEDuQwC1aLag9uq6yA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 15:17:04 INFO - Downloading https://queue.taskcluster.net/v1/task/RdHYxEDuQwC1aLag9uq6yA/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 15:17:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RdHYxEDuQwC1aLag9uq6yA/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 15:17:05 INFO - Downloaded 14413981 bytes. 15:17: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 15:17: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')]} 15:17:05 INFO - trying https://queue.taskcluster.net/v1/task/RdHYxEDuQwC1aLag9uq6yA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 15:17:05 INFO - Downloading https://queue.taskcluster.net/v1/task/RdHYxEDuQwC1aLag9uq6yA/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 15:17:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RdHYxEDuQwC1aLag9uq6yA/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 15:17:08 INFO - Downloaded 59681623 bytes. 15:17:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RdHYxEDuQwC1aLag9uq6yA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 15:17:08 INFO - mkdir: /builds/slave/test/properties 15:17:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:17:08 INFO - Writing to file /builds/slave/test/properties/build_url 15:17:08 INFO - Contents: 15:17:08 INFO - build_url:https://queue.taskcluster.net/v1/task/RdHYxEDuQwC1aLag9uq6yA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 15:17:08 INFO - Running post-action listener: _resource_record_post_action 15:17:08 INFO - Running post-action listener: set_extra_try_arguments 15:17:08 INFO - [mozharness: 2016-08-20 22:17:08.903608Z] Finished download-and-extract step (success) 15:17:08 INFO - [mozharness: 2016-08-20 22:17:08.903831Z] Running populate-webroot step. 15:17:08 INFO - Running pre-action listener: _resource_record_pre_action 15:17:08 INFO - Running main action method: populate_webroot 15:17:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:17:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:17:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:17:08 INFO - '--tppagecycles', 15:17:08 INFO - '7'], 15:17:08 INFO - 'tests': ['tsvgm']}}, 15:17:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:17:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:17:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:17:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:17:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:17:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:17:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:17:08 INFO - 'talos_options': ['--disable-e10s'], 15:17:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:17:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:17:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:17:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:17:08 INFO - 'talos_options': ['--disable-e10s'], 15:17:08 INFO - 'tests': ['damp', 'tps']}, 15:17:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:17:08 INFO - 'tests': ['damp', 'tps']}, 15:17:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:17:08 INFO - 'tests': ['dromaeo_dom']}, 15:17:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:17:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:17:08 INFO - 'tests': ['basic_compositor_video']}, 15:17:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:17:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:17:08 INFO - 'tests': ['a11yr', 15:17:08 INFO - 'ts_paint', 15:17:08 INFO - 'tpaint', 15:17:08 INFO - 'sessionrestore', 15:17:08 INFO - 'sessionrestore_no_auto_restore', 15:17:08 INFO - 'tabpaint']}, 15:17:08 INFO - 'other-e10s': {'tests': ['a11yr', 15:17:08 INFO - 'ts_paint', 15:17:08 INFO - 'tpaint', 15:17:08 INFO - 'sessionrestore', 15:17:08 INFO - 'sessionrestore_no_auto_restore', 15:17:08 INFO - 'tabpaint']}, 15:17:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:17:08 INFO - 'ts_paint', 15:17:08 INFO - 'tpaint', 15:17:08 INFO - 'sessionrestore', 15:17:08 INFO - 'sessionrestore_no_auto_restore', 15:17:08 INFO - 'tabpaint']}, 15:17:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:17:08 INFO - 'ts_paint', 15:17:08 INFO - 'tpaint', 15:17:08 INFO - 'sessionrestore', 15:17:08 INFO - 'sessionrestore_no_auto_restore', 15:17:08 INFO - 'tabpaint']}, 15:17:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:17:08 INFO - 'tests': ['a11yr', 15:17:08 INFO - 'ts_paint', 15:17:08 INFO - 'tpaint', 15:17:08 INFO - 'sessionrestore', 15:17:08 INFO - 'sessionrestore_no_auto_restore', 15:17:08 INFO - 'tabpaint']}, 15:17:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:17:08 INFO - 'tests': ['a11yr', 15:17:08 INFO - 'ts_paint', 15:17:08 INFO - 'tpaint', 15:17:08 INFO - 'sessionrestore', 15:17:08 INFO - 'sessionrestore_no_auto_restore', 15:17:08 INFO - 'tabpaint']}, 15:17:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:17:08 INFO - 'tests': ['tsvgx', 15:17:08 INFO - 'tsvgr_opacity', 15:17:08 INFO - 'tart', 15:17:08 INFO - 'tscrollx', 15:17:08 INFO - 'cart']}, 15:17:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:17:08 INFO - 'tsvgr_opacity', 15:17:08 INFO - 'tart', 15:17:08 INFO - 'tscrollx', 15:17:08 INFO - 'cart']}, 15:17:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:17:08 INFO - 'talos_options': ['--disable-e10s'], 15:17:08 INFO - 'tests': ['tp5o']}, 15:17:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:17:08 INFO - 'tests': ['tp5o']}, 15:17:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:17:08 INFO - 'talos_options': ['--disable-e10s', 15:17:08 INFO - '--xperf_path', 15:17:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:17:08 INFO - 'tests': ['tp5n']}, 15:17:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:17:08 INFO - 'talos_options': ['--xperf_path', 15:17:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:17:08 INFO - 'tests': ['tp5n']}}, 15:17:08 INFO - 'talos.zip': {'path': '', 15:17:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:17:08 INFO - Running post-action listener: _resource_record_post_action 15:17:08 INFO - [mozharness: 2016-08-20 22:17:08.920763Z] Finished populate-webroot step (success) 15:17:08 INFO - [mozharness: 2016-08-20 22:17:08.920850Z] Running create-virtualenv step. 15:17:08 INFO - Running pre-action listener: _resource_record_pre_action 15:17:08 INFO - Running main action method: create_virtualenv 15:17:08 INFO - Creating virtualenv /builds/slave/test/build/venv 15:17: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 15:17:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:17:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:17:09 INFO - Using real prefix '/usr' 15:17:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:17:09 INFO - Installing distribute.............................................................................................................................................................................................done. 15:17:11 INFO - Installing pip.................done. 15:17:11 INFO - Return code: 0 15:17:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:17: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')]} 15:17:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:17:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17: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')]} 15:17:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:17:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17: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 0x7f728fa15f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23de8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ab850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a6370>, '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 0x24a98f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24a9d80>, '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': '41a7e32711b8e08598a63237000001d6-1471729468.226553-397249985', '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 15:17: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 15:17: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 15:17:11 INFO - Using env: {'DISPLAY': ':0', 15:17:11 INFO - 'HOME': '/home/cltbld', 15:17:11 INFO - 'LANG': 'en_US.UTF-8', 15:17:11 INFO - 'LANGUAGE': 'en_US:en', 15:17:11 INFO - 'LOGNAME': 'cltbld', 15:17:11 INFO - 'MAIL': '/var/mail/cltbld', 15:17:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:17:11 INFO - 'MOZ_NO_REMOTE': '1', 15:17:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:17:11 INFO - 'NO_EM_RESTART': '1', 15:17:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:17:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:17:11 INFO - 'PWD': '/builds/slave/test', 15:17:11 INFO - 'SHELL': '/bin/bash', 15:17:11 INFO - 'SHLVL': '1', 15:17:11 INFO - 'TERM': 'linux', 15:17:11 INFO - 'TMOUT': '86400', 15:17:11 INFO - 'USER': 'cltbld', 15:17:11 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1471729468.226553-397249985', 15:17:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:17:11 INFO - '_': '/tools/buildbot/bin/python'} 15:17:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:17: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 15:17:11 INFO - Cleaning up... 15:17:11 INFO - Return code: 0 15:17:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:17: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')]} 15:17:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:17:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17: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')]} 15:17:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:17:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17: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 0x7f728fa15f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23de8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ab850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a6370>, '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 0x24a98f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24a9d80>, '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': '41a7e32711b8e08598a63237000001d6-1471729468.226553-397249985', '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 15:17: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 15:17: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 15:17:11 INFO - Using env: {'DISPLAY': ':0', 15:17:11 INFO - 'HOME': '/home/cltbld', 15:17:11 INFO - 'LANG': 'en_US.UTF-8', 15:17:11 INFO - 'LANGUAGE': 'en_US:en', 15:17:11 INFO - 'LOGNAME': 'cltbld', 15:17:11 INFO - 'MAIL': '/var/mail/cltbld', 15:17:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:17:11 INFO - 'MOZ_NO_REMOTE': '1', 15:17:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:17:11 INFO - 'NO_EM_RESTART': '1', 15:17:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:17:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:17:11 INFO - 'PWD': '/builds/slave/test', 15:17:11 INFO - 'SHELL': '/bin/bash', 15:17:11 INFO - 'SHLVL': '1', 15:17:11 INFO - 'TERM': 'linux', 15:17:11 INFO - 'TMOUT': '86400', 15:17:11 INFO - 'USER': 'cltbld', 15:17:11 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1471729468.226553-397249985', 15:17:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:17:11 INFO - '_': '/tools/buildbot/bin/python'} 15:17:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:17:11 INFO - Downloading/unpacking psutil>=3.1.1 15:17: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 15:17: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 15:17: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 15:17: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 15:17: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 15:17: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 15:17:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:17:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:17:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:17:13 INFO - Installing collected packages: psutil 15:17:13 INFO - Running setup.py install for psutil 15:17:13 INFO - building 'psutil._psutil_linux' extension 15:17: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 15:17: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_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 15:17:14 INFO - building 'psutil._psutil_posix' extension 15:17:14 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 15:17: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 15:17:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:17:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:17:14 INFO - Successfully installed psutil 15:17:14 INFO - Cleaning up... 15:17:14 INFO - Return code: 0 15:17:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:17: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')]} 15:17:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:17:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17: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')]} 15:17:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:17:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17: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 0x7f728fa15f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23de8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ab850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a6370>, '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 0x24a98f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24a9d80>, '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': '41a7e32711b8e08598a63237000001d6-1471729468.226553-397249985', '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 15:17: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 15:17: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 15:17:14 INFO - Using env: {'DISPLAY': ':0', 15:17:14 INFO - 'HOME': '/home/cltbld', 15:17:14 INFO - 'LANG': 'en_US.UTF-8', 15:17:14 INFO - 'LANGUAGE': 'en_US:en', 15:17:14 INFO - 'LOGNAME': 'cltbld', 15:17:14 INFO - 'MAIL': '/var/mail/cltbld', 15:17:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:17:14 INFO - 'MOZ_NO_REMOTE': '1', 15:17:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:17:14 INFO - 'NO_EM_RESTART': '1', 15:17:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:17:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:17:14 INFO - 'PWD': '/builds/slave/test', 15:17:14 INFO - 'SHELL': '/bin/bash', 15:17:14 INFO - 'SHLVL': '1', 15:17:14 INFO - 'TERM': 'linux', 15:17:14 INFO - 'TMOUT': '86400', 15:17:14 INFO - 'USER': 'cltbld', 15:17:14 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1471729468.226553-397249985', 15:17:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:17:14 INFO - '_': '/tools/buildbot/bin/python'} 15:17:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:17:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:17: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 15:17: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 15:17: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 15:17: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 15:17: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 15:17: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 15:17:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:17:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:17:16 INFO - Installing collected packages: mozsystemmonitor 15:17:16 INFO - Running setup.py install for mozsystemmonitor 15:17:16 INFO - Successfully installed mozsystemmonitor 15:17:16 INFO - Cleaning up... 15:17:16 INFO - Return code: 0 15:17:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:17: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')]} 15:17:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:17:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17: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')]} 15:17:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:17:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17: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 0x7f728fa15f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23de8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ab850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a6370>, '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 0x24a98f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24a9d80>, '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': '41a7e32711b8e08598a63237000001d6-1471729468.226553-397249985', '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 15:17: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 15:17: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 15:17:16 INFO - Using env: {'DISPLAY': ':0', 15:17:16 INFO - 'HOME': '/home/cltbld', 15:17:16 INFO - 'LANG': 'en_US.UTF-8', 15:17:16 INFO - 'LANGUAGE': 'en_US:en', 15:17:16 INFO - 'LOGNAME': 'cltbld', 15:17:16 INFO - 'MAIL': '/var/mail/cltbld', 15:17:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:17:16 INFO - 'MOZ_NO_REMOTE': '1', 15:17:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:17:16 INFO - 'NO_EM_RESTART': '1', 15:17:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:17:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:17:16 INFO - 'PWD': '/builds/slave/test', 15:17:16 INFO - 'SHELL': '/bin/bash', 15:17:16 INFO - 'SHLVL': '1', 15:17:16 INFO - 'TERM': 'linux', 15:17:16 INFO - 'TMOUT': '86400', 15:17:16 INFO - 'USER': 'cltbld', 15:17:16 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1471729468.226553-397249985', 15:17:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:17:16 INFO - '_': '/tools/buildbot/bin/python'} 15:17:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:17:16 INFO - Downloading/unpacking jsonschema==2.5.1 15:17: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 15:17: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 15:17: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 15:17: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 15:17: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 15:17: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 15:17:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:17:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:17: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 15:17: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 15:17: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 15:17: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 15:17: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 15:17: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 15:17:19 INFO - Downloading functools32-3.2.3-2.tar.gz 15:17:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:17:19 INFO - warning: no files found matching '*.txt' 15:17:19 INFO - no previously-included directories found matching 'build' 15:17:19 INFO - no previously-included directories found matching 'dist' 15:17:19 INFO - no previously-included directories found matching '.git*' 15:17:19 INFO - Installing collected packages: jsonschema, functools32 15:17:19 INFO - Running setup.py install for functools32 15:17:19 INFO - warning: no files found matching '*.txt' 15:17:19 INFO - no previously-included directories found matching 'build' 15:17:19 INFO - no previously-included directories found matching 'dist' 15:17:19 INFO - no previously-included directories found matching '.git*' 15:17:19 INFO - Successfully installed jsonschema functools32 15:17:19 INFO - Cleaning up... 15:17:19 INFO - Return code: 0 15:17:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:17: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')]} 15:17:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:17:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17: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')]} 15:17:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:17:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17: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 0x7f728fa15f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23de8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ab850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a6370>, '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 0x24a98f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24a9d80>, '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': '41a7e32711b8e08598a63237000001d6-1471729468.226553-397249985', '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 15:17: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 15:17: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 15:17:19 INFO - Using env: {'DISPLAY': ':0', 15:17:19 INFO - 'HOME': '/home/cltbld', 15:17:19 INFO - 'LANG': 'en_US.UTF-8', 15:17:19 INFO - 'LANGUAGE': 'en_US:en', 15:17:19 INFO - 'LOGNAME': 'cltbld', 15:17:19 INFO - 'MAIL': '/var/mail/cltbld', 15:17:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:17:19 INFO - 'MOZ_NO_REMOTE': '1', 15:17:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:17:19 INFO - 'NO_EM_RESTART': '1', 15:17:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:17:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:17:19 INFO - 'PWD': '/builds/slave/test', 15:17:19 INFO - 'SHELL': '/bin/bash', 15:17:19 INFO - 'SHLVL': '1', 15:17:19 INFO - 'TERM': 'linux', 15:17:19 INFO - 'TMOUT': '86400', 15:17:19 INFO - 'USER': 'cltbld', 15:17:19 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1471729468.226553-397249985', 15:17:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:17:19 INFO - '_': '/tools/buildbot/bin/python'} 15:17:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:17:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:17:19 INFO - Cleaning up... 15:17:19 INFO - Return code: 0 15:17:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:17: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')]} 15:17:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:17:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17: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')]} 15:17:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:17:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17: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 0x7f728fa15f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23de8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ab850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a6370>, '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 0x24a98f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24a9d80>, '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': '41a7e32711b8e08598a63237000001d6-1471729468.226553-397249985', '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 15:17: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 15:17: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 15:17:19 INFO - Using env: {'DISPLAY': ':0', 15:17:19 INFO - 'HOME': '/home/cltbld', 15:17:19 INFO - 'LANG': 'en_US.UTF-8', 15:17:19 INFO - 'LANGUAGE': 'en_US:en', 15:17:19 INFO - 'LOGNAME': 'cltbld', 15:17:19 INFO - 'MAIL': '/var/mail/cltbld', 15:17:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:17:19 INFO - 'MOZ_NO_REMOTE': '1', 15:17:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:17:19 INFO - 'NO_EM_RESTART': '1', 15:17:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:17:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:17:19 INFO - 'PWD': '/builds/slave/test', 15:17:19 INFO - 'SHELL': '/bin/bash', 15:17:19 INFO - 'SHLVL': '1', 15:17:19 INFO - 'TERM': 'linux', 15:17:19 INFO - 'TMOUT': '86400', 15:17:19 INFO - 'USER': 'cltbld', 15:17:19 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1471729468.226553-397249985', 15:17:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:17:19 INFO - '_': '/tools/buildbot/bin/python'} 15:17:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:17:19 INFO - Downloading/unpacking blobuploader==1.2.4 15:17: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 15:17: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 15:17: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 15:17: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 15:17: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 15:17: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 15:17:21 INFO - Downloading blobuploader-1.2.4.tar.gz 15:17:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:17:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:17: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 15:17: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 15:17: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 15:17: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 15:17: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 15:17: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 15:17:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:17:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:17: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 15:17: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 15:17: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 15:17: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 15:17: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 15:17: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 15:17:22 INFO - Downloading docopt-0.6.1.tar.gz 15:17:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:17:22 INFO - Installing collected packages: blobuploader, requests, docopt 15:17:22 INFO - Running setup.py install for blobuploader 15:17:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:17:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:17:22 INFO - Running setup.py install for requests 15:17:22 INFO - Running setup.py install for docopt 15:17:22 INFO - Successfully installed blobuploader requests docopt 15:17:22 INFO - Cleaning up... 15:17:23 INFO - Return code: 0 15:17:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:17:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:17:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:17:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:17:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:17:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:23 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 0x7f728fa15f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23de8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ab850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a6370>, '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 0x24a98f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24a9d80>, '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': '41a7e32711b8e08598a63237000001d6-1471729468.226553-397249985', '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 15:17:23 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 15:17:23 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 15:17:23 INFO - Using env: {'DISPLAY': ':0', 15:17:23 INFO - 'HOME': '/home/cltbld', 15:17:23 INFO - 'LANG': 'en_US.UTF-8', 15:17:23 INFO - 'LANGUAGE': 'en_US:en', 15:17:23 INFO - 'LOGNAME': 'cltbld', 15:17:23 INFO - 'MAIL': '/var/mail/cltbld', 15:17:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:17:23 INFO - 'MOZ_NO_REMOTE': '1', 15:17:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:17:23 INFO - 'NO_EM_RESTART': '1', 15:17:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:17:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:17:23 INFO - 'PWD': '/builds/slave/test', 15:17:23 INFO - 'SHELL': '/bin/bash', 15:17:23 INFO - 'SHLVL': '1', 15:17:23 INFO - 'TERM': 'linux', 15:17:23 INFO - 'TMOUT': '86400', 15:17:23 INFO - 'USER': 'cltbld', 15:17:23 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1471729468.226553-397249985', 15:17:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:17:23 INFO - '_': '/tools/buildbot/bin/python'} 15:17:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:17:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:17:23 INFO - Running setup.py (path:/tmp/pip-HEVJ4N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:17:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:17:23 INFO - Running setup.py (path:/tmp/pip-jBE_E_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:17:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:17:23 INFO - Running setup.py (path:/tmp/pip-g6kP4b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:17:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:17:23 INFO - Running setup.py (path:/tmp/pip-_bIn2j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:17:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:17:23 INFO - Running setup.py (path:/tmp/pip-6JSEzp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:17:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:17:23 INFO - Running setup.py (path:/tmp/pip-CdjTfi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:17:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:17:23 INFO - Running setup.py (path:/tmp/pip-DqVWfA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:17:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:17:23 INFO - Running setup.py (path:/tmp/pip-KEjBmk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:17:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:17:23 INFO - Running setup.py (path:/tmp/pip-FBXK8h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:17:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:17:23 INFO - Running setup.py (path:/tmp/pip-zFxtrp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:17:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:17:23 INFO - Running setup.py (path:/tmp/pip-FJMz3f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:17:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:17:24 INFO - Running setup.py (path:/tmp/pip-oJ6Ri2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:17:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:17:24 INFO - Running setup.py (path:/tmp/pip-d2o4wB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:17:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:17:24 INFO - Running setup.py (path:/tmp/pip-YsdrW3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:17:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:17:24 INFO - Running setup.py (path:/tmp/pip-kKw1Ln-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:17:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:17:24 INFO - Running setup.py (path:/tmp/pip-Z6p1Re-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:17:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:17:24 INFO - Running setup.py (path:/tmp/pip-FRuNyx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:17:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:17:24 INFO - Running setup.py install for manifestparser 15:17:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:17:24 INFO - Running setup.py install for mozcrash 15:17:24 INFO - Running setup.py install for mozdebug 15:17:24 INFO - Running setup.py install for mozdevice 15:17:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:17:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:17:24 INFO - Running setup.py install for mozfile 15:17:25 INFO - Running setup.py install for mozhttpd 15:17:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:17:25 INFO - Running setup.py install for mozinfo 15:17:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:17:25 INFO - Running setup.py install for mozInstall 15:17:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:17:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:17:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:17:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:17:25 INFO - Running setup.py install for mozleak 15:17:25 INFO - Running setup.py install for mozlog 15:17:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:17:25 INFO - Running setup.py install for moznetwork 15:17:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:17:25 INFO - Running setup.py install for mozprocess 15:17:25 INFO - Running setup.py install for mozprofile 15:17:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:17:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:17:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:17:25 INFO - Running setup.py install for mozrunner 15:17:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:17:25 INFO - Running setup.py install for mozscreenshot 15:17:26 INFO - Running setup.py install for moztest 15:17:26 INFO - Running setup.py install for mozversion 15:17:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:17:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:17:26 INFO - Cleaning up... 15:17:26 INFO - Return code: 0 15:17:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:17: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')]} 15:17:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:17:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17: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')]} 15:17:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:17:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17: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 0x7f728fa15f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23de8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ab850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a6370>, '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 0x24a98f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24a9d80>, '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': '41a7e32711b8e08598a63237000001d6-1471729468.226553-397249985', '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 15:17: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 15:17: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 15:17:26 INFO - Using env: {'DISPLAY': ':0', 15:17:26 INFO - 'HOME': '/home/cltbld', 15:17:26 INFO - 'LANG': 'en_US.UTF-8', 15:17:26 INFO - 'LANGUAGE': 'en_US:en', 15:17:26 INFO - 'LOGNAME': 'cltbld', 15:17:26 INFO - 'MAIL': '/var/mail/cltbld', 15:17:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:17:26 INFO - 'MOZ_NO_REMOTE': '1', 15:17:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:17:26 INFO - 'NO_EM_RESTART': '1', 15:17:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:17:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:17:26 INFO - 'PWD': '/builds/slave/test', 15:17:26 INFO - 'SHELL': '/bin/bash', 15:17:26 INFO - 'SHLVL': '1', 15:17:26 INFO - 'TERM': 'linux', 15:17:26 INFO - 'TMOUT': '86400', 15:17:26 INFO - 'USER': 'cltbld', 15:17:26 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1471729468.226553-397249985', 15:17:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:17:26 INFO - '_': '/tools/buildbot/bin/python'} 15:17:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:17:26 INFO - Running setup.py (path:/tmp/pip-ianRA2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:17: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)) 15:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:17:26 INFO - Running setup.py (path:/tmp/pip-Lqi54j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:17: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)) 15:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:17:26 INFO - Running setup.py (path:/tmp/pip-UrE8fd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:17: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)) 15:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:17:26 INFO - Running setup.py (path:/tmp/pip-n6K_VV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:17: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)) 15:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:17:26 INFO - Running setup.py (path:/tmp/pip-uAZB6D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:17: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)) 15:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:17:26 INFO - Running setup.py (path:/tmp/pip-mTYYC6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:17: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)) 15:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:17:26 INFO - Running setup.py (path:/tmp/pip-Krl0ct-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:17:27 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)) 15:17:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:17:27 INFO - Running setup.py (path:/tmp/pip-iEAChq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:17: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)) 15:17:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:17:27 INFO - Running setup.py (path:/tmp/pip-w4ssFI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:17: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)) 15:17:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:17:27 INFO - Running setup.py (path:/tmp/pip-vow58L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:17: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)) 15:17:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:17:27 INFO - Running setup.py (path:/tmp/pip-VT4nH3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:17: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)) 15:17:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:17:27 INFO - Running setup.py (path:/tmp/pip-50fY9p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:17: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)) 15:17:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:17:27 INFO - Running setup.py (path:/tmp/pip-5l6Ysf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:17: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)) 15:17:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:17:27 INFO - Running setup.py (path:/tmp/pip-eBxhTP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:17: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)) 15:17:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:17:27 INFO - Running setup.py (path:/tmp/pip-6Bh41W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:17: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)) 15:17:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:17:27 INFO - Running setup.py (path:/tmp/pip-N6Bo5n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:17: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)) 15:17:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:17:27 INFO - Running setup.py (path:/tmp/pip-8cLmet-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:17: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)) 15:17: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)) 15:17: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)) 15:17: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)) 15:17: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)) 15:17: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)) 15:17:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:17: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 15:17: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 15:17: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 15:17: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 15:17: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 15:17: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 15:17:29 INFO - Downloading blessings-1.6.tar.gz 15:17:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:17:29 INFO - Installing collected packages: blessings 15:17:29 INFO - Running setup.py install for blessings 15:17:29 INFO - Successfully installed blessings 15:17:29 INFO - Cleaning up... 15:17:29 INFO - Return code: 0 15:17:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:17:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:17:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:17:30 INFO - Reading from file tmpfile_stdout 15:17:30 INFO - Current package versions: 15:17:30 INFO - argparse == 1.2.1 15:17:30 INFO - blessings == 1.6 15:17:30 INFO - blobuploader == 1.2.4 15:17:30 INFO - docopt == 0.6.1 15:17:30 INFO - functools32 == 3.2.3-2 15:17:30 INFO - jsonschema == 2.5.1 15:17:30 INFO - manifestparser == 1.1 15:17:30 INFO - mozInstall == 1.12 15:17:30 INFO - mozcrash == 0.17 15:17:30 INFO - mozdebug == 0.1 15:17:30 INFO - mozdevice == 0.48 15:17:30 INFO - mozfile == 1.2 15:17:30 INFO - mozhttpd == 0.7 15:17:30 INFO - mozinfo == 0.9 15:17:30 INFO - mozleak == 0.1 15:17:30 INFO - mozlog == 3.3 15:17:30 INFO - moznetwork == 0.27 15:17:30 INFO - mozprocess == 0.23 15:17:30 INFO - mozprofile == 0.28 15:17:30 INFO - mozrunner == 6.12 15:17:30 INFO - mozscreenshot == 0.1 15:17:30 INFO - mozsystemmonitor == 0.3 15:17:30 INFO - moztest == 0.7 15:17:30 INFO - mozversion == 1.4 15:17:30 INFO - psutil == 3.1.1 15:17:30 INFO - requests == 1.2.3 15:17:30 INFO - wsgiref == 0.1.2 15:17:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:17: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')]} 15:17:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:17:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17: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')]} 15:17:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:17:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17: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 0x7f728fa15f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23de8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ab850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a6370>, '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 0x24a98f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24a9d80>, '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': '41a7e32711b8e08598a63237000001d6-1471729468.226553-397249985', '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 15:17: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 15:17: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 15:17:30 INFO - Using env: {'DISPLAY': ':0', 15:17:30 INFO - 'HOME': '/home/cltbld', 15:17:30 INFO - 'LANG': 'en_US.UTF-8', 15:17:30 INFO - 'LANGUAGE': 'en_US:en', 15:17:30 INFO - 'LOGNAME': 'cltbld', 15:17:30 INFO - 'MAIL': '/var/mail/cltbld', 15:17:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:17:30 INFO - 'MOZ_NO_REMOTE': '1', 15:17:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:17:30 INFO - 'NO_EM_RESTART': '1', 15:17:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:17:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:17:30 INFO - 'PWD': '/builds/slave/test', 15:17:30 INFO - 'SHELL': '/bin/bash', 15:17:30 INFO - 'SHLVL': '1', 15:17:30 INFO - 'TERM': 'linux', 15:17:30 INFO - 'TMOUT': '86400', 15:17:30 INFO - 'USER': 'cltbld', 15:17:30 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1471729468.226553-397249985', 15:17:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:17:30 INFO - '_': '/tools/buildbot/bin/python'} 15:17:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:17: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)) 15:17: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)) 15:17: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)) 15:17: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)) 15:17: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)) 15:17: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)) 15:17: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)) 15:17: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)) 15:17: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)) 15:17: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)) 15:17: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)) 15:17:30 INFO - Cleaning up... 15:17:30 INFO - Return code: 0 15:17:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:17: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')]} 15:17:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:17:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17: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')]} 15:17:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:17:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17: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 0x7f728fa15f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23de8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ab850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a6370>, '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 0x24a98f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24a9d80>, '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': '41a7e32711b8e08598a63237000001d6-1471729468.226553-397249985', '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 15:17: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 15:17: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 15:17:30 INFO - Using env: {'DISPLAY': ':0', 15:17:30 INFO - 'HOME': '/home/cltbld', 15:17:30 INFO - 'LANG': 'en_US.UTF-8', 15:17:30 INFO - 'LANGUAGE': 'en_US:en', 15:17:30 INFO - 'LOGNAME': 'cltbld', 15:17:30 INFO - 'MAIL': '/var/mail/cltbld', 15:17:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:17:30 INFO - 'MOZ_NO_REMOTE': '1', 15:17:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:17:30 INFO - 'NO_EM_RESTART': '1', 15:17:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:17:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:17:30 INFO - 'PWD': '/builds/slave/test', 15:17:30 INFO - 'SHELL': '/bin/bash', 15:17:30 INFO - 'SHLVL': '1', 15:17:30 INFO - 'TERM': 'linux', 15:17:30 INFO - 'TMOUT': '86400', 15:17:30 INFO - 'USER': 'cltbld', 15:17:30 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1471729468.226553-397249985', 15:17:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:17:30 INFO - '_': '/tools/buildbot/bin/python'} 15:17:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:17:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:17:30 INFO - Cleaning up... 15:17:30 INFO - Return code: 0 15:17:30 INFO - Running post-action listener: _resource_record_post_action 15:17:30 INFO - Running post-action listener: _start_resource_monitoring 15:17:30 INFO - Starting resource monitoring. 15:17:30 INFO - [mozharness: 2016-08-20 22:17:30.561163Z] Finished create-virtualenv step (success) 15:17:30 INFO - [mozharness: 2016-08-20 22:17:30.561853Z] Running install step. 15:17:30 INFO - Running pre-action listener: _resource_record_pre_action 15:17:30 INFO - Running main action method: install 15:17:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:17:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:17:30 INFO - Reading from file tmpfile_stdout 15:17:30 INFO - Detecting whether we're running mozinstall >=1.0... 15:17:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:17:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:17:30 INFO - Reading from file tmpfile_stdout 15:17:30 INFO - Output received: 15:17:30 INFO - Usage: mozinstall [options] installer 15:17:30 INFO - Options: 15:17:30 INFO - -h, --help show this help message and exit 15:17:30 INFO - -d DEST, --destination=DEST 15:17:30 INFO - Directory to install application into. [default: 15:17:30 INFO - "/builds/slave/test"] 15:17:30 INFO - --app=APP Application being installed. [default: firefox] 15:17:30 INFO - mkdir: /builds/slave/test/build/application 15:17: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'] 15:17: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 15:17:47 INFO - Reading from file tmpfile_stdout 15:17:47 INFO - Output received: 15:17:47 INFO - /builds/slave/test/build/application/firefox/firefox 15:17:47 INFO - Running post-action listener: _resource_record_post_action 15:17:47 INFO - [mozharness: 2016-08-20 22:17:47.818137Z] Finished install step (success) 15:17:47 INFO - [mozharness: 2016-08-20 22:17:47.818381Z] Running run-tests step. 15:17:47 INFO - Running pre-action listener: _resource_record_pre_action 15:17:47 INFO - Running main action method: run_tests 15:17:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:17:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:17:47 INFO - Python 2.7.3 15:17:47 INFO - Return code: 0 15:17:47 INFO - grabbing minidump binary from tooltool 15:17: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')]} 15:17: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 0x24a6370>, '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 0x24a98f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24a9d80>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:17: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 15:17: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 15:17: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 15:17:47 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:17:47 INFO - Return code: 0 15:17:47 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:17:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:17:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:17:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:17:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:17:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RdHYxEDuQwC1aLag9uq6yA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--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 15:17:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RdHYxEDuQwC1aLag9uq6yA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-024 --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 15:17:47 INFO - Using env: {'DISPLAY': ':0', 15:17:47 INFO - 'HOME': '/home/cltbld', 15:17:47 INFO - 'LANG': 'en_US.UTF-8', 15:17:47 INFO - 'LANGUAGE': 'en_US:en', 15:17:47 INFO - 'LOGNAME': 'cltbld', 15:17:47 INFO - 'MAIL': '/var/mail/cltbld', 15:17:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:17:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:17:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:17:47 INFO - 'MOZ_NO_REMOTE': '1', 15:17:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:17:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:17:47 INFO - 'NO_EM_RESTART': '1', 15:17:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:17:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:17:47 INFO - 'PWD': '/builds/slave/test', 15:17:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:17:47 INFO - 'SHELL': '/bin/bash', 15:17:47 INFO - 'SHLVL': '1', 15:17:47 INFO - 'TERM': 'linux', 15:17:47 INFO - 'TMOUT': '86400', 15:17:47 INFO - 'USER': 'cltbld', 15:17:47 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1471729468.226553-397249985', 15:17:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:17:47 INFO - '_': '/tools/buildbot/bin/python'} 15:17:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RdHYxEDuQwC1aLag9uq6yA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--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 15:17:48 INFO - mozversion application_buildid: 20160820123003 15:17:48 INFO - mozversion application_changeset: 3f3459b7c54ac0225037edc6665113603b85e15c 15:17:48 INFO - mozversion application_display_name: Nightly 15:17:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:17:48 INFO - mozversion application_name: Firefox 15:17:48 INFO - mozversion application_remotingname: firefox 15:17:48 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 15:17:48 INFO - mozversion application_vendor: Mozilla 15:17:48 INFO - mozversion application_version: 51.0a1 15:17:48 INFO - mozversion platform_buildid: 20160820123003 15:17:48 INFO - mozversion platform_changeset: 3f3459b7c54ac0225037edc6665113603b85e15c 15:17:48 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 15:17:48 INFO - mozversion platform_version: 51.0a1 15:17:48 INFO - using testdate: 1471731468 15:17:48 INFO - actual date: 1471731468 15:17:48 INFO - starting webserver on 'localhost:47447' 15:17:48 INFO - SUITE-START | Running 2 tests 15:17:48 INFO - TEST-START | dromaeo_css 15:17:48 INFO - operating with platform_type : linux_ 15:17:48 INFO - Initialising browser for dromaeo_css test... 15:17:48 INFO - TEST-INFO | started process 20093 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQ1u9gQ/profile http://localhost:47447/getInfo.html) 15:17:52 INFO - PROCESS | 20093 | __metrics Screen width/height:1600/1200 15:17:52 INFO - PROCESS | 20093 | colorDepth:24 15:17:52 INFO - PROCESS | 20093 | Browser inner width/height: 1024/697 15:17:52 INFO - PROCESS | 20093 | __metrics 15:17:53 INFO - PROCESS | 20093 | 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" 15:17:53 INFO - PROCESS | 20093 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:17:53 INFO - PROCESS | 20093 | console.error: PushService: 15:17:53 INFO - PROCESS | 20093 | stateChangeProcessEnqueue: Error transitioning state 15:17:53 INFO - PROCESS | 20093 | UnknownError 15:17:54 INFO - TEST-INFO | 20093: exit 0 15:17:54 INFO - Browser initialized. 15:17:54 INFO - Running cycle 1/1 for dromaeo_css test... 15:17:54 INFO - TEST-INFO | started process 20177 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQ1u9gQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:18:06 INFO - PROCESS | 20177 | RSS: Main: 175456256 15:18:06 INFO - PROCESS | 20177 | 15:18:57 INFO - PROCESS | 20177 | 1471731537839 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] 15:21:07 INFO - PROCESS | 20177 | Cycle 1(1): loaded http://localhost:47447/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47447/tests/dromaeo/cssquery-ext.html) 15:21:08 INFO - PROCESS | 20177 | RSS: Main: 292839424 15:21:08 INFO - PROCESS | 20177 | 15:24:10 INFO - PROCESS | 20177 | Cycle 1(1): loaded http://localhost:47447/tests/dromaeo/cssquery-ext.html (next: http://localhost:47447/tests/dromaeo/cssquery-jquery.html) 15:24:11 INFO - PROCESS | 20177 | RSS: Main: 275443712 15:24:11 INFO - PROCESS | 20177 | 15:27:13 INFO - PROCESS | 20177 | Cycle 1(1): loaded http://localhost:47447/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47447/tests/dromaeo/cssquery-mootools.html) 15:27:14 INFO - PROCESS | 20177 | RSS: Main: 251830272 15:27:14 INFO - PROCESS | 20177 | 15:30:16 INFO - PROCESS | 20177 | Cycle 1(1): loaded http://localhost:47447/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47447/tests/dromaeo/cssquery-prototype.html) 15:30:16 INFO - PROCESS | 20177 | RSS: Main: 274436096 15:30:16 INFO - PROCESS | 20177 | 15:33:19 INFO - PROCESS | 20177 | Cycle 1(1): loaded http://localhost:47447/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47447/tests/dromaeo/cssquery-yui.html) 15:33:20 INFO - PROCESS | 20177 | RSS: Main: 217047040 15:33:20 INFO - PROCESS | 20177 | 15:36:22 INFO - PROCESS | 20177 | Cycle 1(1): loaded http://localhost:47447/tests/dromaeo/cssquery-yui.html (next: http://localhost:47447/tests/dromaeo/cssquery-dojo.html) 15:36:23 INFO - PROCESS | 20177 | RSS: Main: 294801408 15:36:23 INFO - PROCESS | 20177 | 15:36:23 INFO - PROCESS | 20177 | __start_tp_report 15:36:23 INFO - PROCESS | 20177 | _x_x_mozilla_page_load 15:36:23 INFO - PROCESS | 20177 | _x_x_mozilla_page_load_details 15:36:23 INFO - PROCESS | 20177 | |i|pagename|runs| 15:36:23 INFO - PROCESS | 20177 | |0;dojo.html;3015.743661788748;3056.9235769105785;3069.9846500767485;3074.2314421394635;3087.737542308904;5208.609354298434;5282.20766884967;5301.946980530193;5346.3584369875625;5370.91943620846;5278.419373868871;5283.445238249986;5296.973515132419;5372.7045012524395;5418.349798024233;4784.354112194861;4837.274408838676;4886;4896.387951506062;4978.377702787152;4934.705362006666;4954.603631709472;4969.950300497003;4990.101781679296;5002.549770520653;4722.574968252839;4763.190257656207;4765.213739732974;4836.250381190921;4855.732934688591;4845.248986407112;4851.029794041206;4859.611231101539;4926.4088309403105;4927.162382395001;4872.439669438025;4874.073925954058;4912.484189160158;4913.31213630092;4914;5194.168932970744;5226.346706661668;5291.259223708684;5302;5349.22436246742;5667.886642267132;5673.546116310686;5694.943050569483;5704.201411802351;5712.685802280873;5484.067708489566;5740.196372507852;5747.540196784247;5754.122938530734;5759.971200144015;5223.164293712987;5273.050850846846;5276.709780962084;5323.653962492425;5328.227407025959;5827.621204621686;5829.766809327664;5831.271091113611;5837.241158649348;5838.065909454467;5506.531944784658;5601.467860553241;5608.298962629671;5609.354924183689;5616.634918730351;5649.548036157097;5673.914891276551;5699.829005129853;5753.597248192586;5755.769769209269;5701.173329867146;5702.857428564319;5704.458076482728;5712.6286791358425;5718.1136923776885;5480.068388373986;5655.745491452894;5724.685142317129;5730.627509211888;5736.311642602831;4340.283853164256;4372;4378.474583050054;4411.625011873963;4422.270325396274;3930.331843586597;3933.7639741615594;3937.370020796659;3944.15200731844;3947.1710940702774;5164.8192313268855;5321.388040375329;5334.253204551288;5335.3864305604575;5339.652922560022;5669.34802497718;5681.176229446707;5713.914291285634;5752.453516915886;5752.712364381764;5360.517553420211;5383.2194331821665;5389.946100539023;5395.78416863321;5398.73006349681;4825.638077144228;4996.850094497098;5029.59763218949;5032.924506132411;5035.899282014339;5711.828645140652;5792.623479473821;5795.072788353843;5862.530997520103;5863.179154918314;5661.094224923992;5662.7734890603915;5665.065264231355;5667.773289068391;5686.061799802987;5666.263385759825;5841.065429531254;5843.357230704704;5849.444302791228;5850.210221620197;5515.145152501369;5558.249636299048;5560.193771903132;5628.943710562842;5640.125780504029;5704.172894930295;5728.026235540052;5730.570207234391;5737.225474561048;5746.712664366849;5435.048866448276;5578.107502799533;5580.776768929198;5588.273524441796;5596.216529685928;5442.891142177214;5475.315585551806;5488.780448782164;5546.168074788814;5557.36090349615;5505.201745746925;5506.889862202814;5517.724113794214;5520.66875987442;5520.806771762969;5481.232627432242;5606.523445507178;5610.803621873215;5621.4659607337235;5623.718814059363;5269;5271.525562699376;5290.25936368901;5293.761780719954;5336.332958380202 15:36:23 INFO - PROCESS | 20177 | |1;ext.html;564.1256053117667;585.4876982640187;592.3306663470278;603.5563860562484;613.4908026338139;32254.67745322546;32772;43350.34974475382;44654.33018504717;44738.99999999992;42568.574314256846;45987.31019034709;46046.309305360366;46047.079058418814;46052.0789584209;8843.380963332569;9038.548072596377;9040.276777857789;9046.321525885553;9052.728418147433;8903.287736981058;8982.37123401362;9006.369554131214;9007.729768106967;9009.909900901006;9024.639014439415;9093.227075698573;9105.63577456901;9105.817883642323;9113.225375843029;8965.686200982967;9044.09559044097;9050;9051.773705657346;9064.093590640916;8923.241524470428;8987.236084932789;8988.820223595492;8989.505577193251;8994.775130621721;42580.57419425797;42794;43025.35461968073;43085;43090.35364469535;3512.8243587820507;3740.6446387593137;3811.942820857689;3839.8903739598522;3867.3425517662067;30045.399092018037;30104.69895301063;32581.58056763802;33312;33318.16704582405;4159.230542349675;4203.26442872496;4250.808713607895;4285.849995250152;4426.358178064195;347680;348211;349432;350509;350532;58315.70842145847;58459.70770146122;58630;58684;58718.41281587215;63174.368256317764;63197;63241.683791581665;63276;63297.683511582145;15858.286377113058;16043.43847965339;16059.357625695075;16060;16069.598260043593;13116.606501804961;13213.73572528544;13231.933840330737;13235.205887646773;13244.73510529784;16951.06936020799;19006.429807105807;19087.52281192981;19157.712634310497;19272;7020.719171233196;7066.3993560546905;7085.645717714094;7135.5361901476235;7155.606441645759;763.1948294549304;1043.7025447747355;1054.5623566220086;1077.0952399983894;1140.0594509529506;31269;33440;33606.15984600405;33607.327853442795;33810.154746131055;1117.1398023521842;1158.3223814068695;1174.4949671641104;1187.0562902492538;1206.2762342594374;17233.913830430767;17389.217485213198;17461.126943652765;17649.117544123;17656.823431765773;552530;557852;560588;560992;562338;59382.70308648429;59964.100538491955;60024.69987650121;60037;60067.09899351513;1248.4257241668752;1355.715299787036;1372.8811019019467;1400.6008287637974;1407.7606806842862;31318.68681313158;33012.66987330192;33940;34287.82856085704;34352.82823585866;50647.49352506575;51090;51153.488465114875;51164;51177.23234151565;372903;375028;376159;376191;376473;28551.714482855736;29453.116406507837;29734.553981690726;30403.695963040085;30429.84785076149;47948.280775787694;48814.267785983946;48852.755736221094;49105.26342104943;49165.88762854232;38119.61880381161;40695.29346705203;42347.57652423436;42580.148397032506;43399.34900976425;40574.59425405708;41427.37858932058;41805.961746375295;42523.574764251964;42990.14019719651;41516.79241603773;41611.16777664491;42184.789076054425;42677.35983960181;43366.132677345646;34608;36376.272474549834;36913;37254.06864828401;37871.24257514889;28403.611904725927;28475.145745627666;28866.567001495416;29043.419131617673;29047.854760726063 15:36:23 INFO - PROCESS | 20177 | |2;jquery.html;379.1752937361239;389.2759467390653;390.24682363039324;399.4088748651996;419.08639166616763;14500.274986250684;14963.551093467186;18415.26338946442;19260.903695481502;19277.421677349666;16654.0840253786;17588.560285992855;17606.735898961524;17608.735868961943;17646.911765441157;5636.4927156555905;5928.88142237155;5938.168656388109;5941.168236446901;5946.821595352125;5871.970640146793;7080.362767350937;7299.562026278414;7333.853322933538;7446.032015837934;7080.29197080293;7243.7102515899305;7324.267573242686;7339.119305683299;7339.633018349088;7170.426365890717;7247.094113235846;7250.456215783811;7395.815104622374;7413.332800048021;7274.854502909912;7343.082114735658;7358.300961408658;7371.815704607374;7374.520656157332;26075.73924260752;27244.45511089787;27559.586606200923;28070.298242543897;28328.716712832815;2601.297649634588;2602.10227471522;2723.564229723235;2788.63747712796;2970.1831996201004;15382.846171538253;16469.505914822574;16624;16699.248533816008;16930.407435739813;1951.268274397101;1968.2127149140315;2006.7290915726337;2042.5710600773855;2061.9690704639443;98416.50791746067;102851.28615353804;103251.54478372958;105310;107811;17778.555536111442;18053;18149.546261343574;18405;18595.67554055743;32128.518072228937;32169;32226;32412.189695257806;32441;9673.419594824192;9717.951410242904;9735;9850.753731156778;10026.849597256047;7894.802629934297;7987.400944929153;8155.469894456842;8204.753857384288;8260.917390826135;8482.789677722434;9100.860203229875;9106.590203440861;9267.721968340826;9341.532923353805;3312.337532493511;3348.380549598332;3370.8146051966887;3419.6409377015552;3430.8456119474686;934.7336009237471;965.753732798132;1007.2646967713464;1071.8820929697727;1087.1357270969597;19826.90086549558;20036.499087522927;20101.798982010285;20189.697154542693;20654.46554949582;975.2490582251627;979.2215188924822;980.9362391444533;1002.1131298800614;1012.2458768217725;10592.417417041983;11073.335599864035;11167.329960202414;11187.2031992002;11263.811637941819;455274;487496;497044;500757;509997;89972.5501372489;93249.5337523308;93767.53116234374;98073;98585;961.1349785193382;999.1756800639502;1012.3116280929039;1020.5458570935863;1023.6059117239917;17379.826201738328;17973.640527189644;18157.81842181614;18538.25846966106;18576.071196439854;41034.589654103074;42405;42684;42842.57157428386;43614.345784813886;57573;58194.709026456294;58620;58661.41338586559;59270;7369.815754605963;7509.136449308181;7782.649780759993;8223.95888020556;8419.284360829399;9474.715758527254;12887.291198984145;12996.740065198832;13649.181049137083;14702.55892323232;11317.207795454238;11396.145289103517;11480.598179063692;11832.290062596272;11853.940730296637;11835.644930652094;11856.525738970344;11874.346910919638;12181.939090304491;12524.874751252371;12148.331841748435;12203.69490762738;12411.379431028232;12461.87538124607;12471.563495277622;8671.34964877624;8734.606942687722;8767.824643507221;9014.368994170607;9129.132732390412;11962.880371196177;12269.75460490803;12282.324472154118;12289.139760217056;12318.507259709511 15:36:23 INFO - PROCESS | 20177 | |3;mootools.html;1348.2315080404173;1459.2557795524285;1484.8218213814341;1565.420794306107;1704.7357659562772;7675.731349402765;8528.317734581235;8882.333824963122;8892.599833007514;9114.270858331318;8853.911460885389;8856.690015849446;8892.95553522233;8897.377183597151;8967.686130985418;2350.9412264693347;2383.4756353602265;2383.6067048936907;2383.940401489959;2384.2132096408145;2310.202979971907;2331.1491305673394;2390;2396;2399.796017338528;2245.9663105053355;2247.5729611373868;2266.0709109265204;2374.1809075868796;2385.439421735891;2238.7649296823924;2283.5090455552054;2289.381866895937;2291.4844160064017;2328.6157783965627;2294.4608017115966;2305.4236440889777;2305.7117860267467;2307.8038366738847;2312.757160498157;8622.439541429787;8938;9009.099090090938;9032.50321232332;9043.140901614335;5067.239914012891;5221.138512145491;5293.497117773805;5298.629095963284;5306.469353064663;3444.207832198583;3452.5339079224236;3486.1981744198974;3500.1249687578106;3509.31568344173;1173.3722458484756;1178.935158566287;1179.5399794080315;1188.4889497516065;1205.1925210109248;12497.687557811036;12632.621021369374;12669.93665031669;12703.174293670974;12737.172083814616;4517.11916176349;4658.813647454132;4805.663603547718;4862.489438608965;4902.117618828645;10557.894421055691;10570.942905709368;10571.418571978615;10582.047615714622;10582.894171058344;5266.578673706094;5272.024675435056;5273.156294992783;5277.604179686462;5278.656887302313;4323.573220837116;4660.067986402665;4726.054789042205;4766.380370551806;4795.424549054136;4015.1367455996515;4035.4753881995152;4105.815238314283;4140.23405669952;4164.770937598461;1286.2411177405377;1296.61749783814;1298.6363818130937;1311.7901135818224;1313.7109835836102;916.7524768312652;923.2429407885604;927.174814415171;936.8969413364524;958.9472579008221;5720.513756330675;5819.359870414251;5846.356900740915;5847.853803654943;6137.693115344215;444.48883783649063;446.87040758180416;449.9122671079108;455.8860284928768;463.0923390155266;4033.5361433435532;4069.9389509157386;4107.137501124737;4126.628603425706;4148.419221309019;10415.375077495375;11098.167637427065;11179.888201118047;11187.552497899993;11228.887711122785;10956.945215273872;11146.944265278622;11175.72060698489;11185.66443006711;11191.776164476827;8085.231902969209;8231.218034286732;8249.68005119178;8252.133525979685;8277.130901255523;5705.258316418839;5825.563082768725;5878.647281163144;5909.172715819875;5989.790357337472;10113.898861011296;10183.236257280585;10185.847212291674;10196;10267.94866025665;9836.409815411098;10239.436830974368;10258.897411026093;10533.262671612914;10605.840912386166;6964.616946068015;7909.881351779843;7987.32107770846;8083.595820208849;8196.67213311461;8069.677212911418;8453.365997550085;8466.619002144795;8467.195616416431;8499.8725019126;7353.374963128194;7400.333969942731;7456.32893039629;7521.849563008818;7670.654820533201;7112.893306600508;7251.057362542836;7389.8152546186775;7397.741079062207;7540.17058123617;6215.4772080838675;6227.688615569294;6278.46633036197;6282.591631543935;6283.277423096402;4990.600751939909;5005.074061298671;5057.140286151437;5086.542211200862;5135.8429459626295;4853.733044682435;4902.166631672553;4975.179095449211;5070.340855688737;5070.543651071422 15:36:23 INFO - PROCESS | 20177 | |4;prototype.html;313.2466418264076;315.13024053612025;323.802480486903;325.35904268590866;326.6602733157516;4662.417197850269;4895.339129217555;5073.340465739453;5115.769790359438;5117.257997590347;5060.9878024395175;5062.443131255568;5068.783491961921;5073.260439362749;5074.264231686403;4025.919481610366;4028.67770578353;4038.212548553026;4044.8382064717525;4047.6761859051207;3875.1862108957152;3898.298306304864;4051.210014047247;4052.29084910141;4057.6348128668415;3966.3653815389544;3966.841326346943;3970.3845903884944;3985.900352491182;3995.300822356076;3962.187751510933;3965.8413663453425;3976.4234186043004;3979;3989.860354887565;3512.7189824813954;3512.89226464122;3520.718342532617;3525.876594319212;3533.258015816656;4872.65891387603;4950.851474455772;4955.801767929279;4982.701037937735;4990.6257030722845;4503.166914120898;4512.210363186429;4512.52618475062;4522.592966632986;4531.52418996004;5332.413434522238;5333.200019997032;5333.733313334318;5338.3060202173465;5347.6256662033675;3730.9067273318115;3801.650414103009;3805.334066538345;3831.2912111259507;3832.5784163741964;5263.578913686896;5334.866628334323;5513.8345849624575;5537.335519737658;5565.332160140769;3990.7206495545033;3994.960050399517;4002.4996875390575;4007.6593489553716;4020.7684734971785;4042.919141617151;4047.2310261050307;4051.088505086391;4054.3715724062818;4065.2479291331197;4451.6661250405705;4583.702059366197;4584.977075114603;4586.541345865387;4594.540545945379;5302.946970530245;5334.4398838122215;5342.1452567589;5358.2498450217;5372.274742909657;1375.7661197596433;1376.566381589796;1385.1369907474432;1385.3627331427454;1400.6358346829898;845.8223773007737;879.8825491625599;880.300161371711;888.4180861535705;896.3187977137292;4549.772511374485;4558.677983384781;4565.589096981287;4578.244589642672;4583.785296896325;4799.616270529338;4805.4233491980485;4822.397200349957;4823.971983393835;4844.709317440895;4381.649468042613;4562.908741825145;4568.908621827545;4570.520095390003;4607.9308810367875;2549.4391233928504;2560.87195640221;2564.9615255771178;2570.961435578468;2577.6777902762155;4888.462269150391;4892.437369702458;4894.975525122351;4927.285543596158;4934.827281045218;4959.668955590867;4959.90080198394;4959.975200123977;4969.726665033458;5203.583713302926;5664.291963504562;5676.489115979499;5694.800681976111;5695;5710.172025056344;5502.871911258262;5528.253685752372;5530.80642177538;5537.501624853782;5544.445555444423;5231.398389185292;5454.809081682122;5481.205225242397;5494.203340515683;5495.120780675073;5428.67427954324;5516.282883225155;5618.634788738719;5619.100943848965;5645.520130788765;3937.547182074098;3949.328614135661;3963.9405408918315;3971.7290467050266;3977.7016723745255;5522.917156242579;5529.806456773994;5533.695646739468;5539.501444869981;5541.3904470507405;5172.612054095883;5345.679259244458;5373.650712703662;5379.838604841861;5406.972965135149;5319.946800531945;5340.759665815125;5355.25026496283;5382.381026182039;5386.919196211981;5285.3879566730975;5299.44355842631;5305.787768489217;5306.920396193983;5316.175992721134;5199.4020687621405;5336.331834082958;5394.271773310553;5394.406615272238;5397.244385786072;5232.488405565426;5258.816766227568;5264.6314757966575;5266.657002464347;5267.262583238273 15:36:23 INFO - PROCESS | 20177 | |5;yui.html;672.5359501943659;721.6752461392375;742.4209116888825;771.1324759645396;822.8107535266887;13504.797428038588;14964.925175374136;14973.176475293854;14998.775018374734;15030;14022.859771402284;14116.505922292721;14144.080634758733;14149.71700565988;14281.21453320067;653.2846533046306;662.1259936883316;765.8889461028148;770.9306162445379;794.9364050875945;777.2577188784725;777.5956502618635;778.4589710151447;781.5662307419379;782.6556315221313;834.4492634860962;836.188896770132;836.6318819719335;837.6272558711375;845.5391811462737;687.05186842158;807.5033854179696;814.9266566009088;836.9623366948472;838.3544670603632;610.2158726037021;612.0665984373821;612.6599737145882;612.9141920131185;613.8250598579428;14117.78823317651;14134.646633834134;14169.079009864327;14169.645758856008;14173.078749881224;13136.86863131366;13532.526361577397;13864.930675346659;13903.374348154257;13909.79135312971;10142.645007424704;10332.173426125892;10678.733031674192;12159.331236781973;12798.104132710714;4361.542038085987;4369.9126017479475;4438;4578.221702310615;4581.289900065462;74563.62718186429;74985.62507187483;75996;76099.23900760977;76369.61815190944;10279.948600257101;10431.16550675944;10479.68560943173;10493.737656558647;10619.256652034283;20582.38252852417;20602.793972060088;20618.484537886674;20619.793802061788;20623;7819;7848.254415830487;8033.919660803317;8068.112507624157;8206.794830129294;4461.219286624892;4502.324651302265;4519.028408892038;4526.162659907927;4617.468991066004;3729.160938788805;3885.339492286318;3904.863329783501;3911;3971.72197946141;630.3822254190918;646.8932626116732;667.3493343989668;671.3655595462242;687.7180356054093;653.5294587896802;686.6978529447027;692.580988501949;704.6018999265501;712.5012491256037;9426.764330891645;9538.809223815484;9591.616335346647;9764.462954537568;9799.11807937289;689.9724011039602;703.2369878681585;723.1972510513326;727.058459295214;734.2327268004841;4445.355423463579;4619.099275641285;4683.7892294846815;4709.387779588632;4743.146233677971;74124;86683.56658216669;126548.36725816496;129269;129974;91330.69354451628;108336.4583177079;110378.9999999984;112707;113130.43434782937;91335.54332228296;95030.5248473767;95844.52077739566;96561.5171924136;96786;8491.320694344315;8660.913390866262;8848.601812918305;8886.955565222133;9015.729528114167;53575;54961;54992.72503637456;56149.15776263441;56847;81145;81343.39177427508;83434;83767.5811620938;84752;11650.475728592402;11858.822117668069;12079.75840483203;12253.938730306292;12315.507379704712;11949.522019119138;12200.389980501117;12346.259224446561;12354.25874447536;13159.144655597356;12774.23354598727;12826.935865320615;12954.676133096747;12973.351332433529;13274.80087798703;12124.878751212374;12519.687007824514;12531.248125112523;12631.368431578569;13036.934815325863;11729.882701173221;11995.399025031384;12007.819882701591;12206.32865192423;12842.67893302675;10424.995264537796;10442.084218418093;10779.353238805697;10987.39569323695;11018.228723989243;11047.337159770439;11391.715207119889;11520.711982200512;11574.937423337477;11854.881451185378 15:36:23 INFO - PROCESS | 20177 | __end_tp_report 15:36:23 INFO - PROCESS | 20177 | __start_cc_report 15:36:23 INFO - PROCESS | 20177 | _x_x_mozilla_cycle_collect,1071 15:36:23 INFO - PROCESS | 20177 | __end_cc_report 15:36:23 INFO - PROCESS | 20177 | __startTimestamp1471732583179__endTimestamp 15:36:23 INFO - PROCESS | 20177 | 15:36:23 INFO - PROCESS | 20177 | ------- Summary: start ------- 15:36:23 INFO - PROCESS | 20177 | Number of tests: 6 15:36:23 INFO - PROCESS | 20177 | 15:36:23 INFO - PROCESS | 20177 | [#0] dojo.html Cycles:165 Average:5282.03 Median:5475.32 stddev:585.16 (10.7%) stddev-sans-first:559.30 15:36:23 INFO - PROCESS | 20177 | Values: 3015.7 3056.9 3070.0 3074.2 3087.7 5208.6 5282.2 5301.9 5346.4 5370.9 5278.4 5283.4 5297.0 5372.7 5418.3 4784.4 4837.3 4886.0 4896.4 4978.4 4934.7 4954.6 4970.0 4990.1 5002.5 4722.6 4763.2 4765.2 4836.3 4855.7 4845.2 4851.0 4859.6 4926.4 4927.2 4872.4 4874.1 4912.5 4913.3 4914.0 5194.2 5226.3 5291.3 5302.0 5349.2 5667.9 5673.5 5694.9 5704.2 5712.7 5484.1 5740.2 5747.5 5754.1 5760.0 5223.2 5273.1 5276.7 5323.7 5328.2 5827.6 5829.8 5831.3 5837.2 5838.1 5506.5 5601.5 5608.3 5609.4 5616.6 5649.5 5673.9 5699.8 5753.6 5755.8 5701.2 5702.9 5704.5 5712.6 5718.1 5480.1 5655.7 5724.7 5730.6 5736.3 4340.3 4372.0 4378.5 4411.6 4422.3 3930.3 3933.8 3937.4 3944.2 3947.2 5164.8 5321.4 5334.3 5335.4 5339.7 5669.3 5681.2 5713.9 5752.5 5752.7 5360.5 5383.2 5389.9 5395.8 5398.7 4825.6 4996.9 5029.6 5032.9 5035.9 5711.8 5792.6 5795.1 5862.5 5863.2 5661.1 5662.8 5665.1 5667.8 5686.1 5666.3 5841.1 5843.4 5849.4 5850.2 5515.1 5558.2 5560.2 5628.9 5640.1 5704.2 5728.0 5730.6 5737.2 5746.7 5435.0 5578.1 5580.8 5588.3 5596.2 5442.9 5475.3 5488.8 5546.2 5557.4 5505.2 5506.9 5517.7 5520.7 5520.8 5481.2 5606.5 5610.8 5621.5 5623.7 5269.0 5271.5 5290.3 5293.8 5336.3 15:36:23 INFO - PROCESS | 20177 | 15:36:23 INFO - PROCESS | 20177 | [#1] ext.html Cycles:180 Average:59488.73 Median:42266.18 stddev:116086.53 (274.7%) stddev-sans-first:116327.88 15:36:23 INFO - PROCESS | 20177 | Values: 564.1 585.5 592.3 603.6 613.5 32254.7 32772.0 43350.3 44654.3 44739.0 42568.6 45987.3 46046.3 46047.1 46052.1 8843.4 9038.5 9040.3 9046.3 9052.7 8903.3 8982.4 9006.4 9007.7 9009.9 9024.6 9093.2 9105.6 9105.8 9113.2 8965.7 9044.1 9050.0 9051.8 9064.1 8923.2 8987.2 8988.8 8989.5 8994.8 42580.6 42794.0 43025.4 43085.0 43090.4 3512.8 3740.6 3811.9 3839.9 3867.3 30045.4 30104.7 32581.6 33312.0 33318.2 4159.2 4203.3 4250.8 4285.8 4426.4 347680.0 348211.0 349432.0 350509.0 350532.0 58315.7 58459.7 58630.0 58684.0 58718.4 63174.4 63197.0 63241.7 63276.0 63297.7 15858.3 16043.4 16059.4 16060.0 16069.6 13116.6 13213.7 13231.9 13235.2 13244.7 16951.1 19006.4 19087.5 19157.7 19272.0 7020.7 7066.4 7085.6 7135.5 7155.6 763.2 1043.7 1054.6 1077.1 1140.1 31269.0 33440.0 33606.2 33607.3 33810.2 1117.1 1158.3 1174.5 1187.1 1206.3 17233.9 17389.2 17461.1 17649.1 17656.8 552530.0 557852.0 560588.0 560992.0 562338.0 59382.7 59964.1 60024.7 60037.0 60067.1 1248.4 1355.7 1372.9 1400.6 1407.8 31318.7 33012.7 33940.0 34287.8 34352.8 50647.5 51090.0 51153.5 51164.0 51177.2 372903.0 375028.0 376159.0 376191.0 376473.0 28551.7 29453.1 29734.6 30403.7 30429.8 47948.3 48814.3 48852.8 49105.3 49165.9 38119.6 40695.3 42347.6 42580.1 43399.3 40574.6 41427.4 41806.0 42523.6 42990.1 41516.8 41611.2 42184.8 42677.4 43366.1 34608.0 36376.3 36913.0 37254.1 37871.2 28403.6 28475.1 28866.6 29043.4 29047.9 15:36:23 INFO - PROCESS | 20177 | 15:36:23 INFO - PROCESS | 20177 | [#2] jquery.html Cycles:180 Average:31074.51 Median:32426.59 stddev:81193.19 (250.4%) stddev-sans-first:81388.24 15:36:23 INFO - PROCESS | 20177 | Values: 379.2 389.3 390.2 399.4 419.1 14500.3 14963.6 18415.3 19260.9 19277.4 16654.1 17588.6 17606.7 17608.7 17646.9 5636.5 5928.9 5938.2 5941.2 5946.8 5872.0 7080.4 7299.6 7333.9 7446.0 7080.3 7243.7 7324.3 7339.1 7339.6 7170.4 7247.1 7250.5 7395.8 7413.3 7274.9 7343.1 7358.3 7371.8 7374.5 26075.7 27244.5 27559.6 28070.3 28328.7 2601.3 2602.1 2723.6 2788.6 2970.2 15382.8 16469.5 16624.0 16699.2 16930.4 1951.3 1968.2 2006.7 2042.6 2062.0 98416.5 102851.3 103251.5 105310.0 107811.0 17778.6 18053.0 18149.5 18405.0 18595.7 32128.5 32169.0 32226.0 32412.2 32441.0 9673.4 9718.0 9735.0 9850.8 10026.8 7894.8 7987.4 8155.5 8204.8 8260.9 8482.8 9100.9 9106.6 9267.7 9341.5 3312.3 3348.4 3370.8 3419.6 3430.8 934.7 965.8 1007.3 1071.9 1087.1 19826.9 20036.5 20101.8 20189.7 20654.5 975.2 979.2 980.9 1002.1 1012.2 10592.4 11073.3 11167.3 11187.2 11263.8 455274.0 487496.0 497044.0 500757.0 509997.0 89972.6 93249.5 93767.5 98073.0 98585.0 961.1 999.2 1012.3 1020.5 1023.6 17379.8 17973.6 18157.8 18538.3 18576.1 41034.6 42405.0 42684.0 42842.6 43614.3 57573.0 58194.7 58620.0 58661.4 59270.0 7369.8 7509.1 7782.6 8224.0 8419.3 9474.7 12887.3 12996.7 13649.2 14702.6 11317.2 11396.1 11480.6 11832.3 11853.9 11835.6 11856.5 11874.3 12181.9 12524.9 12148.3 12203.7 12411.4 12461.9 12471.6 8671.3 8734.6 8767.8 9014.4 9129.1 11962.9 12269.8 12282.3 12289.1 12318.5 15:36:23 INFO - PROCESS | 20177 | 15:36:23 INFO - PROCESS | 20177 | [#3] mootools.html Cycles:180 Average:5782.92 Median:2560.95 stddev:3355.30 (131.0%) stddev-sans-first:3348.16 15:36:23 INFO - PROCESS | 20177 | Values: 1348.2 1459.3 1484.8 1565.4 1704.7 7675.7 8528.3 8882.3 8892.6 9114.3 8853.9 8856.7 8893.0 8897.4 8967.7 2350.9 2383.5 2383.6 2383.9 2384.2 2310.2 2331.1 2390.0 2396.0 2399.8 2246.0 2247.6 2266.1 2374.2 2385.4 2238.8 2283.5 2289.4 2291.5 2328.6 2294.5 2305.4 2305.7 2307.8 2312.8 8622.4 8938.0 9009.1 9032.5 9043.1 5067.2 5221.1 5293.5 5298.6 5306.5 3444.2 3452.5 3486.2 3500.1 3509.3 1173.4 1178.9 1179.5 1188.5 1205.2 12497.7 12632.6 12669.9 12703.2 12737.2 4517.1 4658.8 4805.7 4862.5 4902.1 10557.9 10570.9 10571.4 10582.0 10582.9 5266.6 5272.0 5273.2 5277.6 5278.7 4323.6 4660.1 4726.1 4766.4 4795.4 4015.1 4035.5 4105.8 4140.2 4164.8 1286.2 1296.6 1298.6 1311.8 1313.7 916.8 923.2 927.2 936.9 958.9 5720.5 5819.4 5846.4 5847.9 6137.7 444.5 446.9 449.9 455.9 463.1 4033.5 4069.9 4107.1 4126.6 4148.4 10415.4 11098.2 11179.9 11187.6 11228.9 10956.9 11146.9 11175.7 11185.7 11191.8 8085.2 8231.2 8249.7 8252.1 8277.1 5705.3 5825.6 5878.6 5909.2 5989.8 10113.9 10183.2 10185.8 10196.0 10267.9 9836.4 10239.4 10258.9 10533.3 10605.8 6964.6 7909.9 7987.3 8083.6 8196.7 8069.7 8453.4 8466.6 8467.2 8499.9 7353.4 7400.3 7456.3 7521.8 7670.7 7112.9 7251.1 7389.8 7397.7 7540.2 6215.5 6227.7 6278.5 6282.6 6283.3 4990.6 5005.1 5057.1 5086.5 5135.8 4853.7 4902.2 4975.2 5070.3 5070.5 15:36:23 INFO - PROCESS | 20177 | 15:36:23 INFO - PROCESS | 20177 | [#4] prototype.html Cycles:180 Average:4412.20 Median:4911.31 stddev:1289.95 (26.3%) stddev-sans-first:1256.34 15:36:23 INFO - PROCESS | 20177 | Values: 313.2 315.1 323.8 325.4 326.7 4662.4 4895.3 5073.3 5115.8 5117.3 5061.0 5062.4 5068.8 5073.3 5074.3 4025.9 4028.7 4038.2 4044.8 4047.7 3875.2 3898.3 4051.2 4052.3 4057.6 3966.4 3966.8 3970.4 3985.9 3995.3 3962.2 3965.8 3976.4 3979.0 3989.9 3512.7 3512.9 3520.7 3525.9 3533.3 4872.7 4950.9 4955.8 4982.7 4990.6 4503.2 4512.2 4512.5 4522.6 4531.5 5332.4 5333.2 5333.7 5338.3 5347.6 3730.9 3801.7 3805.3 3831.3 3832.6 5263.6 5334.9 5513.8 5537.3 5565.3 3990.7 3995.0 4002.5 4007.7 4020.8 4042.9 4047.2 4051.1 4054.4 4065.2 4451.7 4583.7 4585.0 4586.5 4594.5 5302.9 5334.4 5342.1 5358.2 5372.3 1375.8 1376.6 1385.1 1385.4 1400.6 845.8 879.9 880.3 888.4 896.3 4549.8 4558.7 4565.6 4578.2 4583.8 4799.6 4805.4 4822.4 4824.0 4844.7 4381.6 4562.9 4568.9 4570.5 4607.9 2549.4 2560.9 2565.0 2571.0 2577.7 4888.5 4892.4 4895.0 4927.3 4934.8 4959.7 4959.9 4960.0 4969.7 5203.6 5664.3 5676.5 5694.8 5695.0 5710.2 5502.9 5528.3 5530.8 5537.5 5544.4 5231.4 5454.8 5481.2 5494.2 5495.1 5428.7 5516.3 5618.6 5619.1 5645.5 3937.5 3949.3 3963.9 3971.7 3977.7 5522.9 5529.8 5533.7 5539.5 5541.4 5172.6 5345.7 5373.7 5379.8 5407.0 5319.9 5340.8 5355.3 5382.4 5386.9 5285.4 5299.4 5305.8 5306.9 5316.2 5199.4 5336.3 5394.3 5394.4 5397.2 5232.5 5258.8 5264.6 5266.7 5267.3 15:36:23 INFO - PROCESS | 20177 | 15:36:23 INFO - PROCESS | 20177 | [#5] yui.html Cycles:180 Average:21087.90 Median:4579.76 stddev:31520.04 (688.2%) stddev-sans-first:31571.19 15:36:23 INFO - PROCESS | 20177 | Values: 672.5 721.7 742.4 771.1 822.8 13504.8 14964.9 14973.2 14998.8 15030.0 14022.9 14116.5 14144.1 14149.7 14281.2 653.3 662.1 765.9 770.9 794.9 777.3 777.6 778.5 781.6 782.7 834.4 836.2 836.6 837.6 845.5 687.1 807.5 814.9 837.0 838.4 610.2 612.1 612.7 612.9 613.8 14117.8 14134.6 14169.1 14169.6 14173.1 13136.9 13532.5 13864.9 13903.4 13909.8 10142.6 10332.2 10678.7 12159.3 12798.1 4361.5 4369.9 4438.0 4578.2 4581.3 74563.6 74985.6 75996.0 76099.2 76369.6 10279.9 10431.2 10479.7 10493.7 10619.3 20582.4 20602.8 20618.5 20619.8 20623.0 7819.0 7848.3 8033.9 8068.1 8206.8 4461.2 4502.3 4519.0 4526.2 4617.5 3729.2 3885.3 3904.9 3911.0 3971.7 630.4 646.9 667.3 671.4 687.7 653.5 686.7 692.6 704.6 712.5 9426.8 9538.8 9591.6 9764.5 9799.1 690.0 703.2 723.2 727.1 734.2 4445.4 4619.1 4683.8 4709.4 4743.1 74124.0 86683.6 126548.4 129269.0 129974.0 91330.7 108336.5 110379.0 112707.0 113130.4 91335.5 95030.5 95844.5 96561.5 96786.0 8491.3 8660.9 8848.6 8887.0 9015.7 53575.0 54961.0 54992.7 56149.2 56847.0 81145.0 81343.4 83434.0 83767.6 84752.0 11650.5 11858.8 12079.8 12253.9 12315.5 11949.5 12200.4 12346.3 12354.3 13159.1 12774.2 12826.9 12954.7 12973.4 13274.8 12124.9 12519.7 12531.2 12631.4 13036.9 11729.9 11995.4 12007.8 12206.3 12842.7 10425.0 10442.1 10779.4 10987.4 11018.2 11047.3 11391.7 11520.7 11574.9 11854.9 15:36:23 INFO - PROCESS | 20177 | -------- Summary: end -------- 15:36:23 INFO - PROCESS | 20177 | 15:36:24 INFO - TEST-INFO | 20177: exit 0 15:36:24 INFO - TEST-OK | dromaeo_css | took 1116052ms 15:36:24 INFO - TEST-START | kraken 15:36:24 INFO - operating with platform_type : linux_ 15:36:24 INFO - Initialising browser for kraken test... 15:36:24 INFO - TEST-INFO | started process 20481 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUKsFeJ/profile http://localhost:47447/getInfo.html) 15:36:28 INFO - PROCESS | 20481 | __metrics Screen width/height:1600/1200 15:36:28 INFO - PROCESS | 20481 | colorDepth:24 15:36:28 INFO - PROCESS | 20481 | Browser inner width/height: 1024/697 15:36:28 INFO - PROCESS | 20481 | __metrics 15:36:29 INFO - PROCESS | 20481 | 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" 15:36:29 INFO - PROCESS | 20481 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:36:29 INFO - PROCESS | 20481 | console.error: PushService: 15:36:29 INFO - PROCESS | 20481 | stateChangeProcessEnqueue: Error transitioning state 15:36:29 INFO - PROCESS | 20481 | UnknownError 15:36:30 INFO - TEST-INFO | 20481: exit 0 15:36:30 INFO - Browser initialized. 15:36:30 INFO - Running cycle 1/1 for kraken test... 15:36:30 INFO - TEST-INFO | started process 20562 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUKsFeJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:36:42 INFO - PROCESS | 20562 | RSS: Main: 173662208 15:36:42 INFO - PROCESS | 20562 | 15:36:45 INFO - PROCESS | 20562 | Cycle 1(1): loaded http://localhost:47447/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47447/tests/kraken/driver.html?testName=audio-beat-detection) 15:36:45 INFO - PROCESS | 20562 | RSS: Main: 234635264 15:36:45 INFO - PROCESS | 20562 | 15:36:48 INFO - PROCESS | 20562 | Cycle 1(1): loaded http://localhost:47447/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47447/tests/kraken/driver.html?testName=audio-dft) 15:36:49 INFO - PROCESS | 20562 | RSS: Main: 225382400 15:36:49 INFO - PROCESS | 20562 | 15:36:52 INFO - PROCESS | 20562 | Cycle 1(1): loaded http://localhost:47447/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47447/tests/kraken/driver.html?testName=audio-fft) 15:36:52 INFO - PROCESS | 20562 | RSS: Main: 302505984 15:36:52 INFO - PROCESS | 20562 | 15:36:55 INFO - PROCESS | 20562 | Cycle 1(1): loaded http://localhost:47447/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47447/tests/kraken/driver.html?testName=audio-oscillator) 15:36:55 INFO - PROCESS | 20562 | RSS: Main: 209539072 15:36:55 INFO - PROCESS | 20562 | 15:36:57 INFO - PROCESS | 20562 | Cycle 1(1): loaded http://localhost:47447/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47447/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:36:58 INFO - PROCESS | 20562 | RSS: Main: 212840448 15:36:58 INFO - PROCESS | 20562 | 15:37:01 INFO - PROCESS | 20562 | Cycle 1(1): loaded http://localhost:47447/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47447/tests/kraken/driver.html?testName=imaging-darkroom) 15:37:02 INFO - PROCESS | 20562 | RSS: Main: 249655296 15:37:02 INFO - PROCESS | 20562 | 15:37:05 INFO - PROCESS | 20562 | Cycle 1(1): loaded http://localhost:47447/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47447/tests/kraken/driver.html?testName=imaging-desaturate) 15:37:06 INFO - PROCESS | 20562 | RSS: Main: 248512512 15:37:06 INFO - PROCESS | 20562 | 15:37:09 INFO - PROCESS | 20562 | Cycle 1(1): loaded http://localhost:47447/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47447/tests/kraken/driver.html?testName=json-parse-financial) 15:37:09 INFO - PROCESS | 20562 | RSS: Main: 248680448 15:37:09 INFO - PROCESS | 20562 | 15:37:11 INFO - PROCESS | 20562 | Cycle 1(1): loaded http://localhost:47447/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47447/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:37:11 INFO - PROCESS | 20562 | RSS: Main: 211406848 15:37:11 INFO - PROCESS | 20562 | 15:37:13 INFO - PROCESS | 20562 | Cycle 1(1): loaded http://localhost:47447/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47447/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:37:14 INFO - PROCESS | 20562 | RSS: Main: 218943488 15:37:14 INFO - PROCESS | 20562 | 15:37:16 INFO - PROCESS | 20562 | Cycle 1(1): loaded http://localhost:47447/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47447/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:37:17 INFO - PROCESS | 20562 | RSS: Main: 222154752 15:37:17 INFO - PROCESS | 20562 | 15:37:19 INFO - PROCESS | 20562 | Cycle 1(1): loaded http://localhost:47447/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47447/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:37:20 INFO - PROCESS | 20562 | RSS: Main: 218259456 15:37:20 INFO - PROCESS | 20562 | 15:37:23 INFO - PROCESS | 20562 | Cycle 1(1): loaded http://localhost:47447/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47447/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:37:23 INFO - PROCESS | 20562 | RSS: Main: 221249536 15:37:23 INFO - PROCESS | 20562 | 15:37:25 INFO - PROCESS | 20562 | Cycle 1(1): loaded http://localhost:47447/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47447/tests/kraken/driver.html?testName=ai-astar) 15:37:25 INFO - PROCESS | 20562 | RSS: Main: 272101376 15:37:25 INFO - PROCESS | 20562 | 15:37:25 INFO - PROCESS | 20562 | __start_tp_report 15:37:25 INFO - PROCESS | 20562 | _x_x_mozilla_page_load 15:37:25 INFO - PROCESS | 20562 | _x_x_mozilla_page_load_details 15:37:25 INFO - PROCESS | 20562 | |i|pagename|runs| 15:37:25 INFO - PROCESS | 20562 | |0;ai-astar;99;110;107;112;112;111;117;112;112;111 15:37:25 INFO - PROCESS | 20562 | |1;audio-beat-detection;144;119;149;189;118;143;143;118;189;118 15:37:25 INFO - PROCESS | 20562 | |2;audio-dft;159;159;160;161;162;160;162;162;163;164 15:37:25 INFO - PROCESS | 20562 | |3;audio-fft;76;75;75;186;77;75;76;76;172;75 15:37:25 INFO - PROCESS | 20562 | |4;audio-oscillator;85;85;171;87;86;86;151;85;84;149 15:37:25 INFO - PROCESS | 20562 | |5;imaging-gaussian-blur;117;116;116;117;117;117;117;117;117;117 15:37:25 INFO - PROCESS | 20562 | |6;imaging-darkroom;145;145;145;147;145;145;146;145;145;145 15:37:25 INFO - PROCESS | 20562 | |7;imaging-desaturate;94;95;95;94;95;95;95;94;95;95 15:37:25 INFO - PROCESS | 20562 | |8;json-parse-financial;50;50;51;52;49;51;50;51;52;50 15:37:25 INFO - PROCESS | 20562 | |9;json-stringify-tinderbox;61;62;62;62;63;61;62;61;61;62 15:37:25 INFO - PROCESS | 20562 | |10;stanford-crypto-aes;78;87;79;78;82;77;77;81;78;78 15:37:25 INFO - PROCESS | 20562 | |11;stanford-crypto-ccm;132;121;133;122;124;122;129;130;142;122 15:37:25 INFO - PROCESS | 20562 | |12;stanford-crypto-pbkdf2;164;162;165;161;162;162;166;167;164;161 15:37:25 INFO - PROCESS | 20562 | |13;stanford-crypto-sha256-iterative;65;64;65;64;64;64;67;64;63;62 15:37:25 INFO - PROCESS | 20562 | __end_tp_report 15:37:25 INFO - PROCESS | 20562 | __start_cc_report 15:37:25 INFO - PROCESS | 20562 | _x_x_mozilla_cycle_collect,1671 15:37:25 INFO - PROCESS | 20562 | __end_cc_report 15:37:25 INFO - PROCESS | 20562 | __startTimestamp1471732645728__endTimestamp 15:37:25 INFO - PROCESS | 20562 | 15:37:25 INFO - PROCESS | 20562 | ------- Summary: start ------- 15:37:25 INFO - PROCESS | 20562 | Number of tests: 14 15:37:25 INFO - PROCESS | 20562 | 15:37:25 INFO - PROCESS | 20562 | [#0] ai-astar Cycles:10 Average:110.30 Median:112.00 stddev:4.67 (4.2%) stddev-sans-first:2.60 15:37:25 INFO - PROCESS | 20562 | Values: 99.0 110.0 107.0 112.0 112.0 111.0 117.0 112.0 112.0 111.0 15:37:25 INFO - PROCESS | 20562 | 15:37:25 INFO - PROCESS | 20562 | [#1] audio-beat-detection Cycles:10 Average:143.00 Median:143.50 stddev:27.33 (19.0%) stddev-sans-first:28.98 15:37:25 INFO - PROCESS | 20562 | Values: 144.0 119.0 149.0 189.0 118.0 143.0 143.0 118.0 189.0 118.0 15:37:25 INFO - PROCESS | 20562 | 15:37:25 INFO - PROCESS | 20562 | [#2] audio-dft Cycles:10 Average:161.20 Median:162.00 stddev:1.69 (1.0%) stddev-sans-first:1.59 15:37:25 INFO - PROCESS | 20562 | Values: 159.0 159.0 160.0 161.0 162.0 160.0 162.0 162.0 163.0 164.0 15:37:25 INFO - PROCESS | 20562 | 15:37:25 INFO - PROCESS | 20562 | [#3] audio-fft Cycles:10 Average:96.30 Median:75.50 stddev:43.72 (57.9%) stddev-sans-first:45.75 15:37:25 INFO - PROCESS | 20562 | Values: 76.0 75.0 75.0 186.0 77.0 75.0 76.0 76.0 172.0 75.0 15:37:25 INFO - PROCESS | 20562 | 15:37:25 INFO - PROCESS | 20562 | [#4] audio-oscillator Cycles:10 Average:106.90 Median:85.00 stddev:35.05 (41.2%) stddev-sans-first:36.27 15:37:25 INFO - PROCESS | 20562 | Values: 85.0 85.0 171.0 87.0 86.0 86.0 151.0 85.0 84.0 149.0 15:37:25 INFO - PROCESS | 20562 | 15:37:25 INFO - PROCESS | 20562 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 15:37:25 INFO - PROCESS | 20562 | Values: 117.0 116.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 15:37:25 INFO - PROCESS | 20562 | 15:37:25 INFO - PROCESS | 20562 | [#6] imaging-darkroom Cycles:10 Average:145.30 Median:145.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 15:37:25 INFO - PROCESS | 20562 | Values: 145.0 145.0 145.0 147.0 145.0 145.0 146.0 145.0 145.0 145.0 15:37:25 INFO - PROCESS | 20562 | 15:37:25 INFO - PROCESS | 20562 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 15:37:25 INFO - PROCESS | 20562 | Values: 94.0 95.0 95.0 94.0 95.0 95.0 95.0 94.0 95.0 95.0 15:37:25 INFO - PROCESS | 20562 | 15:37:25 INFO - PROCESS | 20562 | [#8] json-parse-financial Cycles:10 Average:50.60 Median:51.00 stddev:0.97 (1.9%) stddev-sans-first:1.00 15:37:25 INFO - PROCESS | 20562 | Values: 50.0 50.0 51.0 52.0 49.0 51.0 50.0 51.0 52.0 50.0 15:37:25 INFO - PROCESS | 20562 | 15:37:25 INFO - PROCESS | 20562 | [#9] json-stringify-tinderbox Cycles:10 Average:61.70 Median:62.00 stddev:0.67 (1.1%) stddev-sans-first:0.67 15:37:25 INFO - PROCESS | 20562 | Values: 61.0 62.0 62.0 62.0 63.0 61.0 62.0 61.0 61.0 62.0 15:37:25 INFO - PROCESS | 20562 | 15:37:25 INFO - PROCESS | 20562 | [#10] stanford-crypto-aes Cycles:10 Average:79.50 Median:78.50 stddev:3.10 (3.9%) stddev-sans-first:3.24 15:37:25 INFO - PROCESS | 20562 | Values: 78.0 87.0 79.0 78.0 82.0 77.0 77.0 81.0 78.0 78.0 15:37:25 INFO - PROCESS | 20562 | 15:37:25 INFO - PROCESS | 20562 | [#11] stanford-crypto-ccm Cycles:10 Average:127.70 Median:129.50 stddev:6.78 (5.2%) stddev-sans-first:7.01 15:37:25 INFO - PROCESS | 20562 | Values: 132.0 121.0 133.0 122.0 124.0 122.0 129.0 130.0 142.0 122.0 15:37:25 INFO - PROCESS | 20562 | 15:37:25 INFO - PROCESS | 20562 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.40 Median:164.00 stddev:2.12 (1.3%) stddev-sans-first:2.24 15:37:25 INFO - PROCESS | 20562 | Values: 164.0 162.0 165.0 161.0 162.0 162.0 166.0 167.0 164.0 161.0 15:37:25 INFO - PROCESS | 20562 | 15:37:25 INFO - PROCESS | 20562 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.20 Median:64.00 stddev:1.32 (2.1%) stddev-sans-first:1.36 15:37:25 INFO - PROCESS | 20562 | Values: 65.0 64.0 65.0 64.0 64.0 64.0 67.0 64.0 63.0 62.0 15:37:25 INFO - PROCESS | 20562 | -------- Summary: end -------- 15:37:25 INFO - PROCESS | 20562 | 15:37:26 INFO - TEST-INFO | 20562: exit 0 15:37:26 INFO - TEST-OK | kraken | took 62775ms 15:37:26 INFO - SUITE-END | took 1178s 15:37:27 INFO - Completed test suite (00:19:39) 15:37:27 INFO - javascript benchmark 15:37:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3015.743661788748, 3056.9235769105785, 3069.9846500767485, 3074.2314421394635, 3087.737542308904, 5208.609354298434, 5282.20766884967, 5301.946980530193, 5346.3584369875625, 5370.91943620846, 5278.419373868871, 5283.445238249986, 5296.973515132419, 5372.7045012524395, 5418.349798024233, 4784.354112194861, 4837.274408838676, 4886.0, 4896.387951506062, 4978.377702787152, 4934.705362006666, 4954.603631709472, 4969.950300497003, 4990.101781679296, 5002.549770520653, 4722.574968252839, 4763.190257656207, 4765.213739732974, 4836.250381190921, 4855.732934688591, 4845.248986407112, 4851.029794041206, 4859.611231101539, 4926.4088309403105, 4927.162382395001, 4872.439669438025, 4874.073925954058, 4912.484189160158, 4913.31213630092, 4914.0, 5194.168932970744, 5226.346706661668, 5291.259223708684, 5302.0, 5349.22436246742, 5667.886642267132, 5673.546116310686, 5694.943050569483, 5704.201411802351, 5712.685802280873, 5484.067708489566, 5740.196372507852, 5747.540196784247, 5754.122938530734, 5759.971200144015, 5223.164293712987, 5273.050850846846, 5276.709780962084, 5323.653962492425, 5328.227407025959, 5827.621204621686, 5829.766809327664, 5831.271091113611, 5837.241158649348, 5838.065909454467, 5506.531944784658, 5601.467860553241, 5608.298962629671, 5609.354924183689, 5616.634918730351, 5649.548036157097, 5673.914891276551, 5699.829005129853, 5753.597248192586, 5755.769769209269, 5701.173329867146, 5702.857428564319, 5704.458076482728, 5712.6286791358425, 5718.1136923776885, 5480.068388373986, 5655.745491452894, 5724.685142317129, 5730.627509211888, 5736.311642602831, 4340.283853164256, 4372.0, 4378.474583050054, 4411.625011873963, 4422.270325396274, 3930.331843586597, 3933.7639741615594, 3937.370020796659, 3944.15200731844, 3947.1710940702774, 5164.8192313268855, 5321.388040375329, 5334.253204551288, 5335.3864305604575, 5339.652922560022, 5669.34802497718, 5681.176229446707, 5713.914291285634, 5752.453516915886, 5752.712364381764, 5360.517553420211, 5383.2194331821665, 5389.946100539023, 5395.78416863321, 5398.73006349681, 4825.638077144228, 4996.850094497098, 5029.59763218949, 5032.924506132411, 5035.899282014339, 5711.828645140652, 5792.623479473821, 5795.072788353843, 5862.530997520103, 5863.179154918314, 5661.094224923992, 5662.7734890603915, 5665.065264231355, 5667.773289068391, 5686.061799802987, 5666.263385759825, 5841.065429531254, 5843.357230704704, 5849.444302791228, 5850.210221620197, 5515.145152501369, 5558.249636299048, 5560.193771903132, 5628.943710562842, 5640.125780504029, 5704.172894930295, 5728.026235540052, 5730.570207234391, 5737.225474561048, 5746.712664366849, 5435.048866448276, 5578.107502799533, 5580.776768929198, 5588.273524441796, 5596.216529685928, 5442.891142177214, 5475.315585551806, 5488.780448782164, 5546.168074788814, 5557.36090349615, 5505.201745746925, 5506.889862202814, 5517.724113794214, 5520.66875987442, 5520.806771762969, 5481.232627432242, 5606.523445507178, 5610.803621873215, 5621.4659607337235, 5623.718814059363, 5269.0, 5271.525562699376, 5290.25936368901, 5293.761780719954, 5336.332958380202], "value": 5243.183691408157, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [564.1256053117667, 585.4876982640187, 592.3306663470278, 603.5563860562484, 613.4908026338139, 32254.67745322546, 32772.0, 43350.34974475382, 44654.33018504717, 44738.99999999992, 42568.574314256846, 45987.31019034709, 46046.309305360366, 46047.079058418814, 46052.0789584209, 8843.380963332569, 9038.548072596377, 9040.276777857789, 9046.321525885553, 9052.728418147433, 8903.287736981058, 8982.37123401362, 9006.369554131214, 9007.729768106967, 9009.909900901006, 9024.639014439415, 9093.227075698573, 9105.63577456901, 9105.817883642323, 9113.225375843029, 8965.686200982967, 9044.09559044097, 9050.0, 9051.773705657346, 9064.093590640916, 8923.241524470428, 8987.236084932789, 8988.820223595492, 8989.505577193251, 8994.775130621721, 42580.57419425797, 42794.0, 43025.35461968073, 43085.0, 43090.35364469535, 3512.8243587820507, 3740.6446387593137, 3811.942820857689, 3839.8903739598522, 3867.3425517662067, 30045.399092018037, 30104.69895301063, 32581.58056763802, 33312.0, 33318.16704582405, 4159.230542349675, 4203.26442872496, 4250.808713607895, 4285.849995250152, 4426.358178064195, 347680.0, 348211.0, 349432.0, 350509.0, 350532.0, 58315.70842145847, 58459.70770146122, 58630.0, 58684.0, 58718.41281587215, 63174.368256317764, 63197.0, 63241.683791581665, 63276.0, 63297.683511582145, 15858.286377113058, 16043.43847965339, 16059.357625695075, 16060.0, 16069.598260043593, 13116.606501804961, 13213.73572528544, 13231.933840330737, 13235.205887646773, 13244.73510529784, 16951.06936020799, 19006.429807105807, 19087.52281192981, 19157.712634310497, 19272.0, 7020.719171233196, 7066.3993560546905, 7085.645717714094, 7135.5361901476235, 7155.606441645759, 763.1948294549304, 1043.7025447747355, 1054.5623566220086, 1077.0952399983894, 1140.0594509529506, 31269.0, 33440.0, 33606.15984600405, 33607.327853442795, 33810.154746131055, 1117.1398023521842, 1158.3223814068695, 1174.4949671641104, 1187.0562902492538, 1206.2762342594374, 17233.913830430767, 17389.217485213198, 17461.126943652765, 17649.117544123, 17656.823431765773, 552530.0, 557852.0, 560588.0, 560992.0, 562338.0, 59382.70308648429, 59964.100538491955, 60024.69987650121, 60037.0, 60067.09899351513, 1248.4257241668752, 1355.715299787036, 1372.8811019019467, 1400.6008287637974, 1407.7606806842862, 31318.68681313158, 33012.66987330192, 33940.0, 34287.82856085704, 34352.82823585866, 50647.49352506575, 51090.0, 51153.488465114875, 51164.0, 51177.23234151565, 372903.0, 375028.0, 376159.0, 376191.0, 376473.0, 28551.714482855736, 29453.116406507837, 29734.553981690726, 30403.695963040085, 30429.84785076149, 47948.280775787694, 48814.267785983946, 48852.755736221094, 49105.26342104943, 49165.88762854232, 38119.61880381161, 40695.29346705203, 42347.57652423436, 42580.148397032506, 43399.34900976425, 40574.59425405708, 41427.37858932058, 41805.961746375295, 42523.574764251964, 42990.14019719651, 41516.79241603773, 41611.16777664491, 42184.789076054425, 42677.35983960181, 43366.132677345646, 34608.0, 36376.272474549834, 36913.0, 37254.06864828401, 37871.24257514889, 28403.611904725927, 28475.145745627666, 28866.567001495416, 29043.419131617673, 29047.854760726063], "value": 20277.852649547604, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.1752937361239, 389.2759467390653, 390.24682363039324, 399.4088748651996, 419.08639166616763, 14500.274986250684, 14963.551093467186, 18415.26338946442, 19260.903695481502, 19277.421677349666, 16654.0840253786, 17588.560285992855, 17606.735898961524, 17608.735868961943, 17646.911765441157, 5636.4927156555905, 5928.88142237155, 5938.168656388109, 5941.168236446901, 5946.821595352125, 5871.970640146793, 7080.362767350937, 7299.562026278414, 7333.853322933538, 7446.032015837934, 7080.29197080293, 7243.7102515899305, 7324.267573242686, 7339.119305683299, 7339.633018349088, 7170.426365890717, 7247.094113235846, 7250.456215783811, 7395.815104622374, 7413.332800048021, 7274.854502909912, 7343.082114735658, 7358.300961408658, 7371.815704607374, 7374.520656157332, 26075.73924260752, 27244.45511089787, 27559.586606200923, 28070.298242543897, 28328.716712832815, 2601.297649634588, 2602.10227471522, 2723.564229723235, 2788.63747712796, 2970.1831996201004, 15382.846171538253, 16469.505914822574, 16624.0, 16699.248533816008, 16930.407435739813, 1951.268274397101, 1968.2127149140315, 2006.7290915726337, 2042.5710600773855, 2061.9690704639443, 98416.50791746067, 102851.28615353804, 103251.54478372958, 105310.0, 107811.0, 17778.555536111442, 18053.0, 18149.546261343574, 18405.0, 18595.67554055743, 32128.518072228937, 32169.0, 32226.0, 32412.189695257806, 32441.0, 9673.419594824192, 9717.951410242904, 9735.0, 9850.753731156778, 10026.849597256047, 7894.802629934297, 7987.400944929153, 8155.469894456842, 8204.753857384288, 8260.917390826135, 8482.789677722434, 9100.860203229875, 9106.590203440861, 9267.721968340826, 9341.532923353805, 3312.337532493511, 3348.380549598332, 3370.8146051966887, 3419.6409377015552, 3430.8456119474686, 934.7336009237471, 965.753732798132, 1007.2646967713464, 1071.8820929697727, 1087.1357270969597, 19826.90086549558, 20036.499087522927, 20101.798982010285, 20189.697154542693, 20654.46554949582, 975.2490582251627, 979.2215188924822, 980.9362391444533, 1002.1131298800614, 1012.2458768217725, 10592.417417041983, 11073.335599864035, 11167.329960202414, 11187.2031992002, 11263.811637941819, 455274.0, 487496.0, 497044.0, 500757.0, 509997.0, 89972.5501372489, 93249.5337523308, 93767.53116234374, 98073.0, 98585.0, 961.1349785193382, 999.1756800639502, 1012.3116280929039, 1020.5458570935863, 1023.6059117239917, 17379.826201738328, 17973.640527189644, 18157.81842181614, 18538.25846966106, 18576.071196439854, 41034.589654103074, 42405.0, 42684.0, 42842.57157428386, 43614.345784813886, 57573.0, 58194.709026456294, 58620.0, 58661.41338586559, 59270.0, 7369.815754605963, 7509.136449308181, 7782.649780759993, 8223.95888020556, 8419.284360829399, 9474.715758527254, 12887.291198984145, 12996.740065198832, 13649.181049137083, 14702.55892323232, 11317.207795454238, 11396.145289103517, 11480.598179063692, 11832.290062596272, 11853.940730296637, 11835.644930652094, 11856.525738970344, 11874.346910919638, 12181.939090304491, 12524.874751252371, 12148.331841748435, 12203.69490762738, 12411.379431028232, 12461.87538124607, 12471.563495277622, 8671.34964877624, 8734.606942687722, 8767.824643507221, 9014.368994170607, 9129.132732390412, 11962.880371196177, 12269.75460490803, 12282.324472154118, 12289.139760217056, 12318.507259709511], "value": 10517.754836132002, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1348.2315080404173, 1459.2557795524285, 1484.8218213814341, 1565.420794306107, 1704.7357659562772, 7675.731349402765, 8528.317734581235, 8882.333824963122, 8892.599833007514, 9114.270858331318, 8853.911460885389, 8856.690015849446, 8892.95553522233, 8897.377183597151, 8967.686130985418, 2350.9412264693347, 2383.4756353602265, 2383.6067048936907, 2383.940401489959, 2384.2132096408145, 2310.202979971907, 2331.1491305673394, 2390.0, 2396.0, 2399.796017338528, 2245.9663105053355, 2247.5729611373868, 2266.0709109265204, 2374.1809075868796, 2385.439421735891, 2238.7649296823924, 2283.5090455552054, 2289.381866895937, 2291.4844160064017, 2328.6157783965627, 2294.4608017115966, 2305.4236440889777, 2305.7117860267467, 2307.8038366738847, 2312.757160498157, 8622.439541429787, 8938.0, 9009.099090090938, 9032.50321232332, 9043.140901614335, 5067.239914012891, 5221.138512145491, 5293.497117773805, 5298.629095963284, 5306.469353064663, 3444.207832198583, 3452.5339079224236, 3486.1981744198974, 3500.1249687578106, 3509.31568344173, 1173.3722458484756, 1178.935158566287, 1179.5399794080315, 1188.4889497516065, 1205.1925210109248, 12497.687557811036, 12632.621021369374, 12669.93665031669, 12703.174293670974, 12737.172083814616, 4517.11916176349, 4658.813647454132, 4805.663603547718, 4862.489438608965, 4902.117618828645, 10557.894421055691, 10570.942905709368, 10571.418571978615, 10582.047615714622, 10582.894171058344, 5266.578673706094, 5272.024675435056, 5273.156294992783, 5277.604179686462, 5278.656887302313, 4323.573220837116, 4660.067986402665, 4726.054789042205, 4766.380370551806, 4795.424549054136, 4015.1367455996515, 4035.4753881995152, 4105.815238314283, 4140.23405669952, 4164.770937598461, 1286.2411177405377, 1296.61749783814, 1298.6363818130937, 1311.7901135818224, 1313.7109835836102, 916.7524768312652, 923.2429407885604, 927.174814415171, 936.8969413364524, 958.9472579008221, 5720.513756330675, 5819.359870414251, 5846.356900740915, 5847.853803654943, 6137.693115344215, 444.48883783649063, 446.87040758180416, 449.9122671079108, 455.8860284928768, 463.0923390155266, 4033.5361433435532, 4069.9389509157386, 4107.137501124737, 4126.628603425706, 4148.419221309019, 10415.375077495375, 11098.167637427065, 11179.888201118047, 11187.552497899993, 11228.887711122785, 10956.945215273872, 11146.944265278622, 11175.72060698489, 11185.66443006711, 11191.776164476827, 8085.231902969209, 8231.218034286732, 8249.68005119178, 8252.133525979685, 8277.130901255523, 5705.258316418839, 5825.563082768725, 5878.647281163144, 5909.172715819875, 5989.790357337472, 10113.898861011296, 10183.236257280585, 10185.847212291674, 10196.0, 10267.94866025665, 9836.409815411098, 10239.436830974368, 10258.897411026093, 10533.262671612914, 10605.840912386166, 6964.616946068015, 7909.881351779843, 7987.32107770846, 8083.595820208849, 8196.67213311461, 8069.677212911418, 8453.365997550085, 8466.619002144795, 8467.195616416431, 8499.8725019126, 7353.374963128194, 7400.333969942731, 7456.32893039629, 7521.849563008818, 7670.654820533201, 7112.893306600508, 7251.057362542836, 7389.8152546186775, 7397.741079062207, 7540.17058123617, 6215.4772080838675, 6227.688615569294, 6278.46633036197, 6282.591631543935, 6283.277423096402, 4990.600751939909, 5005.074061298671, 5057.140286151437, 5086.542211200862, 5135.8429459626295, 4853.733044682435, 4902.166631672553, 4975.179095449211, 5070.340855688737, 5070.543651071422], "value": 4540.882864642473, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.2466418264076, 315.13024053612025, 323.802480486903, 325.35904268590866, 326.6602733157516, 4662.417197850269, 4895.339129217555, 5073.340465739453, 5115.769790359438, 5117.257997590347, 5060.9878024395175, 5062.443131255568, 5068.783491961921, 5073.260439362749, 5074.264231686403, 4025.919481610366, 4028.67770578353, 4038.212548553026, 4044.8382064717525, 4047.6761859051207, 3875.1862108957152, 3898.298306304864, 4051.210014047247, 4052.29084910141, 4057.6348128668415, 3966.3653815389544, 3966.841326346943, 3970.3845903884944, 3985.900352491182, 3995.300822356076, 3962.187751510933, 3965.8413663453425, 3976.4234186043004, 3979.0, 3989.860354887565, 3512.7189824813954, 3512.89226464122, 3520.718342532617, 3525.876594319212, 3533.258015816656, 4872.65891387603, 4950.851474455772, 4955.801767929279, 4982.701037937735, 4990.6257030722845, 4503.166914120898, 4512.210363186429, 4512.52618475062, 4522.592966632986, 4531.52418996004, 5332.413434522238, 5333.200019997032, 5333.733313334318, 5338.3060202173465, 5347.6256662033675, 3730.9067273318115, 3801.650414103009, 3805.334066538345, 3831.2912111259507, 3832.5784163741964, 5263.578913686896, 5334.866628334323, 5513.8345849624575, 5537.335519737658, 5565.332160140769, 3990.7206495545033, 3994.960050399517, 4002.4996875390575, 4007.6593489553716, 4020.7684734971785, 4042.919141617151, 4047.2310261050307, 4051.088505086391, 4054.3715724062818, 4065.2479291331197, 4451.6661250405705, 4583.702059366197, 4584.977075114603, 4586.541345865387, 4594.540545945379, 5302.946970530245, 5334.4398838122215, 5342.1452567589, 5358.2498450217, 5372.274742909657, 1375.7661197596433, 1376.566381589796, 1385.1369907474432, 1385.3627331427454, 1400.6358346829898, 845.8223773007737, 879.8825491625599, 880.300161371711, 888.4180861535705, 896.3187977137292, 4549.772511374485, 4558.677983384781, 4565.589096981287, 4578.244589642672, 4583.785296896325, 4799.616270529338, 4805.4233491980485, 4822.397200349957, 4823.971983393835, 4844.709317440895, 4381.649468042613, 4562.908741825145, 4568.908621827545, 4570.520095390003, 4607.9308810367875, 2549.4391233928504, 2560.87195640221, 2564.9615255771178, 2570.961435578468, 2577.6777902762155, 4888.462269150391, 4892.437369702458, 4894.975525122351, 4927.285543596158, 4934.827281045218, 4959.668955590867, 4959.90080198394, 4959.975200123977, 4969.726665033458, 5203.583713302926, 5664.291963504562, 5676.489115979499, 5694.800681976111, 5695.0, 5710.172025056344, 5502.871911258262, 5528.253685752372, 5530.80642177538, 5537.501624853782, 5544.445555444423, 5231.398389185292, 5454.809081682122, 5481.205225242397, 5494.203340515683, 5495.120780675073, 5428.67427954324, 5516.282883225155, 5618.634788738719, 5619.100943848965, 5645.520130788765, 3937.547182074098, 3949.328614135661, 3963.9405408918315, 3971.7290467050266, 3977.7016723745255, 5522.917156242579, 5529.806456773994, 5533.695646739468, 5539.501444869981, 5541.3904470507405, 5172.612054095883, 5345.679259244458, 5373.650712703662, 5379.838604841861, 5406.972965135149, 5319.946800531945, 5340.759665815125, 5355.25026496283, 5382.381026182039, 5386.919196211981, 5285.3879566730975, 5299.44355842631, 5305.787768489217, 5306.920396193983, 5316.175992721134, 5199.4020687621405, 5336.331834082958, 5394.271773310553, 5394.406615272238, 5397.244385786072, 5232.488405565426, 5258.816766227568, 5264.6314757966575, 5266.657002464347, 5267.262583238273], "value": 4002.224210929636, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [672.5359501943659, 721.6752461392375, 742.4209116888825, 771.1324759645396, 822.8107535266887, 13504.797428038588, 14964.925175374136, 14973.176475293854, 14998.775018374734, 15030.0, 14022.859771402284, 14116.505922292721, 14144.080634758733, 14149.71700565988, 14281.21453320067, 653.2846533046306, 662.1259936883316, 765.8889461028148, 770.9306162445379, 794.9364050875945, 777.2577188784725, 777.5956502618635, 778.4589710151447, 781.5662307419379, 782.6556315221313, 834.4492634860962, 836.188896770132, 836.6318819719335, 837.6272558711375, 845.5391811462737, 687.05186842158, 807.5033854179696, 814.9266566009088, 836.9623366948472, 838.3544670603632, 610.2158726037021, 612.0665984373821, 612.6599737145882, 612.9141920131185, 613.8250598579428, 14117.78823317651, 14134.646633834134, 14169.079009864327, 14169.645758856008, 14173.078749881224, 13136.86863131366, 13532.526361577397, 13864.930675346659, 13903.374348154257, 13909.79135312971, 10142.645007424704, 10332.173426125892, 10678.733031674192, 12159.331236781973, 12798.104132710714, 4361.542038085987, 4369.9126017479475, 4438.0, 4578.221702310615, 4581.289900065462, 74563.62718186429, 74985.62507187483, 75996.0, 76099.23900760977, 76369.61815190944, 10279.948600257101, 10431.16550675944, 10479.68560943173, 10493.737656558647, 10619.256652034283, 20582.38252852417, 20602.793972060088, 20618.484537886674, 20619.793802061788, 20623.0, 7819.0, 7848.254415830487, 8033.919660803317, 8068.112507624157, 8206.794830129294, 4461.219286624892, 4502.324651302265, 4519.028408892038, 4526.162659907927, 4617.468991066004, 3729.160938788805, 3885.339492286318, 3904.863329783501, 3911.0, 3971.72197946141, 630.3822254190918, 646.8932626116732, 667.3493343989668, 671.3655595462242, 687.7180356054093, 653.5294587896802, 686.6978529447027, 692.580988501949, 704.6018999265501, 712.5012491256037, 9426.764330891645, 9538.809223815484, 9591.616335346647, 9764.462954537568, 9799.11807937289, 689.9724011039602, 703.2369878681585, 723.1972510513326, 727.058459295214, 734.2327268004841, 4445.355423463579, 4619.099275641285, 4683.7892294846815, 4709.387779588632, 4743.146233677971, 74124.0, 86683.56658216669, 126548.36725816496, 129269.0, 129974.0, 91330.69354451628, 108336.4583177079, 110378.9999999984, 112707.0, 113130.43434782937, 91335.54332228296, 95030.5248473767, 95844.52077739566, 96561.5171924136, 96786.0, 8491.320694344315, 8660.913390866262, 8848.601812918305, 8886.955565222133, 9015.729528114167, 53575.0, 54961.0, 54992.72503637456, 56149.15776263441, 56847.0, 81145.0, 81343.39177427508, 83434.0, 83767.5811620938, 84752.0, 11650.475728592402, 11858.822117668069, 12079.75840483203, 12253.938730306292, 12315.507379704712, 11949.522019119138, 12200.389980501117, 12346.259224446561, 12354.25874447536, 13159.144655597356, 12774.23354598727, 12826.935865320615, 12954.676133096747, 12973.351332433529, 13274.80087798703, 12124.878751212374, 12519.687007824514, 12531.248125112523, 12631.368431578569, 13036.934815325863, 11729.882701173221, 11995.399025031384, 12007.819882701591, 12206.32865192423, 12842.67893302675, 10424.995264537796, 10442.084218418093, 10779.353238805697, 10987.39569323695, 11018.228723989243, 11047.337159770439, 11391.715207119889, 11520.711982200512, 11574.937423337477, 11854.881451185378], "value": 7395.904845664634, "unit": "score"}], "name": "dromaeo_css", "value": 7291.8438369993455, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 110.0, 107.0, 112.0, 112.0, 111.0, 117.0, 112.0, 112.0, 111.0], "value": 110.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 119.0, 149.0, 189.0, 118.0, 143.0, 143.0, 118.0, 189.0, 118.0], "value": 143.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 159.0, 160.0, 161.0, 162.0, 160.0, 162.0, 162.0, 163.0, 164.0], "value": 161.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 186.0, 77.0, 75.0, 76.0, 76.0, 172.0, 75.0], "value": 96.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 171.0, 87.0, 86.0, 86.0, 151.0, 85.0, 84.0, 149.0], "value": 106.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 145.0, 147.0, 145.0, 145.0, 146.0, 145.0, 145.0, 145.0], "value": 145.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 51.0, 52.0, 49.0, 51.0, 50.0, 51.0, 52.0, 50.0], "value": 50.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 62.0, 62.0, 63.0, 61.0, 62.0, 61.0, 61.0, 62.0], "value": 61.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 87.0, 79.0, 78.0, 82.0, 77.0, 77.0, 81.0, 78.0, 78.0], "value": 79.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 121.0, 133.0, 122.0, 124.0, 122.0, 129.0, 130.0, 142.0, 122.0], "value": 127.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 162.0, 165.0, 161.0, 162.0, 162.0, 166.0, 167.0, 164.0, 161.0], "value": 163.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 65.0, 64.0, 64.0, 64.0, 67.0, 64.0, 63.0, 62.0], "value": 64.2, "unit": "score"}], "name": "kraken", "value": 1521.6000000000001, "alertThreshold": 2.0}]} 15:37:27 INFO - Return code: 0 15:37:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:37:27 INFO - # TBPL SUCCESS # 15:37:27 INFO - Running post-action listener: _resource_record_post_action 15:37:27 INFO - [mozharness: 2016-08-20 22:37:27.514983Z] Finished run-tests step (success) 15:37:27 INFO - Running post-run listener: _resource_record_post_run 15:37:27 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 0; Write bytes: 417443840; Read time: 0; Write time: 322192 15:37:27 INFO - TinderboxPrint: CPU usage
13.2% 15:37:27 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 15:37:27 INFO - TinderboxPrint: I/O write bytes / time
417,443,840 / 322,192 15:37:27 INFO - TinderboxPrint: CPU idle
8,277.4 (86.7%) 15:37:27 INFO - TinderboxPrint: CPU user
1,195.2 (12.5%) 15:37:27 INFO - TinderboxPrint: Swap in / out
0 / 0 15:37:27 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 118411264; Read time: 0; Write time: 97580 15:37:27 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 0; Write bytes: 287367168; Read time: 0; Write time: 223096 15:37:28 INFO - Running post-run listener: _upload_blobber_files 15:37:28 INFO - Blob upload gear active. 15:37:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:37:28 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 15:37:28 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:37:28 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:37:28 INFO - (blobuploader) - INFO - Open directory for files ... 15:37:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:37:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:37:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:37:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:37:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:37:32 INFO - (blobuploader) - INFO - Done attempting. 15:37:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:37:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:37:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:37:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:37:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:37:33 INFO - (blobuploader) - INFO - Done attempting. 15:37:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:37:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:37:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:37:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:37:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:37:33 INFO - (blobuploader) - INFO - Done attempting. 15:37:33 INFO - (blobuploader) - INFO - Iteration through files over. 15:37:33 INFO - Return code: 0 15:37:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:37:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:37:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1f73b655c3d2a458885b8134f2496ab53c3e81bbc8c4b27100f4b3e23db02837c0ad676c4050c8b9d6cdf0f48184f717194ede5df532b43159e6169dd16e97ad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4857a403c9f5b1d847622f63752063fd9facb766f782b96a9c80cc46020f0956be177662559c5815e1dbadcd9b28cf32a40f7794801a3242bf050898d5b5b8ee", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6fcfde7275ced1c29b920a5e1234168c25463388ce896551f8d2d45fa16f2a79522d01c4964f3b5fdeb021230316701d4db2258d3a4bce9ef2588ff1a004650f"} 15:37:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:37:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:37:33 INFO - Contents: 15:37:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1f73b655c3d2a458885b8134f2496ab53c3e81bbc8c4b27100f4b3e23db02837c0ad676c4050c8b9d6cdf0f48184f717194ede5df532b43159e6169dd16e97ad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4857a403c9f5b1d847622f63752063fd9facb766f782b96a9c80cc46020f0956be177662559c5815e1dbadcd9b28cf32a40f7794801a3242bf050898d5b5b8ee", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6fcfde7275ced1c29b920a5e1234168c25463388ce896551f8d2d45fa16f2a79522d01c4964f3b5fdeb021230316701d4db2258d3a4bce9ef2588ff1a004650f"} 15:37:33 INFO - Running post-run listener: copy_logs_to_upload_dir 15:37:33 INFO - Copying logs to upload dir... 15:37:33 INFO - mkdir: /builds/slave/test/build/upload/logs 15:37:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1241.531184 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-08-20 15:37:33.510103) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-20 15:37:33.519359) ========= 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=41a7e32711b8e08598a63237000001d6-1471729468.226553-397249985 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1f73b655c3d2a458885b8134f2496ab53c3e81bbc8c4b27100f4b3e23db02837c0ad676c4050c8b9d6cdf0f48184f717194ede5df532b43159e6169dd16e97ad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4857a403c9f5b1d847622f63752063fd9facb766f782b96a9c80cc46020f0956be177662559c5815e1dbadcd9b28cf32a40f7794801a3242bf050898d5b5b8ee", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6fcfde7275ced1c29b920a5e1234168c25463388ce896551f8d2d45fa16f2a79522d01c4964f3b5fdeb021230316701d4db2258d3a4bce9ef2588ff1a004650f"} build_url:https://queue.taskcluster.net/v1/task/RdHYxEDuQwC1aLag9uq6yA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011170 build_url: 'https://queue.taskcluster.net/v1/task/RdHYxEDuQwC1aLag9uq6yA/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/Autoland/sha512/1f73b655c3d2a458885b8134f2496ab53c3e81bbc8c4b27100f4b3e23db02837c0ad676c4050c8b9d6cdf0f48184f717194ede5df532b43159e6169dd16e97ad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4857a403c9f5b1d847622f63752063fd9facb766f782b96a9c80cc46020f0956be177662559c5815e1dbadcd9b28cf32a40f7794801a3242bf050898d5b5b8ee", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6fcfde7275ced1c29b920a5e1234168c25463388ce896551f8d2d45fa16f2a79522d01c4964f3b5fdeb021230316701d4db2258d3a4bce9ef2588ff1a004650f"}' ========= master_lag: 0.29 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-20 15:37:33.820441) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-20 15:37:33.821237) ========= 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=41a7e32711b8e08598a63237000001d6-1471729468.226553-397249985 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004446 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-20 15:37:33.903463) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-20 15:37:33.903973) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-20 15:37:33.904251) ========= ========= Total master_lag: 0.46 =========