builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-013 starttime: 1472169988.03 results: success (0) buildid: 20160825135343 builduid: 61045acfb7534bfa8b8cb988907f1fd0 revision: 7cae917bd51939b9f642dd8bcb16e081ae721a6a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-25 17:06:28.029261) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-25 17:06:28.029932) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-25 17:06:28.030251) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-25 17:06:28.046764) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 17:06:28.047108) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1472169338.589573-1913469843 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-25 17:06:28-- 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% 211M=0s 2016-08-25 17:06:28 (211 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.082720 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 17:06:28.144858) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 17:06:28.145404) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1472169338.589573-1913469843 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020584 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 17:06:28.196825) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-25 17:06:28.197213) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 7cae917bd51939b9f642dd8bcb16e081ae721a6a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 7cae917bd51939b9f642dd8bcb16e081ae721a6a --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=9c3a94c35cc88655b05d2c08000001b2-1472169338.589573-1913469843 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-25 17:06:28,341 truncating revision to first 12 chars 2016-08-25 17:06:28,342 Setting DEBUG logging. 2016-08-25 17:06:28,342 attempt 1/10 2016-08-25 17:06:28,342 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/7cae917bd519?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-25 17:06:29,448 unpacking tar archive at: mozilla-inbound-7cae917bd519/testing/mozharness/ program finished with exit code 0 elapsedTime=1.398276 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-25 17:06:29.611135) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-25 17:06:29.611508) ========= script_repo_revision: 7cae917bd51939b9f642dd8bcb16e081ae721a6a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-25 17:06:29.611974) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-25 17:06:29.612285) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-25 17:06:29.621911) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-08-25 17:06:29.622444) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 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', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=9c3a94c35cc88655b05d2c08000001b2-1472169338.589573-1913469843 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:06:29 INFO - MultiFileLogger online at 20160825 17:06:29 in /builds/slave/test 17:06:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 17:06:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:06:29 INFO - {'append_to_log': False, 17:06:29 INFO - 'base_work_dir': '/builds/slave/test', 17:06:29 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 17:06:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:06:29 INFO - 'branch': 'Mozilla-Inbound', 17:06:29 INFO - 'buildbot_json_path': 'buildprops.json', 17:06:29 INFO - 'config_files': ('talos/linux_config.py',), 17:06:29 INFO - 'default_actions': ('clobber', 17:06:29 INFO - 'read-buildbot-config', 17:06:29 INFO - 'download-and-extract', 17:06:29 INFO - 'populate-webroot', 17:06:29 INFO - 'create-virtualenv', 17:06:29 INFO - 'install', 17:06:29 INFO - 'run-tests'), 17:06:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:06:29 INFO - 'download_minidump_stackwalk': True, 17:06:29 INFO - 'download_symbols': 'ondemand', 17:06:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:06:29 INFO - 'tooltool.py': '/tools/tooltool.py', 17:06:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:06:29 INFO - '/tools/misc-python/virtualenv.py')}, 17:06:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:06:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:06:29 INFO - 'installer_path': 'installer.exe', 17:06:29 INFO - 'log_level': 'info', 17:06:29 INFO - 'log_name': 'talos', 17:06:29 INFO - 'log_to_console': True, 17:06:29 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:06:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:06:29 INFO - 'opt_config_files': (), 17:06:29 INFO - 'pip_index': False, 17:06:29 INFO - 'sps_profile': False, 17:06:29 INFO - 'sps_profile_interval': 0, 17:06:29 INFO - 'suite': 'dromaeojs', 17:06:29 INFO - 'system_bits': '32', 17:06:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:06:29 INFO - 'title': 'talos-linux64-ix-013', 17:06:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:06:29 INFO - 'use_talos_json': True, 17:06:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:06:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:06:29 INFO - 'work_dir': 'build'} 17:06:29 INFO - [mozharness: 2016-08-26 00:06:29.866648Z] Running clobber step. 17:06:29 INFO - Running pre-action listener: _resource_record_pre_action 17:06:29 INFO - Running main action method: clobber 17:06:29 INFO - rmtree: /builds/slave/test/build 17:06:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:06:30 INFO - Running post-action listener: _resource_record_post_action 17:06:30 INFO - [mozharness: 2016-08-26 00:06:30.322015Z] Finished clobber step (success) 17:06:30 INFO - [mozharness: 2016-08-26 00:06:30.322115Z] Running read-buildbot-config step. 17:06:30 INFO - Running pre-action listener: _resource_record_pre_action 17:06:30 INFO - Running main action method: read_buildbot_config 17:06:30 INFO - Using buildbot properties: 17:06:30 INFO - { 17:06:30 INFO - "project": "", 17:06:30 INFO - "product": "firefox", 17:06:30 INFO - "script_repo_revision": "production", 17:06:30 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 17:06:30 INFO - "repository": "", 17:06:30 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 17:06:30 INFO - "buildid": "20160825135343", 17:06:30 INFO - "pgo_build": "True", 17:06:30 INFO - "basedir": "/builds/slave/test", 17:06:30 INFO - "buildnumber": 2302, 17:06:30 INFO - "slavename": "talos-linux64-ix-013", 17:06:30 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 17:06:30 INFO - "platform": "ubuntu64_hw", 17:06:30 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 17:06:30 INFO - "branch": "mozilla-inbound", 17:06:30 INFO - "repo_path": "integration/mozilla-inbound", 17:06:30 INFO - "revision": "7cae917bd51939b9f642dd8bcb16e081ae721a6a", 17:06:30 INFO - "stage_platform": "linux64-pgo", 17:06:30 INFO - "builduid": "61045acfb7534bfa8b8cb988907f1fd0", 17:06:30 INFO - "slavebuilddir": "test" 17:06:30 INFO - } 17:06:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/LGQXyqmERTW-GxwgCpavdQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 17:06:30 INFO - Running post-action listener: _resource_record_post_action 17:06:30 INFO - [mozharness: 2016-08-26 00:06:30.324825Z] Finished read-buildbot-config step (success) 17:06:30 INFO - [mozharness: 2016-08-26 00:06:30.324912Z] Running download-and-extract step. 17:06:30 INFO - Running pre-action listener: _resource_record_pre_action 17:06:30 INFO - Running main action method: download_and_extract 17:06:30 INFO - mkdir: /builds/slave/test/build/tests 17:06:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:06:30 INFO - trying https://queue.taskcluster.net/v1/task/LGQXyqmERTW-GxwgCpavdQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 17:06:30 INFO - Downloading https://queue.taskcluster.net/v1/task/LGQXyqmERTW-GxwgCpavdQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 17:06:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LGQXyqmERTW-GxwgCpavdQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 17:06:30 INFO - Downloaded 1361 bytes. 17:06:30 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 17:06:30 INFO - Using the following test package requirements: 17:06:30 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 17:06:30 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 17:06:30 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 17:06:30 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 17:06:30 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 17:06:30 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 17:06:30 INFO - u'jsshell-linux-x86_64.zip'], 17:06:30 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 17:06:30 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 17:06:30 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 17:06:30 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 17:06:30 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 17:06:30 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 17:06:30 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 17:06:30 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 17:06:30 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 17:06:30 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 17:06:30 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 17:06:30 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:06:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:06:30 INFO - trying https://queue.taskcluster.net/v1/task/LGQXyqmERTW-GxwgCpavdQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 17:06:30 INFO - Downloading https://queue.taskcluster.net/v1/task/LGQXyqmERTW-GxwgCpavdQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 17:06:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LGQXyqmERTW-GxwgCpavdQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:06:31 INFO - Downloaded 21915094 bytes. 17:06:31 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 17:06:36 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 17:06: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:06:36 INFO - trying https://queue.taskcluster.net/v1/task/LGQXyqmERTW-GxwgCpavdQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 17:06:36 INFO - Downloading https://queue.taskcluster.net/v1/task/LGQXyqmERTW-GxwgCpavdQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 17:06:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LGQXyqmERTW-GxwgCpavdQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:06:38 INFO - Downloaded 21915094 bytes. 17:06:38 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 17:06: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:06:42 INFO - trying https://queue.taskcluster.net/v1/task/LGQXyqmERTW-GxwgCpavdQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 17:06:42 INFO - Downloading https://queue.taskcluster.net/v1/task/LGQXyqmERTW-GxwgCpavdQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 17:06:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LGQXyqmERTW-GxwgCpavdQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 17:06:43 INFO - Downloaded 14413981 bytes. 17:06:43 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 17:06:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:06:43 INFO - trying https://queue.taskcluster.net/v1/task/LGQXyqmERTW-GxwgCpavdQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 17:06:43 INFO - Downloading https://queue.taskcluster.net/v1/task/LGQXyqmERTW-GxwgCpavdQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 17:06:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LGQXyqmERTW-GxwgCpavdQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 17:06:45 INFO - Downloaded 59767654 bytes. 17:06:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LGQXyqmERTW-GxwgCpavdQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 17:06:45 INFO - mkdir: /builds/slave/test/properties 17:06:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:06:45 INFO - Writing to file /builds/slave/test/properties/build_url 17:06:45 INFO - Contents: 17:06:45 INFO - build_url:https://queue.taskcluster.net/v1/task/LGQXyqmERTW-GxwgCpavdQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 17:06:45 INFO - Running post-action listener: _resource_record_post_action 17:06:45 INFO - Running post-action listener: set_extra_try_arguments 17:06:45 INFO - [mozharness: 2016-08-26 00:06:45.679574Z] Finished download-and-extract step (success) 17:06:45 INFO - [mozharness: 2016-08-26 00:06:45.679797Z] Running populate-webroot step. 17:06:45 INFO - Running pre-action listener: _resource_record_pre_action 17:06:45 INFO - Running main action method: populate_webroot 17:06:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:06:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:06:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:06:45 INFO - '--tppagecycles', 17:06:45 INFO - '7'], 17:06:45 INFO - 'tests': ['tsvgm']}}, 17:06:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:06:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:06:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:06:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:06:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:06:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:06:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:06:45 INFO - 'talos_options': ['--disable-e10s'], 17:06:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:06:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:06:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:06:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:06:45 INFO - 'talos_options': ['--disable-e10s'], 17:06:45 INFO - 'tests': ['damp', 'tps']}, 17:06:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:06:45 INFO - 'tests': ['damp', 'tps']}, 17:06:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:06:45 INFO - 'tests': ['dromaeo_dom']}, 17:06:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:06:45 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:06:45 INFO - 'tests': ['basic_compositor_video']}, 17:06:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:06:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:06:45 INFO - 'tests': ['a11yr', 17:06:45 INFO - 'ts_paint', 17:06:45 INFO - 'tpaint', 17:06:45 INFO - 'sessionrestore', 17:06:45 INFO - 'sessionrestore_no_auto_restore', 17:06:45 INFO - 'tabpaint']}, 17:06:45 INFO - 'other-e10s': {'tests': ['a11yr', 17:06:45 INFO - 'ts_paint', 17:06:45 INFO - 'tpaint', 17:06:45 INFO - 'sessionrestore', 17:06:45 INFO - 'sessionrestore_no_auto_restore', 17:06:45 INFO - 'tabpaint']}, 17:06:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:06:45 INFO - 'ts_paint', 17:06:45 INFO - 'tpaint', 17:06:45 INFO - 'sessionrestore', 17:06:45 INFO - 'sessionrestore_no_auto_restore', 17:06:45 INFO - 'tabpaint']}, 17:06:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:06:45 INFO - 'ts_paint', 17:06:45 INFO - 'tpaint', 17:06:45 INFO - 'sessionrestore', 17:06:45 INFO - 'sessionrestore_no_auto_restore', 17:06:45 INFO - 'tabpaint']}, 17:06:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:06:45 INFO - 'tests': ['a11yr', 17:06:45 INFO - 'ts_paint', 17:06:45 INFO - 'tpaint', 17:06:45 INFO - 'sessionrestore', 17:06:45 INFO - 'sessionrestore_no_auto_restore', 17:06:45 INFO - 'tabpaint']}, 17:06:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:06:45 INFO - 'tests': ['a11yr', 17:06:45 INFO - 'ts_paint', 17:06:45 INFO - 'tpaint', 17:06:45 INFO - 'sessionrestore', 17:06:45 INFO - 'sessionrestore_no_auto_restore', 17:06:45 INFO - 'tabpaint']}, 17:06:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:06:45 INFO - 'tests': ['tsvgx', 17:06:45 INFO - 'tsvgr_opacity', 17:06:45 INFO - 'tart', 17:06:45 INFO - 'tscrollx', 17:06:45 INFO - 'cart']}, 17:06:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:06:45 INFO - 'tsvgr_opacity', 17:06:45 INFO - 'tart', 17:06:45 INFO - 'tscrollx', 17:06:45 INFO - 'cart']}, 17:06:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:06:45 INFO - 'talos_options': ['--disable-e10s'], 17:06:45 INFO - 'tests': ['tp5o']}, 17:06:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:06:45 INFO - 'tests': ['tp5o']}, 17:06:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:06:45 INFO - 'talos_options': ['--disable-e10s', 17:06:45 INFO - '--xperf_path', 17:06:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:06:45 INFO - 'tests': ['tp5n']}, 17:06:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:06:45 INFO - 'talos_options': ['--xperf_path', 17:06:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:06:45 INFO - 'tests': ['tp5n']}}, 17:06:45 INFO - 'talos.zip': {'path': '', 17:06:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:06:45 INFO - Running post-action listener: _resource_record_post_action 17:06:45 INFO - [mozharness: 2016-08-26 00:06:45.702026Z] Finished populate-webroot step (success) 17:06:45 INFO - [mozharness: 2016-08-26 00:06:45.702113Z] Running create-virtualenv step. 17:06:45 INFO - Running pre-action listener: _resource_record_pre_action 17:06:45 INFO - Running main action method: create_virtualenv 17:06:45 INFO - Creating virtualenv /builds/slave/test/build/venv 17:06: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 17:06:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:06:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:06:45 INFO - Using real prefix '/usr' 17:06:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:06:46 INFO - Installing distribute.............................................................................................................................................................................................done. 17:06:48 INFO - Installing pip.................done. 17:06:48 INFO - Return code: 0 17:06:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:06: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:06:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:06:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06: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 0x7f6cd15a3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x150f230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1509090>, '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 0x150c9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x150ce40>, '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': '9c3a94c35cc88655b05d2c08000001b2-1472169338.589573-1913469843', '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 17:06: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 17:06: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 17:06:48 INFO - Using env: {'DISPLAY': ':0', 17:06:48 INFO - 'HOME': '/home/cltbld', 17:06:48 INFO - 'LANG': 'en_US.UTF-8', 17:06:48 INFO - 'LANGUAGE': 'en_US:en', 17:06:48 INFO - 'LOGNAME': 'cltbld', 17:06:48 INFO - 'MAIL': '/var/mail/cltbld', 17:06:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:48 INFO - 'MOZ_NO_REMOTE': '1', 17:06:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:06:48 INFO - 'NO_EM_RESTART': '1', 17:06:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:06:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:48 INFO - 'PWD': '/builds/slave/test', 17:06:48 INFO - 'SHELL': '/bin/bash', 17:06:48 INFO - 'SHLVL': '1', 17:06:48 INFO - 'TERM': 'linux', 17:06:48 INFO - 'TMOUT': '86400', 17:06:48 INFO - 'USER': 'cltbld', 17:06:48 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1472169338.589573-1913469843', 17:06:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:48 INFO - '_': '/tools/buildbot/bin/python'} 17:06:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06: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 17:06:48 INFO - Cleaning up... 17:06:48 INFO - Return code: 0 17:06:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:06: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:06:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:06:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06: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 0x7f6cd15a3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x150f230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1509090>, '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 0x150c9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x150ce40>, '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': '9c3a94c35cc88655b05d2c08000001b2-1472169338.589573-1913469843', '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 17:06: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 17:06: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 17:06:48 INFO - Using env: {'DISPLAY': ':0', 17:06:48 INFO - 'HOME': '/home/cltbld', 17:06:48 INFO - 'LANG': 'en_US.UTF-8', 17:06:48 INFO - 'LANGUAGE': 'en_US:en', 17:06:48 INFO - 'LOGNAME': 'cltbld', 17:06:48 INFO - 'MAIL': '/var/mail/cltbld', 17:06:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:48 INFO - 'MOZ_NO_REMOTE': '1', 17:06:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:06:48 INFO - 'NO_EM_RESTART': '1', 17:06:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:06:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:48 INFO - 'PWD': '/builds/slave/test', 17:06:48 INFO - 'SHELL': '/bin/bash', 17:06:48 INFO - 'SHLVL': '1', 17:06:48 INFO - 'TERM': 'linux', 17:06:48 INFO - 'TMOUT': '86400', 17:06:48 INFO - 'USER': 'cltbld', 17:06:48 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1472169338.589573-1913469843', 17:06:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:48 INFO - '_': '/tools/buildbot/bin/python'} 17:06:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:48 INFO - Downloading/unpacking psutil>=3.1.1 17:06: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 17:06: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 17:06: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 17:06: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 17:06: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 17:06: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 17:06:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:06:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:06:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:06:50 INFO - Installing collected packages: psutil 17:06:50 INFO - Running setup.py install for psutil 17:06:50 INFO - building 'psutil._psutil_linux' extension 17:06: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 17:06: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 17:06:50 INFO - building 'psutil._psutil_posix' extension 17:06: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 17:06:51 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 17:06:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:06:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:06:51 INFO - Successfully installed psutil 17:06:51 INFO - Cleaning up... 17:06:51 INFO - Return code: 0 17:06:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:06: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:06:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:06:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06: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 0x7f6cd15a3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x150f230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1509090>, '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 0x150c9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x150ce40>, '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': '9c3a94c35cc88655b05d2c08000001b2-1472169338.589573-1913469843', '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 17:06: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 17:06: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 17:06:51 INFO - Using env: {'DISPLAY': ':0', 17:06:51 INFO - 'HOME': '/home/cltbld', 17:06:51 INFO - 'LANG': 'en_US.UTF-8', 17:06:51 INFO - 'LANGUAGE': 'en_US:en', 17:06:51 INFO - 'LOGNAME': 'cltbld', 17:06:51 INFO - 'MAIL': '/var/mail/cltbld', 17:06:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:51 INFO - 'MOZ_NO_REMOTE': '1', 17:06:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:06:51 INFO - 'NO_EM_RESTART': '1', 17:06:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:06:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:51 INFO - 'PWD': '/builds/slave/test', 17:06:51 INFO - 'SHELL': '/bin/bash', 17:06:51 INFO - 'SHLVL': '1', 17:06:51 INFO - 'TERM': 'linux', 17:06:51 INFO - 'TMOUT': '86400', 17:06:51 INFO - 'USER': 'cltbld', 17:06:51 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1472169338.589573-1913469843', 17:06:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:51 INFO - '_': '/tools/buildbot/bin/python'} 17:06:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:06: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 17:06: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 17:06: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 17:06: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 17:06: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 17:06: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 17:06:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:06:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:06:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:06:53 INFO - Installing collected packages: mozsystemmonitor 17:06:53 INFO - Running setup.py install for mozsystemmonitor 17:06:53 INFO - Successfully installed mozsystemmonitor 17:06:53 INFO - Cleaning up... 17:06:53 INFO - Return code: 0 17:06:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:06:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:06:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:06:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06: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 0x7f6cd15a3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x150f230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1509090>, '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 0x150c9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x150ce40>, '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': '9c3a94c35cc88655b05d2c08000001b2-1472169338.589573-1913469843', '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 17:06: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 17:06: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 17:06:53 INFO - Using env: {'DISPLAY': ':0', 17:06:53 INFO - 'HOME': '/home/cltbld', 17:06:53 INFO - 'LANG': 'en_US.UTF-8', 17:06:53 INFO - 'LANGUAGE': 'en_US:en', 17:06:53 INFO - 'LOGNAME': 'cltbld', 17:06:53 INFO - 'MAIL': '/var/mail/cltbld', 17:06:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:53 INFO - 'MOZ_NO_REMOTE': '1', 17:06:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:06:53 INFO - 'NO_EM_RESTART': '1', 17:06:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:06:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:53 INFO - 'PWD': '/builds/slave/test', 17:06:53 INFO - 'SHELL': '/bin/bash', 17:06:53 INFO - 'SHLVL': '1', 17:06:53 INFO - 'TERM': 'linux', 17:06:53 INFO - 'TMOUT': '86400', 17:06:53 INFO - 'USER': 'cltbld', 17:06:53 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1472169338.589573-1913469843', 17:06:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:53 INFO - '_': '/tools/buildbot/bin/python'} 17:06:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:53 INFO - Downloading/unpacking jsonschema==2.5.1 17:06: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 17:06: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 17:06: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 17:06: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 17:06: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 17:06: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 17:06:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:06:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:06: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 17:06: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 17:06: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 17:06: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 17:06: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 17:06: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 17:06:55 INFO - Downloading functools32-3.2.3-2.tar.gz 17:06:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:06:55 INFO - warning: no files found matching '*.txt' 17:06:55 INFO - no previously-included directories found matching 'build' 17:06:55 INFO - no previously-included directories found matching 'dist' 17:06:55 INFO - no previously-included directories found matching '.git*' 17:06:55 INFO - Installing collected packages: jsonschema, functools32 17:06:55 INFO - Running setup.py install for functools32 17:06:56 INFO - warning: no files found matching '*.txt' 17:06:56 INFO - no previously-included directories found matching 'build' 17:06:56 INFO - no previously-included directories found matching 'dist' 17:06:56 INFO - no previously-included directories found matching '.git*' 17:06:56 INFO - Successfully installed jsonschema functools32 17:06:56 INFO - Cleaning up... 17:06:56 INFO - Return code: 0 17:06:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:06: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:06:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:06:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06: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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6cd15a3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x150f230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1509090>, '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 0x150c9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x150ce40>, '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': '9c3a94c35cc88655b05d2c08000001b2-1472169338.589573-1913469843', '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 17:06: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:06: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 functools32==3.2.3-2 17:06:56 INFO - Using env: {'DISPLAY': ':0', 17:06:56 INFO - 'HOME': '/home/cltbld', 17:06:56 INFO - 'LANG': 'en_US.UTF-8', 17:06:56 INFO - 'LANGUAGE': 'en_US:en', 17:06:56 INFO - 'LOGNAME': 'cltbld', 17:06:56 INFO - 'MAIL': '/var/mail/cltbld', 17:06:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:56 INFO - 'MOZ_NO_REMOTE': '1', 17:06:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:06:56 INFO - 'NO_EM_RESTART': '1', 17:06:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:06:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:56 INFO - 'PWD': '/builds/slave/test', 17:06:56 INFO - 'SHELL': '/bin/bash', 17:06:56 INFO - 'SHLVL': '1', 17:06:56 INFO - 'TERM': 'linux', 17:06:56 INFO - 'TMOUT': '86400', 17:06:56 INFO - 'USER': 'cltbld', 17:06:56 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1472169338.589573-1913469843', 17:06:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:56 INFO - '_': '/tools/buildbot/bin/python'} 17:06:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:06:56 INFO - Cleaning up... 17:06:56 INFO - Return code: 0 17:06:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:06: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:06:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:06:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06: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 0x7f6cd15a3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x150f230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1509090>, '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 0x150c9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x150ce40>, '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': '9c3a94c35cc88655b05d2c08000001b2-1472169338.589573-1913469843', '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 17:06: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 17:06: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 17:06:56 INFO - Using env: {'DISPLAY': ':0', 17:06:56 INFO - 'HOME': '/home/cltbld', 17:06:56 INFO - 'LANG': 'en_US.UTF-8', 17:06:56 INFO - 'LANGUAGE': 'en_US:en', 17:06:56 INFO - 'LOGNAME': 'cltbld', 17:06:56 INFO - 'MAIL': '/var/mail/cltbld', 17:06:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:56 INFO - 'MOZ_NO_REMOTE': '1', 17:06:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:06:56 INFO - 'NO_EM_RESTART': '1', 17:06:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:06:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:56 INFO - 'PWD': '/builds/slave/test', 17:06:56 INFO - 'SHELL': '/bin/bash', 17:06:56 INFO - 'SHLVL': '1', 17:06:56 INFO - 'TERM': 'linux', 17:06:56 INFO - 'TMOUT': '86400', 17:06:56 INFO - 'USER': 'cltbld', 17:06:56 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1472169338.589573-1913469843', 17:06:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:56 INFO - '_': '/tools/buildbot/bin/python'} 17:06:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:56 INFO - Downloading/unpacking blobuploader==1.2.4 17:06: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 17:06: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 17:06: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 17:06: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 17:06: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 17:06: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 17:06:58 INFO - Downloading blobuploader-1.2.4.tar.gz 17:06:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:06:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:06: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 17:06: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 17:06: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 17:06: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 17:06: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 17:06: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 17:06:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:06:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:06:59 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 17:06:59 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 17:06:59 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 17:06:59 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 17:06:59 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 17:06:59 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 17:06:59 INFO - Downloading docopt-0.6.1.tar.gz 17:06:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:06:59 INFO - Installing collected packages: blobuploader, requests, docopt 17:06:59 INFO - Running setup.py install for blobuploader 17:06:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:06:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:06:59 INFO - Running setup.py install for requests 17:06:59 INFO - Running setup.py install for docopt 17:06:59 INFO - Successfully installed blobuploader requests docopt 17:06:59 INFO - Cleaning up... 17:07:00 INFO - Return code: 0 17:07:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:07:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:07:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:07:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:07:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:07:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:07:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:07:00 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 0x7f6cd15a3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x150f230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1509090>, '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 0x150c9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x150ce40>, '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': '9c3a94c35cc88655b05d2c08000001b2-1472169338.589573-1913469843', '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 17:07:00 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 17:07:00 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 17:07:00 INFO - Using env: {'DISPLAY': ':0', 17:07:00 INFO - 'HOME': '/home/cltbld', 17:07:00 INFO - 'LANG': 'en_US.UTF-8', 17:07:00 INFO - 'LANGUAGE': 'en_US:en', 17:07:00 INFO - 'LOGNAME': 'cltbld', 17:07:00 INFO - 'MAIL': '/var/mail/cltbld', 17:07:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:07:00 INFO - 'MOZ_NO_REMOTE': '1', 17:07:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:07:00 INFO - 'NO_EM_RESTART': '1', 17:07:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:07:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:07:00 INFO - 'PWD': '/builds/slave/test', 17:07:00 INFO - 'SHELL': '/bin/bash', 17:07:00 INFO - 'SHLVL': '1', 17:07:00 INFO - 'TERM': 'linux', 17:07:00 INFO - 'TMOUT': '86400', 17:07:00 INFO - 'USER': 'cltbld', 17:07:00 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1472169338.589573-1913469843', 17:07:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:07:00 INFO - '_': '/tools/buildbot/bin/python'} 17:07:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:07:00 INFO - Running setup.py (path:/tmp/pip-ngMQLZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:07:00 INFO - Running setup.py (path:/tmp/pip-OM1Op6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:07:00 INFO - Running setup.py (path:/tmp/pip-NY1Adq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:07:00 INFO - Running setup.py (path:/tmp/pip-CPsqZv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:07:00 INFO - Running setup.py (path:/tmp/pip-R0QaX0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:07:00 INFO - Running setup.py (path:/tmp/pip-2tHGoA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:07:00 INFO - Running setup.py (path:/tmp/pip-YZ5fla-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:07:00 INFO - Running setup.py (path:/tmp/pip-cl3e1o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:07:00 INFO - Running setup.py (path:/tmp/pip-v1gbvb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:07:00 INFO - Running setup.py (path:/tmp/pip-Igb1lQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:07:00 INFO - Running setup.py (path:/tmp/pip-_VTGZ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:07:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:07:01 INFO - Running setup.py (path:/tmp/pip-A_HgtK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:07:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:07:01 INFO - Running setup.py (path:/tmp/pip-2TEuMv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:07:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:07:01 INFO - Running setup.py (path:/tmp/pip-93Kboc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:07:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:07:01 INFO - Running setup.py (path:/tmp/pip-54keAI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:07:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:07:01 INFO - Running setup.py (path:/tmp/pip-gGFdKs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:07:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:07:01 INFO - Running setup.py (path:/tmp/pip-jLhhs0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:07:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:07:01 INFO - Running setup.py install for manifestparser 17:07:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:07:01 INFO - Running setup.py install for mozcrash 17:07:01 INFO - Running setup.py install for mozdebug 17:07:01 INFO - Running setup.py install for mozdevice 17:07:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:07:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:07:01 INFO - Running setup.py install for mozfile 17:07:01 INFO - Running setup.py install for mozhttpd 17:07:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:07:02 INFO - Running setup.py install for mozinfo 17:07:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:07:02 INFO - Running setup.py install for mozInstall 17:07:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:07:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:07:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:07:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:07:02 INFO - Running setup.py install for mozleak 17:07:02 INFO - Running setup.py install for mozlog 17:07:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:07:02 INFO - Running setup.py install for moznetwork 17:07:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:07:02 INFO - Running setup.py install for mozprocess 17:07:02 INFO - Running setup.py install for mozprofile 17:07:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:07:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:07:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:07:02 INFO - Running setup.py install for mozrunner 17:07:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:07:02 INFO - Running setup.py install for mozscreenshot 17:07:03 INFO - Running setup.py install for moztest 17:07:03 INFO - Running setup.py install for mozversion 17:07:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:07:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:07:03 INFO - Cleaning up... 17:07:03 INFO - Return code: 0 17:07:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:07:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:07:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:07:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:07:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:07:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:07:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:07:03 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 0x7f6cd15a3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x150f230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1509090>, '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 0x150c9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x150ce40>, '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': '9c3a94c35cc88655b05d2c08000001b2-1472169338.589573-1913469843', '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 17:07:03 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 17:07:03 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 17:07:03 INFO - Using env: {'DISPLAY': ':0', 17:07:03 INFO - 'HOME': '/home/cltbld', 17:07:03 INFO - 'LANG': 'en_US.UTF-8', 17:07:03 INFO - 'LANGUAGE': 'en_US:en', 17:07:03 INFO - 'LOGNAME': 'cltbld', 17:07:03 INFO - 'MAIL': '/var/mail/cltbld', 17:07:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:07:03 INFO - 'MOZ_NO_REMOTE': '1', 17:07:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:07:03 INFO - 'NO_EM_RESTART': '1', 17:07:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:07:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:07:03 INFO - 'PWD': '/builds/slave/test', 17:07:03 INFO - 'SHELL': '/bin/bash', 17:07:03 INFO - 'SHLVL': '1', 17:07:03 INFO - 'TERM': 'linux', 17:07:03 INFO - 'TMOUT': '86400', 17:07:03 INFO - 'USER': 'cltbld', 17:07:03 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1472169338.589573-1913469843', 17:07:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:07:03 INFO - '_': '/tools/buildbot/bin/python'} 17:07:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:07:03 INFO - Running setup.py (path:/tmp/pip-9UOwNP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:07: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)) 17:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:07:03 INFO - Running setup.py (path:/tmp/pip-eHDf_h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:07: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)) 17:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:07:03 INFO - Running setup.py (path:/tmp/pip-O0lvBT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:07: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)) 17:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:07:03 INFO - Running setup.py (path:/tmp/pip-aMTN10-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:07: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)) 17:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:07:03 INFO - Running setup.py (path:/tmp/pip-EiQ5vI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:07: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)) 17:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:07:03 INFO - Running setup.py (path:/tmp/pip-2JT30v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:07: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)) 17:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:07:03 INFO - Running setup.py (path:/tmp/pip-FcAP6y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:07: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)) 17:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:07:03 INFO - Running setup.py (path:/tmp/pip-XJAAmQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:07:04 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)) 17:07:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:07:04 INFO - Running setup.py (path:/tmp/pip-wi3eXI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:07:04 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)) 17:07:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:07:04 INFO - Running setup.py (path:/tmp/pip-KqqJSZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:07:04 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)) 17:07:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:07:04 INFO - Running setup.py (path:/tmp/pip-8vj44S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:07:04 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)) 17:07:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:07:04 INFO - Running setup.py (path:/tmp/pip-aV1jIw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:07:04 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)) 17:07:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:07:04 INFO - Running setup.py (path:/tmp/pip-rxyvmK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:07:04 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)) 17:07:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:07:04 INFO - Running setup.py (path:/tmp/pip-gXEw3m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:07: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)) 17:07:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:07:04 INFO - Running setup.py (path:/tmp/pip-_wDDMs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:07: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)) 17:07:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:07:04 INFO - Running setup.py (path:/tmp/pip-tIUA2E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:07: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)) 17:07:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:07:04 INFO - Running setup.py (path:/tmp/pip-s9x2p1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:07: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)) 17:07: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)) 17:07: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)) 17:07: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)) 17:07: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)) 17:07: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)) 17:07:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:07: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 17:07: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 17:07: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 17:07: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 17:07: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 17:07: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 17:07:06 INFO - Downloading blessings-1.6.tar.gz 17:07:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:07:06 INFO - Installing collected packages: blessings 17:07:06 INFO - Running setup.py install for blessings 17:07:06 INFO - Successfully installed blessings 17:07:06 INFO - Cleaning up... 17:07:06 INFO - Return code: 0 17:07:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:07:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:07:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:07:07 INFO - Reading from file tmpfile_stdout 17:07:07 INFO - Current package versions: 17:07:07 INFO - argparse == 1.2.1 17:07:07 INFO - blessings == 1.6 17:07:07 INFO - blobuploader == 1.2.4 17:07:07 INFO - docopt == 0.6.1 17:07:07 INFO - functools32 == 3.2.3-2 17:07:07 INFO - jsonschema == 2.5.1 17:07:07 INFO - manifestparser == 1.1 17:07:07 INFO - mozInstall == 1.12 17:07:07 INFO - mozcrash == 0.17 17:07:07 INFO - mozdebug == 0.1 17:07:07 INFO - mozdevice == 0.48 17:07:07 INFO - mozfile == 1.2 17:07:07 INFO - mozhttpd == 0.7 17:07:07 INFO - mozinfo == 0.9 17:07:07 INFO - mozleak == 0.1 17:07:07 INFO - mozlog == 3.3 17:07:07 INFO - moznetwork == 0.27 17:07:07 INFO - mozprocess == 0.23 17:07:07 INFO - mozprofile == 0.28 17:07:07 INFO - mozrunner == 6.12 17:07:07 INFO - mozscreenshot == 0.1 17:07:07 INFO - mozsystemmonitor == 0.3 17:07:07 INFO - moztest == 0.7 17:07:07 INFO - mozversion == 1.4 17:07:07 INFO - psutil == 3.1.1 17:07:07 INFO - requests == 1.2.3 17:07:07 INFO - wsgiref == 0.1.2 17:07:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:07:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:07:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:07:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:07:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:07:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:07:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:07:07 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 0x7f6cd15a3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x150f230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1509090>, '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 0x150c9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x150ce40>, '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': '9c3a94c35cc88655b05d2c08000001b2-1472169338.589573-1913469843', '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 17:07:07 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 17:07:07 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 17:07:07 INFO - Using env: {'DISPLAY': ':0', 17:07:07 INFO - 'HOME': '/home/cltbld', 17:07:07 INFO - 'LANG': 'en_US.UTF-8', 17:07:07 INFO - 'LANGUAGE': 'en_US:en', 17:07:07 INFO - 'LOGNAME': 'cltbld', 17:07:07 INFO - 'MAIL': '/var/mail/cltbld', 17:07:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:07:07 INFO - 'MOZ_NO_REMOTE': '1', 17:07:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:07:07 INFO - 'NO_EM_RESTART': '1', 17:07:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:07:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:07:07 INFO - 'PWD': '/builds/slave/test', 17:07:07 INFO - 'SHELL': '/bin/bash', 17:07:07 INFO - 'SHLVL': '1', 17:07:07 INFO - 'TERM': 'linux', 17:07:07 INFO - 'TMOUT': '86400', 17:07:07 INFO - 'USER': 'cltbld', 17:07:07 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1472169338.589573-1913469843', 17:07:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:07:07 INFO - '_': '/tools/buildbot/bin/python'} 17:07:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:07:07 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)) 17:07:07 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)) 17:07:07 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)) 17:07:07 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)) 17:07:07 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)) 17:07:07 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)) 17:07:07 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)) 17:07:07 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)) 17:07:07 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)) 17:07:07 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)) 17:07:07 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)) 17:07:07 INFO - Cleaning up... 17:07:07 INFO - Return code: 0 17:07:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:07:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:07:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:07:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:07:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:07:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:07:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:07:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6cd15a3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x150f230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1509090>, '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 0x150c9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x150ce40>, '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': '9c3a94c35cc88655b05d2c08000001b2-1472169338.589573-1913469843', '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 17:07:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:07:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:07:07 INFO - Using env: {'DISPLAY': ':0', 17:07:07 INFO - 'HOME': '/home/cltbld', 17:07:07 INFO - 'LANG': 'en_US.UTF-8', 17:07:07 INFO - 'LANGUAGE': 'en_US:en', 17:07:07 INFO - 'LOGNAME': 'cltbld', 17:07:07 INFO - 'MAIL': '/var/mail/cltbld', 17:07:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:07:07 INFO - 'MOZ_NO_REMOTE': '1', 17:07:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:07:07 INFO - 'NO_EM_RESTART': '1', 17:07:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:07:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:07:07 INFO - 'PWD': '/builds/slave/test', 17:07:07 INFO - 'SHELL': '/bin/bash', 17:07:07 INFO - 'SHLVL': '1', 17:07:07 INFO - 'TERM': 'linux', 17:07:07 INFO - 'TMOUT': '86400', 17:07:07 INFO - 'USER': 'cltbld', 17:07:07 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1472169338.589573-1913469843', 17:07:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:07:07 INFO - '_': '/tools/buildbot/bin/python'} 17:07:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:07:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:07:07 INFO - Cleaning up... 17:07:07 INFO - Return code: 0 17:07:07 INFO - Running post-action listener: _resource_record_post_action 17:07:07 INFO - Running post-action listener: _start_resource_monitoring 17:07:07 INFO - Starting resource monitoring. 17:07:07 INFO - [mozharness: 2016-08-26 00:07:07.559297Z] Finished create-virtualenv step (success) 17:07:07 INFO - [mozharness: 2016-08-26 00:07:07.559806Z] Running install step. 17:07:07 INFO - Running pre-action listener: _resource_record_pre_action 17:07:07 INFO - Running main action method: install 17:07:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:07:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:07:07 INFO - Reading from file tmpfile_stdout 17:07:07 INFO - Detecting whether we're running mozinstall >=1.0... 17:07:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:07:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:07:07 INFO - Reading from file tmpfile_stdout 17:07:07 INFO - Output received: 17:07:07 INFO - Usage: mozinstall [options] installer 17:07:07 INFO - Options: 17:07:07 INFO - -h, --help show this help message and exit 17:07:07 INFO - -d DEST, --destination=DEST 17:07:07 INFO - Directory to install application into. [default: 17:07:07 INFO - "/builds/slave/test"] 17:07:07 INFO - --app=APP Application being installed. [default: firefox] 17:07:07 INFO - mkdir: /builds/slave/test/build/application 17:07:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 17:07:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 17:07:24 INFO - Reading from file tmpfile_stdout 17:07:24 INFO - Output received: 17:07:24 INFO - /builds/slave/test/build/application/firefox/firefox 17:07:24 INFO - Running post-action listener: _resource_record_post_action 17:07:24 INFO - [mozharness: 2016-08-26 00:07:24.835526Z] Finished install step (success) 17:07:24 INFO - [mozharness: 2016-08-26 00:07:24.835773Z] Running run-tests step. 17:07:24 INFO - Running pre-action listener: _resource_record_pre_action 17:07:24 INFO - Running main action method: run_tests 17:07:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:07:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:07:24 INFO - Python 2.7.3 17:07:24 INFO - Return code: 0 17:07:24 INFO - grabbing minidump binary from tooltool 17:07: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:07: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 0x1509090>, '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 0x150c9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x150ce40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:07: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 17:07: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 17:07: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 17:07:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:07:24 INFO - Return code: 0 17:07:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:07:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:07:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:07:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:07:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:07:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LGQXyqmERTW-GxwgCpavdQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--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 17:07:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LGQXyqmERTW-GxwgCpavdQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-013 --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 17:07:24 INFO - Using env: {'DISPLAY': ':0', 17:07:24 INFO - 'HOME': '/home/cltbld', 17:07:24 INFO - 'LANG': 'en_US.UTF-8', 17:07:24 INFO - 'LANGUAGE': 'en_US:en', 17:07:24 INFO - 'LOGNAME': 'cltbld', 17:07:24 INFO - 'MAIL': '/var/mail/cltbld', 17:07:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:07:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:07:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:07:24 INFO - 'MOZ_NO_REMOTE': '1', 17:07:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:07:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:07:24 INFO - 'NO_EM_RESTART': '1', 17:07:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:07:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:07:24 INFO - 'PWD': '/builds/slave/test', 17:07:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:07:24 INFO - 'SHELL': '/bin/bash', 17:07:24 INFO - 'SHLVL': '1', 17:07:24 INFO - 'TERM': 'linux', 17:07:24 INFO - 'TMOUT': '86400', 17:07:24 INFO - 'USER': 'cltbld', 17:07:24 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1472169338.589573-1913469843', 17:07:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:07:24 INFO - '_': '/tools/buildbot/bin/python'} 17:07:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LGQXyqmERTW-GxwgCpavdQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--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 17:07:25 INFO - mozversion application_buildid: 20160825135343 17:07:25 INFO - mozversion application_changeset: 7cae917bd51939b9f642dd8bcb16e081ae721a6a 17:07:25 INFO - mozversion application_display_name: Nightly 17:07:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:07:25 INFO - mozversion application_name: Firefox 17:07:25 INFO - mozversion application_remotingname: firefox 17:07:25 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 17:07:25 INFO - mozversion application_vendor: Mozilla 17:07:25 INFO - mozversion application_version: 51.0a1 17:07:25 INFO - mozversion platform_buildid: 20160825135343 17:07:25 INFO - mozversion platform_changeset: 7cae917bd51939b9f642dd8bcb16e081ae721a6a 17:07:25 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 17:07:25 INFO - mozversion platform_version: 51.0a1 17:07:25 INFO - using testdate: 1472170045 17:07:25 INFO - actual date: 1472170045 17:07:25 INFO - starting webserver on 'localhost:56680' 17:07:25 INFO - SUITE-START | Running 2 tests 17:07:25 INFO - TEST-START | dromaeo_css 17:07:25 INFO - operating with platform_type : linux_ 17:07:25 INFO - Initialising browser for dromaeo_css test... 17:07:25 INFO - TEST-INFO | started process 6606 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjtJpv3/profile http://localhost:56680/getInfo.html) 17:07:29 INFO - PROCESS | 6606 | __metrics Screen width/height:1600/1200 17:07:29 INFO - PROCESS | 6606 | colorDepth:24 17:07:29 INFO - PROCESS | 6606 | Browser inner width/height: 1024/697 17:07:29 INFO - PROCESS | 6606 | __metrics 17:07:30 INFO - PROCESS | 6606 | 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" 17:07:30 INFO - PROCESS | 6606 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:07:30 INFO - PROCESS | 6606 | console.error: PushService: 17:07:30 INFO - PROCESS | 6606 | stateChangeProcessEnqueue: Error transitioning state 17:07:30 INFO - PROCESS | 6606 | UnknownError 17:07:31 INFO - TEST-INFO | 6606: exit 0 17:07:31 INFO - Browser initialized. 17:07:31 INFO - Running cycle 1/1 for dromaeo_css test... 17:07:31 INFO - TEST-INFO | started process 6688 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjtJpv3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:07:43 INFO - PROCESS | 6688 | RSS: Main: 179666944 17:07:43 INFO - PROCESS | 6688 | 17:08:34 INFO - PROCESS | 6688 | 1472170114601 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] 17:10:44 INFO - PROCESS | 6688 | Cycle 1(1): loaded http://localhost:56680/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56680/tests/dromaeo/cssquery-ext.html) 17:10:45 INFO - PROCESS | 6688 | RSS: Main: 267423744 17:10:45 INFO - PROCESS | 6688 | 17:13:47 INFO - PROCESS | 6688 | Cycle 1(1): loaded http://localhost:56680/tests/dromaeo/cssquery-ext.html (next: http://localhost:56680/tests/dromaeo/cssquery-jquery.html) 17:13:48 INFO - PROCESS | 6688 | RSS: Main: 272072704 17:13:48 INFO - PROCESS | 6688 | 17:16:50 INFO - PROCESS | 6688 | Cycle 1(1): loaded http://localhost:56680/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56680/tests/dromaeo/cssquery-mootools.html) 17:16:51 INFO - PROCESS | 6688 | RSS: Main: 241381376 17:16:51 INFO - PROCESS | 6688 | 17:19:53 INFO - PROCESS | 6688 | Cycle 1(1): loaded http://localhost:56680/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56680/tests/dromaeo/cssquery-prototype.html) 17:19:54 INFO - PROCESS | 6688 | RSS: Main: 253145088 17:19:54 INFO - PROCESS | 6688 | 17:22:56 INFO - PROCESS | 6688 | Cycle 1(1): loaded http://localhost:56680/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56680/tests/dromaeo/cssquery-yui.html) 17:22:57 INFO - PROCESS | 6688 | RSS: Main: 213643264 17:22:57 INFO - PROCESS | 6688 | 17:26:00 INFO - PROCESS | 6688 | Cycle 1(1): loaded http://localhost:56680/tests/dromaeo/cssquery-yui.html (next: http://localhost:56680/tests/dromaeo/cssquery-dojo.html) 17:26:00 INFO - PROCESS | 6688 | RSS: Main: 302780416 17:26:00 INFO - PROCESS | 6688 | 17:26:00 INFO - PROCESS | 6688 | __start_tp_report 17:26:00 INFO - PROCESS | 6688 | _x_x_mozilla_page_load 17:26:00 INFO - PROCESS | 6688 | _x_x_mozilla_page_load_details 17:26:00 INFO - PROCESS | 6688 | |i|pagename|runs| 17:26:00 INFO - PROCESS | 6688 | |0;dojo.html;2934.941301173976;2935.0901220621604;2963.5702823090637;2985.1939976206413;3017.803842750221;5172.069027575039;5190.688558686472;5248.501392367729;5266.52601265886;5304.071787437202;5296.523312901838;5329.333833270841;5332.093544097503;5332.413434522199;5494.313210848644;4760.785764640599;4773.832915847946;4814.542618451242;4919.409670839495;4933.087378834909;4835.081334546442;4848.2727590861305;4869.366982292297;4980.252962055684;5014.022265658198;4945.802167913279;4966.850994470172;4968.701877887338;5007.574356179711;5026.1204289249235;4701.036287561076;4716.29255611661;4764.857054288377;4885.413750349981;4952.083864485082;4737.052589482118;4753.524647535219;4767.094252092126;4785.449673287581;4883.02339532095;5286.577073834084;5336.092864213093;5351.063563876306;5363.651362661453;5394.406615272317;5531.861703457445;5534.75093620788;5686.402927692574;5695.430456954271;5703.857403564944;5625.437456254383;5627.212190293362;5628.127640215773;5650.74571644277;5724.513416359614;5291.074062039128;5309.973450132725;5316.707581083;5320.600954928396;5327.067763141435;5666.744996475169;5675.120356344773;5754.5971781975695;5781.364049954502;5785.392533783976;5588.2455868458055;5589.217509548666;5593.216949627055;5648.8870222595315;5656.349519805192;5649.01142300104;5672.631278966854;5673.773049078073;5675.631083979611;5677.602567820213;5562.02664533705;5579.721013949286;5609.186667933209;5609.607327487119;5648.85877853057;5669.06460434032;5672;5675.233843431167;5733.283339582552;5745.396733343023;4365.585269399402;4401.075774087413;4402.779861007001;4408.118376324748;4457.710248833815;3851.9807400963377;3879.3792993120965;3917.4711413958753;3927.2341893330527;3950.9604903951167;5385.380681221632;5387.0841956867425;5391.3799913009725;5435.537979271806;5450.5367043800925;5712.34308054579;5713.028785106542;5718.7426565804635;5720.427957204246;5726.885462290731;5262.7631756571045;5313.0967735485065;5341.225522299244;5376.274202982626;5476.370217424967;4882.584980276716;4923.606111511071;4928.654994150375;4938.185199442052;4940.036692844861;5848.210491583582;5852.795152169653;5856.443637854397;5859.882802344015;5864.32560255559;5487.945120548743;5651.8304450866535;5706.514946229617;5711.714414279187;5711.771529138788;5645.633033852787;5786.797462088806;5789.2763404574425;5795.536357091507;5872.647641141545;5544.944550554443;5547.389787123494;5551.361593416808;5552.805651802168;5556.666600004012;5508.669479831223;5654.208410822571;5658;5663.915041274302;5669.858253543694;5458.044842152528;5512.062949298549;5563.388027317073;5569.526590239875;5670.801521946877;5506.64206826555;5561.860953476195;5571.637843540157;5592.105263157875;5605.663660180402;5577.721113944368;5592.468715472024;5592.86017849557;5593;5593.496585307342;5420.783168673367;5510.035743744908;5517.393086760373;5550.777968881361;5693.829185124453;5486.698231597141;5486.6982315973;5491.450854914477;5492.505674489315;5496.258005169251 17:26:00 INFO - PROCESS | 6688 | |1;ext.html;575.5309422820403;578.910268908319;582.1297160744688;619.4982064527733;636.7484843486822;31965.200869978235;42776.57223427765;43560.3465948011;43599.78200108995;43607.78196109023;45561.544384556226;45934.08131837361;46006.539934600645;46050.76974615131;46068;8119;8936.821263574724;9081.455112693227;9107.043760405162;10578.947105264462;10475.109615682639;10932.56269749213;10951.671449856518;10952.890471095267;10955.4522273886;10989.450527473635;11035.39305338206;11038.17213708971;11042.3374597524;11051.72370690731;10965.122790176767;11015.779684406267;11024;11029.117670586334;11034.282771619819;10706.732331691692;10775.784484310348;10782.67651970442;10794.406307653077;10799.352038877692;42992.57007429917;43116.78441607803;43376.78311608453;43559.12881742379;43587;3588.748787584844;3669.87155449558;3727;3763.6989040876665;4249.978750106261;27936.86031569829;29741.70258297411;29905.850470747726;30037.249068773235;30126.54810177849;4117.97941010296;4250.851220207309;4261.318189089762;4265.224454217689;4409.272470042439;349997;350878;351363;352197;353835;35290.29419411597;36191.457128142574;36416.08959776027;55681.72159139178;56200.71899640476;37697.05757356088;37748.433773493416;37988;38120.42819357712;38133.23733525314;15404.306806193525;15524.456644017406;15539.844601554067;15547.689046219013;15557.22213889301;11110.111191104692;12678.30269335177;12925.095243332877;12972.935135324451;13011.089223754247;8473.406861519634;8490.745277641681;8498.277646400002;8560.61477233526;8585.31317494599;3340.9498857517156;3355.245069859262;3355.899323020313;3358.44585643371;3406.710429613461;1016.872890888639;1040.469360626071;1051.7448496902227;1089.492614832016;1094.9233553651327;31842;31976.520352194737;31979;32108.270796982517;32708.182295442803;1084.2843723142687;1167.1946357013633;1207.7524107558106;1225.473046589975;1248.7502499500135;16045.277962491717;17158.742618860477;17220.31118755261;17243.3964811231;17375.218115184845;581240;584305;584605;584800;584981;59219.11171332433;59923.70038149868;60023.39976600178;60094.09858852121;60109.39890601038;1242.546470538247;1340.530814215017;1386.3761307411705;1394.7419727350675;1414.5402744108205;30841;32005.199870003435;32909.67090329066;33151.29590167958;33331.500027499125;50927.236091459396;51530;51577.7421112907;51595;51719.1554464493;434994;435199;436500;440248;441625;28171.295717607223;29312.387818670177;29332.560011599417;30162.69837301687;30239.848800755855;47597;47711;48396.27405588848;48959.755201223765;49406;40198;40341;40353.917087403686;40962.79518602388;43092;40166.397504036875;40441.39337909993;40554;41507.37738933858;42254.15491690087;39988;41373.96565085897;41770.791146044074;41820.32718691218;41915.37126943159;33786.3242735139;36311.455328170625;36550.634493654725;37169.81415092907;37184.25631487301;27658.861705691343;27869.303267418476;27884.677874929417;27998.440031198854;28019.43961120807 17:26:00 INFO - PROCESS | 6688 | |2;jquery.html;369.8853355459806;395.38517605124036;396.31833246815484;398.92819292527344;420.68869036912696;14048.21942930043;17389.217485213165;19248.807511924875;19462.318818841344;19469.90265048676;19170.233190672392;19182.23271069159;19286.132124054417;19375.612487750237;19450.805491945077;5602.495775380215;5728.365564100637;5776.451237132465;5802.361740208574;5808.651480911138;5502.339719233678;5790.160426738121;5796.420357964212;5803.187553742479;5806.303243610772;5421.864453388657;5617.831465056055;5738.598298119134;5740.512056475184;5742.856428589277;5639.492445679888;5657.801976930808;5674.659520428746;5680.8863822723315;5682.886342273173;5032.622553308516;5039.143345631252;5214.817481388133;5419.241306217133;5451.318585176853;14141.71716565663;14227.573172804832;14301.57095287143;14460.638484037878;14499.492517761933;2320.2575175943703;2388.1402695029774;2445.437549363656;2769.6537932758406;2820.2385355953984;10776.19178561611;10798;10824.837627435594;11014.559417623286;15247.313870875838;1776.9911150444295;1901.5436295289169;1922.5962547864965;1965.2237366240397;1969.3402710092137;82883.58558207231;86996.56501717515;87941.56029219877;88681.55659221727;90436.54781726115;11458.4270786462;11466.713332166762;11537.250078745023;11565.710857228469;11691.181617286707;16004.839951600336;16020.359185632444;16115.919420402823;16130.112843793704;16181.757273640906;8099.959500202462;8504.277136443348;8556.144385561394;8861.512616806138;8883.955580222058;8200.26197642215;8236;8327.084020757711;8510.276626486819;8574.27118694918;4828.479029105891;4868.561829435368;4949.752512374367;5067.366579177603;5069;1984.295575070858;2017.4351181669156;2056.763472200686;2068.596623658402;2106.610277098742;807.7859367267679;856.8329175810657;935.1817159985013;1023.8464230365505;1070.9410982395887;18630.720539191923;19834.20663173457;20002.39992800218;20034.098465569085;20230.190792368434;961.8941916389192;992.5483904823433;999.8700168977987;1026.8973102689672;1036.1710631494775;10506.527206275736;11106;11195.880411958902;11230.550777968952;11236.887631123747;523036;523537;524891;536646;538943;59289;79993.20006799858;94789;102950;105285.47357263164;1005.6027868991854;1016.0601443664581;1051.952662130206;1068.7862427514528;1074.1406874500524;17044.403445879336;17435.912820435817;18216;18260.36088736888;18332.908335458236;42932.57067429286;43393;43473.34789978216;44748;45144.32283515684;57348.71325643512;58403.12395313989;60926.69536652289;61643;63396.366036339045;8671.783205419915;8700.738977830675;8716.78208044804;8859.64561417536;9046.638134474548;10868.67393978182;12893.677658058623;13205.801912971121;13341.86658133445;13431.798523021967;11322.660320190407;11362;11800.350980696145;11808.291502509877;11927;11158.665240042812;11161.553537858395;11565.826512602485;11600.303981760784;12085.637430877088;6005.339412664691;8362.749117526484;9630.518474076129;10569.101626361846;10625.787484250426;8682.52246126469;8699.739007829776;8734.344924130843;8938.597763100546;9244.121808428188;8820.955895220482;10651.89348106509;10715.464226788785;10808.243422960632;11454.599089031844 17:26:00 INFO - PROCESS | 6688 | |3;mootools.html;1451.7532019556675;1488.8436714145014;1569.7488401855705;1735.7743493345863;1754.9736753948694;8715.825683486335;9376.484293363863;9412.341136120474;9423.199028082612;9432.292578056647;7654.502457340269;8587.656493740244;8675.522846243453;8717.041125476208;9209.401388909717;2347.2019513365494;2380.5476959377747;2381.273711517986;2383.5828729972186;2391.9760802392016;2348;2362.208660098869;2366.9467087146227;2367.9644805327935;2372.3950392649936;2355.528894221154;2357.5167090746354;2372.027468737818;2389.378761522008;2396.2451827674313;2306.8385213035012;2310.087515431412;2314.5486630107134;2339.0293028393367;2348.1194552042984;2305.262316058862;2321.292005938188;2329.347782620852;2332.3586013846157;2336.170659415917;9160.81678366429;9175.58709858058;9306.395084319565;9311.580978855967;9315;5239;5368.812091576776;5369.597280203961;5370.275012873251;5374.7043912584795;3407.0460271124125;3462.4113900636953;3494.5806503219524;3517.8416971236356;3536.9646303536892;1110.7611863449317;1119.479442059438;1141.081429449293;1146.5070019891443;1165.102870789496;12225.449854756469;12468.251904885645;12502.124851260416;12632.621021369374;12673.556425525154;4324.329728891964;4566.543345665474;4582.95417045832;4725.64557658177;4733.313669517901;10631.787364252672;10640.78718425627;10663.46682665879;10664.893351066545;10689.679309620724;5125.9743701282005;5137.922931156036;5140.074786538459;5142.280080788692;5143.742812859342;4130.6282434581035;4669.603083737852;4738.50245724194;4907.043126590281;4929.7781599828095;3860.9227815444096;4022.4368588397647;4038.9798051009557;4049.8582549610624;4118.238125946709;1251.174225011488;1267.7908145155945;1274.0571976737149;1275.668326235167;1291.9806202906964;815.7675062607128;958.6548842416724;1002.3735165521549;1011.4639241202175;1051.4007016000805;6091.14723938649;6092.374346642309;6160.753569857246;6256.436920677161;6296.496280297565;443.61848810023355;446.8190382894933;448.0590759405288;1027.7646119689794;1040.6877936618985;4198.454200953917;4374.78126093694;4406.325596273396;4441.578050085302;4519.163954668331;11109.222354435113;11443.25618834773;11452.083833293316;11457.083433325313;11518.654440366803;10667.346561283013;11396.886031139582;11400.030997365151;11415.48630311638;11417.37204453763;8144.633491492897;8492.74521764348;8508.574571271412;8513.361497887683;8536.530490822941;6324.841878953063;6417.486601071996;6491.578047426902;6517.8696426072165;6656.933430665631;10449.16406687448;10541.789164216827;10580.15358771312;10722.303050301705;10725.678229653124;9713.174380177765;10163;10193.337433066827;10278.948605256926;10361.067503924683;7002.3697867192195;7681.385489160742;7742.380609551335;8064.112947575644;8231.135730748185;8799.780005499913;8971.237444817003;8975.37172397952;8984.640614375614;8986.55067246622;7519.849603008019;7572.053493313336;7642.121156067124;7646.8088297793;7809.414293928092;7330.157031941395;7368;7395.223501532477;7451.590162540895;7731.536107833548;6695.866082678222;6814.284500127614;6815.42068924147;6837.982795075564;6847.726090956306;5162.122439185423;5296.232046353334;5309.256704061511;5365.114756065205;5672.290963629546;5449.1826226066405;5478.726063696879;5505.944940550543;5548.916766248429;5673.326368721155 17:26:00 INFO - PROCESS | 6688 | |4;prototype.html;311.9017509484512;312.66388632220367;320.1260558674818;324.92689144942375;336.5944037434891;5091.79632814687;5225.190095535198;5308.654937429069;5331.546818520421;5347.572194224463;5126.436092029874;5138.383393992717;5148.20202868555;5185.948140518593;5227.73861306935;4127.534725172565;4142.440770495975;4142.689298302632;4171.749695018309;4172.5410204877435;3980.0845805464614;4128.896777580554;4144.8549300774475;4148.896277593054;4172.311568591194;4091.8926752722373;4092.1406504634065;4092.9795351023204;4105.158442519277;4124.443200167984;4095.2219078375015;4102.5692302308125;4107.137501124767;4112.321466957948;4114.40341150533;3504.8948531544092;3623.474596183539;3627.292677927805;3635.2002559437146;3660.3411385950517;5055.292259083732;5072.949270507284;5092.905025419553;5093.79624815007;5099.184130539096;4649.163150632885;4654.162250794856;4661.370714953472;4663.813447462097;4685.461171965234;5283.577313814885;5347.866303342409;5372.382176049765;5415.539679127279;5423.538999185074;3974.1917427570675;3975.582563830814;3976.3439032559595;3990.6208910153778;4009.1179940413062;5697.373288938214;5720.9083915315405;5749.59752817303;5760.7695692172265;5772.163036359746;3989.960100399017;4089.6523795477124;4101.85643502476;4123.3196522574035;4204.180184863983;4215.494140703136;4253.97873010633;4261.21167583998;4285.7928166994025;4291.098869237433;4768.070226305837;4776.617870570346;4798.976005119952;4822.71063736177;4827.662063655581;5470.945290547043;5513.531349835229;5540.282512421082;5546.722663866791;5613.46672066153;1365.6108009217326;1445.125698952119;1457.460739526382;1466.6260103673494;1473.0646039764613;1007.1338648762058;1030.9381437113798;1030.9896901031043;1049.5854137615752;1055.1242468750918;5213.296205012351;5214.895702085937;5223.720188553752;5231.267622532849;5257.395399529103;5564.4713752193475;5566.443355664401;5571.470710282517;5580.860478487989;5630.325595342261;5087.287779710843;5176.637635365563;5223.190405487156;5268.262443257947;5294.814681486129;2589.187568701916;2598.453515554767;2604.7106682192057;2609.8516652672765;2610.7519785620716;4995.025969935828;5012.897162624216;5034.180858719129;5036.053908463517;5121.692698438105;4860.397018930377;4924.778384972685;5012.799488020439;5030.522100400456;5038.672486288379;5021.5229553192385;5042.546170844642;5065.645404821701;5065.772040258271;5074.873128171751;4947.183714687037;4948.777305021355;4953.795181925246;4979.327790748347;4992.825251116194;4562.817487300604;4891.217405215149;4895.527758481944;4913.656044077024;4944.53026962438;4985.92802547454;4994.300797888372;5005.4232916631145;5035.697858128524;5057.848264552069;3820;3823;3829.8276577554634;3848.4034974578985;3849.461075449495;4969.975150124226;4991.47589503097;5223.582113430841;5706.828795136153;5737.885242295214;5543.52880005204;5552.139418389995;5574.407900326368;5587.161925711176;5588.105903055492;5491.395946445808;5559.2217089606675;5564.749586268708;5569.888602228014;5594.216809646571;5267.367915850123;5487.780488780404;5534.916976245279;5535.667859928417;5538.667679939216;5568.582356323211;5573.637713548606;5582.692951887685;5588.273524441796;5616.803411880564;5198.59637897758;5452.345718513804;5475.561955043508;5477.616566840283;5508.338999320107 17:26:00 INFO - PROCESS | 6688 | |5;yui.html;738.055289229786;754.3474894216502;769.1164081470837;795.7109482638127;844.7888027993001;13739.862601373996;14906.925465372671;14990.550283491513;14991.775123373158;15011.474598389044;14362.425502979895;14464.842812574994;14504.854951450483;14552.563423097297;14624.488142914974;791.4697152907565;806.8668669669497;809.3444310108831;809.7854068671807;809.9595020248994;645.0710976194289;784.5841703896915;798.6765360029168;805.4402190477621;809.1988930958368;859.7334826203897;859.7549698335976;862.1421685423013;862.5083702289697;868.2055918834259;856.2122846980793;856.6573370651727;856.9357298202659;857.3741168946673;857.6783706110209;500.69457630845153;614.1954040207298;623.0622912516634;627.9748810047593;635.2694401438337;13860.58418247444;13871.237081960488;13887.097338672955;13890.374933128034;13902.235377054258;14037.64905877351;14714.19071951053;14736.263186840615;14898.180600066991;15051.322690478955;13231.603051908458;13309.667258318523;13442.663933401645;13595.796063059062;14141.787873181911;4417;4426.203283408985;4433.113377324516;4652.581267685924;4671.556202160789;82732;84255.15744842596;85868.14131858664;85965.57017214874;86704;11401.372924489067;11407;11424.600138995096;11440.94279528597;11617.302961822317;22548.21081262148;22563.774362256496;22751;22801.201957931393;22836.65745013826;8216.753497395088;8225.547594882339;8389;8426.578671066422;8476.703315384055;4623.791929363187;4683.531646835289;4725.669203155746;4738.857834264978;5005.349304590452;3241.497567876983;3328.783629064103;3333.466645336783;3339.2653616204398;3344.0469466202017;573.7074092213002;581.5899790647584;583.2068386993685;584.243404790797;584.748558120004;533.3493138371181;573.4752701278367;667.4226793823404;687.5702685821361;695.9338862808025;8677.871876656121;8919.241864441596;9052.654508010097;9056.230220431205;9385.15533601979;563.9154126880919;666.3170250493283;682.8463595691029;688.5627626457235;712.918014428337;4278.743275403485;4333.956660433355;4378.474583050054;4379.846705365297;4532;64957.675211624584;68977;132430;132430;133635;82104;104416.47791761144;106091;111620;111991;96715;97611;98367;99530.50234748925;101942.49028754958;8162.334249075101;8204.712835050745;8220.671173153007;8298.419110662197;8478.194571515696;45795;50010.24984625161;50255.994880102924;52483.73758131185;55884.72057639848;46518;46522.30216546687;47381.289280661505;47895;68063;12130.575429859913;12407.503699851904;12556.434960426985;12573.497060117494;12902.935485322514;14189.368222654113;14316.427342906167;14462.27688615586;14526.273686315431;15293.77059344133;13432.664183395102;13465.663358416117;13655.453781848615;13738.175709457464;13917;13942.712304082348;14086.647833804236;14249.28753562297;14711.92644036773;15272.694546108793;12738.808917866425;12843.35783210817;12864.742705145658;12870.549530766379;12882.162659427482;10058.949705251427;10213;10278.486075696035;10475.633352832614;10574.735631609272;9416.246700263826;10385.584576617153;10458.63394781179;10510.369377837354;10522.579096836042 17:26:00 INFO - PROCESS | 6688 | __end_tp_report 17:26:00 INFO - PROCESS | 6688 | __start_cc_report 17:26:00 INFO - PROCESS | 6688 | _x_x_mozilla_cycle_collect,1065 17:26:00 INFO - PROCESS | 6688 | __end_cc_report 17:26:00 INFO - PROCESS | 6688 | __startTimestamp1472171160635__endTimestamp 17:26:00 INFO - PROCESS | 6688 | 17:26:00 INFO - PROCESS | 6688 | ------- Summary: start ------- 17:26:00 INFO - PROCESS | 6688 | Number of tests: 6 17:26:00 INFO - PROCESS | 6688 | 17:26:00 INFO - PROCESS | 6688 | [#0] dojo.html Cycles:165 Average:5274.87 Median:5496.26 stddev:595.25 (10.8%) stddev-sans-first:568.07 17:26:00 INFO - PROCESS | 6688 | Values: 2934.9 2935.1 2963.6 2985.2 3017.8 5172.1 5190.7 5248.5 5266.5 5304.1 5296.5 5329.3 5332.1 5332.4 5494.3 4760.8 4773.8 4814.5 4919.4 4933.1 4835.1 4848.3 4869.4 4980.3 5014.0 4945.8 4966.9 4968.7 5007.6 5026.1 4701.0 4716.3 4764.9 4885.4 4952.1 4737.1 4753.5 4767.1 4785.4 4883.0 5286.6 5336.1 5351.1 5363.7 5394.4 5531.9 5534.8 5686.4 5695.4 5703.9 5625.4 5627.2 5628.1 5650.7 5724.5 5291.1 5310.0 5316.7 5320.6 5327.1 5666.7 5675.1 5754.6 5781.4 5785.4 5588.2 5589.2 5593.2 5648.9 5656.3 5649.0 5672.6 5673.8 5675.6 5677.6 5562.0 5579.7 5609.2 5609.6 5648.9 5669.1 5672.0 5675.2 5733.3 5745.4 4365.6 4401.1 4402.8 4408.1 4457.7 3852.0 3879.4 3917.5 3927.2 3951.0 5385.4 5387.1 5391.4 5435.5 5450.5 5712.3 5713.0 5718.7 5720.4 5726.9 5262.8 5313.1 5341.2 5376.3 5476.4 4882.6 4923.6 4928.7 4938.2 4940.0 5848.2 5852.8 5856.4 5859.9 5864.3 5487.9 5651.8 5706.5 5711.7 5711.8 5645.6 5786.8 5789.3 5795.5 5872.6 5544.9 5547.4 5551.4 5552.8 5556.7 5508.7 5654.2 5658.0 5663.9 5669.9 5458.0 5512.1 5563.4 5569.5 5670.8 5506.6 5561.9 5571.6 5592.1 5605.7 5577.7 5592.5 5592.9 5593.0 5593.5 5420.8 5510.0 5517.4 5550.8 5693.8 5486.7 5486.7 5491.5 5492.5 5496.3 17:26:00 INFO - PROCESS | 6688 | 17:26:00 INFO - PROCESS | 6688 | [#1] ext.html Cycles:180 Average:60511.09 Median:36251.46 stddev:124464.34 (343.3%) stddev-sans-first:124732.14 17:26:00 INFO - PROCESS | 6688 | Values: 575.5 578.9 582.1 619.5 636.7 31965.2 42776.6 43560.3 43599.8 43607.8 45561.5 45934.1 46006.5 46050.8 46068.0 8119.0 8936.8 9081.5 9107.0 10578.9 10475.1 10932.6 10951.7 10952.9 10955.5 10989.5 11035.4 11038.2 11042.3 11051.7 10965.1 11015.8 11024.0 11029.1 11034.3 10706.7 10775.8 10782.7 10794.4 10799.4 42992.6 43116.8 43376.8 43559.1 43587.0 3588.7 3669.9 3727.0 3763.7 4250.0 27936.9 29741.7 29905.9 30037.2 30126.5 4118.0 4250.9 4261.3 4265.2 4409.3 349997.0 350878.0 351363.0 352197.0 353835.0 35290.3 36191.5 36416.1 55681.7 56200.7 37697.1 37748.4 37988.0 38120.4 38133.2 15404.3 15524.5 15539.8 15547.7 15557.2 11110.1 12678.3 12925.1 12972.9 13011.1 8473.4 8490.7 8498.3 8560.6 8585.3 3340.9 3355.2 3355.9 3358.4 3406.7 1016.9 1040.5 1051.7 1089.5 1094.9 31842.0 31976.5 31979.0 32108.3 32708.2 1084.3 1167.2 1207.8 1225.5 1248.8 16045.3 17158.7 17220.3 17243.4 17375.2 581240.0 584305.0 584605.0 584800.0 584981.0 59219.1 59923.7 60023.4 60094.1 60109.4 1242.5 1340.5 1386.4 1394.7 1414.5 30841.0 32005.2 32909.7 33151.3 33331.5 50927.2 51530.0 51577.7 51595.0 51719.2 434994.0 435199.0 436500.0 440248.0 441625.0 28171.3 29312.4 29332.6 30162.7 30239.8 47597.0 47711.0 48396.3 48959.8 49406.0 40198.0 40341.0 40353.9 40962.8 43092.0 40166.4 40441.4 40554.0 41507.4 42254.2 39988.0 41374.0 41770.8 41820.3 41915.4 33786.3 36311.5 36550.6 37169.8 37184.3 27658.9 27869.3 27884.7 27998.4 28019.4 17:26:00 INFO - PROCESS | 6688 | 17:26:00 INFO - PROCESS | 6688 | [#2] jquery.html Cycles:180 Average:30056.43 Median:2794.95 stddev:87250.47 (3121.7%) stddev-sans-first:87466.76 17:26:00 INFO - PROCESS | 6688 | Values: 369.9 395.4 396.3 398.9 420.7 14048.2 17389.2 19248.8 19462.3 19469.9 19170.2 19182.2 19286.1 19375.6 19450.8 5602.5 5728.4 5776.5 5802.4 5808.7 5502.3 5790.2 5796.4 5803.2 5806.3 5421.9 5617.8 5738.6 5740.5 5742.9 5639.5 5657.8 5674.7 5680.9 5682.9 5032.6 5039.1 5214.8 5419.2 5451.3 14141.7 14227.6 14301.6 14460.6 14499.5 2320.3 2388.1 2445.4 2769.7 2820.2 10776.2 10798.0 10824.8 11014.6 15247.3 1777.0 1901.5 1922.6 1965.2 1969.3 82883.6 86996.6 87941.6 88681.6 90436.5 11458.4 11466.7 11537.3 11565.7 11691.2 16004.8 16020.4 16115.9 16130.1 16181.8 8100.0 8504.3 8556.1 8861.5 8884.0 8200.3 8236.0 8327.1 8510.3 8574.3 4828.5 4868.6 4949.8 5067.4 5069.0 1984.3 2017.4 2056.8 2068.6 2106.6 807.8 856.8 935.2 1023.8 1070.9 18630.7 19834.2 20002.4 20034.1 20230.2 961.9 992.5 999.9 1026.9 1036.2 10506.5 11106.0 11195.9 11230.6 11236.9 523036.0 523537.0 524891.0 536646.0 538943.0 59289.0 79993.2 94789.0 102950.0 105285.5 1005.6 1016.1 1052.0 1068.8 1074.1 17044.4 17435.9 18216.0 18260.4 18332.9 42932.6 43393.0 43473.3 44748.0 45144.3 57348.7 58403.1 60926.7 61643.0 63396.4 8671.8 8700.7 8716.8 8859.6 9046.6 10868.7 12893.7 13205.8 13341.9 13431.8 11322.7 11362.0 11800.4 11808.3 11927.0 11158.7 11161.6 11565.8 11600.3 12085.6 6005.3 8362.7 9630.5 10569.1 10625.8 8682.5 8699.7 8734.3 8938.6 9244.1 8821.0 10651.9 10715.5 10808.2 11454.6 17:26:00 INFO - PROCESS | 6688 | 17:26:00 INFO - PROCESS | 6688 | [#3] mootools.html Cycles:180 Average:5924.61 Median:2444.20 stddev:3405.16 (139.3%) stddev-sans-first:3398.12 17:26:00 INFO - PROCESS | 6688 | Values: 1451.8 1488.8 1569.7 1735.8 1755.0 8715.8 9376.5 9412.3 9423.2 9432.3 7654.5 8587.7 8675.5 8717.0 9209.4 2347.2 2380.5 2381.3 2383.6 2392.0 2348.0 2362.2 2366.9 2368.0 2372.4 2355.5 2357.5 2372.0 2389.4 2396.2 2306.8 2310.1 2314.5 2339.0 2348.1 2305.3 2321.3 2329.3 2332.4 2336.2 9160.8 9175.6 9306.4 9311.6 9315.0 5239.0 5368.8 5369.6 5370.3 5374.7 3407.0 3462.4 3494.6 3517.8 3537.0 1110.8 1119.5 1141.1 1146.5 1165.1 12225.4 12468.3 12502.1 12632.6 12673.6 4324.3 4566.5 4583.0 4725.6 4733.3 10631.8 10640.8 10663.5 10664.9 10689.7 5126.0 5137.9 5140.1 5142.3 5143.7 4130.6 4669.6 4738.5 4907.0 4929.8 3860.9 4022.4 4039.0 4049.9 4118.2 1251.2 1267.8 1274.1 1275.7 1292.0 815.8 958.7 1002.4 1011.5 1051.4 6091.1 6092.4 6160.8 6256.4 6296.5 443.6 446.8 448.1 1027.8 1040.7 4198.5 4374.8 4406.3 4441.6 4519.2 11109.2 11443.3 11452.1 11457.1 11518.7 10667.3 11396.9 11400.0 11415.5 11417.4 8144.6 8492.7 8508.6 8513.4 8536.5 6324.8 6417.5 6491.6 6517.9 6656.9 10449.2 10541.8 10580.2 10722.3 10725.7 9713.2 10163.0 10193.3 10278.9 10361.1 7002.4 7681.4 7742.4 8064.1 8231.1 8799.8 8971.2 8975.4 8984.6 8986.6 7519.8 7572.1 7642.1 7646.8 7809.4 7330.2 7368.0 7395.2 7451.6 7731.5 6695.9 6814.3 6815.4 6838.0 6847.7 5162.1 5296.2 5309.3 5365.1 5672.3 5449.2 5478.7 5505.9 5548.9 5673.3 17:26:00 INFO - PROCESS | 6688 | 17:26:00 INFO - PROCESS | 6688 | [#4] prototype.html Cycles:180 Average:4503.50 Median:4994.66 stddev:1287.21 (25.8%) stddev-sans-first:1251.79 17:26:00 INFO - PROCESS | 6688 | Values: 311.9 312.7 320.1 324.9 336.6 5091.8 5225.2 5308.7 5331.5 5347.6 5126.4 5138.4 5148.2 5185.9 5227.7 4127.5 4142.4 4142.7 4171.7 4172.5 3980.1 4128.9 4144.9 4148.9 4172.3 4091.9 4092.1 4093.0 4105.2 4124.4 4095.2 4102.6 4107.1 4112.3 4114.4 3504.9 3623.5 3627.3 3635.2 3660.3 5055.3 5072.9 5092.9 5093.8 5099.2 4649.2 4654.2 4661.4 4663.8 4685.5 5283.6 5347.9 5372.4 5415.5 5423.5 3974.2 3975.6 3976.3 3990.6 4009.1 5697.4 5720.9 5749.6 5760.8 5772.2 3990.0 4089.7 4101.9 4123.3 4204.2 4215.5 4254.0 4261.2 4285.8 4291.1 4768.1 4776.6 4799.0 4822.7 4827.7 5470.9 5513.5 5540.3 5546.7 5613.5 1365.6 1445.1 1457.5 1466.6 1473.1 1007.1 1030.9 1031.0 1049.6 1055.1 5213.3 5214.9 5223.7 5231.3 5257.4 5564.5 5566.4 5571.5 5580.9 5630.3 5087.3 5176.6 5223.2 5268.3 5294.8 2589.2 2598.5 2604.7 2609.9 2610.8 4995.0 5012.9 5034.2 5036.1 5121.7 4860.4 4924.8 5012.8 5030.5 5038.7 5021.5 5042.5 5065.6 5065.8 5074.9 4947.2 4948.8 4953.8 4979.3 4992.8 4562.8 4891.2 4895.5 4913.7 4944.5 4985.9 4994.3 5005.4 5035.7 5057.8 3820.0 3823.0 3829.8 3848.4 3849.5 4970.0 4991.5 5223.6 5706.8 5737.9 5543.5 5552.1 5574.4 5587.2 5588.1 5491.4 5559.2 5564.7 5569.9 5594.2 5267.4 5487.8 5534.9 5535.7 5538.7 5568.6 5573.6 5582.7 5588.3 5616.8 5198.6 5452.3 5475.6 5477.6 5508.3 17:26:00 INFO - PROCESS | 6688 | 17:26:00 INFO - PROCESS | 6688 | [#5] yui.html Cycles:180 Average:20529.99 Median:25209.40 stddev:30549.01 (121.2%) stddev-sans-first:30598.56 17:26:00 INFO - PROCESS | 6688 | Values: 738.1 754.3 769.1 795.7 844.8 13739.9 14906.9 14990.6 14991.8 15011.5 14362.4 14464.8 14504.9 14552.6 14624.5 791.5 806.9 809.3 809.8 810.0 645.1 784.6 798.7 805.4 809.2 859.7 859.8 862.1 862.5 868.2 856.2 856.7 856.9 857.4 857.7 500.7 614.2 623.1 628.0 635.3 13860.6 13871.2 13887.1 13890.4 13902.2 14037.6 14714.2 14736.3 14898.2 15051.3 13231.6 13309.7 13442.7 13595.8 14141.8 4417.0 4426.2 4433.1 4652.6 4671.6 82732.0 84255.2 85868.1 85965.6 86704.0 11401.4 11407.0 11424.6 11440.9 11617.3 22548.2 22563.8 22751.0 22801.2 22836.7 8216.8 8225.5 8389.0 8426.6 8476.7 4623.8 4683.5 4725.7 4738.9 5005.3 3241.5 3328.8 3333.5 3339.3 3344.0 573.7 581.6 583.2 584.2 584.7 533.3 573.5 667.4 687.6 695.9 8677.9 8919.2 9052.7 9056.2 9385.2 563.9 666.3 682.8 688.6 712.9 4278.7 4334.0 4378.5 4379.8 4532.0 64957.7 68977.0 132430.0 132430.0 133635.0 82104.0 104416.5 106091.0 111620.0 111991.0 96715.0 97611.0 98367.0 99530.5 101942.5 8162.3 8204.7 8220.7 8298.4 8478.2 45795.0 50010.2 50256.0 52483.7 55884.7 46518.0 46522.3 47381.3 47895.0 68063.0 12130.6 12407.5 12556.4 12573.5 12902.9 14189.4 14316.4 14462.3 14526.3 15293.8 13432.7 13465.7 13655.5 13738.2 13917.0 13942.7 14086.6 14249.3 14711.9 15272.7 12738.8 12843.4 12864.7 12870.5 12882.2 10058.9 10213.0 10278.5 10475.6 10574.7 9416.2 10385.6 10458.6 10510.4 10522.6 17:26:00 INFO - PROCESS | 6688 | -------- Summary: end -------- 17:26:00 INFO - PROCESS | 6688 | 17:26:01 INFO - TEST-INFO | 6688: exit 0 17:26:01 INFO - TEST-OK | dromaeo_css | took 1116465ms 17:26:01 INFO - TEST-START | kraken 17:26:01 INFO - operating with platform_type : linux_ 17:26:01 INFO - Initialising browser for kraken test... 17:26:01 INFO - TEST-INFO | started process 6995 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2H89Wb/profile http://localhost:56680/getInfo.html) 17:26:06 INFO - PROCESS | 6995 | __metrics Screen width/height:1600/1200 17:26:06 INFO - PROCESS | 6995 | colorDepth:24 17:26:06 INFO - PROCESS | 6995 | Browser inner width/height: 1024/697 17:26:06 INFO - PROCESS | 6995 | __metrics 17:26:06 INFO - PROCESS | 6995 | 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" 17:26:06 INFO - PROCESS | 6995 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:26:07 INFO - PROCESS | 6995 | console.error: PushService: 17:26:07 INFO - PROCESS | 6995 | stateChangeProcessEnqueue: Error transitioning state 17:26:07 INFO - PROCESS | 6995 | UnknownError 17:26:07 INFO - TEST-INFO | 6995: exit 0 17:26:07 INFO - Browser initialized. 17:26:07 INFO - Running cycle 1/1 for kraken test... 17:26:07 INFO - TEST-INFO | started process 7076 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2H89Wb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:26:20 INFO - PROCESS | 7076 | RSS: Main: 179736576 17:26:20 INFO - PROCESS | 7076 | 17:26:22 INFO - PROCESS | 7076 | Cycle 1(1): loaded http://localhost:56680/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56680/tests/kraken/driver.html?testName=audio-beat-detection) 17:26:23 INFO - PROCESS | 7076 | RSS: Main: 241963008 17:26:23 INFO - PROCESS | 7076 | 17:26:26 INFO - PROCESS | 7076 | Cycle 1(1): loaded http://localhost:56680/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56680/tests/kraken/driver.html?testName=audio-dft) 17:26:26 INFO - PROCESS | 7076 | RSS: Main: 224014336 17:26:26 INFO - PROCESS | 7076 | 17:26:29 INFO - PROCESS | 7076 | Cycle 1(1): loaded http://localhost:56680/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56680/tests/kraken/driver.html?testName=audio-fft) 17:26:30 INFO - PROCESS | 7076 | RSS: Main: 301531136 17:26:30 INFO - PROCESS | 7076 | 17:26:32 INFO - PROCESS | 7076 | Cycle 1(1): loaded http://localhost:56680/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56680/tests/kraken/driver.html?testName=audio-oscillator) 17:26:33 INFO - PROCESS | 7076 | RSS: Main: 210878464 17:26:33 INFO - PROCESS | 7076 | 17:26:35 INFO - PROCESS | 7076 | Cycle 1(1): loaded http://localhost:56680/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56680/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:26:35 INFO - PROCESS | 7076 | RSS: Main: 212434944 17:26:35 INFO - PROCESS | 7076 | 17:26:38 INFO - PROCESS | 7076 | Cycle 1(1): loaded http://localhost:56680/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56680/tests/kraken/driver.html?testName=imaging-darkroom) 17:26:39 INFO - PROCESS | 7076 | RSS: Main: 251363328 17:26:39 INFO - PROCESS | 7076 | 17:26:43 INFO - PROCESS | 7076 | Cycle 1(1): loaded http://localhost:56680/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56680/tests/kraken/driver.html?testName=imaging-desaturate) 17:26:43 INFO - PROCESS | 7076 | RSS: Main: 249987072 17:26:43 INFO - PROCESS | 7076 | 17:26:46 INFO - PROCESS | 7076 | Cycle 1(1): loaded http://localhost:56680/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56680/tests/kraken/driver.html?testName=json-parse-financial) 17:26:47 INFO - PROCESS | 7076 | RSS: Main: 249716736 17:26:47 INFO - PROCESS | 7076 | 17:26:48 INFO - PROCESS | 7076 | Cycle 1(1): loaded http://localhost:56680/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56680/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:26:49 INFO - PROCESS | 7076 | RSS: Main: 212627456 17:26:49 INFO - PROCESS | 7076 | 17:26:51 INFO - PROCESS | 7076 | Cycle 1(1): loaded http://localhost:56680/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56680/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:26:51 INFO - PROCESS | 7076 | RSS: Main: 220270592 17:26:51 INFO - PROCESS | 7076 | 17:26:53 INFO - PROCESS | 7076 | Cycle 1(1): loaded http://localhost:56680/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56680/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:26:54 INFO - PROCESS | 7076 | RSS: Main: 221720576 17:26:54 INFO - PROCESS | 7076 | 17:26:57 INFO - PROCESS | 7076 | Cycle 1(1): loaded http://localhost:56680/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56680/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:26:57 INFO - PROCESS | 7076 | RSS: Main: 215326720 17:26:57 INFO - PROCESS | 7076 | 17:27:00 INFO - PROCESS | 7076 | Cycle 1(1): loaded http://localhost:56680/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56680/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:27:01 INFO - PROCESS | 7076 | RSS: Main: 219774976 17:27:01 INFO - PROCESS | 7076 | 17:27:02 INFO - PROCESS | 7076 | Cycle 1(1): loaded http://localhost:56680/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56680/tests/kraken/driver.html?testName=ai-astar) 17:27:03 INFO - PROCESS | 7076 | RSS: Main: 270843904 17:27:03 INFO - PROCESS | 7076 | 17:27:03 INFO - PROCESS | 7076 | __start_tp_report 17:27:03 INFO - PROCESS | 7076 | _x_x_mozilla_page_load 17:27:03 INFO - PROCESS | 7076 | _x_x_mozilla_page_load_details 17:27:03 INFO - PROCESS | 7076 | |i|pagename|runs| 17:27:03 INFO - PROCESS | 7076 | |0;ai-astar;113;113;125;111;112;111;110;110;110;110 17:27:03 INFO - PROCESS | 7076 | |1;audio-beat-detection;144;123;142;171;120;152;143;119;178;117 17:27:03 INFO - PROCESS | 7076 | |2;audio-dft;162;162;162;163;162;162;162;162;161;162 17:27:03 INFO - PROCESS | 7076 | |3;audio-fft;75;75;75;174;76;74;76;74;162;75 17:27:03 INFO - PROCESS | 7076 | |4;audio-oscillator;84;86;147;85;85;85;146;84;85;143 17:27:03 INFO - PROCESS | 7076 | |5;imaging-gaussian-blur;117;116;117;117;117;117;117;117;117;116 17:27:03 INFO - PROCESS | 7076 | |6;imaging-darkroom;146;145;145;146;147;146;145;145;146;145 17:27:03 INFO - PROCESS | 7076 | |7;imaging-desaturate;95;96;96;95;96;96;95;96;96;95 17:27:03 INFO - PROCESS | 7076 | |8;json-parse-financial;53;53;57;57;56;55;52;53;54;52 17:27:03 INFO - PROCESS | 7076 | |9;json-stringify-tinderbox;61;63;63;63;63;63;63;64;62;63 17:27:03 INFO - PROCESS | 7076 | |10;stanford-crypto-aes;79;79;87;79;82;78;78;79;84;79 17:27:03 INFO - PROCESS | 7076 | |11;stanford-crypto-ccm;126;125;121;132;134;124;130;132;210;125 17:27:03 INFO - PROCESS | 7076 | |12;stanford-crypto-pbkdf2;165;167;171;165;168;172;165;172;166;163 17:27:03 INFO - PROCESS | 7076 | |13;stanford-crypto-sha256-iterative;64;66;66;65;63;62;63;63;65;64 17:27:03 INFO - PROCESS | 7076 | __end_tp_report 17:27:03 INFO - PROCESS | 7076 | __start_cc_report 17:27:03 INFO - PROCESS | 7076 | _x_x_mozilla_cycle_collect,1803 17:27:03 INFO - PROCESS | 7076 | __end_cc_report 17:27:03 INFO - PROCESS | 7076 | __startTimestamp1472171223204__endTimestamp 17:27:03 INFO - PROCESS | 7076 | 17:27:03 INFO - PROCESS | 7076 | ------- Summary: start ------- 17:27:03 INFO - PROCESS | 7076 | Number of tests: 14 17:27:03 INFO - PROCESS | 7076 | 17:27:03 INFO - PROCESS | 7076 | [#0] ai-astar Cycles:10 Average:112.50 Median:111.50 stddev:4.55 (4.1%) stddev-sans-first:4.82 17:27:03 INFO - PROCESS | 7076 | Values: 113.0 113.0 125.0 111.0 112.0 111.0 110.0 110.0 110.0 110.0 17:27:03 INFO - PROCESS | 7076 | 17:27:03 INFO - PROCESS | 7076 | [#1] audio-beat-detection Cycles:10 Average:140.90 Median:143.50 stddev:21.68 (15.1%) stddev-sans-first:22.96 17:27:03 INFO - PROCESS | 7076 | Values: 144.0 123.0 142.0 171.0 120.0 152.0 143.0 119.0 178.0 117.0 17:27:03 INFO - PROCESS | 7076 | 17:27:03 INFO - PROCESS | 7076 | [#2] audio-dft Cycles:10 Average:162.00 Median:162.00 stddev:0.47 (0.3%) stddev-sans-first:0.50 17:27:03 INFO - PROCESS | 7076 | Values: 162.0 162.0 162.0 163.0 162.0 162.0 162.0 162.0 161.0 162.0 17:27:03 INFO - PROCESS | 7076 | 17:27:03 INFO - PROCESS | 7076 | [#3] audio-fft Cycles:10 Average:93.60 Median:75.00 stddev:39.32 (52.4%) stddev-sans-first:41.12 17:27:03 INFO - PROCESS | 7076 | Values: 75.0 75.0 75.0 174.0 76.0 74.0 76.0 74.0 162.0 75.0 17:27:03 INFO - PROCESS | 7076 | 17:27:03 INFO - PROCESS | 7076 | [#4] audio-oscillator Cycles:10 Average:103.00 Median:85.00 stddev:29.23 (34.4%) stddev-sans-first:30.19 17:27:03 INFO - PROCESS | 7076 | Values: 84.0 86.0 147.0 85.0 85.0 85.0 146.0 84.0 85.0 143.0 17:27:03 INFO - PROCESS | 7076 | 17:27:03 INFO - PROCESS | 7076 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 17:27:03 INFO - PROCESS | 7076 | Values: 117.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 17:27:03 INFO - PROCESS | 7076 | 17:27:03 INFO - PROCESS | 7076 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 17:27:03 INFO - PROCESS | 7076 | Values: 146.0 145.0 145.0 146.0 147.0 146.0 145.0 145.0 146.0 145.0 17:27:03 INFO - PROCESS | 7076 | 17:27:03 INFO - PROCESS | 7076 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 17:27:03 INFO - PROCESS | 7076 | Values: 95.0 96.0 96.0 95.0 96.0 96.0 95.0 96.0 96.0 95.0 17:27:03 INFO - PROCESS | 7076 | 17:27:03 INFO - PROCESS | 7076 | [#8] json-parse-financial Cycles:10 Average:54.20 Median:54.50 stddev:1.93 (3.5%) stddev-sans-first:2.00 17:27:03 INFO - PROCESS | 7076 | Values: 53.0 53.0 57.0 57.0 56.0 55.0 52.0 53.0 54.0 52.0 17:27:03 INFO - PROCESS | 7076 | 17:27:03 INFO - PROCESS | 7076 | [#9] json-stringify-tinderbox Cycles:10 Average:62.80 Median:63.00 stddev:0.79 (1.3%) stddev-sans-first:0.50 17:27:03 INFO - PROCESS | 7076 | Values: 61.0 63.0 63.0 63.0 63.0 63.0 63.0 64.0 62.0 63.0 17:27:03 INFO - PROCESS | 7076 | 17:27:03 INFO - PROCESS | 7076 | [#10] stanford-crypto-aes Cycles:10 Average:80.40 Median:79.00 stddev:2.99 (3.8%) stddev-sans-first:3.13 17:27:03 INFO - PROCESS | 7076 | Values: 79.0 79.0 87.0 79.0 82.0 78.0 78.0 79.0 84.0 79.0 17:27:03 INFO - PROCESS | 7076 | 17:27:03 INFO - PROCESS | 7076 | [#11] stanford-crypto-ccm Cycles:10 Average:135.90 Median:131.00 stddev:26.37 (20.1%) stddev-sans-first:27.73 17:27:03 INFO - PROCESS | 7076 | Values: 126.0 125.0 121.0 132.0 134.0 124.0 130.0 132.0 210.0 125.0 17:27:03 INFO - PROCESS | 7076 | 17:27:03 INFO - PROCESS | 7076 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.40 Median:167.50 stddev:3.24 (1.9%) stddev-sans-first:3.32 17:27:03 INFO - PROCESS | 7076 | Values: 165.0 167.0 171.0 165.0 168.0 172.0 165.0 172.0 166.0 163.0 17:27:03 INFO - PROCESS | 7076 | 17:27:03 INFO - PROCESS | 7076 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.10 Median:64.50 stddev:1.37 (2.1%) stddev-sans-first:1.45 17:27:03 INFO - PROCESS | 7076 | Values: 64.0 66.0 66.0 65.0 63.0 62.0 63.0 63.0 65.0 64.0 17:27:03 INFO - PROCESS | 7076 | -------- Summary: end -------- 17:27:03 INFO - PROCESS | 7076 | 17:27:04 INFO - TEST-INFO | 7076: exit 0 17:27:04 INFO - TEST-OK | kraken | took 62828ms 17:27:04 INFO - SUITE-END | took 1179s 17:27:04 INFO - Completed test suite (00:19:39) 17:27:04 INFO - javascript benchmark 17:27:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2934.941301173976, 2935.0901220621604, 2963.5702823090637, 2985.1939976206413, 3017.803842750221, 5172.069027575039, 5190.688558686472, 5248.501392367729, 5266.52601265886, 5304.071787437202, 5296.523312901838, 5329.333833270841, 5332.093544097503, 5332.413434522199, 5494.313210848644, 4760.785764640599, 4773.832915847946, 4814.542618451242, 4919.409670839495, 4933.087378834909, 4835.081334546442, 4848.2727590861305, 4869.366982292297, 4980.252962055684, 5014.022265658198, 4945.802167913279, 4966.850994470172, 4968.701877887338, 5007.574356179711, 5026.1204289249235, 4701.036287561076, 4716.29255611661, 4764.857054288377, 4885.413750349981, 4952.083864485082, 4737.052589482118, 4753.524647535219, 4767.094252092126, 4785.449673287581, 4883.02339532095, 5286.577073834084, 5336.092864213093, 5351.063563876306, 5363.651362661453, 5394.406615272317, 5531.861703457445, 5534.75093620788, 5686.402927692574, 5695.430456954271, 5703.857403564944, 5625.437456254383, 5627.212190293362, 5628.127640215773, 5650.74571644277, 5724.513416359614, 5291.074062039128, 5309.973450132725, 5316.707581083, 5320.600954928396, 5327.067763141435, 5666.744996475169, 5675.120356344773, 5754.5971781975695, 5781.364049954502, 5785.392533783976, 5588.2455868458055, 5589.217509548666, 5593.216949627055, 5648.8870222595315, 5656.349519805192, 5649.01142300104, 5672.631278966854, 5673.773049078073, 5675.631083979611, 5677.602567820213, 5562.02664533705, 5579.721013949286, 5609.186667933209, 5609.607327487119, 5648.85877853057, 5669.06460434032, 5672.0, 5675.233843431167, 5733.283339582552, 5745.396733343023, 4365.585269399402, 4401.075774087413, 4402.779861007001, 4408.118376324748, 4457.710248833815, 3851.9807400963377, 3879.3792993120965, 3917.4711413958753, 3927.2341893330527, 3950.9604903951167, 5385.380681221632, 5387.0841956867425, 5391.3799913009725, 5435.537979271806, 5450.5367043800925, 5712.34308054579, 5713.028785106542, 5718.7426565804635, 5720.427957204246, 5726.885462290731, 5262.7631756571045, 5313.0967735485065, 5341.225522299244, 5376.274202982626, 5476.370217424967, 4882.584980276716, 4923.606111511071, 4928.654994150375, 4938.185199442052, 4940.036692844861, 5848.210491583582, 5852.795152169653, 5856.443637854397, 5859.882802344015, 5864.32560255559, 5487.945120548743, 5651.8304450866535, 5706.514946229617, 5711.714414279187, 5711.771529138788, 5645.633033852787, 5786.797462088806, 5789.2763404574425, 5795.536357091507, 5872.647641141545, 5544.944550554443, 5547.389787123494, 5551.361593416808, 5552.805651802168, 5556.666600004012, 5508.669479831223, 5654.208410822571, 5658.0, 5663.915041274302, 5669.858253543694, 5458.044842152528, 5512.062949298549, 5563.388027317073, 5569.526590239875, 5670.801521946877, 5506.64206826555, 5561.860953476195, 5571.637843540157, 5592.105263157875, 5605.663660180402, 5577.721113944368, 5592.468715472024, 5592.86017849557, 5593.0, 5593.496585307342, 5420.783168673367, 5510.035743744908, 5517.393086760373, 5550.777968881361, 5693.829185124453, 5486.698231597141, 5486.6982315973, 5491.450854914477, 5492.505674489315, 5496.258005169251], "value": 5233.930857215165, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [575.5309422820403, 578.910268908319, 582.1297160744688, 619.4982064527733, 636.7484843486822, 31965.200869978235, 42776.57223427765, 43560.3465948011, 43599.78200108995, 43607.78196109023, 45561.544384556226, 45934.08131837361, 46006.539934600645, 46050.76974615131, 46068.0, 8119.0, 8936.821263574724, 9081.455112693227, 9107.043760405162, 10578.947105264462, 10475.109615682639, 10932.56269749213, 10951.671449856518, 10952.890471095267, 10955.4522273886, 10989.450527473635, 11035.39305338206, 11038.17213708971, 11042.3374597524, 11051.72370690731, 10965.122790176767, 11015.779684406267, 11024.0, 11029.117670586334, 11034.282771619819, 10706.732331691692, 10775.784484310348, 10782.67651970442, 10794.406307653077, 10799.352038877692, 42992.57007429917, 43116.78441607803, 43376.78311608453, 43559.12881742379, 43587.0, 3588.748787584844, 3669.87155449558, 3727.0, 3763.6989040876665, 4249.978750106261, 27936.86031569829, 29741.70258297411, 29905.850470747726, 30037.249068773235, 30126.54810177849, 4117.97941010296, 4250.851220207309, 4261.318189089762, 4265.224454217689, 4409.272470042439, 349997.0, 350878.0, 351363.0, 352197.0, 353835.0, 35290.29419411597, 36191.457128142574, 36416.08959776027, 55681.72159139178, 56200.71899640476, 37697.05757356088, 37748.433773493416, 37988.0, 38120.42819357712, 38133.23733525314, 15404.306806193525, 15524.456644017406, 15539.844601554067, 15547.689046219013, 15557.22213889301, 11110.111191104692, 12678.30269335177, 12925.095243332877, 12972.935135324451, 13011.089223754247, 8473.406861519634, 8490.745277641681, 8498.277646400002, 8560.61477233526, 8585.31317494599, 3340.9498857517156, 3355.245069859262, 3355.899323020313, 3358.44585643371, 3406.710429613461, 1016.872890888639, 1040.469360626071, 1051.7448496902227, 1089.492614832016, 1094.9233553651327, 31842.0, 31976.520352194737, 31979.0, 32108.270796982517, 32708.182295442803, 1084.2843723142687, 1167.1946357013633, 1207.7524107558106, 1225.473046589975, 1248.7502499500135, 16045.277962491717, 17158.742618860477, 17220.31118755261, 17243.3964811231, 17375.218115184845, 581240.0, 584305.0, 584605.0, 584800.0, 584981.0, 59219.11171332433, 59923.70038149868, 60023.39976600178, 60094.09858852121, 60109.39890601038, 1242.546470538247, 1340.530814215017, 1386.3761307411705, 1394.7419727350675, 1414.5402744108205, 30841.0, 32005.199870003435, 32909.67090329066, 33151.29590167958, 33331.500027499125, 50927.236091459396, 51530.0, 51577.7421112907, 51595.0, 51719.1554464493, 434994.0, 435199.0, 436500.0, 440248.0, 441625.0, 28171.295717607223, 29312.387818670177, 29332.560011599417, 30162.69837301687, 30239.848800755855, 47597.0, 47711.0, 48396.27405588848, 48959.755201223765, 49406.0, 40198.0, 40341.0, 40353.917087403686, 40962.79518602388, 43092.0, 40166.397504036875, 40441.39337909993, 40554.0, 41507.37738933858, 42254.15491690087, 39988.0, 41373.96565085897, 41770.791146044074, 41820.32718691218, 41915.37126943159, 33786.3242735139, 36311.455328170625, 36550.634493654725, 37169.81415092907, 37184.25631487301, 27658.861705691343, 27869.303267418476, 27884.677874929417, 27998.440031198854, 28019.43961120807], "value": 19420.02353814788, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.8853355459806, 395.38517605124036, 396.31833246815484, 398.92819292527344, 420.68869036912696, 14048.21942930043, 17389.217485213165, 19248.807511924875, 19462.318818841344, 19469.90265048676, 19170.233190672392, 19182.23271069159, 19286.132124054417, 19375.612487750237, 19450.805491945077, 5602.495775380215, 5728.365564100637, 5776.451237132465, 5802.361740208574, 5808.651480911138, 5502.339719233678, 5790.160426738121, 5796.420357964212, 5803.187553742479, 5806.303243610772, 5421.864453388657, 5617.831465056055, 5738.598298119134, 5740.512056475184, 5742.856428589277, 5639.492445679888, 5657.801976930808, 5674.659520428746, 5680.8863822723315, 5682.886342273173, 5032.622553308516, 5039.143345631252, 5214.817481388133, 5419.241306217133, 5451.318585176853, 14141.71716565663, 14227.573172804832, 14301.57095287143, 14460.638484037878, 14499.492517761933, 2320.2575175943703, 2388.1402695029774, 2445.437549363656, 2769.6537932758406, 2820.2385355953984, 10776.19178561611, 10798.0, 10824.837627435594, 11014.559417623286, 15247.313870875838, 1776.9911150444295, 1901.5436295289169, 1922.5962547864965, 1965.2237366240397, 1969.3402710092137, 82883.58558207231, 86996.56501717515, 87941.56029219877, 88681.55659221727, 90436.54781726115, 11458.4270786462, 11466.713332166762, 11537.250078745023, 11565.710857228469, 11691.181617286707, 16004.839951600336, 16020.359185632444, 16115.919420402823, 16130.112843793704, 16181.757273640906, 8099.959500202462, 8504.277136443348, 8556.144385561394, 8861.512616806138, 8883.955580222058, 8200.26197642215, 8236.0, 8327.084020757711, 8510.276626486819, 8574.27118694918, 4828.479029105891, 4868.561829435368, 4949.752512374367, 5067.366579177603, 5069.0, 1984.295575070858, 2017.4351181669156, 2056.763472200686, 2068.596623658402, 2106.610277098742, 807.7859367267679, 856.8329175810657, 935.1817159985013, 1023.8464230365505, 1070.9410982395887, 18630.720539191923, 19834.20663173457, 20002.39992800218, 20034.098465569085, 20230.190792368434, 961.8941916389192, 992.5483904823433, 999.8700168977987, 1026.8973102689672, 1036.1710631494775, 10506.527206275736, 11106.0, 11195.880411958902, 11230.550777968952, 11236.887631123747, 523036.0, 523537.0, 524891.0, 536646.0, 538943.0, 59289.0, 79993.20006799858, 94789.0, 102950.0, 105285.47357263164, 1005.6027868991854, 1016.0601443664581, 1051.952662130206, 1068.7862427514528, 1074.1406874500524, 17044.403445879336, 17435.912820435817, 18216.0, 18260.36088736888, 18332.908335458236, 42932.57067429286, 43393.0, 43473.34789978216, 44748.0, 45144.32283515684, 57348.71325643512, 58403.12395313989, 60926.69536652289, 61643.0, 63396.366036339045, 8671.783205419915, 8700.738977830675, 8716.78208044804, 8859.64561417536, 9046.638134474548, 10868.67393978182, 12893.677658058623, 13205.801912971121, 13341.86658133445, 13431.798523021967, 11322.660320190407, 11362.0, 11800.350980696145, 11808.291502509877, 11927.0, 11158.665240042812, 11161.553537858395, 11565.826512602485, 11600.303981760784, 12085.637430877088, 6005.339412664691, 8362.749117526484, 9630.518474076129, 10569.101626361846, 10625.787484250426, 8682.52246126469, 8699.739007829776, 8734.344924130843, 8938.597763100546, 9244.121808428188, 8820.955895220482, 10651.89348106509, 10715.464226788785, 10808.243422960632, 11454.599089031844], "value": 9170.209744887812, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1451.7532019556675, 1488.8436714145014, 1569.7488401855705, 1735.7743493345863, 1754.9736753948694, 8715.825683486335, 9376.484293363863, 9412.341136120474, 9423.199028082612, 9432.292578056647, 7654.502457340269, 8587.656493740244, 8675.522846243453, 8717.041125476208, 9209.401388909717, 2347.2019513365494, 2380.5476959377747, 2381.273711517986, 2383.5828729972186, 2391.9760802392016, 2348.0, 2362.208660098869, 2366.9467087146227, 2367.9644805327935, 2372.3950392649936, 2355.528894221154, 2357.5167090746354, 2372.027468737818, 2389.378761522008, 2396.2451827674313, 2306.8385213035012, 2310.087515431412, 2314.5486630107134, 2339.0293028393367, 2348.1194552042984, 2305.262316058862, 2321.292005938188, 2329.347782620852, 2332.3586013846157, 2336.170659415917, 9160.81678366429, 9175.58709858058, 9306.395084319565, 9311.580978855967, 9315.0, 5239.0, 5368.812091576776, 5369.597280203961, 5370.275012873251, 5374.7043912584795, 3407.0460271124125, 3462.4113900636953, 3494.5806503219524, 3517.8416971236356, 3536.9646303536892, 1110.7611863449317, 1119.479442059438, 1141.081429449293, 1146.5070019891443, 1165.102870789496, 12225.449854756469, 12468.251904885645, 12502.124851260416, 12632.621021369374, 12673.556425525154, 4324.329728891964, 4566.543345665474, 4582.95417045832, 4725.64557658177, 4733.313669517901, 10631.787364252672, 10640.78718425627, 10663.46682665879, 10664.893351066545, 10689.679309620724, 5125.9743701282005, 5137.922931156036, 5140.074786538459, 5142.280080788692, 5143.742812859342, 4130.6282434581035, 4669.603083737852, 4738.50245724194, 4907.043126590281, 4929.7781599828095, 3860.9227815444096, 4022.4368588397647, 4038.9798051009557, 4049.8582549610624, 4118.238125946709, 1251.174225011488, 1267.7908145155945, 1274.0571976737149, 1275.668326235167, 1291.9806202906964, 815.7675062607128, 958.6548842416724, 1002.3735165521549, 1011.4639241202175, 1051.4007016000805, 6091.14723938649, 6092.374346642309, 6160.753569857246, 6256.436920677161, 6296.496280297565, 443.61848810023355, 446.8190382894933, 448.0590759405288, 1027.7646119689794, 1040.6877936618985, 4198.454200953917, 4374.78126093694, 4406.325596273396, 4441.578050085302, 4519.163954668331, 11109.222354435113, 11443.25618834773, 11452.083833293316, 11457.083433325313, 11518.654440366803, 10667.346561283013, 11396.886031139582, 11400.030997365151, 11415.48630311638, 11417.37204453763, 8144.633491492897, 8492.74521764348, 8508.574571271412, 8513.361497887683, 8536.530490822941, 6324.841878953063, 6417.486601071996, 6491.578047426902, 6517.8696426072165, 6656.933430665631, 10449.16406687448, 10541.789164216827, 10580.15358771312, 10722.303050301705, 10725.678229653124, 9713.174380177765, 10163.0, 10193.337433066827, 10278.948605256926, 10361.067503924683, 7002.3697867192195, 7681.385489160742, 7742.380609551335, 8064.112947575644, 8231.135730748185, 8799.780005499913, 8971.237444817003, 8975.37172397952, 8984.640614375614, 8986.55067246622, 7519.849603008019, 7572.053493313336, 7642.121156067124, 7646.8088297793, 7809.414293928092, 7330.157031941395, 7368.0, 7395.223501532477, 7451.590162540895, 7731.536107833548, 6695.866082678222, 6814.284500127614, 6815.42068924147, 6837.982795075564, 6847.726090956306, 5162.122439185423, 5296.232046353334, 5309.256704061511, 5365.114756065205, 5672.290963629546, 5449.1826226066405, 5478.726063696879, 5505.944940550543, 5548.916766248429, 5673.326368721155], "value": 4689.746831103673, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [311.9017509484512, 312.66388632220367, 320.1260558674818, 324.92689144942375, 336.5944037434891, 5091.79632814687, 5225.190095535198, 5308.654937429069, 5331.546818520421, 5347.572194224463, 5126.436092029874, 5138.383393992717, 5148.20202868555, 5185.948140518593, 5227.73861306935, 4127.534725172565, 4142.440770495975, 4142.689298302632, 4171.749695018309, 4172.5410204877435, 3980.0845805464614, 4128.896777580554, 4144.8549300774475, 4148.896277593054, 4172.311568591194, 4091.8926752722373, 4092.1406504634065, 4092.9795351023204, 4105.158442519277, 4124.443200167984, 4095.2219078375015, 4102.5692302308125, 4107.137501124767, 4112.321466957948, 4114.40341150533, 3504.8948531544092, 3623.474596183539, 3627.292677927805, 3635.2002559437146, 3660.3411385950517, 5055.292259083732, 5072.949270507284, 5092.905025419553, 5093.79624815007, 5099.184130539096, 4649.163150632885, 4654.162250794856, 4661.370714953472, 4663.813447462097, 4685.461171965234, 5283.577313814885, 5347.866303342409, 5372.382176049765, 5415.539679127279, 5423.538999185074, 3974.1917427570675, 3975.582563830814, 3976.3439032559595, 3990.6208910153778, 4009.1179940413062, 5697.373288938214, 5720.9083915315405, 5749.59752817303, 5760.7695692172265, 5772.163036359746, 3989.960100399017, 4089.6523795477124, 4101.85643502476, 4123.3196522574035, 4204.180184863983, 4215.494140703136, 4253.97873010633, 4261.21167583998, 4285.7928166994025, 4291.098869237433, 4768.070226305837, 4776.617870570346, 4798.976005119952, 4822.71063736177, 4827.662063655581, 5470.945290547043, 5513.531349835229, 5540.282512421082, 5546.722663866791, 5613.46672066153, 1365.6108009217326, 1445.125698952119, 1457.460739526382, 1466.6260103673494, 1473.0646039764613, 1007.1338648762058, 1030.9381437113798, 1030.9896901031043, 1049.5854137615752, 1055.1242468750918, 5213.296205012351, 5214.895702085937, 5223.720188553752, 5231.267622532849, 5257.395399529103, 5564.4713752193475, 5566.443355664401, 5571.470710282517, 5580.860478487989, 5630.325595342261, 5087.287779710843, 5176.637635365563, 5223.190405487156, 5268.262443257947, 5294.814681486129, 2589.187568701916, 2598.453515554767, 2604.7106682192057, 2609.8516652672765, 2610.7519785620716, 4995.025969935828, 5012.897162624216, 5034.180858719129, 5036.053908463517, 5121.692698438105, 4860.397018930377, 4924.778384972685, 5012.799488020439, 5030.522100400456, 5038.672486288379, 5021.5229553192385, 5042.546170844642, 5065.645404821701, 5065.772040258271, 5074.873128171751, 4947.183714687037, 4948.777305021355, 4953.795181925246, 4979.327790748347, 4992.825251116194, 4562.817487300604, 4891.217405215149, 4895.527758481944, 4913.656044077024, 4944.53026962438, 4985.92802547454, 4994.300797888372, 5005.4232916631145, 5035.697858128524, 5057.848264552069, 3820.0, 3823.0, 3829.8276577554634, 3848.4034974578985, 3849.461075449495, 4969.975150124226, 4991.47589503097, 5223.582113430841, 5706.828795136153, 5737.885242295214, 5543.52880005204, 5552.139418389995, 5574.407900326368, 5587.161925711176, 5588.105903055492, 5491.395946445808, 5559.2217089606675, 5564.749586268708, 5569.888602228014, 5594.216809646571, 5267.367915850123, 5487.780488780404, 5534.916976245279, 5535.667859928417, 5538.667679939216, 5568.582356323211, 5573.637713548606, 5582.692951887685, 5588.273524441796, 5616.803411880564, 5198.59637897758, 5452.345718513804, 5475.561955043508, 5477.616566840283, 5508.338999320107], "value": 4101.217560062955, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [738.055289229786, 754.3474894216502, 769.1164081470837, 795.7109482638127, 844.7888027993001, 13739.862601373996, 14906.925465372671, 14990.550283491513, 14991.775123373158, 15011.474598389044, 14362.425502979895, 14464.842812574994, 14504.854951450483, 14552.563423097297, 14624.488142914974, 791.4697152907565, 806.8668669669497, 809.3444310108831, 809.7854068671807, 809.9595020248994, 645.0710976194289, 784.5841703896915, 798.6765360029168, 805.4402190477621, 809.1988930958368, 859.7334826203897, 859.7549698335976, 862.1421685423013, 862.5083702289697, 868.2055918834259, 856.2122846980793, 856.6573370651727, 856.9357298202659, 857.3741168946673, 857.6783706110209, 500.69457630845153, 614.1954040207298, 623.0622912516634, 627.9748810047593, 635.2694401438337, 13860.58418247444, 13871.237081960488, 13887.097338672955, 13890.374933128034, 13902.235377054258, 14037.64905877351, 14714.19071951053, 14736.263186840615, 14898.180600066991, 15051.322690478955, 13231.603051908458, 13309.667258318523, 13442.663933401645, 13595.796063059062, 14141.787873181911, 4417.0, 4426.203283408985, 4433.113377324516, 4652.581267685924, 4671.556202160789, 82732.0, 84255.15744842596, 85868.14131858664, 85965.57017214874, 86704.0, 11401.372924489067, 11407.0, 11424.600138995096, 11440.94279528597, 11617.302961822317, 22548.21081262148, 22563.774362256496, 22751.0, 22801.201957931393, 22836.65745013826, 8216.753497395088, 8225.547594882339, 8389.0, 8426.578671066422, 8476.703315384055, 4623.791929363187, 4683.531646835289, 4725.669203155746, 4738.857834264978, 5005.349304590452, 3241.497567876983, 3328.783629064103, 3333.466645336783, 3339.2653616204398, 3344.0469466202017, 573.7074092213002, 581.5899790647584, 583.2068386993685, 584.243404790797, 584.748558120004, 533.3493138371181, 573.4752701278367, 667.4226793823404, 687.5702685821361, 695.9338862808025, 8677.871876656121, 8919.241864441596, 9052.654508010097, 9056.230220431205, 9385.15533601979, 563.9154126880919, 666.3170250493283, 682.8463595691029, 688.5627626457235, 712.918014428337, 4278.743275403485, 4333.956660433355, 4378.474583050054, 4379.846705365297, 4532.0, 64957.675211624584, 68977.0, 132430.0, 132430.0, 133635.0, 82104.0, 104416.47791761144, 106091.0, 111620.0, 111991.0, 96715.0, 97611.0, 98367.0, 99530.50234748925, 101942.49028754958, 8162.334249075101, 8204.712835050745, 8220.671173153007, 8298.419110662197, 8478.194571515696, 45795.0, 50010.24984625161, 50255.994880102924, 52483.73758131185, 55884.72057639848, 46518.0, 46522.30216546687, 47381.289280661505, 47895.0, 68063.0, 12130.575429859913, 12407.503699851904, 12556.434960426985, 12573.497060117494, 12902.935485322514, 14189.368222654113, 14316.427342906167, 14462.27688615586, 14526.273686315431, 15293.77059344133, 13432.664183395102, 13465.663358416117, 13655.453781848615, 13738.175709457464, 13917.0, 13942.712304082348, 14086.647833804236, 14249.28753562297, 14711.92644036773, 15272.694546108793, 12738.808917866425, 12843.35783210817, 12864.742705145658, 12870.549530766379, 12882.162659427482, 10058.949705251427, 10213.0, 10278.486075696035, 10475.633352832614, 10574.735631609272, 9416.246700263826, 10385.584576617153, 10458.63394781179, 10510.369377837354, 10522.579096836042], "value": 7388.480304748218, "unit": "score"}], "name": "dromaeo_css", "value": 7139.808365070545, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 125.0, 111.0, 112.0, 111.0, 110.0, 110.0, 110.0, 110.0], "value": 112.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 123.0, 142.0, 171.0, 120.0, 152.0, 143.0, 119.0, 178.0, 117.0], "value": 140.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 162.0, 163.0, 162.0, 162.0, 162.0, 162.0, 161.0, 162.0], "value": 162.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 174.0, 76.0, 74.0, 76.0, 74.0, 162.0, 75.0], "value": 93.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 147.0, 85.0, 85.0, 85.0, 146.0, 84.0, 85.0, 143.0], "value": 103.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 145.0, 146.0, 147.0, 146.0, 145.0, 145.0, 146.0, 145.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 95.0, 96.0, 96.0, 95.0, 96.0, 96.0, 95.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 57.0, 57.0, 56.0, 55.0, 52.0, 53.0, 54.0, 52.0], "value": 54.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 63.0, 63.0, 63.0, 63.0, 63.0, 64.0, 62.0, 63.0], "value": 62.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 87.0, 79.0, 82.0, 78.0, 78.0, 79.0, 84.0, 79.0], "value": 80.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 125.0, 121.0, 132.0, 134.0, 124.0, 130.0, 132.0, 210.0, 125.0], "value": 135.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 167.0, 171.0, 165.0, 168.0, 172.0, 165.0, 172.0, 166.0, 163.0], "value": 167.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 66.0, 65.0, 63.0, 62.0, 63.0, 63.0, 65.0, 64.0], "value": 64.1, "unit": "score"}], "name": "kraken", "value": 1534.8000000000002, "alertThreshold": 2.0}]} 17:27:04 INFO - Return code: 0 17:27:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:27:05 INFO - # TBPL SUCCESS # 17:27:05 INFO - Running post-action listener: _resource_record_post_action 17:27:05 INFO - [mozharness: 2016-08-26 00:27:05.024392Z] Finished run-tests step (success) 17:27:05 INFO - Running post-run listener: _resource_record_post_run 17:27:05 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 4096; Write bytes: 417329152; Read time: 12; Write time: 373868 17:27:05 INFO - TinderboxPrint: CPU usage
13.3% 17:27:05 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 17:27:05 INFO - TinderboxPrint: I/O write bytes / time
417,329,152 / 373,868 17:27:05 INFO - TinderboxPrint: CPU idle
8,277.9 (86.7%) 17:27:05 INFO - TinderboxPrint: CPU user
1,206.9 (12.6%) 17:27:05 INFO - TinderboxPrint: Swap in / out
0 / 0 17:27:05 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 118407168; Read time: 0; Write time: 123892 17:27:05 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 0; Write bytes: 287309824; Read time: 0; Write time: 248188 17:27:05 INFO - Running post-run listener: _upload_blobber_files 17:27:05 INFO - Blob upload gear active. 17:27:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:27:05 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 17:27:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:27:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:27:06 INFO - (blobuploader) - INFO - Open directory for files ... 17:27:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:27:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:27:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:27:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:27:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:27:06 INFO - (blobuploader) - INFO - Done attempting. 17:27:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:27:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:27:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:27:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:27:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:27:10 INFO - (blobuploader) - INFO - Done attempting. 17:27:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:27:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:27:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:27:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:27:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:27:10 INFO - (blobuploader) - INFO - Done attempting. 17:27:10 INFO - (blobuploader) - INFO - Iteration through files over. 17:27:10 INFO - Return code: 0 17:27:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:27:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:27:10 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a694f63796a88eb2e372a351045761c5c5054f613c6d9aca8ec57fe9e4d831e47f16ae554a283c7293d0ffd0fba38f55451a86a8a72c1ae4cf69aa64aaac4c9c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/62f29874a2b560f70fabcfdb91f4e5a29785dfe94523c1a927792d6d13469c778af13409e09c9d90fb0186c1fde37f615d8ef47ea3c55a74018a966bbd79abf6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/afc0997756f928f469e3ab4c974beaf81bc2935d51d430dd3598833a4053c3cdb845c0c9a186b155fc80503bf8e0caf022c0e521220e2373671d8899788389f6"} 17:27:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:27:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:27:10 INFO - Contents: 17:27:10 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a694f63796a88eb2e372a351045761c5c5054f613c6d9aca8ec57fe9e4d831e47f16ae554a283c7293d0ffd0fba38f55451a86a8a72c1ae4cf69aa64aaac4c9c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/62f29874a2b560f70fabcfdb91f4e5a29785dfe94523c1a927792d6d13469c778af13409e09c9d90fb0186c1fde37f615d8ef47ea3c55a74018a966bbd79abf6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/afc0997756f928f469e3ab4c974beaf81bc2935d51d430dd3598833a4053c3cdb845c0c9a186b155fc80503bf8e0caf022c0e521220e2373671d8899788389f6"} 17:27:10 INFO - Running post-run listener: copy_logs_to_upload_dir 17:27:10 INFO - Copying logs to upload dir... 17:27:10 INFO - mkdir: /builds/slave/test/build/upload/logs 17:27:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1241.145268 ========= master_lag: 0.25 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-08-25 17:27:11.015535) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-25 17:27:11.020985) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1472169338.589573-1913469843 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a694f63796a88eb2e372a351045761c5c5054f613c6d9aca8ec57fe9e4d831e47f16ae554a283c7293d0ffd0fba38f55451a86a8a72c1ae4cf69aa64aaac4c9c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/62f29874a2b560f70fabcfdb91f4e5a29785dfe94523c1a927792d6d13469c778af13409e09c9d90fb0186c1fde37f615d8ef47ea3c55a74018a966bbd79abf6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/afc0997756f928f469e3ab4c974beaf81bc2935d51d430dd3598833a4053c3cdb845c0c9a186b155fc80503bf8e0caf022c0e521220e2373671d8899788389f6"} build_url:https://queue.taskcluster.net/v1/task/LGQXyqmERTW-GxwgCpavdQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011194 build_url: 'https://queue.taskcluster.net/v1/task/LGQXyqmERTW-GxwgCpavdQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a694f63796a88eb2e372a351045761c5c5054f613c6d9aca8ec57fe9e4d831e47f16ae554a283c7293d0ffd0fba38f55451a86a8a72c1ae4cf69aa64aaac4c9c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/62f29874a2b560f70fabcfdb91f4e5a29785dfe94523c1a927792d6d13469c778af13409e09c9d90fb0186c1fde37f615d8ef47ea3c55a74018a966bbd79abf6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/afc0997756f928f469e3ab4c974beaf81bc2935d51d430dd3598833a4053c3cdb845c0c9a186b155fc80503bf8e0caf022c0e521220e2373671d8899788389f6"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-25 17:27:11.045676) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 17:27:11.045994) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1472169338.589573-1913469843 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004560 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 17:27:11.095426) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-25 17:27:11.095749) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-25 17:27:11.096035) ========= ========= Total master_lag: 0.37 =========