builder: autoland_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-035 starttime: 1469218592.9 results: success (0) buildid: 20160722063002 builduid: 57f36f248c89412dbafd6cf2b9c5a6a1 revision: a31e5c25c77773e26017df7ed4bc8355cd80c2af ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-22 13:16:32.903512) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-22 13:16:32.903986) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-22 13:16:32.904434) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-22 13:16:32.920390) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 13:16:32.920649) ========= 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=ed14279e38b05397cf4aa391000001a4-1469218590.635619-1304341150 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-22 13:16:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.4M=0s 2016-07-22 13:16:33 (46.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.159187 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 13:16:33.093389) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 13:16:33.093698) ========= 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=ed14279e38b05397cf4aa391000001a4-1469218590.635619-1304341150 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019326 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 13:16:33.143494) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-22 13:16:33.143826) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev a31e5c25c77773e26017df7ed4bc8355cd80c2af --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 a31e5c25c77773e26017df7ed4bc8355cd80c2af --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=ed14279e38b05397cf4aa391000001a4-1469218590.635619-1304341150 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-22 13:16:33,276 truncating revision to first 12 chars 2016-07-22 13:16:33,276 Setting DEBUG logging. 2016-07-22 13:16:33,276 attempt 1/10 2016-07-22 13:16:33,276 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/a31e5c25c777?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-22 13:16:34,411 unpacking tar archive at: autoland-a31e5c25c777/testing/mozharness/ program finished with exit code 0 elapsedTime=1.497219 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-22 13:16:34.654897) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-22 13:16:34.655235) ========= script_repo_revision: a31e5c25c77773e26017df7ed4bc8355cd80c2af ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-22 13:16:34.655685) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-22 13:16:34.655950) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-22 13:16:34.667355) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-07-22 13:16:34.667781) ========= /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=ed14279e38b05397cf4aa391000001a4-1469218590.635619-1304341150 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 13:16:34 INFO - MultiFileLogger online at 20160722 13:16:34 in /builds/slave/test 13:16:34 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 13:16:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:16:34 INFO - {'append_to_log': False, 13:16:34 INFO - 'base_work_dir': '/builds/slave/test', 13:16:34 INFO - 'blob_upload_branch': 'Autoland', 13:16:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:16:34 INFO - 'branch': 'Autoland', 13:16:34 INFO - 'buildbot_json_path': 'buildprops.json', 13:16:34 INFO - 'config_files': ('talos/linux_config.py',), 13:16:34 INFO - 'default_actions': ('clobber', 13:16:34 INFO - 'read-buildbot-config', 13:16:34 INFO - 'download-and-extract', 13:16:34 INFO - 'populate-webroot', 13:16:34 INFO - 'create-virtualenv', 13:16:34 INFO - 'install', 13:16:34 INFO - 'run-tests'), 13:16:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:16:34 INFO - 'download_minidump_stackwalk': True, 13:16:34 INFO - 'download_symbols': 'ondemand', 13:16:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:16:34 INFO - 'tooltool.py': '/tools/tooltool.py', 13:16:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:16:34 INFO - '/tools/misc-python/virtualenv.py')}, 13:16:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:16:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:16:34 INFO - 'installer_path': 'installer.exe', 13:16:34 INFO - 'log_level': 'info', 13:16:34 INFO - 'log_name': 'talos', 13:16:34 INFO - 'log_to_console': True, 13:16:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 13:16:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 13:16:34 INFO - 'opt_config_files': (), 13:16:34 INFO - 'pip_index': False, 13:16:34 INFO - 'sps_profile': False, 13:16:34 INFO - 'sps_profile_interval': 0, 13:16:34 INFO - 'suite': 'dromaeojs', 13:16:34 INFO - 'system_bits': '32', 13:16:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:16:34 INFO - 'title': 'talos-linux64-ix-035', 13:16:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:16:34 INFO - 'use_talos_json': True, 13:16:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:16:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:16:34 INFO - 'work_dir': 'build'} 13:16:34 INFO - [mozharness: 2016-07-22 20:16:34.896812Z] Running clobber step. 13:16:34 INFO - Running pre-action listener: _resource_record_pre_action 13:16:34 INFO - Running main action method: clobber 13:16:34 INFO - rmtree: /builds/slave/test/build 13:16:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:16:35 INFO - Running post-action listener: _resource_record_post_action 13:16:35 INFO - [mozharness: 2016-07-22 20:16:35.361819Z] Finished clobber step (success) 13:16:35 INFO - [mozharness: 2016-07-22 20:16:35.361920Z] Running read-buildbot-config step. 13:16:35 INFO - Running pre-action listener: _resource_record_pre_action 13:16:35 INFO - Running main action method: read_buildbot_config 13:16:35 INFO - Using buildbot properties: 13:16:35 INFO - { 13:16:35 INFO - "project": "", 13:16:35 INFO - "product": "firefox", 13:16:35 INFO - "script_repo_revision": "production", 13:16:35 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 13:16:35 INFO - "repository": "", 13:16:35 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland pgo talos dromaeojs", 13:16:35 INFO - "buildid": "20160722063002", 13:16:35 INFO - "pgo_build": "True", 13:16:35 INFO - "basedir": "/builds/slave/test", 13:16:35 INFO - "buildnumber": 59, 13:16:35 INFO - "slavename": "talos-linux64-ix-035", 13:16:35 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 13:16:35 INFO - "platform": "ubuntu64_hw", 13:16:35 INFO - "scheduler": "tests-autoland-linux64-pgo-talos", 13:16:35 INFO - "branch": "autoland", 13:16:35 INFO - "repo_path": "integration/autoland", 13:16:35 INFO - "revision": "a31e5c25c77773e26017df7ed4bc8355cd80c2af", 13:16:35 INFO - "stage_platform": "linux64-pgo", 13:16:35 INFO - "builduid": "57f36f248c89412dbafd6cf2b9c5a6a1", 13:16:35 INFO - "slavebuilddir": "test" 13:16:35 INFO - } 13:16:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/T7TiM5WgRg-kK5jJcZh3SQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 13:16:35 INFO - Running post-action listener: _resource_record_post_action 13:16:35 INFO - [mozharness: 2016-07-22 20:16:35.364343Z] Finished read-buildbot-config step (success) 13:16:35 INFO - [mozharness: 2016-07-22 20:16:35.364432Z] Running download-and-extract step. 13:16:35 INFO - Running pre-action listener: _resource_record_pre_action 13:16:35 INFO - Running main action method: download_and_extract 13:16:35 INFO - mkdir: /builds/slave/test/build/tests 13:16:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:16:35 INFO - https://queue.taskcluster.net/v1/task/T7TiM5WgRg-kK5jJcZh3SQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 13:16:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T7TiM5WgRg-kK5jJcZh3SQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 13:16:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T7TiM5WgRg-kK5jJcZh3SQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 13:16:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T7TiM5WgRg-kK5jJcZh3SQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 13:16:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T7TiM5WgRg-kK5jJcZh3SQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 13:16:36 INFO - Downloaded 1361 bytes. 13:16:36 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 13:16:36 INFO - Using the following test package requirements: 13:16:36 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 13:16:36 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 13:16:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 13:16:36 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 13:16:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 13:16:36 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 13:16:36 INFO - u'jsshell-linux-x86_64.zip'], 13:16:36 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 13:16:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 13:16:36 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 13:16:36 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 13:16:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 13:16:36 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 13:16:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 13:16:36 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 13:16:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 13:16:36 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 13:16:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 13:16:36 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 13:16:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:16:36 INFO - https://queue.taskcluster.net/v1/task/T7TiM5WgRg-kK5jJcZh3SQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 13:16:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T7TiM5WgRg-kK5jJcZh3SQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 13:16:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T7TiM5WgRg-kK5jJcZh3SQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 13:16:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T7TiM5WgRg-kK5jJcZh3SQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 13:16:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T7TiM5WgRg-kK5jJcZh3SQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 13:16:37 INFO - Downloaded 24308714 bytes. 13:16:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:16:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 13:16:38 INFO - Return code: 0 13:16:38 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 13:16:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:16:38 INFO - https://queue.taskcluster.net/v1/task/T7TiM5WgRg-kK5jJcZh3SQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 13:16:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T7TiM5WgRg-kK5jJcZh3SQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 13:16:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T7TiM5WgRg-kK5jJcZh3SQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 13:16:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T7TiM5WgRg-kK5jJcZh3SQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 13:16:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T7TiM5WgRg-kK5jJcZh3SQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 13:16:39 INFO - Downloaded 24308714 bytes. 13:16:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:16:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 13:16:41 INFO - Return code: 0 13:16:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:16:41 INFO - https://queue.taskcluster.net/v1/task/T7TiM5WgRg-kK5jJcZh3SQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 13:16:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T7TiM5WgRg-kK5jJcZh3SQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 13:16:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T7TiM5WgRg-kK5jJcZh3SQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 13:16:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T7TiM5WgRg-kK5jJcZh3SQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 13:16:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T7TiM5WgRg-kK5jJcZh3SQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 13:16:42 INFO - Downloaded 14403289 bytes. 13:16:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:16:42 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 13:16:42 INFO - Return code: 0 13:16:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:16:42 INFO - https://queue.taskcluster.net/v1/task/T7TiM5WgRg-kK5jJcZh3SQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 13:16:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T7TiM5WgRg-kK5jJcZh3SQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 13:16:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T7TiM5WgRg-kK5jJcZh3SQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 13:16:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T7TiM5WgRg-kK5jJcZh3SQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 13:16:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T7TiM5WgRg-kK5jJcZh3SQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 13:16:44 INFO - Downloaded 60857663 bytes. 13:16:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/T7TiM5WgRg-kK5jJcZh3SQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 13:16:44 INFO - mkdir: /builds/slave/test/properties 13:16:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:16:44 INFO - Writing to file /builds/slave/test/properties/build_url 13:16:44 INFO - Contents: 13:16:44 INFO - build_url:https://queue.taskcluster.net/v1/task/T7TiM5WgRg-kK5jJcZh3SQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 13:16:45 INFO - Running post-action listener: _resource_record_post_action 13:16:45 INFO - Running post-action listener: set_extra_try_arguments 13:16:45 INFO - [mozharness: 2016-07-22 20:16:45.491722Z] Finished download-and-extract step (success) 13:16:45 INFO - [mozharness: 2016-07-22 20:16:45.491955Z] Running populate-webroot step. 13:16:45 INFO - Running pre-action listener: _resource_record_pre_action 13:16:45 INFO - Running main action method: populate_webroot 13:16:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:16:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:16:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:16:45 INFO - '--tppagecycles', 13:16:45 INFO - '7'], 13:16:45 INFO - 'tests': ['tsvgm']}}, 13:16:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:16:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:16:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:16:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:16:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:16:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:16:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:16:45 INFO - 'talos_options': ['--disable-e10s'], 13:16:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:16:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:16:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:16:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:16:45 INFO - 'talos_options': ['--disable-e10s'], 13:16:45 INFO - 'tests': ['damp', 'tps']}, 13:16:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:16:45 INFO - 'tests': ['damp', 'tps']}, 13:16:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:16:45 INFO - 'tests': ['dromaeo_dom']}, 13:16:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:16:45 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:16:45 INFO - 'tests': ['basic_compositor_video']}, 13:16:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:16:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:16:45 INFO - 'tests': ['a11yr', 13:16:45 INFO - 'ts_paint', 13:16:45 INFO - 'tpaint', 13:16:45 INFO - 'sessionrestore', 13:16:45 INFO - 'sessionrestore_no_auto_restore', 13:16:45 INFO - 'tabpaint']}, 13:16:45 INFO - 'other-e10s': {'tests': ['a11yr', 13:16:45 INFO - 'ts_paint', 13:16:45 INFO - 'tpaint', 13:16:45 INFO - 'sessionrestore', 13:16:45 INFO - 'sessionrestore_no_auto_restore', 13:16:45 INFO - 'tabpaint']}, 13:16:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:16:45 INFO - 'ts_paint', 13:16:45 INFO - 'tpaint', 13:16:45 INFO - 'sessionrestore', 13:16:45 INFO - 'sessionrestore_no_auto_restore', 13:16:45 INFO - 'tabpaint']}, 13:16:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:16:45 INFO - 'ts_paint', 13:16:45 INFO - 'tpaint', 13:16:45 INFO - 'sessionrestore', 13:16:45 INFO - 'sessionrestore_no_auto_restore', 13:16:45 INFO - 'tabpaint']}, 13:16:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:16:45 INFO - 'tests': ['a11yr', 13:16:45 INFO - 'ts_paint', 13:16:45 INFO - 'tpaint', 13:16:45 INFO - 'sessionrestore', 13:16:45 INFO - 'sessionrestore_no_auto_restore', 13:16:45 INFO - 'tabpaint']}, 13:16:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:16:45 INFO - 'tests': ['a11yr', 13:16:45 INFO - 'ts_paint', 13:16:45 INFO - 'tpaint', 13:16:45 INFO - 'sessionrestore', 13:16:45 INFO - 'sessionrestore_no_auto_restore', 13:16:45 INFO - 'tabpaint']}, 13:16:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:16:45 INFO - 'tests': ['tsvgx', 13:16:45 INFO - 'tsvgr_opacity', 13:16:45 INFO - 'tart', 13:16:45 INFO - 'tscrollx', 13:16:45 INFO - 'cart']}, 13:16:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:16:45 INFO - 'tsvgr_opacity', 13:16:45 INFO - 'tart', 13:16:45 INFO - 'tscrollx', 13:16:45 INFO - 'cart']}, 13:16:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:16:45 INFO - 'talos_options': ['--disable-e10s'], 13:16:45 INFO - 'tests': ['tp5o']}, 13:16:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:16:45 INFO - 'tests': ['tp5o']}, 13:16:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:16:45 INFO - 'talos_options': ['--disable-e10s', 13:16:45 INFO - '--xperf_path', 13:16:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:16:45 INFO - 'tests': ['tp5n']}, 13:16:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:16:45 INFO - 'talos_options': ['--xperf_path', 13:16:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:16:45 INFO - 'tests': ['tp5n']}}, 13:16:45 INFO - 'talos.zip': {'path': '', 13:16:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:16:45 INFO - Running post-action listener: _resource_record_post_action 13:16:45 INFO - [mozharness: 2016-07-22 20:16:45.513330Z] Finished populate-webroot step (success) 13:16:45 INFO - [mozharness: 2016-07-22 20:16:45.513420Z] Running create-virtualenv step. 13:16:45 INFO - Running pre-action listener: _resource_record_pre_action 13:16:45 INFO - Running main action method: create_virtualenv 13:16:45 INFO - Creating virtualenv /builds/slave/test/build/venv 13:16:45 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 13:16:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:16:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:16:45 INFO - Using real prefix '/usr' 13:16:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:16:46 INFO - Installing distribute.............................................................................................................................................................................................done. 13:16:48 INFO - Installing pip.................done. 13:16:48 INFO - Return code: 0 13:16:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:16:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:16:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:16:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:16:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:16:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:16:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:16:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x172d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19ee180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ec3b0>, '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 0x19ed2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19ed740>, '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': 'ed14279e38b05397cf4aa391000001a4-1469218590.635619-1304341150', '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 13:16:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 13:16:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 13:16:48 INFO - Using env: {'DISPLAY': ':0', 13:16:48 INFO - 'HOME': '/home/cltbld', 13:16:48 INFO - 'LANG': 'en_US.UTF-8', 13:16:48 INFO - 'LANGUAGE': 'en_US:en', 13:16:48 INFO - 'LOGNAME': 'cltbld', 13:16:48 INFO - 'MAIL': '/var/mail/cltbld', 13:16:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:16:48 INFO - 'MOZ_NO_REMOTE': '1', 13:16:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:16:48 INFO - 'NO_EM_RESTART': '1', 13:16:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:16:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:16:48 INFO - 'PWD': '/builds/slave/test', 13:16:48 INFO - 'SHELL': '/bin/bash', 13:16:48 INFO - 'SHLVL': '1', 13:16:48 INFO - 'TERM': 'linux', 13:16:48 INFO - 'TMOUT': '86400', 13:16:48 INFO - 'USER': 'cltbld', 13:16:48 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1469218590.635619-1304341150', 13:16:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:16:48 INFO - '_': '/tools/buildbot/bin/python'} 13:16:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:16:48 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 13:16:48 INFO - Cleaning up... 13:16:48 INFO - Return code: 0 13:16:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:16:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:16:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:16:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:16:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:16:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:16:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:16:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x172d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19ee180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ec3b0>, '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 0x19ed2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19ed740>, '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': 'ed14279e38b05397cf4aa391000001a4-1469218590.635619-1304341150', '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 13:16:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 13:16:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 13:16:48 INFO - Using env: {'DISPLAY': ':0', 13:16:48 INFO - 'HOME': '/home/cltbld', 13:16:48 INFO - 'LANG': 'en_US.UTF-8', 13:16:48 INFO - 'LANGUAGE': 'en_US:en', 13:16:48 INFO - 'LOGNAME': 'cltbld', 13:16:48 INFO - 'MAIL': '/var/mail/cltbld', 13:16:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:16:48 INFO - 'MOZ_NO_REMOTE': '1', 13:16:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:16:48 INFO - 'NO_EM_RESTART': '1', 13:16:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:16:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:16:48 INFO - 'PWD': '/builds/slave/test', 13:16:48 INFO - 'SHELL': '/bin/bash', 13:16:48 INFO - 'SHLVL': '1', 13:16:48 INFO - 'TERM': 'linux', 13:16:48 INFO - 'TMOUT': '86400', 13:16:48 INFO - 'USER': 'cltbld', 13:16:48 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1469218590.635619-1304341150', 13:16:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:16:48 INFO - '_': '/tools/buildbot/bin/python'} 13:16:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:16:48 INFO - Downloading/unpacking psutil>=3.1.1 13:16:48 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 13:16:48 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 13:16:48 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 13:16:48 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 13:16:48 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 13:16:48 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 13:16:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:16:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:16:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:16:50 INFO - Installing collected packages: psutil 13:16:50 INFO - Running setup.py install for psutil 13:16:50 INFO - building 'psutil._psutil_linux' extension 13:16:50 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 13:16:50 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 13:16:50 INFO - building 'psutil._psutil_posix' extension 13:16:50 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 13:16:50 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 13:16:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:16:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:16:51 INFO - Successfully installed psutil 13:16:51 INFO - Cleaning up... 13:16:51 INFO - Return code: 0 13:16:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:16:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:16:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:16:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:16:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:16:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:16:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:16:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x172d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19ee180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ec3b0>, '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 0x19ed2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19ed740>, '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': 'ed14279e38b05397cf4aa391000001a4-1469218590.635619-1304341150', '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 13:16:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 13:16:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 13:16:51 INFO - Using env: {'DISPLAY': ':0', 13:16:51 INFO - 'HOME': '/home/cltbld', 13:16:51 INFO - 'LANG': 'en_US.UTF-8', 13:16:51 INFO - 'LANGUAGE': 'en_US:en', 13:16:51 INFO - 'LOGNAME': 'cltbld', 13:16:51 INFO - 'MAIL': '/var/mail/cltbld', 13:16:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:16:51 INFO - 'MOZ_NO_REMOTE': '1', 13:16:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:16:51 INFO - 'NO_EM_RESTART': '1', 13:16:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:16:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:16:51 INFO - 'PWD': '/builds/slave/test', 13:16:51 INFO - 'SHELL': '/bin/bash', 13:16:51 INFO - 'SHLVL': '1', 13:16:51 INFO - 'TERM': 'linux', 13:16:51 INFO - 'TMOUT': '86400', 13:16:51 INFO - 'USER': 'cltbld', 13:16:51 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1469218590.635619-1304341150', 13:16:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:16:51 INFO - '_': '/tools/buildbot/bin/python'} 13:16:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:16:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:16:51 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 13:16:51 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 13:16:51 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 13:16:51 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 13:16:51 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 13:16:51 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 13:16:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:16:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:16:53 INFO - Installing collected packages: mozsystemmonitor 13:16:53 INFO - Running setup.py install for mozsystemmonitor 13:16:53 INFO - Successfully installed mozsystemmonitor 13:16:53 INFO - Cleaning up... 13:16:53 INFO - Return code: 0 13:16:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:16:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:16:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:16:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:16:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:16:53 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 0x172d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19ee180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ec3b0>, '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 0x19ed2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19ed740>, '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': 'ed14279e38b05397cf4aa391000001a4-1469218590.635619-1304341150', '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 13:16:53 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 13:16:53 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 13:16:53 INFO - Using env: {'DISPLAY': ':0', 13:16:53 INFO - 'HOME': '/home/cltbld', 13:16:53 INFO - 'LANG': 'en_US.UTF-8', 13:16:53 INFO - 'LANGUAGE': 'en_US:en', 13:16:53 INFO - 'LOGNAME': 'cltbld', 13:16:53 INFO - 'MAIL': '/var/mail/cltbld', 13:16:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:16:53 INFO - 'MOZ_NO_REMOTE': '1', 13:16:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:16:53 INFO - 'NO_EM_RESTART': '1', 13:16:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:16:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:16:53 INFO - 'PWD': '/builds/slave/test', 13:16:53 INFO - 'SHELL': '/bin/bash', 13:16:53 INFO - 'SHLVL': '1', 13:16:53 INFO - 'TERM': 'linux', 13:16:53 INFO - 'TMOUT': '86400', 13:16:53 INFO - 'USER': 'cltbld', 13:16:53 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1469218590.635619-1304341150', 13:16:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:16:53 INFO - '_': '/tools/buildbot/bin/python'} 13:16:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:16:53 INFO - Downloading/unpacking jsonschema==2.5.1 13:16:53 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 13:16:53 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 13:16:53 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 13:16:53 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 13:16:53 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 13:16:53 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 13:16:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:16:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:16:55 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 13:16:55 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 13:16:55 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 13:16:55 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 13:16:55 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 13:16:55 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 13:16:55 INFO - Downloading functools32-3.2.3-2.tar.gz 13:16:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:16:55 INFO - warning: no files found matching '*.txt' 13:16:55 INFO - no previously-included directories found matching 'build' 13:16:55 INFO - no previously-included directories found matching 'dist' 13:16:55 INFO - no previously-included directories found matching '.git*' 13:16:55 INFO - Installing collected packages: jsonschema, functools32 13:16:55 INFO - Running setup.py install for functools32 13:16:55 INFO - warning: no files found matching '*.txt' 13:16:55 INFO - no previously-included directories found matching 'build' 13:16:55 INFO - no previously-included directories found matching 'dist' 13:16:55 INFO - no previously-included directories found matching '.git*' 13:16:55 INFO - Successfully installed jsonschema functools32 13:16:55 INFO - Cleaning up... 13:16:55 INFO - Return code: 0 13:16:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:16:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:16:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:16:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:16:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:16:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:16:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:16:56 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 0x172d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19ee180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ec3b0>, '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 0x19ed2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19ed740>, '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': 'ed14279e38b05397cf4aa391000001a4-1469218590.635619-1304341150', '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 13:16:56 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 13:16:56 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 13:16:56 INFO - Using env: {'DISPLAY': ':0', 13:16:56 INFO - 'HOME': '/home/cltbld', 13:16:56 INFO - 'LANG': 'en_US.UTF-8', 13:16:56 INFO - 'LANGUAGE': 'en_US:en', 13:16:56 INFO - 'LOGNAME': 'cltbld', 13:16:56 INFO - 'MAIL': '/var/mail/cltbld', 13:16:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:16:56 INFO - 'MOZ_NO_REMOTE': '1', 13:16:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:16:56 INFO - 'NO_EM_RESTART': '1', 13:16:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:16:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:16:56 INFO - 'PWD': '/builds/slave/test', 13:16:56 INFO - 'SHELL': '/bin/bash', 13:16:56 INFO - 'SHLVL': '1', 13:16:56 INFO - 'TERM': 'linux', 13:16:56 INFO - 'TMOUT': '86400', 13:16:56 INFO - 'USER': 'cltbld', 13:16:56 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1469218590.635619-1304341150', 13:16:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:16:56 INFO - '_': '/tools/buildbot/bin/python'} 13:16:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:16:56 INFO - Downloading/unpacking blobuploader==1.2.4 13:16:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:16:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:16:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:16:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:16:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:16:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:16:58 INFO - Downloading blobuploader-1.2.4.tar.gz 13:16:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:16:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:16:58 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 13:16:58 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 13:16:58 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 13:16:58 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 13:16:58 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 13:16:58 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 13:16:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:16:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:16:58 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 13:16:58 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 13:16:58 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 13:16:58 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 13:16:58 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 13:16:58 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 13:16:58 INFO - Downloading docopt-0.6.1.tar.gz 13:16:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:16:58 INFO - Installing collected packages: blobuploader, requests, docopt 13:16:58 INFO - Running setup.py install for blobuploader 13:16:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:16:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:16:58 INFO - Running setup.py install for requests 13:16:59 INFO - Running setup.py install for docopt 13:16:59 INFO - Successfully installed blobuploader requests docopt 13:16:59 INFO - Cleaning up... 13:16:59 INFO - Return code: 0 13:16:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:16:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:16:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:16:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:16:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:16:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:16:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:16:59 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 0x172d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19ee180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ec3b0>, '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 0x19ed2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19ed740>, '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': 'ed14279e38b05397cf4aa391000001a4-1469218590.635619-1304341150', '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 13:16:59 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 13:16:59 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 13:16:59 INFO - Using env: {'DISPLAY': ':0', 13:16:59 INFO - 'HOME': '/home/cltbld', 13:16:59 INFO - 'LANG': 'en_US.UTF-8', 13:16:59 INFO - 'LANGUAGE': 'en_US:en', 13:16:59 INFO - 'LOGNAME': 'cltbld', 13:16:59 INFO - 'MAIL': '/var/mail/cltbld', 13:16:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:16:59 INFO - 'MOZ_NO_REMOTE': '1', 13:16:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:16:59 INFO - 'NO_EM_RESTART': '1', 13:16:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:16:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:16:59 INFO - 'PWD': '/builds/slave/test', 13:16:59 INFO - 'SHELL': '/bin/bash', 13:16:59 INFO - 'SHLVL': '1', 13:16:59 INFO - 'TERM': 'linux', 13:16:59 INFO - 'TMOUT': '86400', 13:16:59 INFO - 'USER': 'cltbld', 13:16:59 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1469218590.635619-1304341150', 13:16:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:16:59 INFO - '_': '/tools/buildbot/bin/python'} 13:16:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:16:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:16:59 INFO - Running setup.py (path:/tmp/pip-5NKRPz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:16:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:16:59 INFO - Running setup.py (path:/tmp/pip-TVXOAi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:16:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:16:59 INFO - Running setup.py (path:/tmp/pip-uun9ji-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:16:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:16:59 INFO - Running setup.py (path:/tmp/pip-fY_lph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:17:00 INFO - Running setup.py (path:/tmp/pip-pzDU1w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:17:00 INFO - Running setup.py (path:/tmp/pip-rdF6Pm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:17:00 INFO - Running setup.py (path:/tmp/pip-6jXgNx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:17:00 INFO - Running setup.py (path:/tmp/pip-BkwztK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:17:00 INFO - Running setup.py (path:/tmp/pip-4rt55F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:17:00 INFO - Running setup.py (path:/tmp/pip-zFtXRX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:17:00 INFO - Running setup.py (path:/tmp/pip-VcmqcQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:17:00 INFO - Running setup.py (path:/tmp/pip-W1JRa7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:17:00 INFO - Running setup.py (path:/tmp/pip-2roSxT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:17:00 INFO - Running setup.py (path:/tmp/pip-LCy29G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:17:00 INFO - Running setup.py (path:/tmp/pip-O5m01E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:17:00 INFO - Running setup.py (path:/tmp/pip-Kzlog0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:17:00 INFO - Running setup.py (path:/tmp/pip-vjmhQo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:17:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:17:00 INFO - Running setup.py install for manifestparser 13:17:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:17:01 INFO - Running setup.py install for mozcrash 13:17:01 INFO - Running setup.py install for mozdebug 13:17:01 INFO - Running setup.py install for mozdevice 13:17:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:17:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:17:01 INFO - Running setup.py install for mozfile 13:17:01 INFO - Running setup.py install for mozhttpd 13:17:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:17:01 INFO - Running setup.py install for mozinfo 13:17:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:17:01 INFO - Running setup.py install for mozInstall 13:17:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:17:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:17:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:17:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:17:01 INFO - Running setup.py install for mozleak 13:17:01 INFO - Running setup.py install for mozlog 13:17:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:17:01 INFO - Running setup.py install for moznetwork 13:17:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:17:02 INFO - Running setup.py install for mozprocess 13:17:02 INFO - Running setup.py install for mozprofile 13:17:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:17:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:17:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:17:02 INFO - Running setup.py install for mozrunner 13:17:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:17:02 INFO - Running setup.py install for mozscreenshot 13:17:02 INFO - Running setup.py install for moztest 13:17:02 INFO - Running setup.py install for mozversion 13:17:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:17:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:17:02 INFO - Cleaning up... 13:17:02 INFO - Return code: 0 13:17:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:17:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:17:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:17:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:17:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:17:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:02 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 0x172d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19ee180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ec3b0>, '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 0x19ed2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19ed740>, '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': 'ed14279e38b05397cf4aa391000001a4-1469218590.635619-1304341150', '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 13:17:02 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 13:17:02 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 13:17:02 INFO - Using env: {'DISPLAY': ':0', 13:17:02 INFO - 'HOME': '/home/cltbld', 13:17:02 INFO - 'LANG': 'en_US.UTF-8', 13:17:02 INFO - 'LANGUAGE': 'en_US:en', 13:17:02 INFO - 'LOGNAME': 'cltbld', 13:17:02 INFO - 'MAIL': '/var/mail/cltbld', 13:17:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:17:02 INFO - 'MOZ_NO_REMOTE': '1', 13:17:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:17:02 INFO - 'NO_EM_RESTART': '1', 13:17:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:17:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:17:02 INFO - 'PWD': '/builds/slave/test', 13:17:02 INFO - 'SHELL': '/bin/bash', 13:17:02 INFO - 'SHLVL': '1', 13:17:02 INFO - 'TERM': 'linux', 13:17:02 INFO - 'TMOUT': '86400', 13:17:02 INFO - 'USER': 'cltbld', 13:17:02 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1469218590.635619-1304341150', 13:17:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:17:02 INFO - '_': '/tools/buildbot/bin/python'} 13:17:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:17:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:17:02 INFO - Running setup.py (path:/tmp/pip-f0fvqb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:17:03 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)) 13:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:17:03 INFO - Running setup.py (path:/tmp/pip-8h8Cuo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:17:03 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)) 13:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:17:03 INFO - Running setup.py (path:/tmp/pip-fNovW3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:17:03 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)) 13:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:17:03 INFO - Running setup.py (path:/tmp/pip-Ft4Yx8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:17:03 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)) 13:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:17:03 INFO - Running setup.py (path:/tmp/pip-BwtpgP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:17:03 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)) 13:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:17:03 INFO - Running setup.py (path:/tmp/pip-s4txgJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:17:03 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)) 13:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:17:03 INFO - Running setup.py (path:/tmp/pip-IhUI7V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:17:03 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)) 13:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:17:03 INFO - Running setup.py (path:/tmp/pip-fGMOc_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:17:03 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)) 13:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:17:03 INFO - Running setup.py (path:/tmp/pip-k_Y7ct-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:17:03 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)) 13:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:17:03 INFO - Running setup.py (path:/tmp/pip-eEsLv6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:17:03 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)) 13:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:17:03 INFO - Running setup.py (path:/tmp/pip-lPLCph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:17:03 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)) 13:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:17:03 INFO - Running setup.py (path:/tmp/pip-IS0pzN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:17:03 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)) 13:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:17:03 INFO - Running setup.py (path:/tmp/pip-_m32i6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:17:03 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)) 13:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:17:03 INFO - Running setup.py (path:/tmp/pip-yq95RD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:17:04 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)) 13:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:17:04 INFO - Running setup.py (path:/tmp/pip-7chaOA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:17:04 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)) 13:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:17:04 INFO - Running setup.py (path:/tmp/pip-7CORu2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:17:04 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)) 13:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:17:04 INFO - Running setup.py (path:/tmp/pip-fpg75R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:17:04 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)) 13:17:04 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)) 13:17:04 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)) 13:17:04 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)) 13:17:04 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)) 13:17:04 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)) 13:17:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:17:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:17:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:17:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:17:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:17:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:17:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:17:06 INFO - Downloading blessings-1.6.tar.gz 13:17:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:17:06 INFO - Installing collected packages: blessings 13:17:06 INFO - Running setup.py install for blessings 13:17:06 INFO - Successfully installed blessings 13:17:06 INFO - Cleaning up... 13:17:06 INFO - Return code: 0 13:17:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:17:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:17:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:17:06 INFO - Reading from file tmpfile_stdout 13:17:06 INFO - Current package versions: 13:17:06 INFO - argparse == 1.2.1 13:17:06 INFO - blessings == 1.6 13:17:06 INFO - blobuploader == 1.2.4 13:17:06 INFO - docopt == 0.6.1 13:17:06 INFO - functools32 == 3.2.3-2 13:17:06 INFO - jsonschema == 2.5.1 13:17:06 INFO - manifestparser == 1.1 13:17:06 INFO - mozInstall == 1.12 13:17:06 INFO - mozcrash == 0.17 13:17:06 INFO - mozdebug == 0.1 13:17:06 INFO - mozdevice == 0.48 13:17:06 INFO - mozfile == 1.2 13:17:06 INFO - mozhttpd == 0.7 13:17:06 INFO - mozinfo == 0.9 13:17:06 INFO - mozleak == 0.1 13:17:06 INFO - mozlog == 3.3 13:17:06 INFO - moznetwork == 0.27 13:17:06 INFO - mozprocess == 0.23 13:17:06 INFO - mozprofile == 0.28 13:17:06 INFO - mozrunner == 6.12 13:17:06 INFO - mozscreenshot == 0.1 13:17:06 INFO - mozsystemmonitor == 0.3 13:17:06 INFO - moztest == 0.7 13:17:06 INFO - mozversion == 1.4 13:17:06 INFO - psutil == 3.1.1 13:17:06 INFO - requests == 1.2.3 13:17:06 INFO - wsgiref == 0.1.2 13:17:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:17:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:17:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:17:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:17:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:17:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:06 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 0x172d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19ee180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ec3b0>, '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 0x19ed2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19ed740>, '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': 'ed14279e38b05397cf4aa391000001a4-1469218590.635619-1304341150', '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 13:17:06 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 13:17:06 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 13:17:06 INFO - Using env: {'DISPLAY': ':0', 13:17:06 INFO - 'HOME': '/home/cltbld', 13:17:06 INFO - 'LANG': 'en_US.UTF-8', 13:17:06 INFO - 'LANGUAGE': 'en_US:en', 13:17:06 INFO - 'LOGNAME': 'cltbld', 13:17:06 INFO - 'MAIL': '/var/mail/cltbld', 13:17:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:17:06 INFO - 'MOZ_NO_REMOTE': '1', 13:17:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:17:06 INFO - 'NO_EM_RESTART': '1', 13:17:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:17:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:17:06 INFO - 'PWD': '/builds/slave/test', 13:17:06 INFO - 'SHELL': '/bin/bash', 13:17:06 INFO - 'SHLVL': '1', 13:17:06 INFO - 'TERM': 'linux', 13:17:06 INFO - 'TMOUT': '86400', 13:17:06 INFO - 'USER': 'cltbld', 13:17:06 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1469218590.635619-1304341150', 13:17:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:17:06 INFO - '_': '/tools/buildbot/bin/python'} 13:17:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:17:06 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)) 13:17:06 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)) 13:17:06 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)) 13:17:06 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)) 13:17:06 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)) 13:17:06 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)) 13:17:06 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)) 13:17:06 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)) 13:17:06 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)) 13:17:06 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)) 13:17:06 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)) 13:17:06 INFO - Cleaning up... 13:17:06 INFO - Return code: 0 13:17:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:17:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:17:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:17:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:17:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:17:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:06 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 0x172d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19ee180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ec3b0>, '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 0x19ed2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19ed740>, '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': 'ed14279e38b05397cf4aa391000001a4-1469218590.635619-1304341150', '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 13:17:06 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 13:17:06 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 13:17:06 INFO - Using env: {'DISPLAY': ':0', 13:17:06 INFO - 'HOME': '/home/cltbld', 13:17:06 INFO - 'LANG': 'en_US.UTF-8', 13:17:06 INFO - 'LANGUAGE': 'en_US:en', 13:17:06 INFO - 'LOGNAME': 'cltbld', 13:17:06 INFO - 'MAIL': '/var/mail/cltbld', 13:17:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:17:06 INFO - 'MOZ_NO_REMOTE': '1', 13:17:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:17:06 INFO - 'NO_EM_RESTART': '1', 13:17:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:17:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:17:06 INFO - 'PWD': '/builds/slave/test', 13:17:06 INFO - 'SHELL': '/bin/bash', 13:17:06 INFO - 'SHLVL': '1', 13:17:06 INFO - 'TERM': 'linux', 13:17:06 INFO - 'TMOUT': '86400', 13:17:06 INFO - 'USER': 'cltbld', 13:17:06 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1469218590.635619-1304341150', 13:17:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:17:06 INFO - '_': '/tools/buildbot/bin/python'} 13:17:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:17:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:17:07 INFO - Cleaning up... 13:17:07 INFO - Return code: 0 13:17:07 INFO - Running post-action listener: _resource_record_post_action 13:17:07 INFO - Running post-action listener: _start_resource_monitoring 13:17:07 INFO - Starting resource monitoring. 13:17:07 INFO - [mozharness: 2016-07-22 20:17:07.084414Z] Finished create-virtualenv step (success) 13:17:07 INFO - [mozharness: 2016-07-22 20:17:07.084990Z] Running install step. 13:17:07 INFO - Running pre-action listener: _resource_record_pre_action 13:17:07 INFO - Running main action method: install 13:17:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:17:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:17:07 INFO - Reading from file tmpfile_stdout 13:17:07 INFO - Detecting whether we're running mozinstall >=1.0... 13:17:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:17:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:17:07 INFO - Reading from file tmpfile_stdout 13:17:07 INFO - Output received: 13:17:07 INFO - Usage: mozinstall [options] installer 13:17:07 INFO - Options: 13:17:07 INFO - -h, --help show this help message and exit 13:17:07 INFO - -d DEST, --destination=DEST 13:17:07 INFO - Directory to install application into. [default: 13:17:07 INFO - "/builds/slave/test"] 13:17:07 INFO - --app=APP Application being installed. [default: firefox] 13:17:07 INFO - mkdir: /builds/slave/test/build/application 13:17:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 13:17:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 13:17:24 INFO - Reading from file tmpfile_stdout 13:17:24 INFO - Output received: 13:17:24 INFO - /builds/slave/test/build/application/firefox/firefox 13:17:24 INFO - Running post-action listener: _resource_record_post_action 13:17:24 INFO - [mozharness: 2016-07-22 20:17:24.610111Z] Finished install step (success) 13:17:24 INFO - [mozharness: 2016-07-22 20:17:24.610394Z] Running run-tests step. 13:17:24 INFO - Running pre-action listener: _resource_record_pre_action 13:17:24 INFO - Running main action method: run_tests 13:17:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:17:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:17:24 INFO - Python 2.7.3 13:17:24 INFO - Return code: 0 13:17:24 INFO - grabbing minidump binary from tooltool 13:17:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:17:24 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 0x19ec3b0>, '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 0x19ed2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19ed740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:17:24 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 13:17:24 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 13:17:24 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 13:17:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:17:24 INFO - Return code: 0 13:17:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 13:17:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:17:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:17:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 13:17:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:17:24 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/T7TiM5WgRg-kK5jJcZh3SQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--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 13:17:24 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/T7TiM5WgRg-kK5jJcZh3SQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-035 --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 13:17:24 INFO - Using env: {'DISPLAY': ':0', 13:17:24 INFO - 'HOME': '/home/cltbld', 13:17:24 INFO - 'LANG': 'en_US.UTF-8', 13:17:24 INFO - 'LANGUAGE': 'en_US:en', 13:17:24 INFO - 'LOGNAME': 'cltbld', 13:17:24 INFO - 'MAIL': '/var/mail/cltbld', 13:17:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:17:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:17:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:17:24 INFO - 'MOZ_NO_REMOTE': '1', 13:17:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:17:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:17:24 INFO - 'NO_EM_RESTART': '1', 13:17:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:17:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:17:24 INFO - 'PWD': '/builds/slave/test', 13:17:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:17:24 INFO - 'SHELL': '/bin/bash', 13:17:24 INFO - 'SHLVL': '1', 13:17:24 INFO - 'TERM': 'linux', 13:17:24 INFO - 'TMOUT': '86400', 13:17:24 INFO - 'USER': 'cltbld', 13:17:24 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1469218590.635619-1304341150', 13:17:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:17:24 INFO - '_': '/tools/buildbot/bin/python'} 13:17:24 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/T7TiM5WgRg-kK5jJcZh3SQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--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 13:17:24 INFO - mozversion application_buildid: 20160722063002 13:17:24 INFO - mozversion application_changeset: a31e5c25c77773e26017df7ed4bc8355cd80c2af 13:17:24 INFO - mozversion application_display_name: Nightly 13:17:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:17:24 INFO - mozversion application_name: Firefox 13:17:24 INFO - mozversion application_remotingname: firefox 13:17:24 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 13:17:24 INFO - mozversion application_vendor: Mozilla 13:17:24 INFO - mozversion application_version: 50.0a1 13:17:24 INFO - mozversion platform_buildid: 20160722063002 13:17:24 INFO - mozversion platform_changeset: a31e5c25c77773e26017df7ed4bc8355cd80c2af 13:17:24 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 13:17:24 INFO - mozversion platform_version: 50.0a1 13:17:24 INFO - using testdate: 1469218644 13:17:24 INFO - actual date: 1469218644 13:17:24 INFO - starting webserver on 'localhost:42613' 13:17:24 INFO - SUITE-START | Running 2 tests 13:17:24 INFO - TEST-START | dromaeo_css 13:17:24 INFO - operating with platform_type : linux_ 13:17:24 INFO - Initialising browser for dromaeo_css test... 13:17:24 INFO - TEST-INFO | started process 14595 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbOBK76/profile http://localhost:42613/getInfo.html) 13:17:27 INFO - PROCESS | 14595 | JavaScript error: , line 0: uncaught exception: undefined 13:17:28 INFO - PROCESS | 14595 | __metrics Screen width/height:1600/1200 13:17:28 INFO - PROCESS | 14595 | colorDepth:24 13:17:28 INFO - PROCESS | 14595 | Browser inner width/height: 1024/697 13:17:28 INFO - PROCESS | 14595 | __metrics 13:17:29 INFO - PROCESS | 14595 | 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" 13:17:29 INFO - PROCESS | 14595 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:17:29 INFO - PROCESS | 14595 | console.error: PushService: 13:17:29 INFO - PROCESS | 14595 | stateChangeProcessEnqueue: Error transitioning state 13:17:29 INFO - PROCESS | 14595 | UnknownError 13:17:30 INFO - TEST-INFO | 14595: exit 0 13:17:30 INFO - Browser initialized. 13:17:30 INFO - Running cycle 1/1 for dromaeo_css test... 13:17:30 INFO - TEST-INFO | started process 14672 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbOBK76/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:17:42 INFO - PROCESS | 14672 | RSS: Main: 157728768 13:17:42 INFO - PROCESS | 14672 | 13:18:33 INFO - PROCESS | 14672 | 1469218713424 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] 13:20:41 INFO - PROCESS | 14672 | Cycle 1(1): loaded http://localhost:42613/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42613/tests/dromaeo/cssquery-ext.html) 13:20:42 INFO - PROCESS | 14672 | RSS: Main: 272314368 13:20:42 INFO - PROCESS | 14672 | 13:23:44 INFO - PROCESS | 14672 | Cycle 1(1): loaded http://localhost:42613/tests/dromaeo/cssquery-ext.html (next: http://localhost:42613/tests/dromaeo/cssquery-jquery.html) 13:23:45 INFO - PROCESS | 14672 | RSS: Main: 238698496 13:23:45 INFO - PROCESS | 14672 | 13:26:47 INFO - PROCESS | 14672 | Cycle 1(1): loaded http://localhost:42613/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42613/tests/dromaeo/cssquery-mootools.html) 13:26:48 INFO - PROCESS | 14672 | RSS: Main: 186048512 13:26:48 INFO - PROCESS | 14672 | 13:29:50 INFO - PROCESS | 14672 | Cycle 1(1): loaded http://localhost:42613/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42613/tests/dromaeo/cssquery-prototype.html) 13:29:50 INFO - PROCESS | 14672 | RSS: Main: 217194496 13:29:50 INFO - PROCESS | 14672 | 13:32:53 INFO - PROCESS | 14672 | Cycle 1(1): loaded http://localhost:42613/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42613/tests/dromaeo/cssquery-yui.html) 13:32:53 INFO - PROCESS | 14672 | RSS: Main: 184799232 13:32:53 INFO - PROCESS | 14672 | 13:35:56 INFO - PROCESS | 14672 | Cycle 1(1): loaded http://localhost:42613/tests/dromaeo/cssquery-yui.html (next: http://localhost:42613/tests/dromaeo/cssquery-dojo.html) 13:35:56 INFO - PROCESS | 14672 | RSS: Main: 260083712 13:35:56 INFO - PROCESS | 14672 | 13:35:56 INFO - PROCESS | 14672 | __start_tp_report 13:35:56 INFO - PROCESS | 14672 | _x_x_mozilla_page_load 13:35:56 INFO - PROCESS | 14672 | _x_x_mozilla_page_load_details 13:35:56 INFO - PROCESS | 14672 | |i|pagename|runs| 13:35:56 INFO - PROCESS | 14672 | |0;dojo.html;3042.528408096745;3057.449659061368;3078.30738083931;3083.737882280006;3093.922651933701;5119.104156772559;5124.461931497195;5227.137522308819;5301.628885977978;5321.281626980356;5276.314079169712;5288.709120998344;5314.388845282793;5357.785688572452;5375.704336261514;4759.785809638566;4787.353707249529;4858;4859.416869975608;4974.900501989958;4892.996935628205;4902.289168070628;4930.975345123269;4951.158303088484;4962.702237865721;4717.103750287434;4747.667663263555;4771.976140119312;4794.856154315341;4863.440704319013;4786.688865223749;4827.61379089672;4833.419989601235;4836.322914791932;4879.585235255007;4803.351547541072;4805.8558243252755;4810.6151507879285;4822.975885120587;4825.469198388175;5127.66670166438;5212.609054320903;5279.9208011879855;5282.603804714662;5330.840074797724;5687.744051517691;5693;5734.627249228828;5743.138529220609;5745.827625171251;5715.885682286373;5746.770129194827;5762.654240745527;5773.2494775679315;5777.59556831022;5169.793208271664;5199;5241.764120614582;5242.423333433319;5313.229581710631;5803.419658034163;5804.04233301509;5805.912911306334;5812.128180772918;5815.9709201453725;5584.190292407659;5590.273264475673;5596.692181930033;5598.860028499239;5598.86002849932;5504.834854954358;5621.353544342372;5660.632058916157;5674.801381951612;5747.252857128547;5673.177389278531;5677.772889084473;5678.346990096109;5679.716014199274;5735.71321433935;5636.577256705764;5670;5684.289463817023;5685.3177618685195;5689.004424225744;4186.811593478301;4289.378040184156;4336.501302350207;4340.175366680321;4342.956570434255;3868.052327179803;3874.3994680824517;3888.1446081861945;3914.6085391460624;3921.0589458530317;5410.594205434608;5415.187721841755;5418.322709661293;5422.267993820863;5459.50864422196;5514.834554963358;5616.129499927518;5640;5660.264165658438;5747.453991870766;5338.599605029638;5359.624826262124;5363.973180134074;5367.946320536823;5369.382521010056;4897.216445368724;4900.436449808318;4903.239997800347;4917.262410638361;4939.777710002986;5639.0977443609645;5801.738921748447;5829.621074630136;5833.562482813806;5963.5825492216;5721.799737009184;5728.255326807488;5736.598438109292;5741.770329186786;5745.913811292918;5872.412758724094;5891.58758886891;5906.084556893688;5907.822765317047;5907.940920590911;5383.973080134574;5478;5507.146392309199;5521.861953451196;5589.9938011156755;5596.804111856066;5621.8594535136945;5643.492085712305;5645.971770141123;5651.095824668033;5454.672719636835;5505.862353441035;5506.697131657797;5507.586930980113;5549.417311182429;5320.787168513216;5467.644603100785;5551.639143455824;5562.972185139049;5657.660540367591;5412.404635490172;5415.972920135374;5419.891602168013;5421.810236641856;5422.267993820784;5476.507114359727;5499.890002200013;5541;5548.112302031655;5550.361708403585;5289.259503669567;5340.038793017294;5404.918926216189;5416.51251387377;5449.100898351728 13:35:56 INFO - PROCESS | 14672 | |1;ext.html;571.8456016875443;611.6574718157832;625.9109150078863;634.3688030409743;664.1830548425439;45490.545094549045;46577;46658.06683866321;46734.298985515205;49751.50248497514;46624;47206.0558788824;47240.29139562901;47245.29132063013;47247.52752472474;9148.725538233864;9163.725088247347;9275.257979361635;9305.860412093823;9310.30172737044;9042.321825863053;9135.817283654324;9154.86267705985;9173.724788256362;9175.541222938826;9197.724068277963;9239.95380023099;9253.30600204984;9267.026962168942;9270.351075424724;9152.450852948845;9160.954195228982;9184.127507886808;9194.54027298639;9197.81604367909;9116.1339672731;9127.634894604207;9197.126273004054;9200.067985723006;9201.309901757395;44468.77765611151;44933;44940.77529612364;44969.325460118125;45000;3585.6235095315137;3587.5694916609914;3612.7290453216115;3679;4104.15864747729;33303.83348083268;33386.49920251198;33979.830100849584;34007;34155.829220853986;4304.526502084768;4372.27857403528;4451.554844515555;4469.597736203725;4561.7262964222255;374719;375079;376796;376821.1158944215;378164;61547.38452615506;61822.38177618256;61866.38133618696;61889.38110618926;61891;56033;57755.42244577585;63537.36462635407;65203;65328.34671653223;14510.709785804225;14605.707885842223;14622.341994610268;14921.477748278758;14960.102393856403;10610;10613.469326533643;10615.681529554126;10622.36265824053;10800.243982921278;16077.517674469784;18094.09529523545;18106.45680629583;18213.817861821477;18662.81337186611;5925.970370148121;6191.938080619227;6261.592996455216;6297.527685423612;6359.17330747;1036.1969473657975;1036.9740756481149;1050.826613608746;1080.4057768227444;1094.9616763413242;33167.83416082904;33252;33407;33591.32817343639;33733.325333493194;1064.3826580583243;1123.0902968595465;1124.460259075632;1140.4126874659557;1183.7395772929942;17177.656446871242;17687.557811054827;17821.643567128584;17835.197416116305;17836.55408614795;608209;608620.6551737969;609586;609747;610024.3484173515;60767;60904.69547652322;60939.39060609426;61072;61111.38888611057;1316.5918565244804;1352.4184600621631;1357.0297237475252;1357.307773035739;1374.5807528703842;31855;32649.510257346636;33263;33472.49791253182;34258.314833703684;54333;54699;54805.067543921235;54850.725746372605;54883.17675234795;494685;497553;499586;500594;501640;43664.781676091414;44260.33609495796;45221.32168017548;45425.772871135436;45693.086138277715;49298.26052609142;49515;50004;50288.748556256985;51308.23037654364;41298.58701413067;43096.06067727022;43391.13217735691;44029.27446555123;44314.55685443233;43733.78133109314;43943.56056439522;44044.77977610091;44242.3363649552;44276.557234427244;41971.37042944297;42067.7896610515;42485.787571063185;43145.784271078446;43378;36938.44592331063;38544.42183367308;38567.80716096514;38995.41506877342;39721.60278397179;28482;28510.714892851636;28661.426771464037;28724.425511489237;28942.421151577273 13:35:56 INFO - PROCESS | 14672 | |2;jquery.html;375.8778397020969;388.3146246874267;401.24766063630693;406.9165821006694;417.213552453625;14420.278986050707;18013.7297940531;20693.99999999998;20701.275455359064;20739.481512962182;19242.615147697037;19819.20723171075;19840.206391744312;20928.476788080305;21950.231741889038;4730.952690473086;6568.113304703876;6860.451163906901;6864.2106157791995;6870.553414028072;7351.228121047293;7696.307332340089;7770.9611451942665;7858.567778772165;7864.095629002681;7607.809804754897;7730.8453830923345;7731.574763388011;7738.651760670755;7793.959408734133;7501.43739219558;7704.730334438295;7712.537247765096;7740.419468539854;7796.688132474695;7277.19950805411;7494.775156745306;7500.062492188476;7528.284812942762;7528.322450979384;27204.863975680193;27557.586636200474;27717.307067323276;27778.583321250197;28239;2511.3219430753775;2565.3330134165067;2579.2520169150926;2743.341598016482;3056.2664960409566;15809.841901580952;15854;16235.512934611981;16465.94175728034;17454.127293635396;2104.6527322991687;2127.1918868961416;2129.765725770164;2176.4014895903597;2196.2752291743686;76296;79718;88153.55923220408;90971.09028909693;98988.50505747498;17471.65056698859;17974.191161397768;18144;18669;18828.52928676794;33651.66348336485;33720.49419258713;33723.156921076676;33774.960253577294;33917.760279965005;9071.228945539571;9250.95374523123;9371.671991480402;9388.483633400228;9433.179562277151;7325.670344834495;7498.887516687254;7512;7697.730579429693;7728.845423091507;8673.392862499564;9242.12199841014;9328.486933218737;9335.719928402159;9466.964602610471;3137.262743255333;3388.6614787158933;3478.1652403422995;3478.617352091268;3487.5989261234777;987.5605355616682;997.6408492942535;1005.835037084576;1054.5676272728144;1060.5916722061907;18830.905845470686;20390.490237744176;20540.794592054186;20550.69173962392;20555.38333849987;960.6877764726491;996.038822536256;996.6163027234422;1010.8028934357877;1025.8563801067855;8945.15021072997;10791.78416431667;11110.166737494721;11183.83224251637;11247.606333778278;522884;527514;528831;542858;544509;63921.36078639247;72404;96993.51503242439;98775.50612247037;102705;911.1480765484293;971.4851128901552;978.1881038737973;1015.9593616255268;1018.134585602247;17719.114044297476;18461.353852615095;18663.346782862536;18723.812761872752;18751.531211719815;44012.33981490344;44319.33520997123;45029.774851125534;46063;46670;62624;63694.68152659207;63913.680431597546;63935.68032159809;64610.35389646043;8293.004839419067;8308.376871734523;8534.74395768128;8706.216440520384;8708.433951793366;11366.43167841588;12804.423800929168;12939.223646581235;13171.275579842817;13861.306934653025;11593.88406115928;11692.181547291608;11989.700257493632;12029;12079.39603019863;11568.24806387582;11743.530258789553;11765;11810.468528916392;12109.576164834189;11854;12055.939720301692;12154.939225303817;12200.81698774537;12333.568325108577;8554.325885710288;8581.785455363666;8697.695580654647;8750.431221970552;8977.057408972225;11464.31214127155;11487.483063262007;11516.481758321062;11598.594049208237;12324.075694322783 13:35:56 INFO - PROCESS | 14672 | |3;mootools.html;1445.6964037552111;1507.9623009424765;1598.9840101598982;1736.6787144378284;1756.6837969165554;8830.60262288197;9463.81072378553;9466.952665236682;9472.384295020827;9477.81044379112;8015.4389192756535;8812.206901378875;8990.235829954445;9037.72886813395;9248.352615316931;2370.123054469844;2398.436367453647;2400.23192578375;2401.051584624081;2404.4349577849193;2384.821138414617;2393.796527295182;2435.2815919303757;2435.391152211947;2437.5124975004983;2380.1788383007834;2388.5819981503255;2389.8566086034893;2420.2376251480814;2426.514697060586;2366.656834758959;2366.8343215974887;2375.3942744600104;2375.596148654733;2424.3090719144957;2366.4202270443852;2367.4318163640773;2373.2761507740133;2373.5727569037567;2382.332946774906;9293.72118836436;9329.673461428885;9371.390859594105;9376.718698439057;9379.953100234523;5235.450277720861;5332.706701131437;5361.705106219157;5363.597730170253;5373.113436283009;3330.084226837615;3334.983325083359;3395.49067639856;3395.6943875051115;3496.4230901901155;1148.6439203846835;1148.6439203846835;1151.5739176504662;1162.1051790121644;1179.5399794080315;13216.6695832604;13248.86751132486;13288.933555332162;13322.200667959856;13331.533396331179;4645.953540464619;4728.787204575802;4754.191787396151;4783.23468245086;4941.629377796679;10947.124230061576;10978.341299522055;10991.06575941054;10993.780124397466;11008.229423940247;5335.253064570963;5338;5338.145896656516;5340.332458442695;5343.839684809462;4448.421705178306;4580.702254353457;4802.73584952824;4873.731944743002;4919.4834542373255;3994.540627827837;4058.553559108496;4160.791960401968;4176.665866730655;4204.558521355275;1296.0668318810442;1322.7486777512242;1330.388021510097;1334.9466021359037;1347.238810072306;974.741693451236;976.9071938165863;980.926430517714;989.6486747204783;1002.1982414068862;5981.820545383645;5995.460408563163;6096.207493025967;6127.3259941407305;6332.33510481402;443.7115874681483;446.3438745044779;446.56874716533434;452.9864104076883;458.09754783077284;4012.61880121388;4120.423140760296;4141.276578824413;4203.642690371292;4271.124419493996;11200.551977920955;11805.76388472243;11810.291382517076;11816.586419475278;11834.230774999769;11378.838020776026;11735.530578776925;11767.76464470723;11769.352685602204;11774.646760597196;8495;8723.127687231354;8727;8729.258013068833;8741.431806932529;5856.707164641836;5869.178315035983;5950.19672344228;6052.727627256696;6059.66687328787;10456.215783816093;10611.151107911193;10684.89315106839;10698.62554810578;10936.179786516203;11016;11020.44897755093;11061.557537698402;11075.05862001739;11090.611828585961;7229.277072292729;7720.459567830198;7823;8179.713710020121;8267.090620031671;8773.078826723358;8940.284777217677;8951.686690965786;8956.283497320068;8957.552122393985;7552.848943020999;7712.267334603203;7784.494007889469;8100.71647492335;8112.067112282163;7442.553446792993;7800;7825.569593672402;7849.17583653731;7862.842743144991;7180.928190718026;7213.098362704662;7232.529885557422;7240.52936559122;7245.130584329914;5164.896702066013;5271.578273738015;5303.522682958553;5317.282166907418;5574.386817450159;5142.6400151989;5164.509371609842;5180.818671346485;5229.50319719626;5322.680639161663 13:35:56 INFO - PROCESS | 14672 | |4;prototype.html;323.3645885834335;326.1601376455626;330.64013696546914;338.3267298076827;347.24127780798955;5328.520433161015;5364.597655175858;5371.597130215235;5377.6018235258825;5394.838154855361;5051.585556044304;5161.664491808039;5177.456367081459;5235.921461178076;5328.094223981922;4147.605977432139;4161.375793630949;4166.666666666674;4172.353285240777;4173.707840451162;4183.226103170908;4189.853355132555;4197.664186865059;4215.220184265905;4217.7891105444605;3838;3844.3656796628525;4074.388841673743;4108.322126849066;4126.195391898581;4101.815418306183;4114.979425102855;4119.814608342632;4122.732022418548;4128.360104183841;3623.7644553103964;3623.9128261521437;3626.3110009098186;3636.30910127075;3648.996525955357;4997.250412438127;5068.695878247317;5088.4148322942965;5098.158803797369;5119.411267704188;4711.411073615798;4729.716217026955;4754.833580824655;4759.904801903977;4764.499727528594;5452.372977107644;5471.781128754845;5496.148097044925;5507.917381239285;5530.336359636829;3909.7849618270984;3938.8774199353047;3996.500437445319;4009.7393669411686;4019.537753158395;5635.380108188096;5645.491905728463;5668.348139963916;5670.404607516192;5673.177389278531;4088.3049881520215;4102.953746794548;4124.237016152021;4126.628603425706;4133.55325636025;4074.124063326399;4133.751974881457;4181.330987042059;4188.937165942452;4193.454850869368;4669.276262179367;4689.7655117244;4691.225947718588;4707.587723682881;4719;5483.725813709299;5497.948638505588;5527.143292789624;5527.972360138174;5582.33012038558;1413.0250127412053;1448.7247422989599;1458.5624312706145;1459.7153555056661;1468.1704836767192;940.7488040905579;940.830650482906;943.1511639524346;946.4936259101348;959.6689142245914;4737.336772851803;4759.881002974953;4774.880627984328;4810.508742289901;4879.2925025871045;4741.549552792549;4972.502749725071;4996.525330093636;5014.89970200594;5015.749212539358;4694.943637681563;4736.194846876039;4742.810287588527;4794.688345257547;4801.807927682923;2673.5187666220268;2678.8794504246966;2687.8924843006453;2695.528282550546;2718.8504632245417;4871.269309603588;5097.260897169889;5131.7690703918415;5142.768575414116;5153.304303918998;5164.812093218581;5165.431802501722;5170.560502357266;5206.739663016834;5215.869603259873;4911.508849115131;5140.098163679462;5149.382074151051;5159.664621799571;5216.557072332243;4959.181735013683;4998.700077995332;5014.721246082226;5021.4225364083595;5042.647014709082;5056.8988620226655;5057.544820966131;5067.150490071172;5067.670601410897;5130.48695130484;4879.110012360939;4994.225894986283;5159.658488792866;5198.818041368534;5209.6092793041435;3719.739618226698;3733.1040550268285;3744.794036328028;3752.1932784451474;3763.9435408469444;5085.813100366878;5447.754851031634;5474.945250547603;5590.720463976703;5665.886682266249;5497.092979658311;5522.5858060646415;5526.751296191601;5531.695756733418;5551.167324901297;5211.557017653542;5503.889922201453;5521.254630624814;5524.475174858382;5561.5272701820795;5434.891302173855;5475.342958844804;5476.2333273342565;5476.479734425223;5480.479354461319;5567.749451274622;5598.748056337556;5614.287642269147;5643.689597072201;5645;5233.816816411408;5435.83692489226;5447.754851031792;5497.36780270274;5512.779488820403 13:35:56 INFO - PROCESS | 14672 | |5;yui.html;768.4236822383206;804.5260822969367;812.3501199040767;821.77401214666;846.7756044648168;14959.551213463586;15123;15210.707089897372;15297.311620977054;15438.922805385972;15652.452164174256;16141.354345826183;16248.18759062048;16842.494725158234;16987.490375288762;792.3542313014904;806.0528878567688;806.770070529899;806.7781360125983;806.7862016565585;669.712023829753;795.5107608820563;800.5596921693075;801.1307731111722;802.9638666260032;860.5267103093305;873.3799002708084;875.2297977779546;876.1588874680283;877.1929824561387;872.2847265242503;872.9869051964257;873.2839071960994;873.3711727556149;873.8514452543083;509.6025100421677;610.8625559249177;634.5399585300667;635.777477882742;635.8473966248114;15304.464343748026;15683.607909802233;15706.057636541844;15723.21383930799;15728.764068538982;14604.70790584193;14762.778558321634;15164.090154590649;15206.391744330214;15251;13783.8538550517;13934.442622295095;13950.720985580334;14046.71906561863;14160.716785664332;4605.976970115162;4685.109829132454;4702.8118875244945;4816.51834816519;4853.587445067174;84759;85675.99999999937;86442;87180.56409717911;87196.56401718015;11432.109367978517;11506.194566380273;11590.188686792013;11607;11668.241564298294;20324.59350813005;21686.45783855416;21725.891370543046;21736.239231626816;21764.129434822746;8473.703420380258;8591.828163436696;8783.91216087831;8827.558622069;8937.553122343857;4817.879553011203;5027.974860125676;5076.796928122833;5123.436421993578;5320.308359913187;3523.9428171548434;3624.2026754114054;3636.381815091388;3637.672609465161;3645.5989841117457;646.5603389694959;656.9408753212234;658.4830907737403;660.1418156396666;661.3452681844939;585.88868115058;681.2948598200838;732.4433430592787;749.7675720526654;754.1101500229781;9564.330496865292;9983.900160998443;10172.643957461454;10344.948275258575;10382.844257336146;678.6572780745691;688.2429327739446;690.1959217511643;720.495653042872;732.7801659502128;4821.349117869113;4926.679765815211;4968.180250258739;4977.477364876709;4979.3526841510375;117507;118691.40654296674;119535;120097;123371;103268.48365758124;104711.4764426173;104974;108968;109362;76736.61631691805;91093;91806;93474;94293;9917.504124793588;9956.262027942374;10081.747956300858;10379.221558383;10523.15814734804;54108;55335;55672.72163639156;55702;55863.44136558582;77520.61239693765;77906.6104669473;82041;82059.5897020511;84025;13303.46786128544;13486.123401979232;13724.725505490034;13817.309134543433;13868.237246951514;14981.700365992401;15079.472218472301;15288.159151246788;15566.221688915735;15979.360825566848;13079.346032698517;13185.406656700663;13189.802152967904;13319.400626972003;13683.452661893412;13927;13991.510297139552;14151.292435378396;14200.644983875072;14339.067960582528;13615.93192034073;13872.583822485341;14117.858821411655;14620.561383158521;14643.12141271527;10833.404162771123;11551.653450396501;11684.47419866125;12132.090093242865;12277;11289.05511732702;11353.982080016105;11460.828087578526;11522.13583981188;11786.58746943853 13:35:56 INFO - PROCESS | 14672 | __end_tp_report 13:35:56 INFO - PROCESS | 14672 | __start_cc_report 13:35:56 INFO - PROCESS | 14672 | _x_x_mozilla_cycle_collect,1062 13:35:56 INFO - PROCESS | 14672 | __end_cc_report 13:35:56 INFO - PROCESS | 14672 | __startTimestamp1469219756850__endTimestamp 13:35:56 INFO - PROCESS | 14672 | 13:35:56 INFO - PROCESS | 14672 | ------- Summary: start ------- 13:35:56 INFO - PROCESS | 14672 | Number of tests: 6 13:35:56 INFO - PROCESS | 14672 | 13:35:56 INFO - PROCESS | 14672 | [#0] dojo.html Cycles:165 Average:5260.99 Median:5421.81 stddev:594.55 (11.0%) stddev-sans-first:570.33 13:35:56 INFO - PROCESS | 14672 | Values: 3042.5 3057.4 3078.3 3083.7 3093.9 5119.1 5124.5 5227.1 5301.6 5321.3 5276.3 5288.7 5314.4 5357.8 5375.7 4759.8 4787.4 4858.0 4859.4 4974.9 4893.0 4902.3 4931.0 4951.2 4962.7 4717.1 4747.7 4772.0 4794.9 4863.4 4786.7 4827.6 4833.4 4836.3 4879.6 4803.4 4805.9 4810.6 4823.0 4825.5 5127.7 5212.6 5279.9 5282.6 5330.8 5687.7 5693.0 5734.6 5743.1 5745.8 5715.9 5746.8 5762.7 5773.2 5777.6 5169.8 5199.0 5241.8 5242.4 5313.2 5803.4 5804.0 5805.9 5812.1 5816.0 5584.2 5590.3 5596.7 5598.9 5598.9 5504.8 5621.4 5660.6 5674.8 5747.3 5673.2 5677.8 5678.3 5679.7 5735.7 5636.6 5670.0 5684.3 5685.3 5689.0 4186.8 4289.4 4336.5 4340.2 4343.0 3868.1 3874.4 3888.1 3914.6 3921.1 5410.6 5415.2 5418.3 5422.3 5459.5 5514.8 5616.1 5640.0 5660.3 5747.5 5338.6 5359.6 5364.0 5367.9 5369.4 4897.2 4900.4 4903.2 4917.3 4939.8 5639.1 5801.7 5829.6 5833.6 5963.6 5721.8 5728.3 5736.6 5741.8 5745.9 5872.4 5891.6 5906.1 5907.8 5907.9 5384.0 5478.0 5507.1 5521.9 5590.0 5596.8 5621.9 5643.5 5646.0 5651.1 5454.7 5505.9 5506.7 5507.6 5549.4 5320.8 5467.6 5551.6 5563.0 5657.7 5412.4 5416.0 5419.9 5421.8 5422.3 5476.5 5499.9 5541.0 5548.1 5550.4 5289.3 5340.0 5404.9 5416.5 5449.1 13:35:56 INFO - PROCESS | 14672 | 13:35:56 INFO - PROCESS | 14672 | [#1] ext.html Cycles:180 Average:66114.60 Median:4460.58 stddev:133945.05 (3002.9%) stddev-sans-first:134230.40 13:35:56 INFO - PROCESS | 14672 | Values: 571.8 611.7 625.9 634.4 664.2 45490.5 46577.0 46658.1 46734.3 49751.5 46624.0 47206.1 47240.3 47245.3 47247.5 9148.7 9163.7 9275.3 9305.9 9310.3 9042.3 9135.8 9154.9 9173.7 9175.5 9197.7 9240.0 9253.3 9267.0 9270.4 9152.5 9161.0 9184.1 9194.5 9197.8 9116.1 9127.6 9197.1 9200.1 9201.3 44468.8 44933.0 44940.8 44969.3 45000.0 3585.6 3587.6 3612.7 3679.0 4104.2 33303.8 33386.5 33979.8 34007.0 34155.8 4304.5 4372.3 4451.6 4469.6 4561.7 374719.0 375079.0 376796.0 376821.1 378164.0 61547.4 61822.4 61866.4 61889.4 61891.0 56033.0 57755.4 63537.4 65203.0 65328.3 14510.7 14605.7 14622.3 14921.5 14960.1 10610.0 10613.5 10615.7 10622.4 10800.2 16077.5 18094.1 18106.5 18213.8 18662.8 5926.0 6191.9 6261.6 6297.5 6359.2 1036.2 1037.0 1050.8 1080.4 1095.0 33167.8 33252.0 33407.0 33591.3 33733.3 1064.4 1123.1 1124.5 1140.4 1183.7 17177.7 17687.6 17821.6 17835.2 17836.6 608209.0 608620.7 609586.0 609747.0 610024.3 60767.0 60904.7 60939.4 61072.0 61111.4 1316.6 1352.4 1357.0 1357.3 1374.6 31855.0 32649.5 33263.0 33472.5 34258.3 54333.0 54699.0 54805.1 54850.7 54883.2 494685.0 497553.0 499586.0 500594.0 501640.0 43664.8 44260.3 45221.3 45425.8 45693.1 49298.3 49515.0 50004.0 50288.7 51308.2 41298.6 43096.1 43391.1 44029.3 44314.6 43733.8 43943.6 44044.8 44242.3 44276.6 41971.4 42067.8 42485.8 43145.8 43378.0 36938.4 38544.4 38567.8 38995.4 39721.6 28482.0 28510.7 28661.4 28724.4 28942.4 13:35:56 INFO - PROCESS | 14672 | 13:35:56 INFO - PROCESS | 14672 | [#2] jquery.html Cycles:180 Average:32023.99 Median:3483.11 stddev:87591.35 (2514.7%) stddev-sans-first:87804.83 13:35:56 INFO - PROCESS | 14672 | Values: 375.9 388.3 401.2 406.9 417.2 14420.3 18013.7 20694.0 20701.3 20739.5 19242.6 19819.2 19840.2 20928.5 21950.2 4731.0 6568.1 6860.5 6864.2 6870.6 7351.2 7696.3 7771.0 7858.6 7864.1 7607.8 7730.8 7731.6 7738.7 7794.0 7501.4 7704.7 7712.5 7740.4 7796.7 7277.2 7494.8 7500.1 7528.3 7528.3 27204.9 27557.6 27717.3 27778.6 28239.0 2511.3 2565.3 2579.3 2743.3 3056.3 15809.8 15854.0 16235.5 16465.9 17454.1 2104.7 2127.2 2129.8 2176.4 2196.3 76296.0 79718.0 88153.6 90971.1 98988.5 17471.7 17974.2 18144.0 18669.0 18828.5 33651.7 33720.5 33723.2 33775.0 33917.8 9071.2 9251.0 9371.7 9388.5 9433.2 7325.7 7498.9 7512.0 7697.7 7728.8 8673.4 9242.1 9328.5 9335.7 9467.0 3137.3 3388.7 3478.2 3478.6 3487.6 987.6 997.6 1005.8 1054.6 1060.6 18830.9 20390.5 20540.8 20550.7 20555.4 960.7 996.0 996.6 1010.8 1025.9 8945.2 10791.8 11110.2 11183.8 11247.6 522884.0 527514.0 528831.0 542858.0 544509.0 63921.4 72404.0 96993.5 98775.5 102705.0 911.1 971.5 978.2 1016.0 1018.1 17719.1 18461.4 18663.3 18723.8 18751.5 44012.3 44319.3 45029.8 46063.0 46670.0 62624.0 63694.7 63913.7 63935.7 64610.4 8293.0 8308.4 8534.7 8706.2 8708.4 11366.4 12804.4 12939.2 13171.3 13861.3 11593.9 11692.2 11989.7 12029.0 12079.4 11568.2 11743.5 11765.0 11810.5 12109.6 11854.0 12055.9 12154.9 12200.8 12333.6 8554.3 8581.8 8697.7 8750.4 8977.1 11464.3 11487.5 11516.5 11598.6 12324.1 13:35:56 INFO - PROCESS | 14672 | 13:35:56 INFO - PROCESS | 14672 | [#3] mootools.html Cycles:180 Average:6030.09 Median:455.54 stddev:3555.58 (780.5%) stddev-sans-first:3548.86 13:35:56 INFO - PROCESS | 14672 | Values: 1445.7 1508.0 1599.0 1736.7 1756.7 8830.6 9463.8 9467.0 9472.4 9477.8 8015.4 8812.2 8990.2 9037.7 9248.4 2370.1 2398.4 2400.2 2401.1 2404.4 2384.8 2393.8 2435.3 2435.4 2437.5 2380.2 2388.6 2389.9 2420.2 2426.5 2366.7 2366.8 2375.4 2375.6 2424.3 2366.4 2367.4 2373.3 2373.6 2382.3 9293.7 9329.7 9371.4 9376.7 9380.0 5235.5 5332.7 5361.7 5363.6 5373.1 3330.1 3335.0 3395.5 3395.7 3496.4 1148.6 1148.6 1151.6 1162.1 1179.5 13216.7 13248.9 13288.9 13322.2 13331.5 4646.0 4728.8 4754.2 4783.2 4941.6 10947.1 10978.3 10991.1 10993.8 11008.2 5335.3 5338.0 5338.1 5340.3 5343.8 4448.4 4580.7 4802.7 4873.7 4919.5 3994.5 4058.6 4160.8 4176.7 4204.6 1296.1 1322.7 1330.4 1334.9 1347.2 974.7 976.9 980.9 989.6 1002.2 5981.8 5995.5 6096.2 6127.3 6332.3 443.7 446.3 446.6 453.0 458.1 4012.6 4120.4 4141.3 4203.6 4271.1 11200.6 11805.8 11810.3 11816.6 11834.2 11378.8 11735.5 11767.8 11769.4 11774.6 8495.0 8723.1 8727.0 8729.3 8741.4 5856.7 5869.2 5950.2 6052.7 6059.7 10456.2 10611.2 10684.9 10698.6 10936.2 11016.0 11020.4 11061.6 11075.1 11090.6 7229.3 7720.5 7823.0 8179.7 8267.1 8773.1 8940.3 8951.7 8956.3 8957.6 7552.8 7712.3 7784.5 8100.7 8112.1 7442.6 7800.0 7825.6 7849.2 7862.8 7180.9 7213.1 7232.5 7240.5 7245.1 5164.9 5271.6 5303.5 5317.3 5574.4 5142.6 5164.5 5180.8 5229.5 5322.7 13:35:56 INFO - PROCESS | 14672 | 13:35:56 INFO - PROCESS | 14672 | [#4] prototype.html Cycles:180 Average:4479.53 Median:5014.81 stddev:1279.54 (25.5%) stddev-sans-first:1244.52 13:35:56 INFO - PROCESS | 14672 | Values: 323.4 326.2 330.6 338.3 347.2 5328.5 5364.6 5371.6 5377.6 5394.8 5051.6 5161.7 5177.5 5235.9 5328.1 4147.6 4161.4 4166.7 4172.4 4173.7 4183.2 4189.9 4197.7 4215.2 4217.8 3838.0 3844.4 4074.4 4108.3 4126.2 4101.8 4115.0 4119.8 4122.7 4128.4 3623.8 3623.9 3626.3 3636.3 3649.0 4997.3 5068.7 5088.4 5098.2 5119.4 4711.4 4729.7 4754.8 4759.9 4764.5 5452.4 5471.8 5496.1 5507.9 5530.3 3909.8 3938.9 3996.5 4009.7 4019.5 5635.4 5645.5 5668.3 5670.4 5673.2 4088.3 4103.0 4124.2 4126.6 4133.6 4074.1 4133.8 4181.3 4188.9 4193.5 4669.3 4689.8 4691.2 4707.6 4719.0 5483.7 5497.9 5527.1 5528.0 5582.3 1413.0 1448.7 1458.6 1459.7 1468.2 940.7 940.8 943.2 946.5 959.7 4737.3 4759.9 4774.9 4810.5 4879.3 4741.5 4972.5 4996.5 5014.9 5015.7 4694.9 4736.2 4742.8 4794.7 4801.8 2673.5 2678.9 2687.9 2695.5 2718.9 4871.3 5097.3 5131.8 5142.8 5153.3 5164.8 5165.4 5170.6 5206.7 5215.9 4911.5 5140.1 5149.4 5159.7 5216.6 4959.2 4998.7 5014.7 5021.4 5042.6 5056.9 5057.5 5067.2 5067.7 5130.5 4879.1 4994.2 5159.7 5198.8 5209.6 3719.7 3733.1 3744.8 3752.2 3763.9 5085.8 5447.8 5474.9 5590.7 5665.9 5497.1 5522.6 5526.8 5531.7 5551.2 5211.6 5503.9 5521.3 5524.5 5561.5 5434.9 5475.3 5476.2 5476.5 5480.5 5567.7 5598.7 5614.3 5643.7 5645.0 5233.8 5435.8 5447.8 5497.4 5512.8 13:35:56 INFO - PROCESS | 14672 | 13:35:56 INFO - PROCESS | 14672 | [#5] yui.html Cycles:180 Average:22083.00 Median:4125.79 stddev:31964.42 (774.7%) stddev-sans-first:32014.02 13:35:56 INFO - PROCESS | 14672 | Values: 768.4 804.5 812.4 821.8 846.8 14959.6 15123.0 15210.7 15297.3 15438.9 15652.5 16141.4 16248.2 16842.5 16987.5 792.4 806.1 806.8 806.8 806.8 669.7 795.5 800.6 801.1 803.0 860.5 873.4 875.2 876.2 877.2 872.3 873.0 873.3 873.4 873.9 509.6 610.9 634.5 635.8 635.8 15304.5 15683.6 15706.1 15723.2 15728.8 14604.7 14762.8 15164.1 15206.4 15251.0 13783.9 13934.4 13950.7 14046.7 14160.7 4606.0 4685.1 4702.8 4816.5 4853.6 84759.0 85676.0 86442.0 87180.6 87196.6 11432.1 11506.2 11590.2 11607.0 11668.2 20324.6 21686.5 21725.9 21736.2 21764.1 8473.7 8591.8 8783.9 8827.6 8937.6 4817.9 5028.0 5076.8 5123.4 5320.3 3523.9 3624.2 3636.4 3637.7 3645.6 646.6 656.9 658.5 660.1 661.3 585.9 681.3 732.4 749.8 754.1 9564.3 9983.9 10172.6 10344.9 10382.8 678.7 688.2 690.2 720.5 732.8 4821.3 4926.7 4968.2 4977.5 4979.4 117507.0 118691.4 119535.0 120097.0 123371.0 103268.5 104711.5 104974.0 108968.0 109362.0 76736.6 91093.0 91806.0 93474.0 94293.0 9917.5 9956.3 10081.7 10379.2 10523.2 54108.0 55335.0 55672.7 55702.0 55863.4 77520.6 77906.6 82041.0 82059.6 84025.0 13303.5 13486.1 13724.7 13817.3 13868.2 14981.7 15079.5 15288.2 15566.2 15979.4 13079.3 13185.4 13189.8 13319.4 13683.5 13927.0 13991.5 14151.3 14200.6 14339.1 13615.9 13872.6 14117.9 14620.6 14643.1 10833.4 11551.7 11684.5 12132.1 12277.0 11289.1 11354.0 11460.8 11522.1 11786.6 13:35:56 INFO - PROCESS | 14672 | -------- Summary: end -------- 13:35:56 INFO - PROCESS | 14672 | 13:35:57 INFO - TEST-INFO | 14672: exit 0 13:35:57 INFO - TEST-OK | dromaeo_css | took 1112736ms 13:35:57 INFO - TEST-START | kraken 13:35:57 INFO - operating with platform_type : linux_ 13:35:57 INFO - Initialising browser for kraken test... 13:35:57 INFO - TEST-INFO | started process 14860 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIQerzU/profile http://localhost:42613/getInfo.html) 13:36:00 INFO - PROCESS | 14860 | JavaScript error: , line 0: uncaught exception: undefined 13:36:01 INFO - PROCESS | 14860 | __metrics Screen width/height:1600/1200 13:36:01 INFO - PROCESS | 14860 | colorDepth:24 13:36:01 INFO - PROCESS | 14860 | Browser inner width/height: 1024/697 13:36:01 INFO - PROCESS | 14860 | __metrics 13:36:02 INFO - PROCESS | 14860 | 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" 13:36:02 INFO - PROCESS | 14860 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:36:02 INFO - PROCESS | 14860 | console.error: PushService: 13:36:02 INFO - PROCESS | 14860 | stateChangeProcessEnqueue: Error transitioning state 13:36:02 INFO - PROCESS | 14860 | UnknownError 13:36:03 INFO - TEST-INFO | 14860: exit 0 13:36:03 INFO - Browser initialized. 13:36:03 INFO - Running cycle 1/1 for kraken test... 13:36:03 INFO - TEST-INFO | started process 14937 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIQerzU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:36:14 INFO - PROCESS | 14937 | RSS: Main: 157659136 13:36:14 INFO - PROCESS | 14937 | 13:36:17 INFO - PROCESS | 14937 | Cycle 1(1): loaded http://localhost:42613/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42613/tests/kraken/driver.html?testName=audio-beat-detection) 13:36:18 INFO - PROCESS | 14937 | RSS: Main: 213811200 13:36:18 INFO - PROCESS | 14937 | 13:36:20 INFO - PROCESS | 14937 | Cycle 1(1): loaded http://localhost:42613/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42613/tests/kraken/driver.html?testName=audio-dft) 13:36:21 INFO - PROCESS | 14937 | RSS: Main: 188137472 13:36:21 INFO - PROCESS | 14937 | 13:36:24 INFO - PROCESS | 14937 | Cycle 1(1): loaded http://localhost:42613/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42613/tests/kraken/driver.html?testName=audio-fft) 13:36:25 INFO - PROCESS | 14937 | RSS: Main: 271802368 13:36:25 INFO - PROCESS | 14937 | 13:36:27 INFO - PROCESS | 14937 | Cycle 1(1): loaded http://localhost:42613/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42613/tests/kraken/driver.html?testName=audio-oscillator) 13:36:27 INFO - PROCESS | 14937 | RSS: Main: 181084160 13:36:27 INFO - PROCESS | 14937 | 13:36:30 INFO - PROCESS | 14937 | Cycle 1(1): loaded http://localhost:42613/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42613/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:36:30 INFO - PROCESS | 14937 | RSS: Main: 185970688 13:36:30 INFO - PROCESS | 14937 | 13:36:33 INFO - PROCESS | 14937 | Cycle 1(1): loaded http://localhost:42613/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42613/tests/kraken/driver.html?testName=imaging-darkroom) 13:36:34 INFO - PROCESS | 14937 | RSS: Main: 220577792 13:36:34 INFO - PROCESS | 14937 | 13:36:37 INFO - PROCESS | 14937 | Cycle 1(1): loaded http://localhost:42613/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42613/tests/kraken/driver.html?testName=imaging-desaturate) 13:36:38 INFO - PROCESS | 14937 | RSS: Main: 221085696 13:36:38 INFO - PROCESS | 14937 | 13:36:41 INFO - PROCESS | 14937 | Cycle 1(1): loaded http://localhost:42613/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42613/tests/kraken/driver.html?testName=json-parse-financial) 13:36:41 INFO - PROCESS | 14937 | RSS: Main: 221159424 13:36:41 INFO - PROCESS | 14937 | 13:36:43 INFO - PROCESS | 14937 | Cycle 1(1): loaded http://localhost:42613/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42613/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:36:43 INFO - PROCESS | 14937 | RSS: Main: 184758272 13:36:43 INFO - PROCESS | 14937 | 13:36:45 INFO - PROCESS | 14937 | Cycle 1(1): loaded http://localhost:42613/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42613/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:36:46 INFO - PROCESS | 14937 | RSS: Main: 190189568 13:36:46 INFO - PROCESS | 14937 | 13:36:48 INFO - PROCESS | 14937 | Cycle 1(1): loaded http://localhost:42613/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42613/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:36:48 INFO - PROCESS | 14937 | RSS: Main: 195035136 13:36:48 INFO - PROCESS | 14937 | 13:36:51 INFO - PROCESS | 14937 | Cycle 1(1): loaded http://localhost:42613/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42613/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:36:51 INFO - PROCESS | 14937 | RSS: Main: 188321792 13:36:51 INFO - PROCESS | 14937 | 13:36:54 INFO - PROCESS | 14937 | Cycle 1(1): loaded http://localhost:42613/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42613/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:36:55 INFO - PROCESS | 14937 | RSS: Main: 194174976 13:36:55 INFO - PROCESS | 14937 | 13:36:56 INFO - PROCESS | 14937 | Cycle 1(1): loaded http://localhost:42613/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42613/tests/kraken/driver.html?testName=ai-astar) 13:36:57 INFO - PROCESS | 14937 | RSS: Main: 243277824 13:36:57 INFO - PROCESS | 14937 | 13:36:57 INFO - PROCESS | 14937 | __start_tp_report 13:36:57 INFO - PROCESS | 14937 | _x_x_mozilla_page_load 13:36:57 INFO - PROCESS | 14937 | _x_x_mozilla_page_load_details 13:36:57 INFO - PROCESS | 14937 | |i|pagename|runs| 13:36:57 INFO - PROCESS | 14937 | |0;ai-astar;103;111;110;110;112;111;110;112;113;112 13:36:57 INFO - PROCESS | 14937 | |1;audio-beat-detection;157;117;119;196;120;123;146;117;168;117 13:36:57 INFO - PROCESS | 14937 | |2;audio-dft;161;162;161;160;161;160;161;162;164;162 13:36:57 INFO - PROCESS | 14937 | |3;audio-fft;74;74;73;114;76;76;75;75;103;75 13:36:57 INFO - PROCESS | 14937 | |4;audio-oscillator;82;83;118;84;83;85;126;83;85;120 13:36:57 INFO - PROCESS | 14937 | |5;imaging-gaussian-blur;116;117;117;117;116;116;116;117;117;117 13:36:57 INFO - PROCESS | 14937 | |6;imaging-darkroom;148;151;149;150;150;148;147;151;149;148 13:36:57 INFO - PROCESS | 14937 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;95 13:36:57 INFO - PROCESS | 14937 | |8;json-parse-financial;52;51;53;51;51;52;51;51;51;52 13:36:57 INFO - PROCESS | 14937 | |9;json-stringify-tinderbox;62;63;62;62;62;62;62;61;63;62 13:36:57 INFO - PROCESS | 14937 | |10;stanford-crypto-aes;76;76;75;75;76;75;79;81;75;75 13:36:57 INFO - PROCESS | 14937 | |11;stanford-crypto-ccm;121;121;130;119;118;125;132;118;167;119 13:36:57 INFO - PROCESS | 14937 | |12;stanford-crypto-pbkdf2;164;157;156;158;162;160;160;160;157;160 13:36:57 INFO - PROCESS | 14937 | |13;stanford-crypto-sha256-iterative;63;74;62;63;61;63;63;63;62;60 13:36:57 INFO - PROCESS | 14937 | __end_tp_report 13:36:57 INFO - PROCESS | 14937 | __start_cc_report 13:36:57 INFO - PROCESS | 14937 | _x_x_mozilla_cycle_collect,1713 13:36:57 INFO - PROCESS | 14937 | __end_cc_report 13:36:57 INFO - PROCESS | 14937 | __startTimestamp1469219817196__endTimestamp 13:36:57 INFO - PROCESS | 14937 | 13:36:57 INFO - PROCESS | 14937 | ------- Summary: start ------- 13:36:57 INFO - PROCESS | 14937 | Number of tests: 14 13:36:57 INFO - PROCESS | 14937 | 13:36:57 INFO - PROCESS | 14937 | [#0] ai-astar Cycles:10 Average:110.40 Median:111.50 stddev:2.80 (2.5%) stddev-sans-first:1.09 13:36:57 INFO - PROCESS | 14937 | Values: 103.0 111.0 110.0 110.0 112.0 111.0 110.0 112.0 113.0 112.0 13:36:57 INFO - PROCESS | 14937 | 13:36:57 INFO - PROCESS | 14937 | [#1] audio-beat-detection Cycles:10 Average:138.00 Median:134.50 stddev:27.73 (20.6%) stddev-sans-first:28.55 13:36:57 INFO - PROCESS | 14937 | Values: 157.0 117.0 119.0 196.0 120.0 123.0 146.0 117.0 168.0 117.0 13:36:57 INFO - PROCESS | 14937 | 13:36:57 INFO - PROCESS | 14937 | [#2] audio-dft Cycles:10 Average:161.40 Median:161.50 stddev:1.17 (0.7%) stddev-sans-first:1.24 13:36:57 INFO - PROCESS | 14937 | Values: 161.0 162.0 161.0 160.0 161.0 160.0 161.0 162.0 164.0 162.0 13:36:57 INFO - PROCESS | 14937 | 13:36:57 INFO - PROCESS | 14937 | [#3] audio-fft Cycles:10 Average:81.50 Median:75.00 stddev:14.49 (19.3%) stddev-sans-first:15.12 13:36:57 INFO - PROCESS | 14937 | Values: 74.0 74.0 73.0 114.0 76.0 76.0 75.0 75.0 103.0 75.0 13:36:57 INFO - PROCESS | 14937 | 13:36:57 INFO - PROCESS | 14937 | [#4] audio-oscillator Cycles:10 Average:94.90 Median:83.00 stddev:18.37 (22.1%) stddev-sans-first:18.88 13:36:57 INFO - PROCESS | 14937 | Values: 82.0 83.0 118.0 84.0 83.0 85.0 126.0 83.0 85.0 120.0 13:36:57 INFO - PROCESS | 14937 | 13:36:57 INFO - PROCESS | 14937 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 13:36:57 INFO - PROCESS | 14937 | Values: 116.0 117.0 117.0 117.0 116.0 116.0 116.0 117.0 117.0 117.0 13:36:57 INFO - PROCESS | 14937 | 13:36:57 INFO - PROCESS | 14937 | [#6] imaging-darkroom Cycles:10 Average:149.10 Median:149.50 stddev:1.37 (0.9%) stddev-sans-first:1.39 13:36:57 INFO - PROCESS | 14937 | Values: 148.0 151.0 149.0 150.0 150.0 148.0 147.0 151.0 149.0 148.0 13:36:57 INFO - PROCESS | 14937 | 13:36:57 INFO - PROCESS | 14937 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 13:36:57 INFO - PROCESS | 14937 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 13:36:57 INFO - PROCESS | 14937 | 13:36:57 INFO - PROCESS | 14937 | [#8] json-parse-financial Cycles:10 Average:51.50 Median:51.50 stddev:0.71 (1.4%) stddev-sans-first:0.73 13:36:57 INFO - PROCESS | 14937 | Values: 52.0 51.0 53.0 51.0 51.0 52.0 51.0 51.0 51.0 52.0 13:36:57 INFO - PROCESS | 14937 | 13:36:57 INFO - PROCESS | 14937 | [#9] json-stringify-tinderbox Cycles:10 Average:62.10 Median:62.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 13:36:57 INFO - PROCESS | 14937 | Values: 62.0 63.0 62.0 62.0 62.0 62.0 62.0 61.0 63.0 62.0 13:36:57 INFO - PROCESS | 14937 | 13:36:57 INFO - PROCESS | 14937 | [#10] stanford-crypto-aes Cycles:10 Average:76.30 Median:76.00 stddev:2.06 (2.7%) stddev-sans-first:2.18 13:36:57 INFO - PROCESS | 14937 | Values: 76.0 76.0 75.0 75.0 76.0 75.0 79.0 81.0 75.0 75.0 13:36:57 INFO - PROCESS | 14937 | 13:36:57 INFO - PROCESS | 14937 | [#11] stanford-crypto-ccm Cycles:10 Average:127.00 Median:123.00 stddev:14.91 (12.1%) stddev-sans-first:15.65 13:36:57 INFO - PROCESS | 14937 | Values: 121.0 121.0 130.0 119.0 118.0 125.0 132.0 118.0 167.0 119.0 13:36:57 INFO - PROCESS | 14937 | 13:36:57 INFO - PROCESS | 14937 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.40 Median:160.00 stddev:2.46 (1.5%) stddev-sans-first:1.96 13:36:57 INFO - PROCESS | 14937 | Values: 164.0 157.0 156.0 158.0 162.0 160.0 160.0 160.0 157.0 160.0 13:36:57 INFO - PROCESS | 14937 | 13:36:57 INFO - PROCESS | 14937 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.40 Median:63.00 stddev:3.86 (6.1%) stddev-sans-first:4.10 13:36:57 INFO - PROCESS | 14937 | Values: 63.0 74.0 62.0 63.0 61.0 63.0 63.0 63.0 62.0 60.0 13:36:57 INFO - PROCESS | 14937 | -------- Summary: end -------- 13:36:57 INFO - PROCESS | 14937 | 13:36:58 INFO - TEST-INFO | 14937: exit 0 13:36:58 INFO - TEST-OK | kraken | took 60629ms 13:36:58 INFO - SUITE-END | took 1173s 13:36:58 INFO - Completed test suite (00:19:33) 13:36:58 INFO - javascript benchmark 13:36:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3042.528408096745, 3057.449659061368, 3078.30738083931, 3083.737882280006, 3093.922651933701, 5119.104156772559, 5124.461931497195, 5227.137522308819, 5301.628885977978, 5321.281626980356, 5276.314079169712, 5288.709120998344, 5314.388845282793, 5357.785688572452, 5375.704336261514, 4759.785809638566, 4787.353707249529, 4858.0, 4859.416869975608, 4974.900501989958, 4892.996935628205, 4902.289168070628, 4930.975345123269, 4951.158303088484, 4962.702237865721, 4717.103750287434, 4747.667663263555, 4771.976140119312, 4794.856154315341, 4863.440704319013, 4786.688865223749, 4827.61379089672, 4833.419989601235, 4836.322914791932, 4879.585235255007, 4803.351547541072, 4805.8558243252755, 4810.6151507879285, 4822.975885120587, 4825.469198388175, 5127.66670166438, 5212.609054320903, 5279.9208011879855, 5282.603804714662, 5330.840074797724, 5687.744051517691, 5693.0, 5734.627249228828, 5743.138529220609, 5745.827625171251, 5715.885682286373, 5746.770129194827, 5762.654240745527, 5773.2494775679315, 5777.59556831022, 5169.793208271664, 5199.0, 5241.764120614582, 5242.423333433319, 5313.229581710631, 5803.419658034163, 5804.04233301509, 5805.912911306334, 5812.128180772918, 5815.9709201453725, 5584.190292407659, 5590.273264475673, 5596.692181930033, 5598.860028499239, 5598.86002849932, 5504.834854954358, 5621.353544342372, 5660.632058916157, 5674.801381951612, 5747.252857128547, 5673.177389278531, 5677.772889084473, 5678.346990096109, 5679.716014199274, 5735.71321433935, 5636.577256705764, 5670.0, 5684.289463817023, 5685.3177618685195, 5689.004424225744, 4186.811593478301, 4289.378040184156, 4336.501302350207, 4340.175366680321, 4342.956570434255, 3868.052327179803, 3874.3994680824517, 3888.1446081861945, 3914.6085391460624, 3921.0589458530317, 5410.594205434608, 5415.187721841755, 5418.322709661293, 5422.267993820863, 5459.50864422196, 5514.834554963358, 5616.129499927518, 5640.0, 5660.264165658438, 5747.453991870766, 5338.599605029638, 5359.624826262124, 5363.973180134074, 5367.946320536823, 5369.382521010056, 4897.216445368724, 4900.436449808318, 4903.239997800347, 4917.262410638361, 4939.777710002986, 5639.0977443609645, 5801.738921748447, 5829.621074630136, 5833.562482813806, 5963.5825492216, 5721.799737009184, 5728.255326807488, 5736.598438109292, 5741.770329186786, 5745.913811292918, 5872.412758724094, 5891.58758886891, 5906.084556893688, 5907.822765317047, 5907.940920590911, 5383.973080134574, 5478.0, 5507.146392309199, 5521.861953451196, 5589.9938011156755, 5596.804111856066, 5621.8594535136945, 5643.492085712305, 5645.971770141123, 5651.095824668033, 5454.672719636835, 5505.862353441035, 5506.697131657797, 5507.586930980113, 5549.417311182429, 5320.787168513216, 5467.644603100785, 5551.639143455824, 5562.972185139049, 5657.660540367591, 5412.404635490172, 5415.972920135374, 5419.891602168013, 5421.810236641856, 5422.267993820784, 5476.507114359727, 5499.890002200013, 5541.0, 5548.112302031655, 5550.361708403585, 5289.259503669567, 5340.038793017294, 5404.918926216189, 5416.51251387377, 5449.100898351728], "value": 5221.090392236736, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [571.8456016875443, 611.6574718157832, 625.9109150078863, 634.3688030409743, 664.1830548425439, 45490.545094549045, 46577.0, 46658.06683866321, 46734.298985515205, 49751.50248497514, 46624.0, 47206.0558788824, 47240.29139562901, 47245.29132063013, 47247.52752472474, 9148.725538233864, 9163.725088247347, 9275.257979361635, 9305.860412093823, 9310.30172737044, 9042.321825863053, 9135.817283654324, 9154.86267705985, 9173.724788256362, 9175.541222938826, 9197.724068277963, 9239.95380023099, 9253.30600204984, 9267.026962168942, 9270.351075424724, 9152.450852948845, 9160.954195228982, 9184.127507886808, 9194.54027298639, 9197.81604367909, 9116.1339672731, 9127.634894604207, 9197.126273004054, 9200.067985723006, 9201.309901757395, 44468.77765611151, 44933.0, 44940.77529612364, 44969.325460118125, 45000.0, 3585.6235095315137, 3587.5694916609914, 3612.7290453216115, 3679.0, 4104.15864747729, 33303.83348083268, 33386.49920251198, 33979.830100849584, 34007.0, 34155.829220853986, 4304.526502084768, 4372.27857403528, 4451.554844515555, 4469.597736203725, 4561.7262964222255, 374719.0, 375079.0, 376796.0, 376821.1158944215, 378164.0, 61547.38452615506, 61822.38177618256, 61866.38133618696, 61889.38110618926, 61891.0, 56033.0, 57755.42244577585, 63537.36462635407, 65203.0, 65328.34671653223, 14510.709785804225, 14605.707885842223, 14622.341994610268, 14921.477748278758, 14960.102393856403, 10610.0, 10613.469326533643, 10615.681529554126, 10622.36265824053, 10800.243982921278, 16077.517674469784, 18094.09529523545, 18106.45680629583, 18213.817861821477, 18662.81337186611, 5925.970370148121, 6191.938080619227, 6261.592996455216, 6297.527685423612, 6359.17330747, 1036.1969473657975, 1036.9740756481149, 1050.826613608746, 1080.4057768227444, 1094.9616763413242, 33167.83416082904, 33252.0, 33407.0, 33591.32817343639, 33733.325333493194, 1064.3826580583243, 1123.0902968595465, 1124.460259075632, 1140.4126874659557, 1183.7395772929942, 17177.656446871242, 17687.557811054827, 17821.643567128584, 17835.197416116305, 17836.55408614795, 608209.0, 608620.6551737969, 609586.0, 609747.0, 610024.3484173515, 60767.0, 60904.69547652322, 60939.39060609426, 61072.0, 61111.38888611057, 1316.5918565244804, 1352.4184600621631, 1357.0297237475252, 1357.307773035739, 1374.5807528703842, 31855.0, 32649.510257346636, 33263.0, 33472.49791253182, 34258.314833703684, 54333.0, 54699.0, 54805.067543921235, 54850.725746372605, 54883.17675234795, 494685.0, 497553.0, 499586.0, 500594.0, 501640.0, 43664.781676091414, 44260.33609495796, 45221.32168017548, 45425.772871135436, 45693.086138277715, 49298.26052609142, 49515.0, 50004.0, 50288.748556256985, 51308.23037654364, 41298.58701413067, 43096.06067727022, 43391.13217735691, 44029.27446555123, 44314.55685443233, 43733.78133109314, 43943.56056439522, 44044.77977610091, 44242.3363649552, 44276.557234427244, 41971.37042944297, 42067.7896610515, 42485.787571063185, 43145.784271078446, 43378.0, 36938.44592331063, 38544.42183367308, 38567.80716096514, 38995.41506877342, 39721.60278397179, 28482.0, 28510.714892851636, 28661.426771464037, 28724.425511489237, 28942.421151577273], "value": 20946.521510005867, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [375.8778397020969, 388.3146246874267, 401.24766063630693, 406.9165821006694, 417.213552453625, 14420.278986050707, 18013.7297940531, 20693.99999999998, 20701.275455359064, 20739.481512962182, 19242.615147697037, 19819.20723171075, 19840.206391744312, 20928.476788080305, 21950.231741889038, 4730.952690473086, 6568.113304703876, 6860.451163906901, 6864.2106157791995, 6870.553414028072, 7351.228121047293, 7696.307332340089, 7770.9611451942665, 7858.567778772165, 7864.095629002681, 7607.809804754897, 7730.8453830923345, 7731.574763388011, 7738.651760670755, 7793.959408734133, 7501.43739219558, 7704.730334438295, 7712.537247765096, 7740.419468539854, 7796.688132474695, 7277.19950805411, 7494.775156745306, 7500.062492188476, 7528.284812942762, 7528.322450979384, 27204.863975680193, 27557.586636200474, 27717.307067323276, 27778.583321250197, 28239.0, 2511.3219430753775, 2565.3330134165067, 2579.2520169150926, 2743.341598016482, 3056.2664960409566, 15809.841901580952, 15854.0, 16235.512934611981, 16465.94175728034, 17454.127293635396, 2104.6527322991687, 2127.1918868961416, 2129.765725770164, 2176.4014895903597, 2196.2752291743686, 76296.0, 79718.0, 88153.55923220408, 90971.09028909693, 98988.50505747498, 17471.65056698859, 17974.191161397768, 18144.0, 18669.0, 18828.52928676794, 33651.66348336485, 33720.49419258713, 33723.156921076676, 33774.960253577294, 33917.760279965005, 9071.228945539571, 9250.95374523123, 9371.671991480402, 9388.483633400228, 9433.179562277151, 7325.670344834495, 7498.887516687254, 7512.0, 7697.730579429693, 7728.845423091507, 8673.392862499564, 9242.12199841014, 9328.486933218737, 9335.719928402159, 9466.964602610471, 3137.262743255333, 3388.6614787158933, 3478.1652403422995, 3478.617352091268, 3487.5989261234777, 987.5605355616682, 997.6408492942535, 1005.835037084576, 1054.5676272728144, 1060.5916722061907, 18830.905845470686, 20390.490237744176, 20540.794592054186, 20550.69173962392, 20555.38333849987, 960.6877764726491, 996.038822536256, 996.6163027234422, 1010.8028934357877, 1025.8563801067855, 8945.15021072997, 10791.78416431667, 11110.166737494721, 11183.83224251637, 11247.606333778278, 522884.0, 527514.0, 528831.0, 542858.0, 544509.0, 63921.36078639247, 72404.0, 96993.51503242439, 98775.50612247037, 102705.0, 911.1480765484293, 971.4851128901552, 978.1881038737973, 1015.9593616255268, 1018.134585602247, 17719.114044297476, 18461.353852615095, 18663.346782862536, 18723.812761872752, 18751.531211719815, 44012.33981490344, 44319.33520997123, 45029.774851125534, 46063.0, 46670.0, 62624.0, 63694.68152659207, 63913.680431597546, 63935.68032159809, 64610.35389646043, 8293.004839419067, 8308.376871734523, 8534.74395768128, 8706.216440520384, 8708.433951793366, 11366.43167841588, 12804.423800929168, 12939.223646581235, 13171.275579842817, 13861.306934653025, 11593.88406115928, 11692.181547291608, 11989.700257493632, 12029.0, 12079.39603019863, 11568.24806387582, 11743.530258789553, 11765.0, 11810.468528916392, 12109.576164834189, 11854.0, 12055.939720301692, 12154.939225303817, 12200.81698774537, 12333.568325108577, 8554.325885710288, 8581.785455363666, 8697.695580654647, 8750.431221970552, 8977.057408972225, 11464.31214127155, 11487.483063262007, 11516.481758321062, 11598.594049208237, 12324.075694322783], "value": 10669.286549321889, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1445.6964037552111, 1507.9623009424765, 1598.9840101598982, 1736.6787144378284, 1756.6837969165554, 8830.60262288197, 9463.81072378553, 9466.952665236682, 9472.384295020827, 9477.81044379112, 8015.4389192756535, 8812.206901378875, 8990.235829954445, 9037.72886813395, 9248.352615316931, 2370.123054469844, 2398.436367453647, 2400.23192578375, 2401.051584624081, 2404.4349577849193, 2384.821138414617, 2393.796527295182, 2435.2815919303757, 2435.391152211947, 2437.5124975004983, 2380.1788383007834, 2388.5819981503255, 2389.8566086034893, 2420.2376251480814, 2426.514697060586, 2366.656834758959, 2366.8343215974887, 2375.3942744600104, 2375.596148654733, 2424.3090719144957, 2366.4202270443852, 2367.4318163640773, 2373.2761507740133, 2373.5727569037567, 2382.332946774906, 9293.72118836436, 9329.673461428885, 9371.390859594105, 9376.718698439057, 9379.953100234523, 5235.450277720861, 5332.706701131437, 5361.705106219157, 5363.597730170253, 5373.113436283009, 3330.084226837615, 3334.983325083359, 3395.49067639856, 3395.6943875051115, 3496.4230901901155, 1148.6439203846835, 1148.6439203846835, 1151.5739176504662, 1162.1051790121644, 1179.5399794080315, 13216.6695832604, 13248.86751132486, 13288.933555332162, 13322.200667959856, 13331.533396331179, 4645.953540464619, 4728.787204575802, 4754.191787396151, 4783.23468245086, 4941.629377796679, 10947.124230061576, 10978.341299522055, 10991.06575941054, 10993.780124397466, 11008.229423940247, 5335.253064570963, 5338.0, 5338.145896656516, 5340.332458442695, 5343.839684809462, 4448.421705178306, 4580.702254353457, 4802.73584952824, 4873.731944743002, 4919.4834542373255, 3994.540627827837, 4058.553559108496, 4160.791960401968, 4176.665866730655, 4204.558521355275, 1296.0668318810442, 1322.7486777512242, 1330.388021510097, 1334.9466021359037, 1347.238810072306, 974.741693451236, 976.9071938165863, 980.926430517714, 989.6486747204783, 1002.1982414068862, 5981.820545383645, 5995.460408563163, 6096.207493025967, 6127.3259941407305, 6332.33510481402, 443.7115874681483, 446.3438745044779, 446.56874716533434, 452.9864104076883, 458.09754783077284, 4012.61880121388, 4120.423140760296, 4141.276578824413, 4203.642690371292, 4271.124419493996, 11200.551977920955, 11805.76388472243, 11810.291382517076, 11816.586419475278, 11834.230774999769, 11378.838020776026, 11735.530578776925, 11767.76464470723, 11769.352685602204, 11774.646760597196, 8495.0, 8723.127687231354, 8727.0, 8729.258013068833, 8741.431806932529, 5856.707164641836, 5869.178315035983, 5950.19672344228, 6052.727627256696, 6059.66687328787, 10456.215783816093, 10611.151107911193, 10684.89315106839, 10698.62554810578, 10936.179786516203, 11016.0, 11020.44897755093, 11061.557537698402, 11075.05862001739, 11090.611828585961, 7229.277072292729, 7720.459567830198, 7823.0, 8179.713710020121, 8267.090620031671, 8773.078826723358, 8940.284777217677, 8951.686690965786, 8956.283497320068, 8957.552122393985, 7552.848943020999, 7712.267334603203, 7784.494007889469, 8100.71647492335, 8112.067112282163, 7442.553446792993, 7800.0, 7825.569593672402, 7849.17583653731, 7862.842743144991, 7180.928190718026, 7213.098362704662, 7232.529885557422, 7240.52936559122, 7245.130584329914, 5164.896702066013, 5271.578273738015, 5303.522682958553, 5317.282166907418, 5574.386817450159, 5142.6400151989, 5164.509371609842, 5180.818671346485, 5229.50319719626, 5322.680639161663], "value": 4699.986140483261, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.3645885834335, 326.1601376455626, 330.64013696546914, 338.3267298076827, 347.24127780798955, 5328.520433161015, 5364.597655175858, 5371.597130215235, 5377.6018235258825, 5394.838154855361, 5051.585556044304, 5161.664491808039, 5177.456367081459, 5235.921461178076, 5328.094223981922, 4147.605977432139, 4161.375793630949, 4166.666666666674, 4172.353285240777, 4173.707840451162, 4183.226103170908, 4189.853355132555, 4197.664186865059, 4215.220184265905, 4217.7891105444605, 3838.0, 3844.3656796628525, 4074.388841673743, 4108.322126849066, 4126.195391898581, 4101.815418306183, 4114.979425102855, 4119.814608342632, 4122.732022418548, 4128.360104183841, 3623.7644553103964, 3623.9128261521437, 3626.3110009098186, 3636.30910127075, 3648.996525955357, 4997.250412438127, 5068.695878247317, 5088.4148322942965, 5098.158803797369, 5119.411267704188, 4711.411073615798, 4729.716217026955, 4754.833580824655, 4759.904801903977, 4764.499727528594, 5452.372977107644, 5471.781128754845, 5496.148097044925, 5507.917381239285, 5530.336359636829, 3909.7849618270984, 3938.8774199353047, 3996.500437445319, 4009.7393669411686, 4019.537753158395, 5635.380108188096, 5645.491905728463, 5668.348139963916, 5670.404607516192, 5673.177389278531, 4088.3049881520215, 4102.953746794548, 4124.237016152021, 4126.628603425706, 4133.55325636025, 4074.124063326399, 4133.751974881457, 4181.330987042059, 4188.937165942452, 4193.454850869368, 4669.276262179367, 4689.7655117244, 4691.225947718588, 4707.587723682881, 4719.0, 5483.725813709299, 5497.948638505588, 5527.143292789624, 5527.972360138174, 5582.33012038558, 1413.0250127412053, 1448.7247422989599, 1458.5624312706145, 1459.7153555056661, 1468.1704836767192, 940.7488040905579, 940.830650482906, 943.1511639524346, 946.4936259101348, 959.6689142245914, 4737.336772851803, 4759.881002974953, 4774.880627984328, 4810.508742289901, 4879.2925025871045, 4741.549552792549, 4972.502749725071, 4996.525330093636, 5014.89970200594, 5015.749212539358, 4694.943637681563, 4736.194846876039, 4742.810287588527, 4794.688345257547, 4801.807927682923, 2673.5187666220268, 2678.8794504246966, 2687.8924843006453, 2695.528282550546, 2718.8504632245417, 4871.269309603588, 5097.260897169889, 5131.7690703918415, 5142.768575414116, 5153.304303918998, 5164.812093218581, 5165.431802501722, 5170.560502357266, 5206.739663016834, 5215.869603259873, 4911.508849115131, 5140.098163679462, 5149.382074151051, 5159.664621799571, 5216.557072332243, 4959.181735013683, 4998.700077995332, 5014.721246082226, 5021.4225364083595, 5042.647014709082, 5056.8988620226655, 5057.544820966131, 5067.150490071172, 5067.670601410897, 5130.48695130484, 4879.110012360939, 4994.225894986283, 5159.658488792866, 5198.818041368534, 5209.6092793041435, 3719.739618226698, 3733.1040550268285, 3744.794036328028, 3752.1932784451474, 3763.9435408469444, 5085.813100366878, 5447.754851031634, 5474.945250547603, 5590.720463976703, 5665.886682266249, 5497.092979658311, 5522.5858060646415, 5526.751296191601, 5531.695756733418, 5551.167324901297, 5211.557017653542, 5503.889922201453, 5521.254630624814, 5524.475174858382, 5561.5272701820795, 5434.891302173855, 5475.342958844804, 5476.2333273342565, 5476.479734425223, 5480.479354461319, 5567.749451274622, 5598.748056337556, 5614.287642269147, 5643.689597072201, 5645.0, 5233.816816411408, 5435.83692489226, 5447.754851031792, 5497.36780270274, 5512.779488820403], "value": 4079.068114673295, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [768.4236822383206, 804.5260822969367, 812.3501199040767, 821.77401214666, 846.7756044648168, 14959.551213463586, 15123.0, 15210.707089897372, 15297.311620977054, 15438.922805385972, 15652.452164174256, 16141.354345826183, 16248.18759062048, 16842.494725158234, 16987.490375288762, 792.3542313014904, 806.0528878567688, 806.770070529899, 806.7781360125983, 806.7862016565585, 669.712023829753, 795.5107608820563, 800.5596921693075, 801.1307731111722, 802.9638666260032, 860.5267103093305, 873.3799002708084, 875.2297977779546, 876.1588874680283, 877.1929824561387, 872.2847265242503, 872.9869051964257, 873.2839071960994, 873.3711727556149, 873.8514452543083, 509.6025100421677, 610.8625559249177, 634.5399585300667, 635.777477882742, 635.8473966248114, 15304.464343748026, 15683.607909802233, 15706.057636541844, 15723.21383930799, 15728.764068538982, 14604.70790584193, 14762.778558321634, 15164.090154590649, 15206.391744330214, 15251.0, 13783.8538550517, 13934.442622295095, 13950.720985580334, 14046.71906561863, 14160.716785664332, 4605.976970115162, 4685.109829132454, 4702.8118875244945, 4816.51834816519, 4853.587445067174, 84759.0, 85675.99999999937, 86442.0, 87180.56409717911, 87196.56401718015, 11432.109367978517, 11506.194566380273, 11590.188686792013, 11607.0, 11668.241564298294, 20324.59350813005, 21686.45783855416, 21725.891370543046, 21736.239231626816, 21764.129434822746, 8473.703420380258, 8591.828163436696, 8783.91216087831, 8827.558622069, 8937.553122343857, 4817.879553011203, 5027.974860125676, 5076.796928122833, 5123.436421993578, 5320.308359913187, 3523.9428171548434, 3624.2026754114054, 3636.381815091388, 3637.672609465161, 3645.5989841117457, 646.5603389694959, 656.9408753212234, 658.4830907737403, 660.1418156396666, 661.3452681844939, 585.88868115058, 681.2948598200838, 732.4433430592787, 749.7675720526654, 754.1101500229781, 9564.330496865292, 9983.900160998443, 10172.643957461454, 10344.948275258575, 10382.844257336146, 678.6572780745691, 688.2429327739446, 690.1959217511643, 720.495653042872, 732.7801659502128, 4821.349117869113, 4926.679765815211, 4968.180250258739, 4977.477364876709, 4979.3526841510375, 117507.0, 118691.40654296674, 119535.0, 120097.0, 123371.0, 103268.48365758124, 104711.4764426173, 104974.0, 108968.0, 109362.0, 76736.61631691805, 91093.0, 91806.0, 93474.0, 94293.0, 9917.504124793588, 9956.262027942374, 10081.747956300858, 10379.221558383, 10523.15814734804, 54108.0, 55335.0, 55672.72163639156, 55702.0, 55863.44136558582, 77520.61239693765, 77906.6104669473, 82041.0, 82059.5897020511, 84025.0, 13303.46786128544, 13486.123401979232, 13724.725505490034, 13817.309134543433, 13868.237246951514, 14981.700365992401, 15079.472218472301, 15288.159151246788, 15566.221688915735, 15979.360825566848, 13079.346032698517, 13185.406656700663, 13189.802152967904, 13319.400626972003, 13683.452661893412, 13927.0, 13991.510297139552, 14151.292435378396, 14200.644983875072, 14339.067960582528, 13615.93192034073, 13872.583822485341, 14117.858821411655, 14620.561383158521, 14643.12141271527, 10833.404162771123, 11551.653450396501, 11684.47419866125, 12132.090093242865, 12277.0, 11289.05511732702, 11353.982080016105, 11460.828087578526, 11522.13583981188, 11786.58746943853], "value": 7885.906970147668, "unit": "score"}], "name": "dromaeo_css", "value": 7489.072747783317, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 111.0, 110.0, 110.0, 112.0, 111.0, 110.0, 112.0, 113.0, 112.0], "value": 110.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 117.0, 119.0, 196.0, 120.0, 123.0, 146.0, 117.0, 168.0, 117.0], "value": 138.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 161.0, 160.0, 161.0, 160.0, 161.0, 162.0, 164.0, 162.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 73.0, 114.0, 76.0, 76.0, 75.0, 75.0, 103.0, 75.0], "value": 81.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 118.0, 84.0, 83.0, 85.0, 126.0, 83.0, 85.0, 120.0], "value": 94.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 116.0, 116.0, 116.0, 117.0, 117.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 151.0, 149.0, 150.0, 150.0, 148.0, 147.0, 151.0, 149.0, 148.0], "value": 149.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 53.0, 51.0, 51.0, 52.0, 51.0, 51.0, 51.0, 52.0], "value": 51.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 62.0, 62.0, 62.0, 62.0, 62.0, 61.0, 63.0, 62.0], "value": 62.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 75.0, 75.0, 76.0, 75.0, 79.0, 81.0, 75.0, 75.0], "value": 76.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 121.0, 130.0, 119.0, 118.0, 125.0, 132.0, 118.0, 167.0, 119.0], "value": 127.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 157.0, 156.0, 158.0, 162.0, 160.0, 160.0, 160.0, 157.0, 160.0], "value": 159.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 74.0, 62.0, 63.0, 61.0, 63.0, 63.0, 63.0, 62.0, 60.0], "value": 63.4, "unit": "score"}], "name": "kraken", "value": 1486.6000000000001, "alertThreshold": 2.0}]} 13:36:58 INFO - Return code: 0 13:36:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:36:58 INFO - # TBPL SUCCESS # 13:36:58 INFO - Running post-action listener: _resource_record_post_action 13:36:58 INFO - [mozharness: 2016-07-22 20:36:58.492920Z] Finished run-tests step (success) 13:36:58 INFO - Running post-run listener: _resource_record_post_run 13:36:58 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 560263168; Read time: 184; Write time: 453312 13:36:58 INFO - TinderboxPrint: CPU usage
13.1% 13:36:58 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 184 13:36:58 INFO - TinderboxPrint: I/O write bytes / time
560,263,168 / 453,312 13:36:58 INFO - TinderboxPrint: CPU idle
8,260.2 (86.9%) 13:36:58 INFO - TinderboxPrint: CPU user
1,201.8 (12.6%) 13:36:58 INFO - TinderboxPrint: Swap in / out
0 / 0 13:36:58 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 0; Write bytes: 263114752; Read time: 0; Write time: 253028 13:36:58 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 292634624; Read time: 184; Write time: 199520 13:36:59 INFO - Running post-run listener: _upload_blobber_files 13:36:59 INFO - Blob upload gear active. 13:36:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:36:59 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 13:36:59 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'] 13:36:59 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 13:36:59 INFO - (blobuploader) - INFO - Open directory for files ... 13:36:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:36:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:36:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:37:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:37:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:37:00 INFO - (blobuploader) - INFO - Done attempting. 13:37:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:37:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:37:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:37:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:37:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:37:16 INFO - (blobuploader) - INFO - Done attempting. 13:37:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:37:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:37:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:37:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:37:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:37:17 INFO - (blobuploader) - INFO - Done attempting. 13:37:17 INFO - (blobuploader) - INFO - Iteration through files over. 13:37:17 INFO - Return code: 0 13:37:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:37:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:37:17 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/883ff766d6ef0a4652750957a71d7223a795c17633a196b13eb2ba2573b987de3885a8dce979d3d055baf36951c9a2f27f73f5063c3ddcf73665a309e2d2bbeb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6f2c71c5c70b8fb919afa8654d9758b541dd664158724efca8e98bb90994d61f3214b3fad5e43f8dd59d266f55967eb6bb26560a289e6fa5ff337098f05ec9e8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/01fc47a69240138e560058f2baf6dc79d07c047772b8924d38883d4abaf9cef03a39152244966a308cac3f6479a8023ccf8926ed806cde3687436fdf834d36ae"} 13:37:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:37:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:37:17 INFO - Contents: 13:37:17 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/883ff766d6ef0a4652750957a71d7223a795c17633a196b13eb2ba2573b987de3885a8dce979d3d055baf36951c9a2f27f73f5063c3ddcf73665a309e2d2bbeb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6f2c71c5c70b8fb919afa8654d9758b541dd664158724efca8e98bb90994d61f3214b3fad5e43f8dd59d266f55967eb6bb26560a289e6fa5ff337098f05ec9e8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/01fc47a69240138e560058f2baf6dc79d07c047772b8924d38883d4abaf9cef03a39152244966a308cac3f6479a8023ccf8926ed806cde3687436fdf834d36ae"} 13:37:17 INFO - Running post-run listener: copy_logs_to_upload_dir 13:37:17 INFO - Copying logs to upload dir... 13:37:17 INFO - mkdir: /builds/slave/test/build/upload/logs 13:37:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.567550 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-07-22 13:37:17.244267) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-22 13:37:17.247605) ========= 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=ed14279e38b05397cf4aa391000001a4-1469218590.635619-1304341150 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/883ff766d6ef0a4652750957a71d7223a795c17633a196b13eb2ba2573b987de3885a8dce979d3d055baf36951c9a2f27f73f5063c3ddcf73665a309e2d2bbeb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6f2c71c5c70b8fb919afa8654d9758b541dd664158724efca8e98bb90994d61f3214b3fad5e43f8dd59d266f55967eb6bb26560a289e6fa5ff337098f05ec9e8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/01fc47a69240138e560058f2baf6dc79d07c047772b8924d38883d4abaf9cef03a39152244966a308cac3f6479a8023ccf8926ed806cde3687436fdf834d36ae"} build_url:https://queue.taskcluster.net/v1/task/T7TiM5WgRg-kK5jJcZh3SQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011547 build_url: 'https://queue.taskcluster.net/v1/task/T7TiM5WgRg-kK5jJcZh3SQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/883ff766d6ef0a4652750957a71d7223a795c17633a196b13eb2ba2573b987de3885a8dce979d3d055baf36951c9a2f27f73f5063c3ddcf73665a309e2d2bbeb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6f2c71c5c70b8fb919afa8654d9758b541dd664158724efca8e98bb90994d61f3214b3fad5e43f8dd59d266f55967eb6bb26560a289e6fa5ff337098f05ec9e8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/01fc47a69240138e560058f2baf6dc79d07c047772b8924d38883d4abaf9cef03a39152244966a308cac3f6479a8023ccf8926ed806cde3687436fdf834d36ae"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-22 13:37:17.276553) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 13:37:17.277162) ========= 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=ed14279e38b05397cf4aa391000001a4-1469218590.635619-1304341150 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004496 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 13:37:17.358704) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-22 13:37:17.358985) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-22 13:37:17.359263) ========= ========= Total master_lag: 0.16 =========