builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-013 starttime: 1470784700.54 results: success (0) buildid: 20160809133022 builduid: 8d250a640280423d8442da2051b46e65 revision: bd03c5af655d4fcd00c6764be4e4d92ff74efcd3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-09 16:18:20.540500) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-09 16:18:20.540991) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-09 16:18:20.541389) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-09 16:18:20.557282) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 16:18:20.557660) ========= 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-1470784700.188244-260802693 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-09 16:18:21-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 49.8M=0s 2016-08-09 16:18:21 (49.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.124896 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 16:18:20.700992) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 16:18:20.701469) ========= 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-1470784700.188244-260802693 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018522 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 16:18:20.753352) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-09 16:18:20.753965) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev bd03c5af655d4fcd00c6764be4e4d92ff74efcd3 --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 bd03c5af655d4fcd00c6764be4e4d92ff74efcd3 --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-1470784700.188244-260802693 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-09 16:18:21,379 truncating revision to first 12 chars 2016-08-09 16:18:21,379 Setting DEBUG logging. 2016-08-09 16:18:21,379 attempt 1/10 2016-08-09 16:18:21,379 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/bd03c5af655d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-09 16:18:22,214 unpacking tar archive at: mozilla-inbound-bd03c5af655d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.254268 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-09 16:18:22.024960) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-09 16:18:22.025381) ========= script_repo_revision: bd03c5af655d4fcd00c6764be4e4d92ff74efcd3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-09 16:18:22.025869) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-09 16:18:22.026171) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-09 16:18:22.040365) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 29 secs) (at 2016-08-09 16:18:22.040799) ========= /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-1470784700.188244-260802693 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:18:22 INFO - MultiFileLogger online at 20160809 16:18:22 in /builds/slave/test 16:18:22 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 16:18:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:18:22 INFO - {'append_to_log': False, 16:18:22 INFO - 'base_work_dir': '/builds/slave/test', 16:18:22 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 16:18:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:18:22 INFO - 'branch': 'Mozilla-Inbound', 16:18:22 INFO - 'buildbot_json_path': 'buildprops.json', 16:18:22 INFO - 'config_files': ('talos/linux_config.py',), 16:18:22 INFO - 'default_actions': ('clobber', 16:18:22 INFO - 'read-buildbot-config', 16:18:22 INFO - 'download-and-extract', 16:18:22 INFO - 'populate-webroot', 16:18:22 INFO - 'create-virtualenv', 16:18:22 INFO - 'install', 16:18:22 INFO - 'run-tests'), 16:18:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:18:22 INFO - 'download_minidump_stackwalk': True, 16:18:22 INFO - 'download_symbols': 'ondemand', 16:18:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:18:22 INFO - 'tooltool.py': '/tools/tooltool.py', 16:18:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:18:22 INFO - '/tools/misc-python/virtualenv.py')}, 16:18:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:18:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:18:22 INFO - 'installer_path': 'installer.exe', 16:18:22 INFO - 'log_level': 'info', 16:18:22 INFO - 'log_name': 'talos', 16:18:22 INFO - 'log_to_console': True, 16:18:22 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:18:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:18:22 INFO - 'opt_config_files': (), 16:18:22 INFO - 'pip_index': False, 16:18:22 INFO - 'sps_profile': False, 16:18:22 INFO - 'sps_profile_interval': 0, 16:18:22 INFO - 'suite': 'dromaeojs', 16:18:22 INFO - 'system_bits': '32', 16:18:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:18:22 INFO - 'title': 'talos-linux64-ix-013', 16:18:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:18:22 INFO - 'use_talos_json': True, 16:18:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:18:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:18:22 INFO - 'work_dir': 'build'} 16:18:22 INFO - [mozharness: 2016-08-09 23:18:22.758855Z] Running clobber step. 16:18:22 INFO - Running pre-action listener: _resource_record_pre_action 16:18:22 INFO - Running main action method: clobber 16:18:22 INFO - rmtree: /builds/slave/test/build 16:18:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:18:23 INFO - Running post-action listener: _resource_record_post_action 16:18:23 INFO - [mozharness: 2016-08-09 23:18:23.222509Z] Finished clobber step (success) 16:18:23 INFO - [mozharness: 2016-08-09 23:18:23.222609Z] Running read-buildbot-config step. 16:18:23 INFO - Running pre-action listener: _resource_record_pre_action 16:18:23 INFO - Running main action method: read_buildbot_config 16:18:23 INFO - Using buildbot properties: 16:18:23 INFO - { 16:18:23 INFO - "project": "", 16:18:23 INFO - "product": "firefox", 16:18:23 INFO - "script_repo_revision": "production", 16:18:23 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 16:18:23 INFO - "repository": "", 16:18:23 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 16:18:23 INFO - "buildid": "20160809133022", 16:18:23 INFO - "pgo_build": "True", 16:18:23 INFO - "basedir": "/builds/slave/test", 16:18:23 INFO - "buildnumber": 2223, 16:18:23 INFO - "slavename": "talos-linux64-ix-013", 16:18:23 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:18:23 INFO - "platform": "ubuntu64_hw", 16:18:23 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 16:18:23 INFO - "branch": "mozilla-inbound", 16:18:23 INFO - "repo_path": "integration/mozilla-inbound", 16:18:23 INFO - "revision": "bd03c5af655d4fcd00c6764be4e4d92ff74efcd3", 16:18:23 INFO - "stage_platform": "linux64-pgo", 16:18:23 INFO - "builduid": "8d250a640280423d8442da2051b46e65", 16:18:23 INFO - "slavebuilddir": "test" 16:18:23 INFO - } 16:18:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/LwezZlkZQ6GBJF7meFL3YQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 16:18:23 INFO - Running post-action listener: _resource_record_post_action 16:18:23 INFO - [mozharness: 2016-08-09 23:18:23.225041Z] Finished read-buildbot-config step (success) 16:18:23 INFO - [mozharness: 2016-08-09 23:18:23.225130Z] Running download-and-extract step. 16:18:23 INFO - Running pre-action listener: _resource_record_pre_action 16:18:23 INFO - Running main action method: download_and_extract 16:18:23 INFO - mkdir: /builds/slave/test/build/tests 16:18:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:23 INFO - trying https://queue.taskcluster.net/v1/task/LwezZlkZQ6GBJF7meFL3YQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 16:18:23 INFO - Downloading https://queue.taskcluster.net/v1/task/LwezZlkZQ6GBJF7meFL3YQ/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 16:18:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LwezZlkZQ6GBJF7meFL3YQ/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 16:18:23 INFO - Downloaded 1361 bytes. 16:18:23 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 16:18:23 INFO - Using the following test package requirements: 16:18:23 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 16:18:23 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:18:23 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:18:23 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:18:23 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:18:23 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:18:23 INFO - u'jsshell-linux-x86_64.zip'], 16:18:23 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:18:23 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:18:23 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 16:18:23 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:18:23 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:18:23 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:18:23 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:18:23 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:18:23 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:18:23 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:18:23 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:18:23 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:18:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:23 INFO - trying https://queue.taskcluster.net/v1/task/LwezZlkZQ6GBJF7meFL3YQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 16:18:23 INFO - Downloading https://queue.taskcluster.net/v1/task/LwezZlkZQ6GBJF7meFL3YQ/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 16:18:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LwezZlkZQ6GBJF7meFL3YQ/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 16:18:24 INFO - Downloaded 21831188 bytes. 16:18:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:18:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:18:25 INFO - Return code: 0 16:18:25 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 16:18:25 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')]} 16:18:25 INFO - trying https://queue.taskcluster.net/v1/task/LwezZlkZQ6GBJF7meFL3YQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 16:18:25 INFO - Downloading https://queue.taskcluster.net/v1/task/LwezZlkZQ6GBJF7meFL3YQ/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 16:18:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LwezZlkZQ6GBJF7meFL3YQ/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 16:18:26 INFO - Downloaded 21831188 bytes. 16:18:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:18:26 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:18:28 INFO - Return code: 0 16:18:28 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')]} 16:18:28 INFO - trying https://queue.taskcluster.net/v1/task/LwezZlkZQ6GBJF7meFL3YQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 16:18:28 INFO - Downloading https://queue.taskcluster.net/v1/task/LwezZlkZQ6GBJF7meFL3YQ/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 16:18:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LwezZlkZQ6GBJF7meFL3YQ/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 16:18:28 INFO - Downloaded 14413719 bytes. 16:18:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:18:28 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 16:18:29 INFO - Return code: 0 16:18:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:29 INFO - trying https://queue.taskcluster.net/v1/task/LwezZlkZQ6GBJF7meFL3YQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:18:29 INFO - Downloading https://queue.taskcluster.net/v1/task/LwezZlkZQ6GBJF7meFL3YQ/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 16:18:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LwezZlkZQ6GBJF7meFL3YQ/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 16:18:30 INFO - Downloaded 59340932 bytes. 16:18:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LwezZlkZQ6GBJF7meFL3YQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:18:30 INFO - mkdir: /builds/slave/test/properties 16:18:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:18:30 INFO - Writing to file /builds/slave/test/properties/build_url 16:18:30 INFO - Contents: 16:18:30 INFO - build_url:https://queue.taskcluster.net/v1/task/LwezZlkZQ6GBJF7meFL3YQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:18:30 INFO - Running post-action listener: _resource_record_post_action 16:18:30 INFO - Running post-action listener: set_extra_try_arguments 16:18:30 INFO - [mozharness: 2016-08-09 23:18:30.868862Z] Finished download-and-extract step (success) 16:18:30 INFO - [mozharness: 2016-08-09 23:18:30.869093Z] Running populate-webroot step. 16:18:30 INFO - Running pre-action listener: _resource_record_pre_action 16:18:30 INFO - Running main action method: populate_webroot 16:18:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:18:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:18:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:18:30 INFO - '--tppagecycles', 16:18:30 INFO - '7'], 16:18:30 INFO - 'tests': ['tsvgm']}}, 16:18:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:18:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:18:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:18:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:18:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:18:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:18:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:18:30 INFO - 'talos_options': ['--disable-e10s'], 16:18:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:18:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:18:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:18:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:18:30 INFO - 'talos_options': ['--disable-e10s'], 16:18:30 INFO - 'tests': ['damp', 'tps']}, 16:18:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:18:30 INFO - 'tests': ['damp', 'tps']}, 16:18:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:18:30 INFO - 'tests': ['dromaeo_dom']}, 16:18:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:18:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:18:30 INFO - 'tests': ['basic_compositor_video']}, 16:18:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:18:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:18:30 INFO - 'tests': ['a11yr', 16:18:30 INFO - 'ts_paint', 16:18:30 INFO - 'tpaint', 16:18:30 INFO - 'sessionrestore', 16:18:30 INFO - 'sessionrestore_no_auto_restore', 16:18:30 INFO - 'tabpaint']}, 16:18:30 INFO - 'other-e10s': {'tests': ['a11yr', 16:18:30 INFO - 'ts_paint', 16:18:30 INFO - 'tpaint', 16:18:30 INFO - 'sessionrestore', 16:18:30 INFO - 'sessionrestore_no_auto_restore', 16:18:30 INFO - 'tabpaint']}, 16:18:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:18:30 INFO - 'ts_paint', 16:18:30 INFO - 'tpaint', 16:18:30 INFO - 'sessionrestore', 16:18:30 INFO - 'sessionrestore_no_auto_restore', 16:18:30 INFO - 'tabpaint']}, 16:18:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:18:30 INFO - 'ts_paint', 16:18:30 INFO - 'tpaint', 16:18:30 INFO - 'sessionrestore', 16:18:30 INFO - 'sessionrestore_no_auto_restore', 16:18:30 INFO - 'tabpaint']}, 16:18:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:18:30 INFO - 'tests': ['a11yr', 16:18:30 INFO - 'ts_paint', 16:18:30 INFO - 'tpaint', 16:18:30 INFO - 'sessionrestore', 16:18:30 INFO - 'sessionrestore_no_auto_restore', 16:18:30 INFO - 'tabpaint']}, 16:18:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:18:30 INFO - 'tests': ['a11yr', 16:18:30 INFO - 'ts_paint', 16:18:30 INFO - 'tpaint', 16:18:30 INFO - 'sessionrestore', 16:18:30 INFO - 'sessionrestore_no_auto_restore', 16:18:30 INFO - 'tabpaint']}, 16:18:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:18:30 INFO - 'tests': ['tsvgx', 16:18:30 INFO - 'tsvgr_opacity', 16:18:30 INFO - 'tart', 16:18:30 INFO - 'tscrollx', 16:18:30 INFO - 'cart']}, 16:18:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:18:30 INFO - 'tsvgr_opacity', 16:18:30 INFO - 'tart', 16:18:30 INFO - 'tscrollx', 16:18:30 INFO - 'cart']}, 16:18:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:18:30 INFO - 'talos_options': ['--disable-e10s'], 16:18:30 INFO - 'tests': ['tp5o']}, 16:18:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:18:30 INFO - 'tests': ['tp5o']}, 16:18:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:18:30 INFO - 'talos_options': ['--disable-e10s', 16:18:30 INFO - '--xperf_path', 16:18:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:18:30 INFO - 'tests': ['tp5n']}, 16:18:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:18:30 INFO - 'talos_options': ['--xperf_path', 16:18:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:18:30 INFO - 'tests': ['tp5n']}}, 16:18:30 INFO - 'talos.zip': {'path': '', 16:18:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:18:30 INFO - Running post-action listener: _resource_record_post_action 16:18:30 INFO - [mozharness: 2016-08-09 23:18:30.890301Z] Finished populate-webroot step (success) 16:18:30 INFO - [mozharness: 2016-08-09 23:18:30.890390Z] Running create-virtualenv step. 16:18:30 INFO - Running pre-action listener: _resource_record_pre_action 16:18:30 INFO - Running main action method: create_virtualenv 16:18:30 INFO - Creating virtualenv /builds/slave/test/build/venv 16:18:30 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 16:18:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:18:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:18:30 INFO - Using real prefix '/usr' 16:18:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:18:31 INFO - Installing distribute.............................................................................................................................................................................................done. 16:18:33 INFO - Installing pip.................done. 16:18:33 INFO - Return code: 0 16:18:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:18:33 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')]} 16:18:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:33 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')]} 16:18:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:33 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 0xc70030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf31180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2f3b0>, '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 0xf302b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf30740>, '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-1470784700.188244-260802693', '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 16:18:33 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 16:18:33 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 16:18:33 INFO - Using env: {'DISPLAY': ':0', 16:18:33 INFO - 'HOME': '/home/cltbld', 16:18:33 INFO - 'LANG': 'en_US.UTF-8', 16:18:33 INFO - 'LANGUAGE': 'en_US:en', 16:18:33 INFO - 'LOGNAME': 'cltbld', 16:18:33 INFO - 'MAIL': '/var/mail/cltbld', 16:18:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:33 INFO - 'MOZ_NO_REMOTE': '1', 16:18:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:33 INFO - 'NO_EM_RESTART': '1', 16:18:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:33 INFO - 'PWD': '/builds/slave/test', 16:18:33 INFO - 'SHELL': '/bin/bash', 16:18:33 INFO - 'SHLVL': '1', 16:18:33 INFO - 'TERM': 'linux', 16:18:33 INFO - 'TMOUT': '86400', 16:18:33 INFO - 'USER': 'cltbld', 16:18:33 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1470784700.188244-260802693', 16:18:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:33 INFO - '_': '/tools/buildbot/bin/python'} 16:18:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:33 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 16:18:33 INFO - Cleaning up... 16:18:33 INFO - Return code: 0 16:18:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:18:33 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')]} 16:18:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:33 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')]} 16:18:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:33 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 0xc70030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf31180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2f3b0>, '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 0xf302b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf30740>, '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-1470784700.188244-260802693', '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 16:18:33 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 16:18:33 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 16:18:33 INFO - Using env: {'DISPLAY': ':0', 16:18:33 INFO - 'HOME': '/home/cltbld', 16:18:33 INFO - 'LANG': 'en_US.UTF-8', 16:18:33 INFO - 'LANGUAGE': 'en_US:en', 16:18:33 INFO - 'LOGNAME': 'cltbld', 16:18:33 INFO - 'MAIL': '/var/mail/cltbld', 16:18:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:33 INFO - 'MOZ_NO_REMOTE': '1', 16:18:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:33 INFO - 'NO_EM_RESTART': '1', 16:18:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:33 INFO - 'PWD': '/builds/slave/test', 16:18:33 INFO - 'SHELL': '/bin/bash', 16:18:33 INFO - 'SHLVL': '1', 16:18:33 INFO - 'TERM': 'linux', 16:18:33 INFO - 'TMOUT': '86400', 16:18:33 INFO - 'USER': 'cltbld', 16:18:33 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1470784700.188244-260802693', 16:18:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:33 INFO - '_': '/tools/buildbot/bin/python'} 16:18:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:33 INFO - Downloading/unpacking psutil>=3.1.1 16:18:33 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 16:18:33 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 16:18:33 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 16:18:33 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 16:18:33 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 16:18:33 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 16:18:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:18:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:18:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:18:35 INFO - Installing collected packages: psutil 16:18:35 INFO - Running setup.py install for psutil 16:18:35 INFO - building 'psutil._psutil_linux' extension 16:18:35 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 16:18:36 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 16:18:36 INFO - building 'psutil._psutil_posix' extension 16:18:36 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 16:18:36 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 16:18:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:18:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:18:36 INFO - Successfully installed psutil 16:18:36 INFO - Cleaning up... 16:18:36 INFO - Return code: 0 16:18:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:18: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')]} 16:18:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18: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')]} 16:18:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc70030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf31180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2f3b0>, '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 0xf302b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf30740>, '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-1470784700.188244-260802693', '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 16:18:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:18:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:18:36 INFO - Using env: {'DISPLAY': ':0', 16:18:36 INFO - 'HOME': '/home/cltbld', 16:18:36 INFO - 'LANG': 'en_US.UTF-8', 16:18:36 INFO - 'LANGUAGE': 'en_US:en', 16:18:36 INFO - 'LOGNAME': 'cltbld', 16:18:36 INFO - 'MAIL': '/var/mail/cltbld', 16:18:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:36 INFO - 'MOZ_NO_REMOTE': '1', 16:18:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:36 INFO - 'NO_EM_RESTART': '1', 16:18:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:36 INFO - 'PWD': '/builds/slave/test', 16:18:36 INFO - 'SHELL': '/bin/bash', 16:18:36 INFO - 'SHLVL': '1', 16:18:36 INFO - 'TERM': 'linux', 16:18:36 INFO - 'TMOUT': '86400', 16:18:36 INFO - 'USER': 'cltbld', 16:18:36 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1470784700.188244-260802693', 16:18:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:36 INFO - '_': '/tools/buildbot/bin/python'} 16:18:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:18:36 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 16:18:36 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 16:18:36 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 16:18:36 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 16:18:36 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 16:18:36 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 16:18:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:18:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:18:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:18:38 INFO - Installing collected packages: mozsystemmonitor 16:18:38 INFO - Running setup.py install for mozsystemmonitor 16:18:38 INFO - Successfully installed mozsystemmonitor 16:18:38 INFO - Cleaning up... 16:18:38 INFO - Return code: 0 16:18:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:18:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:38 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 0xc70030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf31180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2f3b0>, '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 0xf302b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf30740>, '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-1470784700.188244-260802693', '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 16:18:38 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 16:18:38 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 16:18:38 INFO - Using env: {'DISPLAY': ':0', 16:18:38 INFO - 'HOME': '/home/cltbld', 16:18:38 INFO - 'LANG': 'en_US.UTF-8', 16:18:38 INFO - 'LANGUAGE': 'en_US:en', 16:18:38 INFO - 'LOGNAME': 'cltbld', 16:18:38 INFO - 'MAIL': '/var/mail/cltbld', 16:18:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:38 INFO - 'MOZ_NO_REMOTE': '1', 16:18:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:38 INFO - 'NO_EM_RESTART': '1', 16:18:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:38 INFO - 'PWD': '/builds/slave/test', 16:18:38 INFO - 'SHELL': '/bin/bash', 16:18:38 INFO - 'SHLVL': '1', 16:18:38 INFO - 'TERM': 'linux', 16:18:38 INFO - 'TMOUT': '86400', 16:18:38 INFO - 'USER': 'cltbld', 16:18:38 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1470784700.188244-260802693', 16:18:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:38 INFO - '_': '/tools/buildbot/bin/python'} 16:18:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:38 INFO - Downloading/unpacking jsonschema==2.5.1 16:18:38 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 16:18:38 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 16:18:38 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 16:18:38 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 16:18:38 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 16:18:38 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 16:18:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:18:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:18:40 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 16:18:40 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 16:18:40 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 16:18:40 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 16:18:40 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 16:18:40 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 16:18:41 INFO - Downloading functools32-3.2.3-2.tar.gz 16:18:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:18:41 INFO - warning: no files found matching '*.txt' 16:18:41 INFO - no previously-included directories found matching 'build' 16:18:41 INFO - no previously-included directories found matching 'dist' 16:18:41 INFO - no previously-included directories found matching '.git*' 16:18:41 INFO - Installing collected packages: jsonschema, functools32 16:18:41 INFO - Running setup.py install for functools32 16:18:41 INFO - warning: no files found matching '*.txt' 16:18:41 INFO - no previously-included directories found matching 'build' 16:18:41 INFO - no previously-included directories found matching 'dist' 16:18:41 INFO - no previously-included directories found matching '.git*' 16:18:41 INFO - Successfully installed jsonschema functools32 16:18:41 INFO - Cleaning up... 16:18:41 INFO - Return code: 0 16:18:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:18:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:41 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 0xc70030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf31180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2f3b0>, '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 0xf302b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf30740>, '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-1470784700.188244-260802693', '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 16:18:41 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 16:18:41 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 16:18:41 INFO - Using env: {'DISPLAY': ':0', 16:18:41 INFO - 'HOME': '/home/cltbld', 16:18:41 INFO - 'LANG': 'en_US.UTF-8', 16:18:41 INFO - 'LANGUAGE': 'en_US:en', 16:18:41 INFO - 'LOGNAME': 'cltbld', 16:18:41 INFO - 'MAIL': '/var/mail/cltbld', 16:18:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:41 INFO - 'MOZ_NO_REMOTE': '1', 16:18:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:41 INFO - 'NO_EM_RESTART': '1', 16:18:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:41 INFO - 'PWD': '/builds/slave/test', 16:18:41 INFO - 'SHELL': '/bin/bash', 16:18:41 INFO - 'SHLVL': '1', 16:18:41 INFO - 'TERM': 'linux', 16:18:41 INFO - 'TMOUT': '86400', 16:18:41 INFO - 'USER': 'cltbld', 16:18:41 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1470784700.188244-260802693', 16:18:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:41 INFO - '_': '/tools/buildbot/bin/python'} 16:18:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:18:41 INFO - Cleaning up... 16:18:41 INFO - Return code: 0 16:18:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:18:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:41 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 0xc70030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf31180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2f3b0>, '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 0xf302b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf30740>, '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-1470784700.188244-260802693', '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 16:18:41 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 16:18:41 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 16:18:41 INFO - Using env: {'DISPLAY': ':0', 16:18:41 INFO - 'HOME': '/home/cltbld', 16:18:41 INFO - 'LANG': 'en_US.UTF-8', 16:18:41 INFO - 'LANGUAGE': 'en_US:en', 16:18:41 INFO - 'LOGNAME': 'cltbld', 16:18:41 INFO - 'MAIL': '/var/mail/cltbld', 16:18:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:41 INFO - 'MOZ_NO_REMOTE': '1', 16:18:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:41 INFO - 'NO_EM_RESTART': '1', 16:18:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:41 INFO - 'PWD': '/builds/slave/test', 16:18:41 INFO - 'SHELL': '/bin/bash', 16:18:41 INFO - 'SHLVL': '1', 16:18:41 INFO - 'TERM': 'linux', 16:18:41 INFO - 'TMOUT': '86400', 16:18:41 INFO - 'USER': 'cltbld', 16:18:41 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1470784700.188244-260802693', 16:18:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:41 INFO - '_': '/tools/buildbot/bin/python'} 16:18:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:41 INFO - Downloading/unpacking blobuploader==1.2.4 16:18:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:43 INFO - Downloading blobuploader-1.2.4.tar.gz 16:18:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:18:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:18:43 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 16:18:43 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 16:18:43 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 16:18:43 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 16:18:43 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 16:18:43 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 16:18:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:18:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:18:44 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 16:18:44 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 16:18:44 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 16:18:44 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 16:18:44 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 16:18:44 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 16:18:44 INFO - Downloading docopt-0.6.1.tar.gz 16:18:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:18:44 INFO - Installing collected packages: blobuploader, requests, docopt 16:18:44 INFO - Running setup.py install for blobuploader 16:18:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:18:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:18:44 INFO - Running setup.py install for requests 16:18:44 INFO - Running setup.py install for docopt 16:18:44 INFO - Successfully installed blobuploader requests docopt 16:18:44 INFO - Cleaning up... 16:18:45 INFO - Return code: 0 16:18:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:18:45 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')]} 16:18:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:45 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')]} 16:18:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:45 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 0xc70030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf31180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2f3b0>, '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 0xf302b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf30740>, '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-1470784700.188244-260802693', '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 16:18:45 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 16:18:45 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 16:18:45 INFO - Using env: {'DISPLAY': ':0', 16:18:45 INFO - 'HOME': '/home/cltbld', 16:18:45 INFO - 'LANG': 'en_US.UTF-8', 16:18:45 INFO - 'LANGUAGE': 'en_US:en', 16:18:45 INFO - 'LOGNAME': 'cltbld', 16:18:45 INFO - 'MAIL': '/var/mail/cltbld', 16:18:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:45 INFO - 'MOZ_NO_REMOTE': '1', 16:18:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:45 INFO - 'NO_EM_RESTART': '1', 16:18:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:45 INFO - 'PWD': '/builds/slave/test', 16:18:45 INFO - 'SHELL': '/bin/bash', 16:18:45 INFO - 'SHLVL': '1', 16:18:45 INFO - 'TERM': 'linux', 16:18:45 INFO - 'TMOUT': '86400', 16:18:45 INFO - 'USER': 'cltbld', 16:18:45 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1470784700.188244-260802693', 16:18:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:45 INFO - '_': '/tools/buildbot/bin/python'} 16:18:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:18:45 INFO - Running setup.py (path:/tmp/pip-C6uYEm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:18:45 INFO - Running setup.py (path:/tmp/pip-cVhHlN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:18:45 INFO - Running setup.py (path:/tmp/pip-4iUJVU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:18:45 INFO - Running setup.py (path:/tmp/pip-wvGD00-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:18:45 INFO - Running setup.py (path:/tmp/pip-AeFd43-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:18:45 INFO - Running setup.py (path:/tmp/pip-G7Jh22-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:18:45 INFO - Running setup.py (path:/tmp/pip-ENWgGa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:18:45 INFO - Running setup.py (path:/tmp/pip-wtX3M5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:18:45 INFO - Running setup.py (path:/tmp/pip-XhIvqn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:18:45 INFO - Running setup.py (path:/tmp/pip-4GkJ5M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:18:45 INFO - Running setup.py (path:/tmp/pip-i6Q3Tg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:18:46 INFO - Running setup.py (path:/tmp/pip-rrM1Mx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:18:46 INFO - Running setup.py (path:/tmp/pip-mWuU2j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:18:46 INFO - Running setup.py (path:/tmp/pip-S5UKrR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:18:46 INFO - Running setup.py (path:/tmp/pip-yKlrHk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:18:46 INFO - Running setup.py (path:/tmp/pip-VjXdNK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:18:46 INFO - Running setup.py (path:/tmp/pip-B8Tyqb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:18:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:18:46 INFO - Running setup.py install for manifestparser 16:18:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:18:46 INFO - Running setup.py install for mozcrash 16:18:46 INFO - Running setup.py install for mozdebug 16:18:46 INFO - Running setup.py install for mozdevice 16:18:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:18:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:18:46 INFO - Running setup.py install for mozfile 16:18:46 INFO - Running setup.py install for mozhttpd 16:18:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:18:47 INFO - Running setup.py install for mozinfo 16:18:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:18:47 INFO - Running setup.py install for mozInstall 16:18:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:18:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:18:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:18:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:18:47 INFO - Running setup.py install for mozleak 16:18:47 INFO - Running setup.py install for mozlog 16:18:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:18:47 INFO - Running setup.py install for moznetwork 16:18:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:18:47 INFO - Running setup.py install for mozprocess 16:18:47 INFO - Running setup.py install for mozprofile 16:18:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:18:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:18:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:18:47 INFO - Running setup.py install for mozrunner 16:18:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:18:47 INFO - Running setup.py install for mozscreenshot 16:18:48 INFO - Running setup.py install for moztest 16:18:48 INFO - Running setup.py install for mozversion 16:18:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:18:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:18:48 INFO - Cleaning up... 16:18:48 INFO - Return code: 0 16:18:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:18: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')]} 16:18:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18: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')]} 16:18:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:48 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 0xc70030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf31180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2f3b0>, '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 0xf302b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf30740>, '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-1470784700.188244-260802693', '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 16:18:48 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 16:18:48 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 16:18:48 INFO - Using env: {'DISPLAY': ':0', 16:18:48 INFO - 'HOME': '/home/cltbld', 16:18:48 INFO - 'LANG': 'en_US.UTF-8', 16:18:48 INFO - 'LANGUAGE': 'en_US:en', 16:18:48 INFO - 'LOGNAME': 'cltbld', 16:18:48 INFO - 'MAIL': '/var/mail/cltbld', 16:18:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:48 INFO - 'MOZ_NO_REMOTE': '1', 16:18:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:48 INFO - 'NO_EM_RESTART': '1', 16:18:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:48 INFO - 'PWD': '/builds/slave/test', 16:18:48 INFO - 'SHELL': '/bin/bash', 16:18:48 INFO - 'SHLVL': '1', 16:18:48 INFO - 'TERM': 'linux', 16:18:48 INFO - 'TMOUT': '86400', 16:18:48 INFO - 'USER': 'cltbld', 16:18:48 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1470784700.188244-260802693', 16:18:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:48 INFO - '_': '/tools/buildbot/bin/python'} 16:18:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:18:48 INFO - Running setup.py (path:/tmp/pip-E_PNDu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:18:48 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)) 16:18:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:18:48 INFO - Running setup.py (path:/tmp/pip-KpyluD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:18:48 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)) 16:18:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:18:48 INFO - Running setup.py (path:/tmp/pip-wfY1LR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:18:48 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)) 16:18:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:18:48 INFO - Running setup.py (path:/tmp/pip-0QSpNH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:18:48 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)) 16:18:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:18:48 INFO - Running setup.py (path:/tmp/pip-FxyNkn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:18:48 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)) 16:18:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:18:48 INFO - Running setup.py (path:/tmp/pip-1Ik3y6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:18:48 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)) 16:18:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:18:48 INFO - Running setup.py (path:/tmp/pip-Vger3w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:18:48 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)) 16:18:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:18:48 INFO - Running setup.py (path:/tmp/pip-wBJjJ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:18:49 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)) 16:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:18:49 INFO - Running setup.py (path:/tmp/pip-u3xrcS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:18:49 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)) 16:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:18:49 INFO - Running setup.py (path:/tmp/pip-gtVIO9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:18:49 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)) 16:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:18:49 INFO - Running setup.py (path:/tmp/pip-bDteZr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:18:49 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)) 16:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:18:49 INFO - Running setup.py (path:/tmp/pip-eZV401-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:18:49 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)) 16:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:18:49 INFO - Running setup.py (path:/tmp/pip-y7cvrZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:18:49 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)) 16:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:18:49 INFO - Running setup.py (path:/tmp/pip-kKoyKE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:18:49 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)) 16:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:18:49 INFO - Running setup.py (path:/tmp/pip-4jwyfX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:18:49 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)) 16:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:18:49 INFO - Running setup.py (path:/tmp/pip-wYBOnq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:18:49 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)) 16:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:18:49 INFO - Running setup.py (path:/tmp/pip-D3oOkO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:18:49 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)) 16:18:49 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)) 16:18:49 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)) 16:18:49 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)) 16:18:49 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)) 16:18:49 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)) 16:18:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:18:49 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 16:18:49 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 16:18:49 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 16:18:49 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 16:18:49 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 16:18:49 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 16:18:51 INFO - Downloading blessings-1.6.tar.gz 16:18:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:18:51 INFO - Installing collected packages: blessings 16:18:51 INFO - Running setup.py install for blessings 16:18:51 INFO - Successfully installed blessings 16:18:51 INFO - Cleaning up... 16:18:51 INFO - Return code: 0 16:18:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:18:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:18:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:18:52 INFO - Reading from file tmpfile_stdout 16:18:52 INFO - Current package versions: 16:18:52 INFO - argparse == 1.2.1 16:18:52 INFO - blessings == 1.6 16:18:52 INFO - blobuploader == 1.2.4 16:18:52 INFO - docopt == 0.6.1 16:18:52 INFO - functools32 == 3.2.3-2 16:18:52 INFO - jsonschema == 2.5.1 16:18:52 INFO - manifestparser == 1.1 16:18:52 INFO - mozInstall == 1.12 16:18:52 INFO - mozcrash == 0.17 16:18:52 INFO - mozdebug == 0.1 16:18:52 INFO - mozdevice == 0.48 16:18:52 INFO - mozfile == 1.2 16:18:52 INFO - mozhttpd == 0.7 16:18:52 INFO - mozinfo == 0.9 16:18:52 INFO - mozleak == 0.1 16:18:52 INFO - mozlog == 3.3 16:18:52 INFO - moznetwork == 0.27 16:18:52 INFO - mozprocess == 0.23 16:18:52 INFO - mozprofile == 0.28 16:18:52 INFO - mozrunner == 6.12 16:18:52 INFO - mozscreenshot == 0.1 16:18:52 INFO - mozsystemmonitor == 0.3 16:18:52 INFO - moztest == 0.7 16:18:52 INFO - mozversion == 1.4 16:18:52 INFO - psutil == 3.1.1 16:18:52 INFO - requests == 1.2.3 16:18:52 INFO - wsgiref == 0.1.2 16:18:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:18:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:52 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 0xc70030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf31180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2f3b0>, '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 0xf302b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf30740>, '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-1470784700.188244-260802693', '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 16:18:52 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 16:18:52 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 16:18:52 INFO - Using env: {'DISPLAY': ':0', 16:18:52 INFO - 'HOME': '/home/cltbld', 16:18:52 INFO - 'LANG': 'en_US.UTF-8', 16:18:52 INFO - 'LANGUAGE': 'en_US:en', 16:18:52 INFO - 'LOGNAME': 'cltbld', 16:18:52 INFO - 'MAIL': '/var/mail/cltbld', 16:18:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:52 INFO - 'MOZ_NO_REMOTE': '1', 16:18:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:52 INFO - 'NO_EM_RESTART': '1', 16:18:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:52 INFO - 'PWD': '/builds/slave/test', 16:18:52 INFO - 'SHELL': '/bin/bash', 16:18:52 INFO - 'SHLVL': '1', 16:18:52 INFO - 'TERM': 'linux', 16:18:52 INFO - 'TMOUT': '86400', 16:18:52 INFO - 'USER': 'cltbld', 16:18:52 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1470784700.188244-260802693', 16:18:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:52 INFO - '_': '/tools/buildbot/bin/python'} 16:18:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:52 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)) 16:18:52 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)) 16:18:52 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)) 16:18:52 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)) 16:18:52 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)) 16:18:52 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)) 16:18:52 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)) 16:18:52 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)) 16:18:52 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)) 16:18:52 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)) 16:18:52 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)) 16:18:52 INFO - Cleaning up... 16:18:52 INFO - Return code: 0 16:18:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:18:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:52 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 0xc70030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf31180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2f3b0>, '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 0xf302b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf30740>, '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-1470784700.188244-260802693', '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 16:18:52 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 16:18:52 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 16:18:52 INFO - Using env: {'DISPLAY': ':0', 16:18:52 INFO - 'HOME': '/home/cltbld', 16:18:52 INFO - 'LANG': 'en_US.UTF-8', 16:18:52 INFO - 'LANGUAGE': 'en_US:en', 16:18:52 INFO - 'LOGNAME': 'cltbld', 16:18:52 INFO - 'MAIL': '/var/mail/cltbld', 16:18:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:52 INFO - 'MOZ_NO_REMOTE': '1', 16:18:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:52 INFO - 'NO_EM_RESTART': '1', 16:18:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:52 INFO - 'PWD': '/builds/slave/test', 16:18:52 INFO - 'SHELL': '/bin/bash', 16:18:52 INFO - 'SHLVL': '1', 16:18:52 INFO - 'TERM': 'linux', 16:18:52 INFO - 'TMOUT': '86400', 16:18:52 INFO - 'USER': 'cltbld', 16:18:52 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1470784700.188244-260802693', 16:18:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:52 INFO - '_': '/tools/buildbot/bin/python'} 16:18:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:18:52 INFO - Cleaning up... 16:18:52 INFO - Return code: 0 16:18:52 INFO - Running post-action listener: _resource_record_post_action 16:18:52 INFO - Running post-action listener: _start_resource_monitoring 16:18:52 INFO - Starting resource monitoring. 16:18:52 INFO - [mozharness: 2016-08-09 23:18:52.498666Z] Finished create-virtualenv step (success) 16:18:52 INFO - [mozharness: 2016-08-09 23:18:52.499109Z] Running install step. 16:18:52 INFO - Running pre-action listener: _resource_record_pre_action 16:18:52 INFO - Running main action method: install 16:18:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:18:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:18:52 INFO - Reading from file tmpfile_stdout 16:18:52 INFO - Detecting whether we're running mozinstall >=1.0... 16:18:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:18:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:18:52 INFO - Reading from file tmpfile_stdout 16:18:52 INFO - Output received: 16:18:52 INFO - Usage: mozinstall [options] installer 16:18:52 INFO - Options: 16:18:52 INFO - -h, --help show this help message and exit 16:18:52 INFO - -d DEST, --destination=DEST 16:18:52 INFO - Directory to install application into. [default: 16:18:52 INFO - "/builds/slave/test"] 16:18:52 INFO - --app=APP Application being installed. [default: firefox] 16:18:52 INFO - mkdir: /builds/slave/test/build/application 16:18:52 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'] 16:18:52 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 16:19:09 INFO - Reading from file tmpfile_stdout 16:19:09 INFO - Output received: 16:19:09 INFO - /builds/slave/test/build/application/firefox/firefox 16:19:09 INFO - Running post-action listener: _resource_record_post_action 16:19:09 INFO - [mozharness: 2016-08-09 23:19:09.671515Z] Finished install step (success) 16:19:09 INFO - [mozharness: 2016-08-09 23:19:09.671851Z] Running run-tests step. 16:19:09 INFO - Running pre-action listener: _resource_record_pre_action 16:19:09 INFO - Running main action method: run_tests 16:19:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:19:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:19:09 INFO - Python 2.7.3 16:19:09 INFO - Return code: 0 16:19:09 INFO - grabbing minidump binary from tooltool 16:19:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:19:09 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 0xf2f3b0>, '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 0xf302b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf30740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:19:09 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 16:19:09 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 16:19:09 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 16:19:09 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:19:09 INFO - Return code: 0 16:19:09 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:19:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:19:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:19:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:19:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:19:09 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/LwezZlkZQ6GBJF7meFL3YQ/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 16:19:09 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/LwezZlkZQ6GBJF7meFL3YQ/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 16:19:09 INFO - Using env: {'DISPLAY': ':0', 16:19:09 INFO - 'HOME': '/home/cltbld', 16:19:09 INFO - 'LANG': 'en_US.UTF-8', 16:19:09 INFO - 'LANGUAGE': 'en_US:en', 16:19:09 INFO - 'LOGNAME': 'cltbld', 16:19:09 INFO - 'MAIL': '/var/mail/cltbld', 16:19:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:19:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:19:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:09 INFO - 'MOZ_NO_REMOTE': '1', 16:19:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:19:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:19:09 INFO - 'NO_EM_RESTART': '1', 16:19:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:19:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:09 INFO - 'PWD': '/builds/slave/test', 16:19:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:19:09 INFO - 'SHELL': '/bin/bash', 16:19:09 INFO - 'SHLVL': '1', 16:19:09 INFO - 'TERM': 'linux', 16:19:09 INFO - 'TMOUT': '86400', 16:19:09 INFO - 'USER': 'cltbld', 16:19:09 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1470784700.188244-260802693', 16:19:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:09 INFO - '_': '/tools/buildbot/bin/python'} 16:19:09 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/LwezZlkZQ6GBJF7meFL3YQ/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 16:19:09 INFO - mozversion application_buildid: 20160809133022 16:19:09 INFO - mozversion application_changeset: bd03c5af655d4fcd00c6764be4e4d92ff74efcd3 16:19:09 INFO - mozversion application_display_name: Nightly 16:19:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:19:09 INFO - mozversion application_name: Firefox 16:19:09 INFO - mozversion application_remotingname: firefox 16:19:09 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 16:19:09 INFO - mozversion application_vendor: Mozilla 16:19:09 INFO - mozversion application_version: 51.0a1 16:19:09 INFO - mozversion platform_buildid: 20160809133022 16:19:09 INFO - mozversion platform_changeset: bd03c5af655d4fcd00c6764be4e4d92ff74efcd3 16:19:09 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 16:19:09 INFO - mozversion platform_version: 51.0a1 16:19:09 INFO - using testdate: 1470784749 16:19:09 INFO - actual date: 1470784749 16:19:09 INFO - starting webserver on 'localhost:59721' 16:19:09 INFO - SUITE-START | Running 2 tests 16:19:09 INFO - TEST-START | dromaeo_css 16:19:09 INFO - operating with platform_type : linux_ 16:19:09 INFO - Initialising browser for dromaeo_css test... 16:19:09 INFO - TEST-INFO | started process 8948 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpG8phyg/profile http://localhost:59721/getInfo.html) 16:19:14 INFO - PROCESS | 8948 | __metrics Screen width/height:1600/1200 16:19:14 INFO - PROCESS | 8948 | colorDepth:24 16:19:14 INFO - PROCESS | 8948 | Browser inner width/height: 1024/697 16:19:14 INFO - PROCESS | 8948 | __metrics 16:19:15 INFO - PROCESS | 8948 | 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" 16:19:15 INFO - PROCESS | 8948 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:19:15 INFO - PROCESS | 8948 | console.error: PushService: 16:19:15 INFO - PROCESS | 8948 | stateChangeProcessEnqueue: Error transitioning state 16:19:15 INFO - PROCESS | 8948 | UnknownError 16:19:15 INFO - TEST-INFO | 8948: exit 0 16:19:15 INFO - Browser initialized. 16:19:15 INFO - Running cycle 1/1 for dromaeo_css test... 16:19:15 INFO - TEST-INFO | started process 9029 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpG8phyg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:19:27 INFO - PROCESS | 9029 | RSS: Main: 155127808 16:19:27 INFO - PROCESS | 9029 | 16:20:18 INFO - PROCESS | 9029 | 1470784818929 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] 16:22:28 INFO - PROCESS | 9029 | Cycle 1(1): loaded http://localhost:59721/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59721/tests/dromaeo/cssquery-ext.html) 16:22:29 INFO - PROCESS | 9029 | RSS: Main: 233295872 16:22:29 INFO - PROCESS | 9029 | 16:25:32 INFO - PROCESS | 9029 | Cycle 1(1): loaded http://localhost:59721/tests/dromaeo/cssquery-ext.html (next: http://localhost:59721/tests/dromaeo/cssquery-jquery.html) 16:25:32 INFO - PROCESS | 9029 | RSS: Main: 244412416 16:25:32 INFO - PROCESS | 9029 | 16:28:34 INFO - PROCESS | 9029 | Cycle 1(1): loaded http://localhost:59721/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59721/tests/dromaeo/cssquery-mootools.html) 16:28:35 INFO - PROCESS | 9029 | RSS: Main: 181837824 16:28:35 INFO - PROCESS | 9029 | 16:31:37 INFO - PROCESS | 9029 | Cycle 1(1): loaded http://localhost:59721/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59721/tests/dromaeo/cssquery-prototype.html) 16:31:37 INFO - PROCESS | 9029 | RSS: Main: 198877184 16:31:37 INFO - PROCESS | 9029 | 16:34:40 INFO - PROCESS | 9029 | Cycle 1(1): loaded http://localhost:59721/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59721/tests/dromaeo/cssquery-yui.html) 16:34:41 INFO - PROCESS | 9029 | RSS: Main: 182931456 16:34:41 INFO - PROCESS | 9029 | 16:37:44 INFO - PROCESS | 9029 | Cycle 1(1): loaded http://localhost:59721/tests/dromaeo/cssquery-yui.html (next: http://localhost:59721/tests/dromaeo/cssquery-dojo.html) 16:37:44 INFO - PROCESS | 9029 | RSS: Main: 273174528 16:37:44 INFO - PROCESS | 9029 | 16:37:44 INFO - PROCESS | 9029 | __start_tp_report 16:37:44 INFO - PROCESS | 9029 | _x_x_mozilla_page_load 16:37:44 INFO - PROCESS | 9029 | _x_x_mozilla_page_load_details 16:37:44 INFO - PROCESS | 9029 | |i|pagename|runs| 16:37:44 INFO - PROCESS | 9029 | |0;dojo.html;3058.1590062732744;3078.1996680862967;3109.2071521761955;3109.8445077746105;3119.4696901526736;5173.301604283421;5239.816606418776;5239.816606418776;5305.204219367092;5354.089804733195;5292.920606190901;5307.65500242484;5331.147016477365;5371.060064488718;5424.945750542493;4815.494373090827;4848.587870031051;4855.5387238212315;4866.610671146309;4983.252512123175;4925.679830811004;4946.08497427977;4959.578435832958;4963.0322087192835;4970.850874473772;4694.29585562165;4726.810927562893;4737.076270127326;4895.681780684244;4926.45808961014;4772.73749943753;4780.856574302777;4790.976045119752;4791.496892826272;4792.376990991149;4676.321933319684;4739.454962679267;4751.619870410359;4773.307870358779;4776.283557466373;5113.360829896263;5122.897542049138;5175.197844334134;5202.817901373472;5234.581233501349;5559.110542313251;5601.943980560183;5613.691246981414;5616.747246373882;5621.915671264935;5438.3473983121885;5536.529395001429;5574.414686457944;5655.717214139277;5679.7160141993145;5247.291615631879;5254.448282930237;5268.9736551317;5278.313819203479;5293.550048245942;5599.524040456607;5738.799142030009;5791.247137872134;5794.304683438015;5796.42035796417;5484.232207490954;5513.338399392099;5524.861878453071;5599.552035837123;5603.551715862721;5624.29696287964;5668.348139963957;5672.149177623307;5677.460641239076;5681.829545113653;5423.105187643994;5596.356419011866;5605;5610.7194640268635;5616.550675945915;5640.746166422521;5643.294588176478;5668.206451096849;5705.942940570541;5760.049591817387;4395.077033822869;4396.4064851244675;4403.713758605744;4409.4488188976375;4447.399601053817;3822.9235415292096;3902.0635047588944;3902.6292502212245;3917.6865850731874;3968.980155099206;5321.068812957717;5396.595255355864;5424.077906755782;5462.945370546244;5484.506394424521;5638.210650508932;5638.35158956717;5647.237622920853;5678.574106941914;5695.886082278331;5391.865203369947;5430.266913966564;5438.157085651651;5443.5100840923715;5499.945000550023;4810.831620893322;4821.662483626113;4826.6621336506105;4830.565249127525;4908;5902.704864756745;5909.970450147808;5912.73392697321;5921.171036055042;5922.7334769936315;5674.687892165805;5681.289838770153;5684.317881854038;5687.9431205687415;5688.63023903445;5638.464345887134;5862.091375836752;5888.411158884249;5901.793437229677;5986.730597123053;5530.640508366943;5568.276124103841;5580.637258578179;5581.386047534849;5587.329520457572;5664.5751568631695;5668.914966275427;5673.631213971079;5676.34722006958;5688.914666279927;5369.543588794996;5430.484104010113;5476.397596264328;5493.23094766725;5570.749316280696;5527.088030474926;5530.253415788817;5531.308586426697;5532.723363831873;5541.085720856014;5413.53984911287;5431.4840090191365;5446.645968012067;5455.099908515209;5519.972400137974;5434.3207099112615;5475.4250803665045;5503.449655034465;5525.419830917856;5616.213730077874;5414.83755487336;5447.972760136174;5449.1826226066405;5459.617826752089;5462.0441422751655 16:37:44 INFO - PROCESS | 9029 | |1;ext.html;601.5247954116248;604.3744724210443;610.2676787854575;612.6109920200671;632.9715162817671;43031.78484107579;44368.55631443684;44448.77775611117;44466.555334446646;47801.52198478014;45406;45960.3105953411;46012.769936150355;46055.53944460563;46060.769696151474;9013.909860901405;9158.40470369424;9163.175314221719;9163.679271225508;9166.22087122592;8993;9063.773405664846;9081.636734530612;9082;9089.091090890925;9099.272058235325;9101.95449022754;9133.17801397874;9136.269098472107;9138.497382643953;9066.320025998044;9076.954615226881;9102.63589456421;9111.088891110901;9111.72664820053;8952.283817294667;9038.954805225998;9048.366614337;9048.457092574401;9051.728448146567;42822.14355712899;43058.13883722308;43123.568764312266;43139.35290970638;43174.78412607917;3710.9443358349645;3754.755940863835;3907.7459965102175;3917.275304068756;4340.7178533395545;33276;33777.831110844534;33828.32343353144;34229.14427139317;34240;4219.556946520633;4257.297545904922;4286.39990401344;4305.461817272841;4352.36890650857;339396;340266.2986685051;341906;342848;343273;57867.131993020135;57883;58033.419665803645;58289.70855145697;58318.125228121615;60452.697736511036;60453;60475;60578;60591.69704151539;15517.224138793015;15784.526464206092;15816.28826702801;17125.314987400365;17672.823271767375;12610.11729178967;13143.60569182926;13515.729685406237;13529.932350338186;13811.582972979688;16200.918995404947;16245.837541624669;16266.105364205083;16280.348786048426;16388.180590970405;6468.417842394207;6583.539152259392;6622.437092847162;6649.73401063952;6720.227173875067;784.9882251766228;947.3084648206848;1023.3092662452815;1052.2476429352896;1122.7529943412437;31561.21096972594;31996.68003319937;32286.83856580749;32304.35391292161;32476;1108.4346983038708;1119.4234968990977;1125.2179735084037;1153.106342584487;1199.9880001199876;16748.665026699397;16880.577985550215;16890.155492225338;16901.492955211364;17082.743758843626;463841;467409;468096;470993;471488;58945.115823262684;58989.70505147447;59163;59258;59313;1252.8997680185564;1305.3929922586044;1312.173330801608;1375.6010756880594;1402.4530433131088;30933.226669333446;33689;34391.45238464225;34554;34757.422157856425;52308.21537677014;53358.19962700485;53385.466145339604;53486.1977070352;53566.46433535615;470783;474672;475120;475519;476272;43379.78310108429;44745;44777.77611111924;47320;48562.27156592583;49146.75426622844;49997.250041248684;50070.499295006586;50430.747846260536;50675.23987140269;41546.16907661769;41836.79081604572;42149.15284742171;42734.358984614635;44167.116657667306;41940.161196776506;42148;42310.36534451924;42873.571264286955;43581.78209108934;41459.1708165829;42492.78753606212;43040.139197215256;43153.35269971016;45011;34116;37944.620553794106;38148.23703525969;38312;39073.60926390813;28662.283442914093;28911.710882891744;29249.7075029247;29325.120246392642;29429.558556622094 16:37:44 INFO - PROCESS | 9029 | |2;jquery.html;350.4515433346814;386.7640739149119;398.32285115303984;400.74752905669425;419.664268585132;16950.321987120515;19443;19445.41663750086;19723.1124599393;20433.284835030776;19837.20651173955;19965.500862478446;20362.083706233218;20500.077496512655;20547;4924.827631032915;5614.635048721841;5785.710714464281;5839.328477225131;5845.824625261249;4226.535081141072;5736.340320863092;6150.846228844292;6411.038344248377;6467.644279564622;6120.63276203427;6426.7750628728;6436.420722135007;6438.130852334945;6442.903356449657;6311.463525600329;6379.23449186096;6390.265119511269;6406.295307516216;6434.260060093103;5501.339839219319;5675.51758100562;5677.943220567824;5697.487226149667;5721.628094173865;24307.392315192086;24424.511509769705;24550.140745073837;24617.38456538583;24806.751932480805;2268.943276418086;2415.6014257647466;2416.2026531244824;2730.2901245676067;2787.512185367553;10417.166626669848;10420.374777513374;10422.270441069128;10983;11076.889231107747;1912.6174765047047;1955.8630895837298;1957.9510512237166;1962.7154062660977;2023.8077382648626;77902.22097779006;82526.58736706278;83059;84555.57722211411;85519.57240213822;11109.333439993627;11164;11231.26996745209;11346.829797553044;11477.196596238351;15702.293396797171;15718.764218536731;15753.369865205492;15757.212139392985;15772.447964321424;8092.878606820902;8192.467489613156;8263.793405164919;8293.252439778878;8447.493150410995;7840.41196910234;7947.761567152995;8115.553644549488;8145.389095817837;8178.182181781894;4735.573798358164;4827.565519103297;4850.83022094225;4917.65576409655;4975.776090075955;1744.97382539262;1825.5296809532908;1890.357278525308;1981.5343394302326;2051.3640771360924;923.5566927874657;982.729749318943;1025.5795123999123;1033.1373303291684;1037.5071840875523;18571.44285671432;19018.239270429305;19472.90263548673;19622.70565941512;19938;1001.1040119093516;1014.2038499777786;1040.458961340099;1046.267612671118;1106.8007758603528;9612.711618651452;10982.615608453665;11009.229353945148;11074.612388566362;11080.168987325982;459264;477115;508560;524624;531101;83214;88793;89384;91796;96352.51823740837;1014.8934361892043;1015.6140666546813;1022.99488502557;1023.4422239879284;1027.4503140819775;16696.33214671448;17241.56896077608;17596.91201543984;17636.294548217928;17924.731129032814;29597.704022959493;31183.220419489695;38317.616823831406;40007.79996100001;41429.378559321034;60233;60312.98435078141;60960.08559871694;62046.37953620406;62325.06512402227;7618.69525218985;7622.504537205064;8569.528675922884;8595.957020214859;8619.396642234984;13225.603231903058;13349.599512014654;13369.66575835573;13392;13728.862711373158;11385.829212561652;11697.649070527897;11835.763284734085;11915.880841191478;11944.820827687765;11108.61119860801;11196.160287978271;11493.138014648768;11662;11765.705857353634;11585.594504192313;11678.824817627912;11727.413629318671;11791.469383877575;11979.221350612183;7589.279018493235;7809.453338266267;8732.646439801842;9032.096790320915;9126.315526335418;11350.148738844455;11868.82196767032;11945;12110.515579376726;12201.51193952232 16:37:44 INFO - PROCESS | 9029 | |3;mootools.html;1306.719055403088;1465.7434948883947;1509.7508911029681;1647.373997880805;1788.8926664400128;8555.529445880475;8937.329700272481;9029.277657787377;9289.674861379854;9297.163255307038;9088.50013249271;9202.677906273282;9205.677801276957;9206.079392060807;9209.033051529594;2387.4747555537842;2412.6501657259687;2414.3843319953476;2414.396400899775;2415.577273977056;2190.9780902190932;2252.4706693926996;2262.615355389588;2267.036509483463;2268.5689718953345;2236.9664455033108;2244.5623103494827;2256.8420210585264;2392.234484964812;2405.278416475059;2201.9779802201933;2205.9779402206013;2206.4152999454996;2209.2930262315986;2280.3614987803276;2290.209877592228;2292.1404473322505;2292.403974966504;2292.747797742247;2294.4837411582425;8901.955490222572;9113.498757568332;9174.724758257264;9212.769680758034;9214.124658157465;5230;5426.294581704393;5429.348478182604;5439.646422982493;5443.265159203496;3297.50537419389;3314;3345.916352091193;3379.9155021124425;3388.5594872666643;977.6236149082583;983.8524221366781;990.1187942730975;997.6558087459815;1010.7422607235181;12270.938645306805;12616.621501354974;12635.93682031584;12955.417006234742;13103.14829536077;4519.525449827721;4633.606143477775;4755.262934245197;4760;4773.689710168828;9955.751106222258;10022.398656080659;10023.34848234877;10027.699169024942;10033.548490317953;5415.539679127318;5416.214648875891;5423.078076726887;5424.105022671294;5433.755481003364;4027.81874815634;4277.101808620225;4398.296272596369;4932.358793356841;5098.082345177903;3835.7314987951136;3851.2682590308314;3926.4895563577124;3959;3962.445257663929;1150.4362862197463;1174.653477224221;1182.846229990113;1185.6265276437894;1192.928424294545;860.7650360227694;962.6919385796477;974.741693451236;979.9755006124761;1045.2839804733783;6080.361562036011;6167.321594624587;6231.813045608639;6312.4534489050475;6363.9045414318825;447.1325628281189;451.23515640988865;452.7057412681717;497.2888769060206;497.39814824062563;3955.110100227426;4129.380592911028;4307.849225277163;4309.332053531708;4435.2016637005;11245.212835101463;11763.235389699643;11764.88235117655;11771.823422648667;11782.823257651142;11509.769804604028;11557.826632600518;11593.24643898144;11626.476808543635;11647.951684348449;8374.748757537283;8507.447015943944;8577.442466239674;8598.828023439497;8607.26838218746;6067.089936509559;6211.409916057967;6235.625862448268;6253.312135664983;6292.339304373158;10601.1519078472;10653.520591573242;10679.305845120043;10718.785624287315;10782.622608208674;10479.423631700025;10569.788604227719;10768.461576920967;10852.67441976742;10916.945415272872;7737.806554835949;7828.256315650005;7829.765107046797;7836.608169591612;7840.764777056697;8754.430961987706;8861.202491775772;8868.64525418976;8929.910700893168;8973.147550982647;7750.534967901944;7852.528848269122;7927;7933.761987140395;7976.082750483769;7634.160242373224;7842.372610191285;7921.762347129596;7960.761177164694;7963.442559020813;7325.706971721284;7331.963340183265;7374.373178279691;7379.520331178671;7382.335589796944;5282.735863206901;5363.356397232357;5413.431589683184;5486.753096110764;5684.971575142098;5295.735213239245;5334.333208348957;5424.810131645373;5505.366882808528;5677.659340439587 16:37:44 INFO - PROCESS | 9029 | |4;prototype.html;298.08042189844343;308.3539983734077;314.363414086475;315.4526895835725;318.6781350835657;5190.792368305268;5220.817271395502;5252.264682944391;5291.92062119068;5292.650374154595;4905.656604037718;5021.322121513604;5034.421041580219;5047.823326183584;5156.845294641157;3936.940945885814;4017.3973903914352;4049.655779258752;4060.4112403701447;4116.732412393185;4087.1417002429375;4101.200265948141;4101.589841015904;4108.36320370343;4132.462779838616;3824.2733880562605;3989.8204580793936;4060.152630553091;4066.552679205285;4121.1757648470275;4031.717779755418;4032.979835100835;4037.4347591337237;4044.7573145611063;4066.227416790815;3586.444101164324;3587.695045921074;3590.263995880838;3592.371335016388;3604.3331983582857;4829.879253018703;5124.205748109011;5140.588752899759;5153.948460515385;5155.32980712505;4563.155816174019;4579.564941330568;4589.862304130881;4598.344595945457;4601.723896566216;5399.8390346075785;5439.564834813205;5445.863853403657;5467.398586155519;5487.42382049883;3834.7566673495758;3897.4348719435807;3898.707596930213;3908.882733517999;3916.725829191958;5568.1647752837;5568.888622227532;5577.191307260465;5585.944140558583;5644.57665675076;3849.8845034648443;3861.691064714816;3873.7288389813007;3890.7471014383973;3901.648851603369;3940.625640564142;3944.2703099926607;3945.191235796655;3950.9604903951167;3951.980240098781;4385.934210986838;4474.709143905636;4483.282674772021;4496.526910199377;4557.612602928788;5363.2759577456545;5375;5382.650127741685;5394.811181608625;5401.1358182691365;1296.857345691973;1385.88220001301;1397.3991183790865;1398.4895513137835;1408.2536255784246;931.888173419194;943.099340130174;956.8469044952774;962.6823148361024;967.4727273635885;5276.182191760283;5277.86525896934;5285.656432331886;5305.946940530545;5307.150855863043;5485.588580856452;5491.807786727525;5493.752781124859;5547.583931205095;5561.63849349791;5124.8206312778875;5331.173668081454;5339.919901201485;5349.598780091509;5362.302900622894;2931.3844092740765;2945.955810662842;2957.807742496731;2962.674105848355;2978.836164011;5703.315602127688;5742.310922689304;5779.624324418899;5847;5866.1494083357675;5652.293463317085;5653.830385088454;5656.85857853557;5667.773289068474;5672.602917795714;5765.625234359753;5789.334226563999;5790.420957904176;5806.806256558876;5842.094475356326;5425.782968681208;5508.697021663847;5563.610547261653;5566.192902029264;5588.86027849307;5524.944750552604;5525.392206857323;5544.695041772741;5547.667139971614;5556.638818476786;5622.353429355677;5770.336411312753;5853.912191317048;5860.977962602895;5864.328666330075;3998.060455792828;4031.778252196157;4035.878923632296;4039.2325458162854;4065.5160866283004;5627.352854421794;5627.549796016391;5630.690312032878;5632.85917852057;5648.519875810603;5329.626926115135;5373.382061062872;5467.25047984657;5510.448955104457;5514.09017512122;5358.97320513395;5374.355077390738;5377.919331210113;5391.973040134774;5395.622306438511;5128.487151284841;5270.28715667402;5270.759927031073;5325.467453254643;5327.360716714019;4897.804087836446;5334.013207556614;5342.9465705344;5430.266913966564;5460.808871689472;5345.812896548602;5353.170258609922;5355.598330125178;5357.491038351351;5362.65142765719 16:37:44 INFO - PROCESS | 9029 | |5;yui.html;722.2380388689934;749.3892477630732;753.4688044928326;759.9126100498445;793.4247670438932;13778.173309601418;14610.19643919584;14669.559913202607;14682;14691.485797997073;13705;13730.176189428616;13742.931285343559;13761.65595860104;13793.44826206953;642.4186111569037;766.2452484302958;787.0830482487895;793.5833687314149;802.6187560908551;798.2177466083242;809.2837838512929;809.6923169195697;809.7165991902816;810.0320117459638;862.3144600042981;863.223099210709;863.568215892054;863.5854789700948;863.9265662418702;689.2659317826524;823.8681810910225;843.3801156150224;858.9012263589674;859.9938072455211;617.1853153836937;618.9907151392732;619.1393962392278;619.6684773646123;619.7397093220858;12735.363231838372;12883.291418972047;12885.291308978003;12888.742225155444;12889.291088990101;12915.935420322932;13167.670808229776;13476.191428514321;13570.321483925864;13717.245551494663;11296.435178241056;11496.252743571724;11510.194286399956;11579.652610421701;11820.645380638594;3845.4616353710503;4237.63980061692;4244.278472659656;4246.5541118182455;4428.734275943454;76305.47389052188;76577;85699.57150214272;86718;86807;11333.43332833355;11336.546538138547;11384.373859437645;11566.94216528912;11596.594119205787;23165.652515212285;24293.514129717307;24475.510489790104;24576.631350529755;24591.385215369402;8375.371847111492;8451.154884511623;8466.957665211634;8483.660653573788;8739.475631462132;4816.518348165226;4827.08285425768;4907.362042934467;4956.058348913695;5026.572741316956;3586.30067136911;3724.031751744566;3735.34631439497;3736.6823819975607;3737.439384092408;582.1791274303212;588.1354409018736;605.1104875832519;628.1864984844638;636.2047440699126;710.2826145592988;727.959962202084;739.9630018499054;779.8401327727802;785.5522352259271;10136;10219.18246540275;10379.325343852626;10435.032532048852;10852.782944341068;684.7469131489363;697.9162500499871;755.6737186348595;792.8771040488733;806.6652339279251;4815.494373090775;4912.533309335608;5083.8983220335385;5127.333446651912;5145.562627176732;71925.64037179781;73977;116865.4156729228;127545;129499;64290.67854660697;65058.67470662617;101101.49449252707;101914;104674.95325046804;93301;94688.52655736677;95008.52495737477;96131.51934240284;100499;9751.648450232253;9757.707268781947;9941.859600600597;10462.94768526183;10532.89467105319;53596.732016339665;54431.45568544264;54522.45477545333;54622.726886365315;55039.44960550343;68661.65669171822;69809.95285070626;76696;79175;79265.414691707;13460.702127978708;13461.663458413617;13629.863701363256;13760.587182384144;13781.724365512835;15217.77173342423;15665;15698.686026279638;15853.52439426819;16084.517464476085;13654.180749155083;13677.726445471233;13750.931245343709;14229.64425889361;14336.641583960485;14057.297135142997;14598.029266048947;14923.328450219984;15167.924160379127;15202.543923682308;14326.713465730418;14423;14533.273336333352;14680.26598670041;15235.847641523444;11241.887581124083;11360.204785664924;11414.600488982845;11540.538378464767;11962.700932476757;11289.943550282196;11979;12194.390280485763;12267.75464490723;12389.194702344319 16:37:44 INFO - PROCESS | 9029 | __end_tp_report 16:37:44 INFO - PROCESS | 9029 | __start_cc_report 16:37:44 INFO - PROCESS | 9029 | _x_x_mozilla_cycle_collect,1065 16:37:44 INFO - PROCESS | 9029 | __end_cc_report 16:37:44 INFO - PROCESS | 9029 | __startTimestamp1470785864315__endTimestamp 16:37:44 INFO - PROCESS | 9029 | 16:37:44 INFO - PROCESS | 9029 | ------- Summary: start ------- 16:37:44 INFO - PROCESS | 9029 | Number of tests: 6 16:37:44 INFO - PROCESS | 9029 | 16:37:44 INFO - PROCESS | 9029 | [#0] dojo.html Cycles:165 Average:5256.38 Median:5449.18 stddev:582.92 (10.7%) stddev-sans-first:558.62 16:37:44 INFO - PROCESS | 9029 | Values: 3058.2 3078.2 3109.2 3109.8 3119.5 5173.3 5239.8 5239.8 5305.2 5354.1 5292.9 5307.7 5331.1 5371.1 5424.9 4815.5 4848.6 4855.5 4866.6 4983.3 4925.7 4946.1 4959.6 4963.0 4970.9 4694.3 4726.8 4737.1 4895.7 4926.5 4772.7 4780.9 4791.0 4791.5 4792.4 4676.3 4739.5 4751.6 4773.3 4776.3 5113.4 5122.9 5175.2 5202.8 5234.6 5559.1 5601.9 5613.7 5616.7 5621.9 5438.3 5536.5 5574.4 5655.7 5679.7 5247.3 5254.4 5269.0 5278.3 5293.6 5599.5 5738.8 5791.2 5794.3 5796.4 5484.2 5513.3 5524.9 5599.6 5603.6 5624.3 5668.3 5672.1 5677.5 5681.8 5423.1 5596.4 5605.0 5610.7 5616.6 5640.7 5643.3 5668.2 5705.9 5760.0 4395.1 4396.4 4403.7 4409.4 4447.4 3822.9 3902.1 3902.6 3917.7 3969.0 5321.1 5396.6 5424.1 5462.9 5484.5 5638.2 5638.4 5647.2 5678.6 5695.9 5391.9 5430.3 5438.2 5443.5 5499.9 4810.8 4821.7 4826.7 4830.6 4908.0 5902.7 5910.0 5912.7 5921.2 5922.7 5674.7 5681.3 5684.3 5687.9 5688.6 5638.5 5862.1 5888.4 5901.8 5986.7 5530.6 5568.3 5580.6 5581.4 5587.3 5664.6 5668.9 5673.6 5676.3 5688.9 5369.5 5430.5 5476.4 5493.2 5570.7 5527.1 5530.3 5531.3 5532.7 5541.1 5413.5 5431.5 5446.6 5455.1 5520.0 5434.3 5475.4 5503.4 5525.4 5616.2 5414.8 5448.0 5449.2 5459.6 5462.0 16:37:44 INFO - PROCESS | 9029 | 16:37:44 INFO - PROCESS | 9029 | [#1] ext.html Cycles:180 Average:60181.11 Median:4346.54 stddev:114243.59 (2628.4%) stddev-sans-first:114476.49 16:37:44 INFO - PROCESS | 9029 | Values: 601.5 604.4 610.3 612.6 633.0 43031.8 44368.6 44448.8 44466.6 47801.5 45406.0 45960.3 46012.8 46055.5 46060.8 9013.9 9158.4 9163.2 9163.7 9166.2 8993.0 9063.8 9081.6 9082.0 9089.1 9099.3 9102.0 9133.2 9136.3 9138.5 9066.3 9077.0 9102.6 9111.1 9111.7 8952.3 9039.0 9048.4 9048.5 9051.7 42822.1 43058.1 43123.6 43139.4 43174.8 3710.9 3754.8 3907.7 3917.3 4340.7 33276.0 33777.8 33828.3 34229.1 34240.0 4219.6 4257.3 4286.4 4305.5 4352.4 339396.0 340266.3 341906.0 342848.0 343273.0 57867.1 57883.0 58033.4 58289.7 58318.1 60452.7 60453.0 60475.0 60578.0 60591.7 15517.2 15784.5 15816.3 17125.3 17672.8 12610.1 13143.6 13515.7 13529.9 13811.6 16200.9 16245.8 16266.1 16280.3 16388.2 6468.4 6583.5 6622.4 6649.7 6720.2 785.0 947.3 1023.3 1052.2 1122.8 31561.2 31996.7 32286.8 32304.4 32476.0 1108.4 1119.4 1125.2 1153.1 1200.0 16748.7 16880.6 16890.2 16901.5 17082.7 463841.0 467409.0 468096.0 470993.0 471488.0 58945.1 58989.7 59163.0 59258.0 59313.0 1252.9 1305.4 1312.2 1375.6 1402.5 30933.2 33689.0 34391.5 34554.0 34757.4 52308.2 53358.2 53385.5 53486.2 53566.5 470783.0 474672.0 475120.0 475519.0 476272.0 43379.8 44745.0 44777.8 47320.0 48562.3 49146.8 49997.3 50070.5 50430.7 50675.2 41546.2 41836.8 42149.2 42734.4 44167.1 41940.2 42148.0 42310.4 42873.6 43581.8 41459.2 42492.8 43040.1 43153.4 45011.0 34116.0 37944.6 38148.2 38312.0 39073.6 28662.3 28911.7 29249.7 29325.1 29429.6 16:37:44 INFO - PROCESS | 9029 | 16:37:44 INFO - PROCESS | 9029 | [#2] jquery.html Cycles:180 Average:29359.08 Median:24365.95 stddev:82518.60 (338.7%) stddev-sans-first:82721.34 16:37:44 INFO - PROCESS | 9029 | Values: 350.5 386.8 398.3 400.7 419.7 16950.3 19443.0 19445.4 19723.1 20433.3 19837.2 19965.5 20362.1 20500.1 20547.0 4924.8 5614.6 5785.7 5839.3 5845.8 4226.5 5736.3 6150.8 6411.0 6467.6 6120.6 6426.8 6436.4 6438.1 6442.9 6311.5 6379.2 6390.3 6406.3 6434.3 5501.3 5675.5 5677.9 5697.5 5721.6 24307.4 24424.5 24550.1 24617.4 24806.8 2268.9 2415.6 2416.2 2730.3 2787.5 10417.2 10420.4 10422.3 10983.0 11076.9 1912.6 1955.9 1958.0 1962.7 2023.8 77902.2 82526.6 83059.0 84555.6 85519.6 11109.3 11164.0 11231.3 11346.8 11477.2 15702.3 15718.8 15753.4 15757.2 15772.4 8092.9 8192.5 8263.8 8293.3 8447.5 7840.4 7947.8 8115.6 8145.4 8178.2 4735.6 4827.6 4850.8 4917.7 4975.8 1745.0 1825.5 1890.4 1981.5 2051.4 923.6 982.7 1025.6 1033.1 1037.5 18571.4 19018.2 19472.9 19622.7 19938.0 1001.1 1014.2 1040.5 1046.3 1106.8 9612.7 10982.6 11009.2 11074.6 11080.2 459264.0 477115.0 508560.0 524624.0 531101.0 83214.0 88793.0 89384.0 91796.0 96352.5 1014.9 1015.6 1023.0 1023.4 1027.5 16696.3 17241.6 17596.9 17636.3 17924.7 29597.7 31183.2 38317.6 40007.8 41429.4 60233.0 60313.0 60960.1 62046.4 62325.1 7618.7 7622.5 8569.5 8596.0 8619.4 13225.6 13349.6 13369.7 13392.0 13728.9 11385.8 11697.6 11835.8 11915.9 11944.8 11108.6 11196.2 11493.1 11662.0 11765.7 11585.6 11678.8 11727.4 11791.5 11979.2 7589.3 7809.5 8732.6 9032.1 9126.3 11350.1 11868.8 11945.0 12110.5 12201.5 16:37:44 INFO - PROCESS | 9029 | 16:37:44 INFO - PROCESS | 9029 | [#3] mootools.html Cycles:180 Average:5959.52 Median:4766.84 stddev:3496.88 (73.4%) stddev-sans-first:3489.20 16:37:44 INFO - PROCESS | 9029 | Values: 1306.7 1465.7 1509.8 1647.4 1788.9 8555.5 8937.3 9029.3 9289.7 9297.2 9088.5 9202.7 9205.7 9206.1 9209.0 2387.5 2412.7 2414.4 2414.4 2415.6 2191.0 2252.5 2262.6 2267.0 2268.6 2237.0 2244.6 2256.8 2392.2 2405.3 2202.0 2206.0 2206.4 2209.3 2280.4 2290.2 2292.1 2292.4 2292.7 2294.5 8902.0 9113.5 9174.7 9212.8 9214.1 5230.0 5426.3 5429.3 5439.6 5443.3 3297.5 3314.0 3345.9 3379.9 3388.6 977.6 983.9 990.1 997.7 1010.7 12270.9 12616.6 12635.9 12955.4 13103.1 4519.5 4633.6 4755.3 4760.0 4773.7 9955.8 10022.4 10023.3 10027.7 10033.5 5415.5 5416.2 5423.1 5424.1 5433.8 4027.8 4277.1 4398.3 4932.4 5098.1 3835.7 3851.3 3926.5 3959.0 3962.4 1150.4 1174.7 1182.8 1185.6 1192.9 860.8 962.7 974.7 980.0 1045.3 6080.4 6167.3 6231.8 6312.5 6363.9 447.1 451.2 452.7 497.3 497.4 3955.1 4129.4 4307.8 4309.3 4435.2 11245.2 11763.2 11764.9 11771.8 11782.8 11509.8 11557.8 11593.2 11626.5 11648.0 8374.7 8507.4 8577.4 8598.8 8607.3 6067.1 6211.4 6235.6 6253.3 6292.3 10601.2 10653.5 10679.3 10718.8 10782.6 10479.4 10569.8 10768.5 10852.7 10916.9 7737.8 7828.3 7829.8 7836.6 7840.8 8754.4 8861.2 8868.6 8929.9 8973.1 7750.5 7852.5 7927.0 7933.8 7976.1 7634.2 7842.4 7921.8 7960.8 7963.4 7325.7 7332.0 7374.4 7379.5 7382.3 5282.7 5363.4 5413.4 5486.8 5685.0 5295.7 5334.3 5424.8 5505.4 5677.7 16:37:44 INFO - PROCESS | 9029 | 16:37:44 INFO - PROCESS | 9029 | [#4] prototype.html Cycles:180 Average:4571.35 Median:5288.79 stddev:1346.31 (25.5%) stddev-sans-first:1311.33 16:37:44 INFO - PROCESS | 9029 | Values: 298.1 308.4 314.4 315.5 318.7 5190.8 5220.8 5252.3 5291.9 5292.7 4905.7 5021.3 5034.4 5047.8 5156.8 3936.9 4017.4 4049.7 4060.4 4116.7 4087.1 4101.2 4101.6 4108.4 4132.5 3824.3 3989.8 4060.2 4066.6 4121.2 4031.7 4033.0 4037.4 4044.8 4066.2 3586.4 3587.7 3590.3 3592.4 3604.3 4829.9 5124.2 5140.6 5153.9 5155.3 4563.2 4579.6 4589.9 4598.3 4601.7 5399.8 5439.6 5445.9 5467.4 5487.4 3834.8 3897.4 3898.7 3908.9 3916.7 5568.2 5568.9 5577.2 5585.9 5644.6 3849.9 3861.7 3873.7 3890.7 3901.6 3940.6 3944.3 3945.2 3951.0 3952.0 4385.9 4474.7 4483.3 4496.5 4557.6 5363.3 5375.0 5382.7 5394.8 5401.1 1296.9 1385.9 1397.4 1398.5 1408.3 931.9 943.1 956.8 962.7 967.5 5276.2 5277.9 5285.7 5305.9 5307.2 5485.6 5491.8 5493.8 5547.6 5561.6 5124.8 5331.2 5339.9 5349.6 5362.3 2931.4 2946.0 2957.8 2962.7 2978.8 5703.3 5742.3 5779.6 5847.0 5866.1 5652.3 5653.8 5656.9 5667.8 5672.6 5765.6 5789.3 5790.4 5806.8 5842.1 5425.8 5508.7 5563.6 5566.2 5588.9 5524.9 5525.4 5544.7 5547.7 5556.6 5622.4 5770.3 5853.9 5861.0 5864.3 3998.1 4031.8 4035.9 4039.2 4065.5 5627.4 5627.5 5630.7 5632.9 5648.5 5329.6 5373.4 5467.3 5510.4 5514.1 5359.0 5374.4 5377.9 5392.0 5395.6 5128.5 5270.3 5270.8 5325.5 5327.4 4897.8 5334.0 5342.9 5430.3 5460.8 5345.8 5353.2 5355.6 5357.5 5362.7 16:37:44 INFO - PROCESS | 9029 | 16:37:44 INFO - PROCESS | 9029 | [#5] yui.html Cycles:180 Average:20841.78 Median:4240.96 stddev:29761.46 (701.8%) stddev-sans-first:29806.60 16:37:44 INFO - PROCESS | 9029 | Values: 722.2 749.4 753.5 759.9 793.4 13778.2 14610.2 14669.6 14682.0 14691.5 13705.0 13730.2 13742.9 13761.7 13793.4 642.4 766.2 787.1 793.6 802.6 798.2 809.3 809.7 809.7 810.0 862.3 863.2 863.6 863.6 863.9 689.3 823.9 843.4 858.9 860.0 617.2 619.0 619.1 619.7 619.7 12735.4 12883.3 12885.3 12888.7 12889.3 12915.9 13167.7 13476.2 13570.3 13717.2 11296.4 11496.3 11510.2 11579.7 11820.6 3845.5 4237.6 4244.3 4246.6 4428.7 76305.5 76577.0 85699.6 86718.0 86807.0 11333.4 11336.5 11384.4 11566.9 11596.6 23165.7 24293.5 24475.5 24576.6 24591.4 8375.4 8451.2 8467.0 8483.7 8739.5 4816.5 4827.1 4907.4 4956.1 5026.6 3586.3 3724.0 3735.3 3736.7 3737.4 582.2 588.1 605.1 628.2 636.2 710.3 728.0 740.0 779.8 785.6 10136.0 10219.2 10379.3 10435.0 10852.8 684.7 697.9 755.7 792.9 806.7 4815.5 4912.5 5083.9 5127.3 5145.6 71925.6 73977.0 116865.4 127545.0 129499.0 64290.7 65058.7 101101.5 101914.0 104675.0 93301.0 94688.5 95008.5 96131.5 100499.0 9751.6 9757.7 9941.9 10462.9 10532.9 53596.7 54431.5 54522.5 54622.7 55039.4 68661.7 69810.0 76696.0 79175.0 79265.4 13460.7 13461.7 13629.9 13760.6 13781.7 15217.8 15665.0 15698.7 15853.5 16084.5 13654.2 13677.7 13750.9 14229.6 14336.6 14057.3 14598.0 14923.3 15167.9 15202.5 14326.7 14423.0 14533.3 14680.3 15235.8 11241.9 11360.2 11414.6 11540.5 11962.7 11289.9 11979.0 12194.4 12267.8 12389.2 16:37:44 INFO - PROCESS | 9029 | -------- Summary: end -------- 16:37:44 INFO - PROCESS | 9029 | 16:37:45 INFO - TEST-INFO | 9029: exit 0 16:37:45 INFO - TEST-OK | dromaeo_css | took 1115201ms 16:37:45 INFO - TEST-START | kraken 16:37:45 INFO - operating with platform_type : linux_ 16:37:45 INFO - Initialising browser for kraken test... 16:37:45 INFO - TEST-INFO | started process 9216 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1SGJih/profile http://localhost:59721/getInfo.html) 16:37:49 INFO - PROCESS | 9216 | __metrics Screen width/height:1600/1200 16:37:49 INFO - PROCESS | 9216 | colorDepth:24 16:37:49 INFO - PROCESS | 9216 | Browser inner width/height: 1024/697 16:37:49 INFO - PROCESS | 9216 | __metrics 16:37:49 INFO - PROCESS | 9216 | 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" 16:37:49 INFO - PROCESS | 9216 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:37:50 INFO - PROCESS | 9216 | console.error: PushService: 16:37:50 INFO - PROCESS | 9216 | stateChangeProcessEnqueue: Error transitioning state 16:37:50 INFO - PROCESS | 9216 | UnknownError 16:37:50 INFO - TEST-INFO | 9216: exit 0 16:37:50 INFO - Browser initialized. 16:37:50 INFO - Running cycle 1/1 for kraken test... 16:37:50 INFO - TEST-INFO | started process 9298 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1SGJih/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:38:02 INFO - PROCESS | 9298 | RSS: Main: 155283456 16:38:02 INFO - PROCESS | 9298 | 16:38:05 INFO - PROCESS | 9298 | Cycle 1(1): loaded http://localhost:59721/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59721/tests/kraken/driver.html?testName=audio-beat-detection) 16:38:06 INFO - PROCESS | 9298 | RSS: Main: 212357120 16:38:06 INFO - PROCESS | 9298 | 16:38:08 INFO - PROCESS | 9298 | Cycle 1(1): loaded http://localhost:59721/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59721/tests/kraken/driver.html?testName=audio-dft) 16:38:09 INFO - PROCESS | 9298 | RSS: Main: 191520768 16:38:09 INFO - PROCESS | 9298 | 16:38:12 INFO - PROCESS | 9298 | Cycle 1(1): loaded http://localhost:59721/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59721/tests/kraken/driver.html?testName=audio-fft) 16:38:13 INFO - PROCESS | 9298 | RSS: Main: 269058048 16:38:13 INFO - PROCESS | 9298 | 16:38:15 INFO - PROCESS | 9298 | Cycle 1(1): loaded http://localhost:59721/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59721/tests/kraken/driver.html?testName=audio-oscillator) 16:38:15 INFO - PROCESS | 9298 | RSS: Main: 178827264 16:38:15 INFO - PROCESS | 9298 | 16:38:18 INFO - PROCESS | 9298 | Cycle 1(1): loaded http://localhost:59721/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59721/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:38:18 INFO - PROCESS | 9298 | RSS: Main: 182657024 16:38:18 INFO - PROCESS | 9298 | 16:38:21 INFO - PROCESS | 9298 | Cycle 1(1): loaded http://localhost:59721/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59721/tests/kraken/driver.html?testName=imaging-darkroom) 16:38:22 INFO - PROCESS | 9298 | RSS: Main: 218439680 16:38:22 INFO - PROCESS | 9298 | 16:38:25 INFO - PROCESS | 9298 | Cycle 1(1): loaded http://localhost:59721/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59721/tests/kraken/driver.html?testName=imaging-desaturate) 16:38:26 INFO - PROCESS | 9298 | RSS: Main: 218648576 16:38:26 INFO - PROCESS | 9298 | 16:38:28 INFO - PROCESS | 9298 | Cycle 1(1): loaded http://localhost:59721/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59721/tests/kraken/driver.html?testName=json-parse-financial) 16:38:29 INFO - PROCESS | 9298 | RSS: Main: 218132480 16:38:29 INFO - PROCESS | 9298 | 16:38:31 INFO - PROCESS | 9298 | Cycle 1(1): loaded http://localhost:59721/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59721/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:38:31 INFO - PROCESS | 9298 | RSS: Main: 183078912 16:38:31 INFO - PROCESS | 9298 | 16:38:33 INFO - PROCESS | 9298 | Cycle 1(1): loaded http://localhost:59721/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59721/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:38:34 INFO - PROCESS | 9298 | RSS: Main: 190328832 16:38:34 INFO - PROCESS | 9298 | 16:38:35 INFO - PROCESS | 9298 | Cycle 1(1): loaded http://localhost:59721/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59721/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:38:36 INFO - PROCESS | 9298 | RSS: Main: 193413120 16:38:36 INFO - PROCESS | 9298 | 16:38:39 INFO - PROCESS | 9298 | Cycle 1(1): loaded http://localhost:59721/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59721/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:38:39 INFO - PROCESS | 9298 | RSS: Main: 188477440 16:38:39 INFO - PROCESS | 9298 | 16:38:42 INFO - PROCESS | 9298 | Cycle 1(1): loaded http://localhost:59721/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59721/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:38:43 INFO - PROCESS | 9298 | RSS: Main: 191021056 16:38:43 INFO - PROCESS | 9298 | 16:38:44 INFO - PROCESS | 9298 | Cycle 1(1): loaded http://localhost:59721/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59721/tests/kraken/driver.html?testName=ai-astar) 16:38:44 INFO - PROCESS | 9298 | RSS: Main: 242987008 16:38:45 INFO - PROCESS | 9298 | 16:38:45 INFO - PROCESS | 9298 | __start_tp_report 16:38:45 INFO - PROCESS | 9298 | _x_x_mozilla_page_load 16:38:45 INFO - PROCESS | 9298 | _x_x_mozilla_page_load_details 16:38:45 INFO - PROCESS | 9298 | |i|pagename|runs| 16:38:45 INFO - PROCESS | 9298 | |0;ai-astar;103;111;111;110;112;111;111;111;111;109 16:38:45 INFO - PROCESS | 9298 | |1;audio-beat-detection;147;118;118;172;122;117;145;123;154;115 16:38:45 INFO - PROCESS | 9298 | |2;audio-dft;164;161;161;160;160;159;162;159;161;160 16:38:45 INFO - PROCESS | 9298 | |3;audio-fft;74;75;74;108;76;74;74;76;102;75 16:38:45 INFO - PROCESS | 9298 | |4;audio-oscillator;85;85;139;83;84;83;121;83;83;131 16:38:45 INFO - PROCESS | 9298 | |5;imaging-gaussian-blur;117;116;116;116;116;117;117;116;117;116 16:38:45 INFO - PROCESS | 9298 | |6;imaging-darkroom;145;146;146;145;145;147;145;146;146;145 16:38:45 INFO - PROCESS | 9298 | |7;imaging-desaturate;95;95;96;95;96;95;95;95;96;95 16:38:45 INFO - PROCESS | 9298 | |8;json-parse-financial;50;50;50;50;50;49;53;51;49;50 16:38:45 INFO - PROCESS | 9298 | |9;json-stringify-tinderbox;60;60;59;59;60;59;59;60;59;60 16:38:45 INFO - PROCESS | 9298 | |10;stanford-crypto-aes;76;76;75;76;76;76;76;76;75;76 16:38:45 INFO - PROCESS | 9298 | |11;stanford-crypto-ccm;126;131;122;130;118;118;123;119;138;128 16:38:45 INFO - PROCESS | 9298 | |12;stanford-crypto-pbkdf2;162;160;163;158;159;160;161;161;158;158 16:38:45 INFO - PROCESS | 9298 | |13;stanford-crypto-sha256-iterative;65;64;65;68;65;63;62;62;62;65 16:38:45 INFO - PROCESS | 9298 | __end_tp_report 16:38:45 INFO - PROCESS | 9298 | __start_cc_report 16:38:45 INFO - PROCESS | 9298 | _x_x_mozilla_cycle_collect,1695 16:38:45 INFO - PROCESS | 9298 | __end_cc_report 16:38:45 INFO - PROCESS | 9298 | __startTimestamp1470785925000__endTimestamp 16:38:45 INFO - PROCESS | 9298 | 16:38:45 INFO - PROCESS | 9298 | ------- Summary: start ------- 16:38:45 INFO - PROCESS | 9298 | Number of tests: 14 16:38:45 INFO - PROCESS | 9298 | 16:38:45 INFO - PROCESS | 9298 | [#0] ai-astar Cycles:10 Average:110.00 Median:111.00 stddev:2.58 (2.3%) stddev-sans-first:0.83 16:38:45 INFO - PROCESS | 9298 | Values: 103.0 111.0 111.0 110.0 112.0 111.0 111.0 111.0 111.0 109.0 16:38:45 INFO - PROCESS | 9298 | 16:38:45 INFO - PROCESS | 9298 | [#1] audio-beat-detection Cycles:10 Average:133.10 Median:134.00 stddev:19.87 (14.8%) stddev-sans-first:20.43 16:38:45 INFO - PROCESS | 9298 | Values: 147.0 118.0 118.0 172.0 122.0 117.0 145.0 123.0 154.0 115.0 16:38:45 INFO - PROCESS | 9298 | 16:38:45 INFO - PROCESS | 9298 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:1.49 (0.9%) stddev-sans-first:1.00 16:38:45 INFO - PROCESS | 9298 | Values: 164.0 161.0 161.0 160.0 160.0 159.0 162.0 159.0 161.0 160.0 16:38:45 INFO - PROCESS | 9298 | 16:38:45 INFO - PROCESS | 9298 | [#3] audio-fft Cycles:10 Average:80.80 Median:74.50 stddev:12.86 (17.3%) stddev-sans-first:13.40 16:38:45 INFO - PROCESS | 9298 | Values: 74.0 75.0 74.0 108.0 76.0 74.0 74.0 76.0 102.0 75.0 16:38:45 INFO - PROCESS | 9298 | 16:38:45 INFO - PROCESS | 9298 | [#4] audio-oscillator Cycles:10 Average:97.70 Median:83.00 stddev:22.93 (27.6%) stddev-sans-first:23.86 16:38:45 INFO - PROCESS | 9298 | Values: 85.0 85.0 139.0 83.0 84.0 83.0 121.0 83.0 83.0 131.0 16:38:45 INFO - PROCESS | 9298 | 16:38:45 INFO - PROCESS | 9298 | [#5] imaging-gaussian-blur Cycles:10 Average:116.40 Median:116.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 16:38:45 INFO - PROCESS | 9298 | Values: 117.0 116.0 116.0 116.0 116.0 117.0 117.0 116.0 117.0 116.0 16:38:45 INFO - PROCESS | 9298 | 16:38:45 INFO - PROCESS | 9298 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.70 (0.5%) stddev-sans-first:0.71 16:38:45 INFO - PROCESS | 9298 | Values: 145.0 146.0 146.0 145.0 145.0 147.0 145.0 146.0 146.0 145.0 16:38:45 INFO - PROCESS | 9298 | 16:38:45 INFO - PROCESS | 9298 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 16:38:45 INFO - PROCESS | 9298 | Values: 95.0 95.0 96.0 95.0 96.0 95.0 95.0 95.0 96.0 95.0 16:38:45 INFO - PROCESS | 9298 | 16:38:45 INFO - PROCESS | 9298 | [#8] json-parse-financial Cycles:10 Average:50.20 Median:50.00 stddev:1.14 (2.3%) stddev-sans-first:1.20 16:38:45 INFO - PROCESS | 9298 | Values: 50.0 50.0 50.0 50.0 50.0 49.0 53.0 51.0 49.0 50.0 16:38:45 INFO - PROCESS | 9298 | 16:38:45 INFO - PROCESS | 9298 | [#9] json-stringify-tinderbox Cycles:10 Average:59.50 Median:60.00 stddev:0.53 (0.9%) stddev-sans-first:0.53 16:38:45 INFO - PROCESS | 9298 | Values: 60.0 60.0 59.0 59.0 60.0 59.0 59.0 60.0 59.0 60.0 16:38:45 INFO - PROCESS | 9298 | 16:38:45 INFO - PROCESS | 9298 | [#10] stanford-crypto-aes Cycles:10 Average:75.80 Median:76.00 stddev:0.42 (0.6%) stddev-sans-first:0.44 16:38:45 INFO - PROCESS | 9298 | Values: 76.0 76.0 75.0 76.0 76.0 76.0 76.0 76.0 75.0 76.0 16:38:45 INFO - PROCESS | 9298 | 16:38:45 INFO - PROCESS | 9298 | [#11] stanford-crypto-ccm Cycles:10 Average:125.30 Median:127.00 stddev:6.55 (5.2%) stddev-sans-first:6.94 16:38:45 INFO - PROCESS | 9298 | Values: 126.0 131.0 122.0 130.0 118.0 118.0 123.0 119.0 138.0 128.0 16:38:45 INFO - PROCESS | 9298 | 16:38:45 INFO - PROCESS | 9298 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.00 Median:160.50 stddev:1.76 (1.1%) stddev-sans-first:1.72 16:38:45 INFO - PROCESS | 9298 | Values: 162.0 160.0 163.0 158.0 159.0 160.0 161.0 161.0 158.0 158.0 16:38:45 INFO - PROCESS | 9298 | 16:38:45 INFO - PROCESS | 9298 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.10 Median:65.00 stddev:1.91 (2.9%) stddev-sans-first:2.00 16:38:45 INFO - PROCESS | 9298 | Values: 65.0 64.0 65.0 68.0 65.0 63.0 62.0 62.0 62.0 65.0 16:38:45 INFO - PROCESS | 9298 | -------- Summary: end -------- 16:38:45 INFO - PROCESS | 9298 | 16:38:45 INFO - TEST-INFO | 9298: exit 0 16:38:46 INFO - TEST-OK | kraken | took 60889ms 16:38:46 INFO - SUITE-END | took 1176s 16:38:46 INFO - Completed test suite (00:19:36) 16:38:46 INFO - javascript benchmark 16:38:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3058.1590062732744, 3078.1996680862967, 3109.2071521761955, 3109.8445077746105, 3119.4696901526736, 5173.301604283421, 5239.816606418776, 5239.816606418776, 5305.204219367092, 5354.089804733195, 5292.920606190901, 5307.65500242484, 5331.147016477365, 5371.060064488718, 5424.945750542493, 4815.494373090827, 4848.587870031051, 4855.5387238212315, 4866.610671146309, 4983.252512123175, 4925.679830811004, 4946.08497427977, 4959.578435832958, 4963.0322087192835, 4970.850874473772, 4694.29585562165, 4726.810927562893, 4737.076270127326, 4895.681780684244, 4926.45808961014, 4772.73749943753, 4780.856574302777, 4790.976045119752, 4791.496892826272, 4792.376990991149, 4676.321933319684, 4739.454962679267, 4751.619870410359, 4773.307870358779, 4776.283557466373, 5113.360829896263, 5122.897542049138, 5175.197844334134, 5202.817901373472, 5234.581233501349, 5559.110542313251, 5601.943980560183, 5613.691246981414, 5616.747246373882, 5621.915671264935, 5438.3473983121885, 5536.529395001429, 5574.414686457944, 5655.717214139277, 5679.7160141993145, 5247.291615631879, 5254.448282930237, 5268.9736551317, 5278.313819203479, 5293.550048245942, 5599.524040456607, 5738.799142030009, 5791.247137872134, 5794.304683438015, 5796.42035796417, 5484.232207490954, 5513.338399392099, 5524.861878453071, 5599.552035837123, 5603.551715862721, 5624.29696287964, 5668.348139963957, 5672.149177623307, 5677.460641239076, 5681.829545113653, 5423.105187643994, 5596.356419011866, 5605.0, 5610.7194640268635, 5616.550675945915, 5640.746166422521, 5643.294588176478, 5668.206451096849, 5705.942940570541, 5760.049591817387, 4395.077033822869, 4396.4064851244675, 4403.713758605744, 4409.4488188976375, 4447.399601053817, 3822.9235415292096, 3902.0635047588944, 3902.6292502212245, 3917.6865850731874, 3968.980155099206, 5321.068812957717, 5396.595255355864, 5424.077906755782, 5462.945370546244, 5484.506394424521, 5638.210650508932, 5638.35158956717, 5647.237622920853, 5678.574106941914, 5695.886082278331, 5391.865203369947, 5430.266913966564, 5438.157085651651, 5443.5100840923715, 5499.945000550023, 4810.831620893322, 4821.662483626113, 4826.6621336506105, 4830.565249127525, 4908.0, 5902.704864756745, 5909.970450147808, 5912.73392697321, 5921.171036055042, 5922.7334769936315, 5674.687892165805, 5681.289838770153, 5684.317881854038, 5687.9431205687415, 5688.63023903445, 5638.464345887134, 5862.091375836752, 5888.411158884249, 5901.793437229677, 5986.730597123053, 5530.640508366943, 5568.276124103841, 5580.637258578179, 5581.386047534849, 5587.329520457572, 5664.5751568631695, 5668.914966275427, 5673.631213971079, 5676.34722006958, 5688.914666279927, 5369.543588794996, 5430.484104010113, 5476.397596264328, 5493.23094766725, 5570.749316280696, 5527.088030474926, 5530.253415788817, 5531.308586426697, 5532.723363831873, 5541.085720856014, 5413.53984911287, 5431.4840090191365, 5446.645968012067, 5455.099908515209, 5519.972400137974, 5434.3207099112615, 5475.4250803665045, 5503.449655034465, 5525.419830917856, 5616.213730077874, 5414.83755487336, 5447.972760136174, 5449.1826226066405, 5459.617826752089, 5462.0441422751655], "value": 5218.068920626663, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [601.5247954116248, 604.3744724210443, 610.2676787854575, 612.6109920200671, 632.9715162817671, 43031.78484107579, 44368.55631443684, 44448.77775611117, 44466.555334446646, 47801.52198478014, 45406.0, 45960.3105953411, 46012.769936150355, 46055.53944460563, 46060.769696151474, 9013.909860901405, 9158.40470369424, 9163.175314221719, 9163.679271225508, 9166.22087122592, 8993.0, 9063.773405664846, 9081.636734530612, 9082.0, 9089.091090890925, 9099.272058235325, 9101.95449022754, 9133.17801397874, 9136.269098472107, 9138.497382643953, 9066.320025998044, 9076.954615226881, 9102.63589456421, 9111.088891110901, 9111.72664820053, 8952.283817294667, 9038.954805225998, 9048.366614337, 9048.457092574401, 9051.728448146567, 42822.14355712899, 43058.13883722308, 43123.568764312266, 43139.35290970638, 43174.78412607917, 3710.9443358349645, 3754.755940863835, 3907.7459965102175, 3917.275304068756, 4340.7178533395545, 33276.0, 33777.831110844534, 33828.32343353144, 34229.14427139317, 34240.0, 4219.556946520633, 4257.297545904922, 4286.39990401344, 4305.461817272841, 4352.36890650857, 339396.0, 340266.2986685051, 341906.0, 342848.0, 343273.0, 57867.131993020135, 57883.0, 58033.419665803645, 58289.70855145697, 58318.125228121615, 60452.697736511036, 60453.0, 60475.0, 60578.0, 60591.69704151539, 15517.224138793015, 15784.526464206092, 15816.28826702801, 17125.314987400365, 17672.823271767375, 12610.11729178967, 13143.60569182926, 13515.729685406237, 13529.932350338186, 13811.582972979688, 16200.918995404947, 16245.837541624669, 16266.105364205083, 16280.348786048426, 16388.180590970405, 6468.417842394207, 6583.539152259392, 6622.437092847162, 6649.73401063952, 6720.227173875067, 784.9882251766228, 947.3084648206848, 1023.3092662452815, 1052.2476429352896, 1122.7529943412437, 31561.21096972594, 31996.68003319937, 32286.83856580749, 32304.35391292161, 32476.0, 1108.4346983038708, 1119.4234968990977, 1125.2179735084037, 1153.106342584487, 1199.9880001199876, 16748.665026699397, 16880.577985550215, 16890.155492225338, 16901.492955211364, 17082.743758843626, 463841.0, 467409.0, 468096.0, 470993.0, 471488.0, 58945.115823262684, 58989.70505147447, 59163.0, 59258.0, 59313.0, 1252.8997680185564, 1305.3929922586044, 1312.173330801608, 1375.6010756880594, 1402.4530433131088, 30933.226669333446, 33689.0, 34391.45238464225, 34554.0, 34757.422157856425, 52308.21537677014, 53358.19962700485, 53385.466145339604, 53486.1977070352, 53566.46433535615, 470783.0, 474672.0, 475120.0, 475519.0, 476272.0, 43379.78310108429, 44745.0, 44777.77611111924, 47320.0, 48562.27156592583, 49146.75426622844, 49997.250041248684, 50070.499295006586, 50430.747846260536, 50675.23987140269, 41546.16907661769, 41836.79081604572, 42149.15284742171, 42734.358984614635, 44167.116657667306, 41940.161196776506, 42148.0, 42310.36534451924, 42873.571264286955, 43581.78209108934, 41459.1708165829, 42492.78753606212, 43040.139197215256, 43153.35269971016, 45011.0, 34116.0, 37944.620553794106, 38148.23703525969, 38312.0, 39073.60926390813, 28662.283442914093, 28911.710882891744, 29249.7075029247, 29325.120246392642, 29429.558556622094], "value": 20608.136446159882, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.4515433346814, 386.7640739149119, 398.32285115303984, 400.74752905669425, 419.664268585132, 16950.321987120515, 19443.0, 19445.41663750086, 19723.1124599393, 20433.284835030776, 19837.20651173955, 19965.500862478446, 20362.083706233218, 20500.077496512655, 20547.0, 4924.827631032915, 5614.635048721841, 5785.710714464281, 5839.328477225131, 5845.824625261249, 4226.535081141072, 5736.340320863092, 6150.846228844292, 6411.038344248377, 6467.644279564622, 6120.63276203427, 6426.7750628728, 6436.420722135007, 6438.130852334945, 6442.903356449657, 6311.463525600329, 6379.23449186096, 6390.265119511269, 6406.295307516216, 6434.260060093103, 5501.339839219319, 5675.51758100562, 5677.943220567824, 5697.487226149667, 5721.628094173865, 24307.392315192086, 24424.511509769705, 24550.140745073837, 24617.38456538583, 24806.751932480805, 2268.943276418086, 2415.6014257647466, 2416.2026531244824, 2730.2901245676067, 2787.512185367553, 10417.166626669848, 10420.374777513374, 10422.270441069128, 10983.0, 11076.889231107747, 1912.6174765047047, 1955.8630895837298, 1957.9510512237166, 1962.7154062660977, 2023.8077382648626, 77902.22097779006, 82526.58736706278, 83059.0, 84555.57722211411, 85519.57240213822, 11109.333439993627, 11164.0, 11231.26996745209, 11346.829797553044, 11477.196596238351, 15702.293396797171, 15718.764218536731, 15753.369865205492, 15757.212139392985, 15772.447964321424, 8092.878606820902, 8192.467489613156, 8263.793405164919, 8293.252439778878, 8447.493150410995, 7840.41196910234, 7947.761567152995, 8115.553644549488, 8145.389095817837, 8178.182181781894, 4735.573798358164, 4827.565519103297, 4850.83022094225, 4917.65576409655, 4975.776090075955, 1744.97382539262, 1825.5296809532908, 1890.357278525308, 1981.5343394302326, 2051.3640771360924, 923.5566927874657, 982.729749318943, 1025.5795123999123, 1033.1373303291684, 1037.5071840875523, 18571.44285671432, 19018.239270429305, 19472.90263548673, 19622.70565941512, 19938.0, 1001.1040119093516, 1014.2038499777786, 1040.458961340099, 1046.267612671118, 1106.8007758603528, 9612.711618651452, 10982.615608453665, 11009.229353945148, 11074.612388566362, 11080.168987325982, 459264.0, 477115.0, 508560.0, 524624.0, 531101.0, 83214.0, 88793.0, 89384.0, 91796.0, 96352.51823740837, 1014.8934361892043, 1015.6140666546813, 1022.99488502557, 1023.4422239879284, 1027.4503140819775, 16696.33214671448, 17241.56896077608, 17596.91201543984, 17636.294548217928, 17924.731129032814, 29597.704022959493, 31183.220419489695, 38317.616823831406, 40007.79996100001, 41429.378559321034, 60233.0, 60312.98435078141, 60960.08559871694, 62046.37953620406, 62325.06512402227, 7618.69525218985, 7622.504537205064, 8569.528675922884, 8595.957020214859, 8619.396642234984, 13225.603231903058, 13349.599512014654, 13369.66575835573, 13392.0, 13728.862711373158, 11385.829212561652, 11697.649070527897, 11835.763284734085, 11915.880841191478, 11944.820827687765, 11108.61119860801, 11196.160287978271, 11493.138014648768, 11662.0, 11765.705857353634, 11585.594504192313, 11678.824817627912, 11727.413629318671, 11791.469383877575, 11979.221350612183, 7589.279018493235, 7809.453338266267, 8732.646439801842, 9032.096790320915, 9126.315526335418, 11350.148738844455, 11868.82196767032, 11945.0, 12110.515579376726, 12201.51193952232], "value": 9350.790855753265, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1306.719055403088, 1465.7434948883947, 1509.7508911029681, 1647.373997880805, 1788.8926664400128, 8555.529445880475, 8937.329700272481, 9029.277657787377, 9289.674861379854, 9297.163255307038, 9088.50013249271, 9202.677906273282, 9205.677801276957, 9206.079392060807, 9209.033051529594, 2387.4747555537842, 2412.6501657259687, 2414.3843319953476, 2414.396400899775, 2415.577273977056, 2190.9780902190932, 2252.4706693926996, 2262.615355389588, 2267.036509483463, 2268.5689718953345, 2236.9664455033108, 2244.5623103494827, 2256.8420210585264, 2392.234484964812, 2405.278416475059, 2201.9779802201933, 2205.9779402206013, 2206.4152999454996, 2209.2930262315986, 2280.3614987803276, 2290.209877592228, 2292.1404473322505, 2292.403974966504, 2292.747797742247, 2294.4837411582425, 8901.955490222572, 9113.498757568332, 9174.724758257264, 9212.769680758034, 9214.124658157465, 5230.0, 5426.294581704393, 5429.348478182604, 5439.646422982493, 5443.265159203496, 3297.50537419389, 3314.0, 3345.916352091193, 3379.9155021124425, 3388.5594872666643, 977.6236149082583, 983.8524221366781, 990.1187942730975, 997.6558087459815, 1010.7422607235181, 12270.938645306805, 12616.621501354974, 12635.93682031584, 12955.417006234742, 13103.14829536077, 4519.525449827721, 4633.606143477775, 4755.262934245197, 4760.0, 4773.689710168828, 9955.751106222258, 10022.398656080659, 10023.34848234877, 10027.699169024942, 10033.548490317953, 5415.539679127318, 5416.214648875891, 5423.078076726887, 5424.105022671294, 5433.755481003364, 4027.81874815634, 4277.101808620225, 4398.296272596369, 4932.358793356841, 5098.082345177903, 3835.7314987951136, 3851.2682590308314, 3926.4895563577124, 3959.0, 3962.445257663929, 1150.4362862197463, 1174.653477224221, 1182.846229990113, 1185.6265276437894, 1192.928424294545, 860.7650360227694, 962.6919385796477, 974.741693451236, 979.9755006124761, 1045.2839804733783, 6080.361562036011, 6167.321594624587, 6231.813045608639, 6312.4534489050475, 6363.9045414318825, 447.1325628281189, 451.23515640988865, 452.7057412681717, 497.2888769060206, 497.39814824062563, 3955.110100227426, 4129.380592911028, 4307.849225277163, 4309.332053531708, 4435.2016637005, 11245.212835101463, 11763.235389699643, 11764.88235117655, 11771.823422648667, 11782.823257651142, 11509.769804604028, 11557.826632600518, 11593.24643898144, 11626.476808543635, 11647.951684348449, 8374.748757537283, 8507.447015943944, 8577.442466239674, 8598.828023439497, 8607.26838218746, 6067.089936509559, 6211.409916057967, 6235.625862448268, 6253.312135664983, 6292.339304373158, 10601.1519078472, 10653.520591573242, 10679.305845120043, 10718.785624287315, 10782.622608208674, 10479.423631700025, 10569.788604227719, 10768.461576920967, 10852.67441976742, 10916.945415272872, 7737.806554835949, 7828.256315650005, 7829.765107046797, 7836.608169591612, 7840.764777056697, 8754.430961987706, 8861.202491775772, 8868.64525418976, 8929.910700893168, 8973.147550982647, 7750.534967901944, 7852.528848269122, 7927.0, 7933.761987140395, 7976.082750483769, 7634.160242373224, 7842.372610191285, 7921.762347129596, 7960.761177164694, 7963.442559020813, 7325.706971721284, 7331.963340183265, 7374.373178279691, 7379.520331178671, 7382.335589796944, 5282.735863206901, 5363.356397232357, 5413.431589683184, 5486.753096110764, 5684.971575142098, 5295.735213239245, 5334.333208348957, 5424.810131645373, 5505.366882808528, 5677.659340439587], "value": 4617.627710233399, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.08042189844343, 308.3539983734077, 314.363414086475, 315.4526895835725, 318.6781350835657, 5190.792368305268, 5220.817271395502, 5252.264682944391, 5291.92062119068, 5292.650374154595, 4905.656604037718, 5021.322121513604, 5034.421041580219, 5047.823326183584, 5156.845294641157, 3936.940945885814, 4017.3973903914352, 4049.655779258752, 4060.4112403701447, 4116.732412393185, 4087.1417002429375, 4101.200265948141, 4101.589841015904, 4108.36320370343, 4132.462779838616, 3824.2733880562605, 3989.8204580793936, 4060.152630553091, 4066.552679205285, 4121.1757648470275, 4031.717779755418, 4032.979835100835, 4037.4347591337237, 4044.7573145611063, 4066.227416790815, 3586.444101164324, 3587.695045921074, 3590.263995880838, 3592.371335016388, 3604.3331983582857, 4829.879253018703, 5124.205748109011, 5140.588752899759, 5153.948460515385, 5155.32980712505, 4563.155816174019, 4579.564941330568, 4589.862304130881, 4598.344595945457, 4601.723896566216, 5399.8390346075785, 5439.564834813205, 5445.863853403657, 5467.398586155519, 5487.42382049883, 3834.7566673495758, 3897.4348719435807, 3898.707596930213, 3908.882733517999, 3916.725829191958, 5568.1647752837, 5568.888622227532, 5577.191307260465, 5585.944140558583, 5644.57665675076, 3849.8845034648443, 3861.691064714816, 3873.7288389813007, 3890.7471014383973, 3901.648851603369, 3940.625640564142, 3944.2703099926607, 3945.191235796655, 3950.9604903951167, 3951.980240098781, 4385.934210986838, 4474.709143905636, 4483.282674772021, 4496.526910199377, 4557.612602928788, 5363.2759577456545, 5375.0, 5382.650127741685, 5394.811181608625, 5401.1358182691365, 1296.857345691973, 1385.88220001301, 1397.3991183790865, 1398.4895513137835, 1408.2536255784246, 931.888173419194, 943.099340130174, 956.8469044952774, 962.6823148361024, 967.4727273635885, 5276.182191760283, 5277.86525896934, 5285.656432331886, 5305.946940530545, 5307.150855863043, 5485.588580856452, 5491.807786727525, 5493.752781124859, 5547.583931205095, 5561.63849349791, 5124.8206312778875, 5331.173668081454, 5339.919901201485, 5349.598780091509, 5362.302900622894, 2931.3844092740765, 2945.955810662842, 2957.807742496731, 2962.674105848355, 2978.836164011, 5703.315602127688, 5742.310922689304, 5779.624324418899, 5847.0, 5866.1494083357675, 5652.293463317085, 5653.830385088454, 5656.85857853557, 5667.773289068474, 5672.602917795714, 5765.625234359753, 5789.334226563999, 5790.420957904176, 5806.806256558876, 5842.094475356326, 5425.782968681208, 5508.697021663847, 5563.610547261653, 5566.192902029264, 5588.86027849307, 5524.944750552604, 5525.392206857323, 5544.695041772741, 5547.667139971614, 5556.638818476786, 5622.353429355677, 5770.336411312753, 5853.912191317048, 5860.977962602895, 5864.328666330075, 3998.060455792828, 4031.778252196157, 4035.878923632296, 4039.2325458162854, 4065.5160866283004, 5627.352854421794, 5627.549796016391, 5630.690312032878, 5632.85917852057, 5648.519875810603, 5329.626926115135, 5373.382061062872, 5467.25047984657, 5510.448955104457, 5514.09017512122, 5358.97320513395, 5374.355077390738, 5377.919331210113, 5391.973040134774, 5395.622306438511, 5128.487151284841, 5270.28715667402, 5270.759927031073, 5325.467453254643, 5327.360716714019, 4897.804087836446, 5334.013207556614, 5342.9465705344, 5430.266913966564, 5460.808871689472, 5345.812896548602, 5353.170258609922, 5355.598330125178, 5357.491038351351, 5362.65142765719], "value": 4139.868667314664, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [722.2380388689934, 749.3892477630732, 753.4688044928326, 759.9126100498445, 793.4247670438932, 13778.173309601418, 14610.19643919584, 14669.559913202607, 14682.0, 14691.485797997073, 13705.0, 13730.176189428616, 13742.931285343559, 13761.65595860104, 13793.44826206953, 642.4186111569037, 766.2452484302958, 787.0830482487895, 793.5833687314149, 802.6187560908551, 798.2177466083242, 809.2837838512929, 809.6923169195697, 809.7165991902816, 810.0320117459638, 862.3144600042981, 863.223099210709, 863.568215892054, 863.5854789700948, 863.9265662418702, 689.2659317826524, 823.8681810910225, 843.3801156150224, 858.9012263589674, 859.9938072455211, 617.1853153836937, 618.9907151392732, 619.1393962392278, 619.6684773646123, 619.7397093220858, 12735.363231838372, 12883.291418972047, 12885.291308978003, 12888.742225155444, 12889.291088990101, 12915.935420322932, 13167.670808229776, 13476.191428514321, 13570.321483925864, 13717.245551494663, 11296.435178241056, 11496.252743571724, 11510.194286399956, 11579.652610421701, 11820.645380638594, 3845.4616353710503, 4237.63980061692, 4244.278472659656, 4246.5541118182455, 4428.734275943454, 76305.47389052188, 76577.0, 85699.57150214272, 86718.0, 86807.0, 11333.43332833355, 11336.546538138547, 11384.373859437645, 11566.94216528912, 11596.594119205787, 23165.652515212285, 24293.514129717307, 24475.510489790104, 24576.631350529755, 24591.385215369402, 8375.371847111492, 8451.154884511623, 8466.957665211634, 8483.660653573788, 8739.475631462132, 4816.518348165226, 4827.08285425768, 4907.362042934467, 4956.058348913695, 5026.572741316956, 3586.30067136911, 3724.031751744566, 3735.34631439497, 3736.6823819975607, 3737.439384092408, 582.1791274303212, 588.1354409018736, 605.1104875832519, 628.1864984844638, 636.2047440699126, 710.2826145592988, 727.959962202084, 739.9630018499054, 779.8401327727802, 785.5522352259271, 10136.0, 10219.18246540275, 10379.325343852626, 10435.032532048852, 10852.782944341068, 684.7469131489363, 697.9162500499871, 755.6737186348595, 792.8771040488733, 806.6652339279251, 4815.494373090775, 4912.533309335608, 5083.8983220335385, 5127.333446651912, 5145.562627176732, 71925.64037179781, 73977.0, 116865.4156729228, 127545.0, 129499.0, 64290.67854660697, 65058.67470662617, 101101.49449252707, 101914.0, 104674.95325046804, 93301.0, 94688.52655736677, 95008.52495737477, 96131.51934240284, 100499.0, 9751.648450232253, 9757.707268781947, 9941.859600600597, 10462.94768526183, 10532.89467105319, 53596.732016339665, 54431.45568544264, 54522.45477545333, 54622.726886365315, 55039.44960550343, 68661.65669171822, 69809.95285070626, 76696.0, 79175.0, 79265.414691707, 13460.702127978708, 13461.663458413617, 13629.863701363256, 13760.587182384144, 13781.724365512835, 15217.77173342423, 15665.0, 15698.686026279638, 15853.52439426819, 16084.517464476085, 13654.180749155083, 13677.726445471233, 13750.931245343709, 14229.64425889361, 14336.641583960485, 14057.297135142997, 14598.029266048947, 14923.328450219984, 15167.924160379127, 15202.543923682308, 14326.713465730418, 14423.0, 14533.273336333352, 14680.26598670041, 15235.847641523444, 11241.887581124083, 11360.204785664924, 11414.600488982845, 11540.538378464767, 11962.700932476757, 11289.943550282196, 11979.0, 12194.390280485763, 12267.75464490723, 12389.194702344319], "value": 7658.894091108625, "unit": "score"}], "name": "dromaeo_css", "value": 7266.680026717792, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 111.0, 111.0, 110.0, 112.0, 111.0, 111.0, 111.0, 111.0, 109.0], "value": 110.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 118.0, 118.0, 172.0, 122.0, 117.0, 145.0, 123.0, 154.0, 115.0], "value": 133.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 161.0, 161.0, 160.0, 160.0, 159.0, 162.0, 159.0, 161.0, 160.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 74.0, 108.0, 76.0, 74.0, 74.0, 76.0, 102.0, 75.0], "value": 80.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 139.0, 83.0, 84.0, 83.0, 121.0, 83.0, 83.0, 131.0], "value": 97.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 116.0, 116.0, 117.0, 117.0, 116.0, 117.0, 116.0], "value": 116.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 145.0, 145.0, 147.0, 145.0, 146.0, 146.0, 145.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 96.0, 95.0, 95.0, 95.0, 96.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 50.0, 50.0, 50.0, 49.0, 53.0, 51.0, 49.0, 50.0], "value": 50.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 59.0, 59.0, 60.0, 59.0, 59.0, 60.0, 59.0, 60.0], "value": 59.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 75.0, 76.0, 76.0, 76.0, 76.0, 76.0, 75.0, 76.0], "value": 75.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 131.0, 122.0, 130.0, 118.0, 118.0, 123.0, 119.0, 138.0, 128.0], "value": 125.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 163.0, 158.0, 159.0, 160.0, 161.0, 161.0, 158.0, 158.0], "value": 160.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 65.0, 68.0, 65.0, 63.0, 62.0, 62.0, 62.0, 65.0], "value": 64.1, "unit": "score"}], "name": "kraken", "value": 1474.4999999999998, "alertThreshold": 2.0}]} 16:38:46 INFO - Return code: 0 16:38:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:38:46 INFO - # TBPL SUCCESS # 16:38:46 INFO - Running post-action listener: _resource_record_post_action 16:38:46 INFO - [mozharness: 2016-08-09 23:38:46.290373Z] Finished run-tests step (success) 16:38:46 INFO - Running post-run listener: _resource_record_post_run 16:38:46 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 0; Write bytes: 555323392; Read time: 0; Write time: 507364 16:38:46 INFO - TinderboxPrint: CPU usage
13.0% 16:38:46 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 16:38:46 INFO - TinderboxPrint: I/O write bytes / time
555,323,392 / 507,364 16:38:46 INFO - TinderboxPrint: CPU idle
8,282.2 (87.0%) 16:38:46 INFO - TinderboxPrint: CPU user
1,195.8 (12.6%) 16:38:46 INFO - TinderboxPrint: Swap in / out
0 / 0 16:38:46 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 263208960; Read time: 0; Write time: 295080 16:38:46 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 0; Write bytes: 291590144; Read time: 0; Write time: 212200 16:38:46 INFO - Running post-run listener: _upload_blobber_files 16:38:46 INFO - Blob upload gear active. 16:38:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:38:46 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 16:38:46 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'] 16:38:46 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 16:38:47 INFO - (blobuploader) - INFO - Open directory for files ... 16:38:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:38:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:38:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:38:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:38:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:38:48 INFO - (blobuploader) - INFO - Done attempting. 16:38:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:38:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:38:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:38:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:38:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:38:51 INFO - (blobuploader) - INFO - Done attempting. 16:38:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:38:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:38:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:38:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:38:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:38:52 INFO - (blobuploader) - INFO - Done attempting. 16:38:52 INFO - (blobuploader) - INFO - Iteration through files over. 16:38:52 INFO - Return code: 0 16:38:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:38:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:38:52 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/97760510a16468279afd0c9ab1d0e3e19fa44533b989088913070aba3f478330f239951a74d256e3ee5e8fd2ea7fbe35051fa245f43f0161923e1cb71045dfb5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3c7623595c295f79335e50600f23139b4a093fda239c15583c3ce7e4f7b9dd87ef00463878cfa69e1434bd031557e7c7c1049f09ff8afd4fa65262ee85abb6d1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/25ed907506207e92a71269c25856bd32a31a332a24b123726ed21df7533bfb1692d934c8738c119a8341c59c20355967acc023af89fa90b21f8f96b6b9653a38"} 16:38:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:38:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:38:52 INFO - Contents: 16:38:52 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/97760510a16468279afd0c9ab1d0e3e19fa44533b989088913070aba3f478330f239951a74d256e3ee5e8fd2ea7fbe35051fa245f43f0161923e1cb71045dfb5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3c7623595c295f79335e50600f23139b4a093fda239c15583c3ce7e4f7b9dd87ef00463878cfa69e1434bd031557e7c7c1049f09ff8afd4fa65262ee85abb6d1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/25ed907506207e92a71269c25856bd32a31a332a24b123726ed21df7533bfb1692d934c8738c119a8341c59c20355967acc023af89fa90b21f8f96b6b9653a38"} 16:38:52 INFO - Running post-run listener: copy_logs_to_upload_dir 16:38:52 INFO - Copying logs to upload dir... 16:38:52 INFO - mkdir: /builds/slave/test/build/upload/logs 16:38:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1229.626077 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 29 secs) (at 2016-08-09 16:38:51.692109) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-09 16:38:51.696801) ========= 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-1470784700.188244-260802693 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/97760510a16468279afd0c9ab1d0e3e19fa44533b989088913070aba3f478330f239951a74d256e3ee5e8fd2ea7fbe35051fa245f43f0161923e1cb71045dfb5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3c7623595c295f79335e50600f23139b4a093fda239c15583c3ce7e4f7b9dd87ef00463878cfa69e1434bd031557e7c7c1049f09ff8afd4fa65262ee85abb6d1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/25ed907506207e92a71269c25856bd32a31a332a24b123726ed21df7533bfb1692d934c8738c119a8341c59c20355967acc023af89fa90b21f8f96b6b9653a38"} build_url:https://queue.taskcluster.net/v1/task/LwezZlkZQ6GBJF7meFL3YQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011524 build_url: 'https://queue.taskcluster.net/v1/task/LwezZlkZQ6GBJF7meFL3YQ/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/97760510a16468279afd0c9ab1d0e3e19fa44533b989088913070aba3f478330f239951a74d256e3ee5e8fd2ea7fbe35051fa245f43f0161923e1cb71045dfb5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3c7623595c295f79335e50600f23139b4a093fda239c15583c3ce7e4f7b9dd87ef00463878cfa69e1434bd031557e7c7c1049f09ff8afd4fa65262ee85abb6d1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/25ed907506207e92a71269c25856bd32a31a332a24b123726ed21df7533bfb1692d934c8738c119a8341c59c20355967acc023af89fa90b21f8f96b6b9653a38"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-09 16:38:51.722772) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 16:38:51.723141) ========= 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-1470784700.188244-260802693 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004427 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 16:38:51.807372) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-09 16:38:51.807722) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-09 16:38:51.808055) ========= ========= Total master_lag: 0.19 =========