builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-055 starttime: 1470889978.18 results: success (0) buildid: 20160810193023 builduid: f93bbfd2ac634dc79b2cf707de6b214e revision: 32e07e32891d070cdba97e2872cd30265278c0bf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-10 21:32:58.179634) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-10 21:32:58.180159) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-10 21:32:58.180468) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-10 21:32:58.195260) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 21:32:58.195614) ========= 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=bb9b54edd4a927dc735d112f000001d9-1470889977.506884-776553730 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-10 21:32:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 67.2M=0s 2016-08-10 21:32:58 (67.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.122887 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 21:32:58.332674) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 21:32:58.332990) ========= 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=bb9b54edd4a927dc735d112f000001d9-1470889977.506884-776553730 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015525 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 21:32:58.382421) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-10 21:32:58.382770) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 32e07e32891d070cdba97e2872cd30265278c0bf --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 32e07e32891d070cdba97e2872cd30265278c0bf --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=bb9b54edd4a927dc735d112f000001d9-1470889977.506884-776553730 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-10 21:32:58,480 truncating revision to first 12 chars 2016-08-10 21:32:58,480 Setting DEBUG logging. 2016-08-10 21:32:58,481 attempt 1/10 2016-08-10 21:32:58,481 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/32e07e32891d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-10 21:32:59,178 unpacking tar archive at: mozilla-inbound-32e07e32891d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.042059 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-10 21:32:59.438738) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-10 21:32:59.439072) ========= script_repo_revision: 32e07e32891d070cdba97e2872cd30265278c0bf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-10 21:32:59.439502) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-10 21:32:59.439783) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-10 21:32:59.448860) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2016-08-10 21:32:59.449259) ========= /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=bb9b54edd4a927dc735d112f000001d9-1470889977.506884-776553730 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:32:59 INFO - MultiFileLogger online at 20160810 21:32:59 in /builds/slave/test 21:32:59 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 21:32:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:32:59 INFO - {'append_to_log': False, 21:32:59 INFO - 'base_work_dir': '/builds/slave/test', 21:32:59 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 21:32:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:32:59 INFO - 'branch': 'Mozilla-Inbound', 21:32:59 INFO - 'buildbot_json_path': 'buildprops.json', 21:32:59 INFO - 'config_files': ('talos/linux_config.py',), 21:32:59 INFO - 'default_actions': ('clobber', 21:32:59 INFO - 'read-buildbot-config', 21:32:59 INFO - 'download-and-extract', 21:32:59 INFO - 'populate-webroot', 21:32:59 INFO - 'create-virtualenv', 21:32:59 INFO - 'install', 21:32:59 INFO - 'run-tests'), 21:32:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:32:59 INFO - 'download_minidump_stackwalk': True, 21:32:59 INFO - 'download_symbols': 'ondemand', 21:32:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:32:59 INFO - 'tooltool.py': '/tools/tooltool.py', 21:32:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:32:59 INFO - '/tools/misc-python/virtualenv.py')}, 21:32:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:32:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:32:59 INFO - 'installer_path': 'installer.exe', 21:32:59 INFO - 'log_level': 'info', 21:32:59 INFO - 'log_name': 'talos', 21:32:59 INFO - 'log_to_console': True, 21:32:59 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:32:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:32:59 INFO - 'opt_config_files': (), 21:32:59 INFO - 'pip_index': False, 21:32:59 INFO - 'sps_profile': False, 21:32:59 INFO - 'sps_profile_interval': 0, 21:32:59 INFO - 'suite': 'dromaeojs', 21:32:59 INFO - 'system_bits': '32', 21:32:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:32:59 INFO - 'title': 'talos-linux64-ix-055', 21:32:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:32:59 INFO - 'use_talos_json': True, 21:32:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:32:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:32:59 INFO - 'work_dir': 'build'} 21:32:59 INFO - [mozharness: 2016-08-11 04:32:59.634204Z] Running clobber step. 21:32:59 INFO - Running pre-action listener: _resource_record_pre_action 21:32:59 INFO - Running main action method: clobber 21:32:59 INFO - rmtree: /builds/slave/test/build 21:32:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:33:00 INFO - Running post-action listener: _resource_record_post_action 21:33:00 INFO - [mozharness: 2016-08-11 04:33:00.085321Z] Finished clobber step (success) 21:33:00 INFO - [mozharness: 2016-08-11 04:33:00.085422Z] Running read-buildbot-config step. 21:33:00 INFO - Running pre-action listener: _resource_record_pre_action 21:33:00 INFO - Running main action method: read_buildbot_config 21:33:00 INFO - Using buildbot properties: 21:33:00 INFO - { 21:33:00 INFO - "project": "", 21:33:00 INFO - "product": "firefox", 21:33:00 INFO - "script_repo_revision": "production", 21:33:00 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 21:33:00 INFO - "repository": "", 21:33:00 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 21:33:00 INFO - "buildid": "20160810193023", 21:33:00 INFO - "pgo_build": "True", 21:33:00 INFO - "basedir": "/builds/slave/test", 21:33:00 INFO - "buildnumber": 1386, 21:33:00 INFO - "slavename": "talos-linux64-ix-055", 21:33:00 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 21:33:00 INFO - "platform": "ubuntu64_hw", 21:33:00 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 21:33:00 INFO - "branch": "mozilla-inbound", 21:33:00 INFO - "repo_path": "integration/mozilla-inbound", 21:33:00 INFO - "revision": "32e07e32891d070cdba97e2872cd30265278c0bf", 21:33:00 INFO - "stage_platform": "linux64-pgo", 21:33:00 INFO - "builduid": "f93bbfd2ac634dc79b2cf707de6b214e", 21:33:00 INFO - "slavebuilddir": "test" 21:33:00 INFO - } 21:33:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/Eo7eWyG2RMi-qtK3nyhBmA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 21:33:00 INFO - Running post-action listener: _resource_record_post_action 21:33:00 INFO - [mozharness: 2016-08-11 04:33:00.087900Z] Finished read-buildbot-config step (success) 21:33:00 INFO - [mozharness: 2016-08-11 04:33:00.087991Z] Running download-and-extract step. 21:33:00 INFO - Running pre-action listener: _resource_record_pre_action 21:33:00 INFO - Running main action method: download_and_extract 21:33:00 INFO - mkdir: /builds/slave/test/build/tests 21:33:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:33:00 INFO - trying https://queue.taskcluster.net/v1/task/Eo7eWyG2RMi-qtK3nyhBmA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 21:33:00 INFO - Downloading https://queue.taskcluster.net/v1/task/Eo7eWyG2RMi-qtK3nyhBmA/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 21:33:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Eo7eWyG2RMi-qtK3nyhBmA/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 21:33:00 INFO - Downloaded 1361 bytes. 21:33:00 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 21:33:00 INFO - Using the following test package requirements: 21:33:00 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 21:33:00 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 21:33:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:33:00 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 21:33:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 21:33:00 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 21:33:00 INFO - u'jsshell-linux-x86_64.zip'], 21:33:00 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 21:33:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:33:00 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 21:33:00 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 21:33:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:33:00 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 21:33:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:33:00 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 21:33:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:33:00 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 21:33:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:33:00 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:33:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:33:00 INFO - trying https://queue.taskcluster.net/v1/task/Eo7eWyG2RMi-qtK3nyhBmA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 21:33:00 INFO - Downloading https://queue.taskcluster.net/v1/task/Eo7eWyG2RMi-qtK3nyhBmA/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 21:33:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Eo7eWyG2RMi-qtK3nyhBmA/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 21:33:01 INFO - Downloaded 21833907 bytes. 21:33:01 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'] 21:33:01 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 21:33:02 INFO - Return code: 0 21:33:02 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 21:33:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:33:02 INFO - trying https://queue.taskcluster.net/v1/task/Eo7eWyG2RMi-qtK3nyhBmA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 21:33:02 INFO - Downloading https://queue.taskcluster.net/v1/task/Eo7eWyG2RMi-qtK3nyhBmA/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 21:33:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Eo7eWyG2RMi-qtK3nyhBmA/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 21:33:03 INFO - Downloaded 21833907 bytes. 21:33:03 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'] 21:33:03 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 21:33:05 INFO - Return code: 0 21:33:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:33:05 INFO - trying https://queue.taskcluster.net/v1/task/Eo7eWyG2RMi-qtK3nyhBmA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 21:33:05 INFO - Downloading https://queue.taskcluster.net/v1/task/Eo7eWyG2RMi-qtK3nyhBmA/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 21:33:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Eo7eWyG2RMi-qtK3nyhBmA/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 21:33:06 INFO - Downloaded 14413719 bytes. 21:33:06 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'] 21:33:06 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 21:33:06 INFO - Return code: 0 21:33:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:33:06 INFO - trying https://queue.taskcluster.net/v1/task/Eo7eWyG2RMi-qtK3nyhBmA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 21:33:06 INFO - Downloading https://queue.taskcluster.net/v1/task/Eo7eWyG2RMi-qtK3nyhBmA/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 21:33:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Eo7eWyG2RMi-qtK3nyhBmA/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 21:33:07 INFO - Downloaded 59339792 bytes. 21:33:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Eo7eWyG2RMi-qtK3nyhBmA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 21:33:07 INFO - mkdir: /builds/slave/test/properties 21:33:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:33:07 INFO - Writing to file /builds/slave/test/properties/build_url 21:33:07 INFO - Contents: 21:33:07 INFO - build_url:https://queue.taskcluster.net/v1/task/Eo7eWyG2RMi-qtK3nyhBmA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 21:33:08 INFO - Running post-action listener: _resource_record_post_action 21:33:08 INFO - Running post-action listener: set_extra_try_arguments 21:33:08 INFO - [mozharness: 2016-08-11 04:33:08.316552Z] Finished download-and-extract step (success) 21:33:08 INFO - [mozharness: 2016-08-11 04:33:08.316787Z] Running populate-webroot step. 21:33:08 INFO - Running pre-action listener: _resource_record_pre_action 21:33:08 INFO - Running main action method: populate_webroot 21:33:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:33:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:33:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:33:08 INFO - '--tppagecycles', 21:33:08 INFO - '7'], 21:33:08 INFO - 'tests': ['tsvgm']}}, 21:33:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:33:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:33:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:33:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:33:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:33:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:33:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:33:08 INFO - 'talos_options': ['--disable-e10s'], 21:33:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:33:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:33:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:33:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:33:08 INFO - 'talos_options': ['--disable-e10s'], 21:33:08 INFO - 'tests': ['damp', 'tps']}, 21:33:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:33:08 INFO - 'tests': ['damp', 'tps']}, 21:33:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:33:08 INFO - 'tests': ['dromaeo_dom']}, 21:33:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:33:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:33:08 INFO - 'tests': ['basic_compositor_video']}, 21:33:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:33:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:33:08 INFO - 'tests': ['a11yr', 21:33:08 INFO - 'ts_paint', 21:33:08 INFO - 'tpaint', 21:33:08 INFO - 'sessionrestore', 21:33:08 INFO - 'sessionrestore_no_auto_restore', 21:33:08 INFO - 'tabpaint']}, 21:33:08 INFO - 'other-e10s': {'tests': ['a11yr', 21:33:08 INFO - 'ts_paint', 21:33:08 INFO - 'tpaint', 21:33:08 INFO - 'sessionrestore', 21:33:08 INFO - 'sessionrestore_no_auto_restore', 21:33:08 INFO - 'tabpaint']}, 21:33:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:33:08 INFO - 'ts_paint', 21:33:08 INFO - 'tpaint', 21:33:08 INFO - 'sessionrestore', 21:33:08 INFO - 'sessionrestore_no_auto_restore', 21:33:08 INFO - 'tabpaint']}, 21:33:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:33:08 INFO - 'ts_paint', 21:33:08 INFO - 'tpaint', 21:33:08 INFO - 'sessionrestore', 21:33:08 INFO - 'sessionrestore_no_auto_restore', 21:33:08 INFO - 'tabpaint']}, 21:33:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:33:08 INFO - 'tests': ['a11yr', 21:33:08 INFO - 'ts_paint', 21:33:08 INFO - 'tpaint', 21:33:08 INFO - 'sessionrestore', 21:33:08 INFO - 'sessionrestore_no_auto_restore', 21:33:08 INFO - 'tabpaint']}, 21:33:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:33:08 INFO - 'tests': ['a11yr', 21:33:08 INFO - 'ts_paint', 21:33:08 INFO - 'tpaint', 21:33:08 INFO - 'sessionrestore', 21:33:08 INFO - 'sessionrestore_no_auto_restore', 21:33:08 INFO - 'tabpaint']}, 21:33:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:33:08 INFO - 'tests': ['tsvgx', 21:33:08 INFO - 'tsvgr_opacity', 21:33:08 INFO - 'tart', 21:33:08 INFO - 'tscrollx', 21:33:08 INFO - 'cart']}, 21:33:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:33:08 INFO - 'tsvgr_opacity', 21:33:08 INFO - 'tart', 21:33:08 INFO - 'tscrollx', 21:33:08 INFO - 'cart']}, 21:33:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:33:08 INFO - 'talos_options': ['--disable-e10s'], 21:33:08 INFO - 'tests': ['tp5o']}, 21:33:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:33:08 INFO - 'tests': ['tp5o']}, 21:33:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:33:08 INFO - 'talos_options': ['--disable-e10s', 21:33:08 INFO - '--xperf_path', 21:33:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:33:08 INFO - 'tests': ['tp5n']}, 21:33:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:33:08 INFO - 'talos_options': ['--xperf_path', 21:33:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:33:08 INFO - 'tests': ['tp5n']}}, 21:33:08 INFO - 'talos.zip': {'path': '', 21:33:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:33:08 INFO - Running post-action listener: _resource_record_post_action 21:33:08 INFO - [mozharness: 2016-08-11 04:33:08.338424Z] Finished populate-webroot step (success) 21:33:08 INFO - [mozharness: 2016-08-11 04:33:08.338513Z] Running create-virtualenv step. 21:33:08 INFO - Running pre-action listener: _resource_record_pre_action 21:33:08 INFO - Running main action method: create_virtualenv 21:33:08 INFO - Creating virtualenv /builds/slave/test/build/venv 21:33:08 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 21:33:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:33:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:33:08 INFO - Using real prefix '/usr' 21:33:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:33:08 INFO - Installing distribute.............................................................................................................................................................................................done. 21:33:10 INFO - Installing pip.................done. 21:33:10 INFO - Return code: 0 21:33:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:33:10 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')]} 21:33:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:33:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:33:10 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')]} 21:33:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:33:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:33:10 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 0x21aa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x230d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x246b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24693b0>, '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 0x246a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x246a740>, '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': 'bb9b54edd4a927dc735d112f000001d9-1470889977.506884-776553730', '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 21:33:10 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 21:33:10 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 21:33:10 INFO - Using env: {'DISPLAY': ':0', 21:33:10 INFO - 'HOME': '/home/cltbld', 21:33:10 INFO - 'LANG': 'en_US.UTF-8', 21:33:10 INFO - 'LANGUAGE': 'en_US:en', 21:33:10 INFO - 'LOGNAME': 'cltbld', 21:33:10 INFO - 'MAIL': '/var/mail/cltbld', 21:33:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:33:10 INFO - 'MOZ_NO_REMOTE': '1', 21:33:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:33:10 INFO - 'NO_EM_RESTART': '1', 21:33:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:33:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:33:10 INFO - 'PWD': '/builds/slave/test', 21:33:10 INFO - 'SHELL': '/bin/bash', 21:33:10 INFO - 'SHLVL': '1', 21:33:10 INFO - 'TERM': 'linux', 21:33:10 INFO - 'TMOUT': '86400', 21:33:10 INFO - 'USER': 'cltbld', 21:33:10 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1470889977.506884-776553730', 21:33:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:33:10 INFO - '_': '/tools/buildbot/bin/python'} 21:33:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:33:11 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 21:33:11 INFO - Cleaning up... 21:33:11 INFO - Return code: 0 21:33:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:33:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:33:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:33:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:33:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:33:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:33:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:33:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21aa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x230d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x246b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24693b0>, '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 0x246a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x246a740>, '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': 'bb9b54edd4a927dc735d112f000001d9-1470889977.506884-776553730', '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 21:33:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 21:33:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 21:33:11 INFO - Using env: {'DISPLAY': ':0', 21:33:11 INFO - 'HOME': '/home/cltbld', 21:33:11 INFO - 'LANG': 'en_US.UTF-8', 21:33:11 INFO - 'LANGUAGE': 'en_US:en', 21:33:11 INFO - 'LOGNAME': 'cltbld', 21:33:11 INFO - 'MAIL': '/var/mail/cltbld', 21:33:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:33:11 INFO - 'MOZ_NO_REMOTE': '1', 21:33:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:33:11 INFO - 'NO_EM_RESTART': '1', 21:33:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:33:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:33:11 INFO - 'PWD': '/builds/slave/test', 21:33:11 INFO - 'SHELL': '/bin/bash', 21:33:11 INFO - 'SHLVL': '1', 21:33:11 INFO - 'TERM': 'linux', 21:33:11 INFO - 'TMOUT': '86400', 21:33:11 INFO - 'USER': 'cltbld', 21:33:11 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1470889977.506884-776553730', 21:33:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:33:11 INFO - '_': '/tools/buildbot/bin/python'} 21:33:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:33:11 INFO - Downloading/unpacking psutil>=3.1.1 21:33:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:33:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:33:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:33:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:33:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:33:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:33:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:33:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:33:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:33:13 INFO - Installing collected packages: psutil 21:33:13 INFO - Running setup.py install for psutil 21:33:13 INFO - building 'psutil._psutil_linux' extension 21:33:13 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 21:33:13 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 21:33:13 INFO - building 'psutil._psutil_posix' extension 21:33:13 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 21:33:13 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 21:33:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:33:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:33:13 INFO - Successfully installed psutil 21:33:13 INFO - Cleaning up... 21:33:13 INFO - Return code: 0 21:33:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:33:13 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')]} 21:33:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:33:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:33:13 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')]} 21:33:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:33:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:33:13 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 0x21aa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x230d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x246b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24693b0>, '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 0x246a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x246a740>, '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': 'bb9b54edd4a927dc735d112f000001d9-1470889977.506884-776553730', '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 21:33:13 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 21:33:13 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 21:33:13 INFO - Using env: {'DISPLAY': ':0', 21:33:13 INFO - 'HOME': '/home/cltbld', 21:33:13 INFO - 'LANG': 'en_US.UTF-8', 21:33:13 INFO - 'LANGUAGE': 'en_US:en', 21:33:13 INFO - 'LOGNAME': 'cltbld', 21:33:13 INFO - 'MAIL': '/var/mail/cltbld', 21:33:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:33:13 INFO - 'MOZ_NO_REMOTE': '1', 21:33:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:33:13 INFO - 'NO_EM_RESTART': '1', 21:33:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:33:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:33:13 INFO - 'PWD': '/builds/slave/test', 21:33:13 INFO - 'SHELL': '/bin/bash', 21:33:13 INFO - 'SHLVL': '1', 21:33:13 INFO - 'TERM': 'linux', 21:33:13 INFO - 'TMOUT': '86400', 21:33:13 INFO - 'USER': 'cltbld', 21:33:13 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1470889977.506884-776553730', 21:33:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:33:13 INFO - '_': '/tools/buildbot/bin/python'} 21:33:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:33:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:33:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:33:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:33:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:33:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:33:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:33:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:33:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:33:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:33:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:33:16 INFO - Installing collected packages: mozsystemmonitor 21:33:16 INFO - Running setup.py install for mozsystemmonitor 21:33:16 INFO - Successfully installed mozsystemmonitor 21:33:16 INFO - Cleaning up... 21:33:17 INFO - Return code: 0 21:33:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:33:17 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')]} 21:33:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:33:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:33:17 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')]} 21:33:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:33:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:33:17 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 0x21aa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x230d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x246b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24693b0>, '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 0x246a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x246a740>, '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': 'bb9b54edd4a927dc735d112f000001d9-1470889977.506884-776553730', '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 21:33:17 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 21:33:17 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 21:33:17 INFO - Using env: {'DISPLAY': ':0', 21:33:17 INFO - 'HOME': '/home/cltbld', 21:33:17 INFO - 'LANG': 'en_US.UTF-8', 21:33:17 INFO - 'LANGUAGE': 'en_US:en', 21:33:17 INFO - 'LOGNAME': 'cltbld', 21:33:17 INFO - 'MAIL': '/var/mail/cltbld', 21:33:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:33:17 INFO - 'MOZ_NO_REMOTE': '1', 21:33:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:33:17 INFO - 'NO_EM_RESTART': '1', 21:33:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:33:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:33:17 INFO - 'PWD': '/builds/slave/test', 21:33:17 INFO - 'SHELL': '/bin/bash', 21:33:17 INFO - 'SHLVL': '1', 21:33:17 INFO - 'TERM': 'linux', 21:33:17 INFO - 'TMOUT': '86400', 21:33:17 INFO - 'USER': 'cltbld', 21:33:17 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1470889977.506884-776553730', 21:33:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:33:17 INFO - '_': '/tools/buildbot/bin/python'} 21:33:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:33:17 INFO - Downloading/unpacking jsonschema==2.5.1 21:33:17 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 21:33:17 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 21:33:17 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 21:33:17 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 21:33:17 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 21:33:17 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 21:33:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:33:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:33:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:33:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:33:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:33:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:33:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:33:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:33:19 INFO - Downloading functools32-3.2.3-2.tar.gz 21:33:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:33:19 INFO - warning: no files found matching '*.txt' 21:33:19 INFO - no previously-included directories found matching 'build' 21:33:19 INFO - no previously-included directories found matching 'dist' 21:33:19 INFO - no previously-included directories found matching '.git*' 21:33:19 INFO - Installing collected packages: jsonschema, functools32 21:33:19 INFO - Running setup.py install for functools32 21:33:19 INFO - warning: no files found matching '*.txt' 21:33:19 INFO - no previously-included directories found matching 'build' 21:33:19 INFO - no previously-included directories found matching 'dist' 21:33:19 INFO - no previously-included directories found matching '.git*' 21:33:19 INFO - Successfully installed jsonschema functools32 21:33:19 INFO - Cleaning up... 21:33:19 INFO - Return code: 0 21:33:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:33:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:33:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:33:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:33:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:33:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:33:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:33:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21aa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x230d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x246b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24693b0>, '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 0x246a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x246a740>, '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': 'bb9b54edd4a927dc735d112f000001d9-1470889977.506884-776553730', '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 21:33:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 21:33:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 21:33:19 INFO - Using env: {'DISPLAY': ':0', 21:33:19 INFO - 'HOME': '/home/cltbld', 21:33:19 INFO - 'LANG': 'en_US.UTF-8', 21:33:19 INFO - 'LANGUAGE': 'en_US:en', 21:33:19 INFO - 'LOGNAME': 'cltbld', 21:33:19 INFO - 'MAIL': '/var/mail/cltbld', 21:33:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:33:19 INFO - 'MOZ_NO_REMOTE': '1', 21:33:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:33:19 INFO - 'NO_EM_RESTART': '1', 21:33:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:33:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:33:19 INFO - 'PWD': '/builds/slave/test', 21:33:19 INFO - 'SHELL': '/bin/bash', 21:33:19 INFO - 'SHLVL': '1', 21:33:19 INFO - 'TERM': 'linux', 21:33:19 INFO - 'TMOUT': '86400', 21:33:19 INFO - 'USER': 'cltbld', 21:33:19 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1470889977.506884-776553730', 21:33:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:33:19 INFO - '_': '/tools/buildbot/bin/python'} 21:33:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:33:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:33:19 INFO - Cleaning up... 21:33:19 INFO - Return code: 0 21:33:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:33:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:33:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:33:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:33:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:33:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:33:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:33:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21aa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x230d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x246b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24693b0>, '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 0x246a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x246a740>, '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': 'bb9b54edd4a927dc735d112f000001d9-1470889977.506884-776553730', '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 21:33:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 21:33:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:33:19 INFO - Using env: {'DISPLAY': ':0', 21:33:19 INFO - 'HOME': '/home/cltbld', 21:33:19 INFO - 'LANG': 'en_US.UTF-8', 21:33:19 INFO - 'LANGUAGE': 'en_US:en', 21:33:19 INFO - 'LOGNAME': 'cltbld', 21:33:19 INFO - 'MAIL': '/var/mail/cltbld', 21:33:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:33:19 INFO - 'MOZ_NO_REMOTE': '1', 21:33:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:33:19 INFO - 'NO_EM_RESTART': '1', 21:33:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:33:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:33:19 INFO - 'PWD': '/builds/slave/test', 21:33:19 INFO - 'SHELL': '/bin/bash', 21:33:19 INFO - 'SHLVL': '1', 21:33:19 INFO - 'TERM': 'linux', 21:33:19 INFO - 'TMOUT': '86400', 21:33:19 INFO - 'USER': 'cltbld', 21:33:19 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1470889977.506884-776553730', 21:33:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:33:19 INFO - '_': '/tools/buildbot/bin/python'} 21:33:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:33:20 INFO - Downloading/unpacking blobuploader==1.2.4 21:33:20 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 21:33:20 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 21:33:20 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 21:33:20 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 21:33:20 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 21:33:20 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 21:33:21 INFO - Downloading blobuploader-1.2.4.tar.gz 21:33:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:33:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:33:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:33:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:33:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:33:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:33:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:33:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:33:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:33:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:33:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:33:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:33:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:33:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:33:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:33:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:33:22 INFO - Downloading docopt-0.6.1.tar.gz 21:33:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:33:22 INFO - Installing collected packages: blobuploader, requests, docopt 21:33:22 INFO - Running setup.py install for blobuploader 21:33:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:33:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:33:22 INFO - Running setup.py install for requests 21:33:23 INFO - Running setup.py install for docopt 21:33:23 INFO - Successfully installed blobuploader requests docopt 21:33:23 INFO - Cleaning up... 21:33:23 INFO - Return code: 0 21:33:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:33: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')]} 21:33:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:33:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:33: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')]} 21:33:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:33:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:33:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21aa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x230d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x246b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24693b0>, '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 0x246a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x246a740>, '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': 'bb9b54edd4a927dc735d112f000001d9-1470889977.506884-776553730', '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 21:33:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 21:33:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:33:23 INFO - Using env: {'DISPLAY': ':0', 21:33:23 INFO - 'HOME': '/home/cltbld', 21:33:23 INFO - 'LANG': 'en_US.UTF-8', 21:33:23 INFO - 'LANGUAGE': 'en_US:en', 21:33:23 INFO - 'LOGNAME': 'cltbld', 21:33:23 INFO - 'MAIL': '/var/mail/cltbld', 21:33:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:33:23 INFO - 'MOZ_NO_REMOTE': '1', 21:33:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:33:23 INFO - 'NO_EM_RESTART': '1', 21:33:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:33:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:33:23 INFO - 'PWD': '/builds/slave/test', 21:33:23 INFO - 'SHELL': '/bin/bash', 21:33:23 INFO - 'SHLVL': '1', 21:33:23 INFO - 'TERM': 'linux', 21:33:23 INFO - 'TMOUT': '86400', 21:33:23 INFO - 'USER': 'cltbld', 21:33:23 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1470889977.506884-776553730', 21:33:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:33:23 INFO - '_': '/tools/buildbot/bin/python'} 21:33:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:33:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:33:23 INFO - Running setup.py (path:/tmp/pip-IqQNnV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:33:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:33:23 INFO - Running setup.py (path:/tmp/pip-2KD3cv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:33:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:33:23 INFO - Running setup.py (path:/tmp/pip-ngqkhV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:33:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:33:23 INFO - Running setup.py (path:/tmp/pip-KAOXVt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:33:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:33:23 INFO - Running setup.py (path:/tmp/pip-lt2zXQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:33:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:33:23 INFO - Running setup.py (path:/tmp/pip-F_dCT4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:33:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:33:23 INFO - Running setup.py (path:/tmp/pip-77h_o6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:33:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:33:23 INFO - Running setup.py (path:/tmp/pip-wMFLVr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:33:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:33:24 INFO - Running setup.py (path:/tmp/pip-ripbs5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:33:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:33:24 INFO - Running setup.py (path:/tmp/pip-U88Et_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:33:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:33:24 INFO - Running setup.py (path:/tmp/pip-cjYkbn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:33:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:33:24 INFO - Running setup.py (path:/tmp/pip-zWVwv9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:33:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:33:24 INFO - Running setup.py (path:/tmp/pip-DRxHZD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:33:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:33:24 INFO - Running setup.py (path:/tmp/pip-Dt6yuq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:33:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:33:24 INFO - Running setup.py (path:/tmp/pip-EJ2Tcm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:33:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:33:24 INFO - Running setup.py (path:/tmp/pip-EROSwL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:33:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:33:24 INFO - Running setup.py (path:/tmp/pip-_QivTW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:33:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:33:24 INFO - Running setup.py install for manifestparser 21:33:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:33:24 INFO - Running setup.py install for mozcrash 21:33:24 INFO - Running setup.py install for mozdebug 21:33:25 INFO - Running setup.py install for mozdevice 21:33:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:33:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:33:25 INFO - Running setup.py install for mozfile 21:33:25 INFO - Running setup.py install for mozhttpd 21:33:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:33:25 INFO - Running setup.py install for mozinfo 21:33:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:33:25 INFO - Running setup.py install for mozInstall 21:33:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:33:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:33:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:33:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:33:25 INFO - Running setup.py install for mozleak 21:33:25 INFO - Running setup.py install for mozlog 21:33:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:33:25 INFO - Running setup.py install for moznetwork 21:33:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:33:25 INFO - Running setup.py install for mozprocess 21:33:25 INFO - Running setup.py install for mozprofile 21:33:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:33:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:33:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:33:26 INFO - Running setup.py install for mozrunner 21:33:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:33:26 INFO - Running setup.py install for mozscreenshot 21:33:26 INFO - Running setup.py install for moztest 21:33:26 INFO - Running setup.py install for mozversion 21:33:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:33:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:33:26 INFO - Cleaning up... 21:33:26 INFO - Return code: 0 21:33:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:33:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:33:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:33:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:33:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:33:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:33:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:33:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21aa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x230d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x246b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24693b0>, '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 0x246a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x246a740>, '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': 'bb9b54edd4a927dc735d112f000001d9-1470889977.506884-776553730', '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 21:33:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 21:33:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:33:26 INFO - Using env: {'DISPLAY': ':0', 21:33:26 INFO - 'HOME': '/home/cltbld', 21:33:26 INFO - 'LANG': 'en_US.UTF-8', 21:33:26 INFO - 'LANGUAGE': 'en_US:en', 21:33:26 INFO - 'LOGNAME': 'cltbld', 21:33:26 INFO - 'MAIL': '/var/mail/cltbld', 21:33:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:33:26 INFO - 'MOZ_NO_REMOTE': '1', 21:33:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:33:26 INFO - 'NO_EM_RESTART': '1', 21:33:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:33:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:33:26 INFO - 'PWD': '/builds/slave/test', 21:33:26 INFO - 'SHELL': '/bin/bash', 21:33:26 INFO - 'SHLVL': '1', 21:33:26 INFO - 'TERM': 'linux', 21:33:26 INFO - 'TMOUT': '86400', 21:33:26 INFO - 'USER': 'cltbld', 21:33:26 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1470889977.506884-776553730', 21:33:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:33:26 INFO - '_': '/tools/buildbot/bin/python'} 21:33:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:33:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:33:26 INFO - Running setup.py (path:/tmp/pip-AKaQ9z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:33:26 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 21:33:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:33:26 INFO - Running setup.py (path:/tmp/pip-Di0eP9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:33:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:33:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:33:26 INFO - Running setup.py (path:/tmp/pip-AAUzVp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:33:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 21:33:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:33:26 INFO - Running setup.py (path:/tmp/pip-aWmg_h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:33:27 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)) 21:33:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:33:27 INFO - Running setup.py (path:/tmp/pip-4FJHpq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:33:27 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)) 21:33:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:33:27 INFO - Running setup.py (path:/tmp/pip-ewYp4d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:33:27 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)) 21:33:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:33:27 INFO - Running setup.py (path:/tmp/pip-CYDapU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:33:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 21:33:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:33:27 INFO - Running setup.py (path:/tmp/pip-yHEjen-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:33:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 21:33:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:33:27 INFO - Running setup.py (path:/tmp/pip-VPkycW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:33:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 21:33:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:33:27 INFO - Running setup.py (path:/tmp/pip-SQPQfn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:33:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:33:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:33:27 INFO - Running setup.py (path:/tmp/pip-Sl4gdq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:33:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 21:33:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:33:27 INFO - Running setup.py (path:/tmp/pip-CtHLTw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:33:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 21:33:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:33:27 INFO - Running setup.py (path:/tmp/pip-Xnmq8B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:33:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 21:33:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:33:27 INFO - Running setup.py (path:/tmp/pip-1H7XtX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:33:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 21:33:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:33:27 INFO - Running setup.py (path:/tmp/pip-2oXAR_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:33:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 21:33:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:33:27 INFO - Running setup.py (path:/tmp/pip-hbD_E_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:33:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 21:33:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:33:27 INFO - Running setup.py (path:/tmp/pip-g9ipeC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:33:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 21:33:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:33:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:33:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 21:33:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:33:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:33:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:33:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:33:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:33:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:33:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:33:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:33:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:33:29 INFO - Downloading blessings-1.6.tar.gz 21:33:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:33:29 INFO - Installing collected packages: blessings 21:33:29 INFO - Running setup.py install for blessings 21:33:30 INFO - Successfully installed blessings 21:33:30 INFO - Cleaning up... 21:33:30 INFO - Return code: 0 21:33:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:33:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:33:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:33:30 INFO - Reading from file tmpfile_stdout 21:33:30 INFO - Current package versions: 21:33:30 INFO - argparse == 1.2.1 21:33:30 INFO - blessings == 1.6 21:33:30 INFO - blobuploader == 1.2.4 21:33:30 INFO - docopt == 0.6.1 21:33:30 INFO - functools32 == 3.2.3-2 21:33:30 INFO - jsonschema == 2.5.1 21:33:30 INFO - manifestparser == 1.1 21:33:30 INFO - mozInstall == 1.12 21:33:30 INFO - mozcrash == 0.17 21:33:30 INFO - mozdebug == 0.1 21:33:30 INFO - mozdevice == 0.48 21:33:30 INFO - mozfile == 1.2 21:33:30 INFO - mozhttpd == 0.7 21:33:30 INFO - mozinfo == 0.9 21:33:30 INFO - mozleak == 0.1 21:33:30 INFO - mozlog == 3.3 21:33:30 INFO - moznetwork == 0.27 21:33:30 INFO - mozprocess == 0.23 21:33:30 INFO - mozprofile == 0.28 21:33:30 INFO - mozrunner == 6.12 21:33:30 INFO - mozscreenshot == 0.1 21:33:30 INFO - mozsystemmonitor == 0.3 21:33:30 INFO - moztest == 0.7 21:33:30 INFO - mozversion == 1.4 21:33:30 INFO - psutil == 3.1.1 21:33:30 INFO - requests == 1.2.3 21:33:30 INFO - wsgiref == 0.1.2 21:33:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:33:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:33:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:33:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:33:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:33:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:33:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:33:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21aa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x230d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x246b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24693b0>, '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 0x246a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x246a740>, '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': 'bb9b54edd4a927dc735d112f000001d9-1470889977.506884-776553730', '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 21:33:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 21:33:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:33:30 INFO - Using env: {'DISPLAY': ':0', 21:33:30 INFO - 'HOME': '/home/cltbld', 21:33:30 INFO - 'LANG': 'en_US.UTF-8', 21:33:30 INFO - 'LANGUAGE': 'en_US:en', 21:33:30 INFO - 'LOGNAME': 'cltbld', 21:33:30 INFO - 'MAIL': '/var/mail/cltbld', 21:33:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:33:30 INFO - 'MOZ_NO_REMOTE': '1', 21:33:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:33:30 INFO - 'NO_EM_RESTART': '1', 21:33:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:33:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:33:30 INFO - 'PWD': '/builds/slave/test', 21:33:30 INFO - 'SHELL': '/bin/bash', 21:33:30 INFO - 'SHLVL': '1', 21:33:30 INFO - 'TERM': 'linux', 21:33:30 INFO - 'TMOUT': '86400', 21:33:30 INFO - 'USER': 'cltbld', 21:33:30 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1470889977.506884-776553730', 21:33:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:33:30 INFO - '_': '/tools/buildbot/bin/python'} 21:33:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:33:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 21:33:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 21:33:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 21:33:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 21:33:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 21:33:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 21:33:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 21:33:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 21:33:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 21:33:30 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 21:33:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 21:33:30 INFO - Cleaning up... 21:33:30 INFO - Return code: 0 21:33:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:33:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:33:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:33:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:33:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:33:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:33:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:33:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21aa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x230d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x246b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24693b0>, '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 0x246a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x246a740>, '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': 'bb9b54edd4a927dc735d112f000001d9-1470889977.506884-776553730', '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 21:33:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 21:33:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 21:33:30 INFO - Using env: {'DISPLAY': ':0', 21:33:30 INFO - 'HOME': '/home/cltbld', 21:33:30 INFO - 'LANG': 'en_US.UTF-8', 21:33:30 INFO - 'LANGUAGE': 'en_US:en', 21:33:30 INFO - 'LOGNAME': 'cltbld', 21:33:30 INFO - 'MAIL': '/var/mail/cltbld', 21:33:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:33:30 INFO - 'MOZ_NO_REMOTE': '1', 21:33:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:33:30 INFO - 'NO_EM_RESTART': '1', 21:33:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:33:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:33:30 INFO - 'PWD': '/builds/slave/test', 21:33:30 INFO - 'SHELL': '/bin/bash', 21:33:30 INFO - 'SHLVL': '1', 21:33:30 INFO - 'TERM': 'linux', 21:33:30 INFO - 'TMOUT': '86400', 21:33:30 INFO - 'USER': 'cltbld', 21:33:30 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1470889977.506884-776553730', 21:33:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:33:30 INFO - '_': '/tools/buildbot/bin/python'} 21:33:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:33:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:33:30 INFO - Cleaning up... 21:33:30 INFO - Return code: 0 21:33:30 INFO - Running post-action listener: _resource_record_post_action 21:33:30 INFO - Running post-action listener: _start_resource_monitoring 21:33:30 INFO - Starting resource monitoring. 21:33:30 INFO - [mozharness: 2016-08-11 04:33:30.774282Z] Finished create-virtualenv step (success) 21:33:30 INFO - [mozharness: 2016-08-11 04:33:30.774763Z] Running install step. 21:33:30 INFO - Running pre-action listener: _resource_record_pre_action 21:33:30 INFO - Running main action method: install 21:33:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:33:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:33:30 INFO - Reading from file tmpfile_stdout 21:33:30 INFO - Detecting whether we're running mozinstall >=1.0... 21:33:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:33:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:33:31 INFO - Reading from file tmpfile_stdout 21:33:31 INFO - Output received: 21:33:31 INFO - Usage: mozinstall [options] installer 21:33:31 INFO - Options: 21:33:31 INFO - -h, --help show this help message and exit 21:33:31 INFO - -d DEST, --destination=DEST 21:33:31 INFO - Directory to install application into. [default: 21:33:31 INFO - "/builds/slave/test"] 21:33:31 INFO - --app=APP Application being installed. [default: firefox] 21:33:31 INFO - mkdir: /builds/slave/test/build/application 21:33:31 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'] 21:33:31 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 21:33:47 INFO - Reading from file tmpfile_stdout 21:33:47 INFO - Output received: 21:33:47 INFO - /builds/slave/test/build/application/firefox/firefox 21:33:47 INFO - Running post-action listener: _resource_record_post_action 21:33:47 INFO - [mozharness: 2016-08-11 04:33:47.944112Z] Finished install step (success) 21:33:47 INFO - [mozharness: 2016-08-11 04:33:47.944361Z] Running run-tests step. 21:33:47 INFO - Running pre-action listener: _resource_record_pre_action 21:33:47 INFO - Running main action method: run_tests 21:33:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:33:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:33:47 INFO - Python 2.7.3 21:33:47 INFO - Return code: 0 21:33:47 INFO - grabbing minidump binary from tooltool 21:33:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:33:47 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24693b0>, '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 0x246a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x246a740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:33:47 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 21:33:47 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 21:33:47 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 21:33:48 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:33:48 INFO - Return code: 0 21:33:48 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:33:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:33:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:33:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:33:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:33:48 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/Eo7eWyG2RMi-qtK3nyhBmA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--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 21:33:48 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/Eo7eWyG2RMi-qtK3nyhBmA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-055 --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 21:33:48 INFO - Using env: {'DISPLAY': ':0', 21:33:48 INFO - 'HOME': '/home/cltbld', 21:33:48 INFO - 'LANG': 'en_US.UTF-8', 21:33:48 INFO - 'LANGUAGE': 'en_US:en', 21:33:48 INFO - 'LOGNAME': 'cltbld', 21:33:48 INFO - 'MAIL': '/var/mail/cltbld', 21:33:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:33:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:33:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:33:48 INFO - 'MOZ_NO_REMOTE': '1', 21:33:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:33:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:33:48 INFO - 'NO_EM_RESTART': '1', 21:33:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:33:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:33:48 INFO - 'PWD': '/builds/slave/test', 21:33:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:33:48 INFO - 'SHELL': '/bin/bash', 21:33:48 INFO - 'SHLVL': '1', 21:33:48 INFO - 'TERM': 'linux', 21:33:48 INFO - 'TMOUT': '86400', 21:33:48 INFO - 'USER': 'cltbld', 21:33:48 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1470889977.506884-776553730', 21:33:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:33:48 INFO - '_': '/tools/buildbot/bin/python'} 21:33:48 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/Eo7eWyG2RMi-qtK3nyhBmA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--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 21:33:48 INFO - mozversion application_buildid: 20160810193023 21:33:48 INFO - mozversion application_changeset: 32e07e32891d070cdba97e2872cd30265278c0bf 21:33:48 INFO - mozversion application_display_name: Nightly 21:33:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:33:48 INFO - mozversion application_name: Firefox 21:33:48 INFO - mozversion application_remotingname: firefox 21:33:48 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 21:33:48 INFO - mozversion application_vendor: Mozilla 21:33:48 INFO - mozversion application_version: 51.0a1 21:33:48 INFO - mozversion platform_buildid: 20160810193023 21:33:48 INFO - mozversion platform_changeset: 32e07e32891d070cdba97e2872cd30265278c0bf 21:33:48 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 21:33:48 INFO - mozversion platform_version: 51.0a1 21:33:48 INFO - using testdate: 1470890028 21:33:48 INFO - actual date: 1470890028 21:33:48 INFO - starting webserver on 'localhost:34539' 21:33:48 INFO - SUITE-START | Running 2 tests 21:33:48 INFO - TEST-START | dromaeo_css 21:33:48 INFO - operating with platform_type : linux_ 21:33:48 INFO - Initialising browser for dromaeo_css test... 21:33:48 INFO - TEST-INFO | started process 5042 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZhE4KB/profile http://localhost:34539/getInfo.html) 21:33:52 INFO - PROCESS | 5042 | __metrics Screen width/height:1600/1200 21:33:52 INFO - PROCESS | 5042 | colorDepth:24 21:33:52 INFO - PROCESS | 5042 | Browser inner width/height: 1024/697 21:33:52 INFO - PROCESS | 5042 | __metrics 21:33:53 INFO - PROCESS | 5042 | 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" 21:33:53 INFO - PROCESS | 5042 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:33:53 INFO - PROCESS | 5042 | console.error: PushService: 21:33:53 INFO - PROCESS | 5042 | stateChangeProcessEnqueue: Error transitioning state 21:33:53 INFO - PROCESS | 5042 | UnknownError 21:33:53 INFO - TEST-INFO | 5042: exit 0 21:33:53 INFO - Browser initialized. 21:33:53 INFO - Running cycle 1/1 for dromaeo_css test... 21:33:53 INFO - TEST-INFO | started process 5123 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZhE4KB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:34:05 INFO - PROCESS | 5123 | RSS: Main: 155996160 21:34:05 INFO - PROCESS | 5123 | 21:34:57 INFO - PROCESS | 5123 | 1470890097468 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] 21:37:06 INFO - PROCESS | 5123 | Cycle 1(1): loaded http://localhost:34539/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34539/tests/dromaeo/cssquery-ext.html) 21:37:07 INFO - PROCESS | 5123 | RSS: Main: 263467008 21:37:07 INFO - PROCESS | 5123 | 21:40:10 INFO - PROCESS | 5123 | Cycle 1(1): loaded http://localhost:34539/tests/dromaeo/cssquery-ext.html (next: http://localhost:34539/tests/dromaeo/cssquery-jquery.html) 21:40:10 INFO - PROCESS | 5123 | RSS: Main: 241647616 21:40:10 INFO - PROCESS | 5123 | 21:43:12 INFO - PROCESS | 5123 | Cycle 1(1): loaded http://localhost:34539/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34539/tests/dromaeo/cssquery-mootools.html) 21:43:13 INFO - PROCESS | 5123 | RSS: Main: 185856000 21:43:13 INFO - PROCESS | 5123 | 21:46:15 INFO - PROCESS | 5123 | Cycle 1(1): loaded http://localhost:34539/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34539/tests/dromaeo/cssquery-prototype.html) 21:46:16 INFO - PROCESS | 5123 | RSS: Main: 183357440 21:46:16 INFO - PROCESS | 5123 | 21:49:18 INFO - PROCESS | 5123 | Cycle 1(1): loaded http://localhost:34539/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34539/tests/dromaeo/cssquery-yui.html) 21:49:19 INFO - PROCESS | 5123 | RSS: Main: 185032704 21:49:19 INFO - PROCESS | 5123 | 21:52:22 INFO - PROCESS | 5123 | Cycle 1(1): loaded http://localhost:34539/tests/dromaeo/cssquery-yui.html (next: http://localhost:34539/tests/dromaeo/cssquery-dojo.html) 21:52:22 INFO - PROCESS | 5123 | RSS: Main: 276172800 21:52:22 INFO - PROCESS | 5123 | 21:52:22 INFO - PROCESS | 5123 | __start_tp_report 21:52:22 INFO - PROCESS | 5123 | _x_x_mozilla_page_load 21:52:22 INFO - PROCESS | 5123 | _x_x_mozilla_page_load_details 21:52:22 INFO - PROCESS | 5123 | |i|pagename|runs| 21:52:22 INFO - PROCESS | 5123 | |0;dojo.html;3078.5843911072;3107.937841243174;3114.7819652624316;3129.561861339413;3143.701348371905;5328.360596728388;5335.946640533598;5437.6465529740535;5438.428964958682;5483.698396588191;5370.892582148354;5383.515483606475;5402.9729851350685;5427.83716488506;5445.319335083114;4800.279958006301;4919.26211068339;4932.112219800434;4935;5104.438511763703;5029.698218106907;5041.546260836524;5065.493450654923;5079.847604571869;5081.161608334621;4805.927911081337;4837.9516204837855;4852.393450818648;4903.828366007173;5021.347224860763;4868.659193856396;4875.561199492027;4879.341288925985;4879.707217566957;4978.751062446863;4882.2432522959;4893.021395720834;4905.509449055102;4923.236898280772;4953.108440480712;5325.094733895247;5332.520073193393;5376.892462150735;5404.243405923174;5415.512603865632;5810.1865738796605;5818;5863.58954873159;5870.471657550798;5875.559333049996;5821.854453638651;5861.70691465425;5864.7067646617925;5869.7652093916195;5909.734061967179;5339.866503337409;5365.436629153922;5418.078926582491;5419.620626556103;5502.944970550323;5989.700514974234;6018.518518518508;6020.247469066367;6024.186734790835;6027.457528822427;5789.507891829242;5790.044642634002;5792.362840087587;5794.101914203306;5798;5574.860628484239;5597.6641401516035;5602.663840169603;5620.044592419298;5873.735681894325;5654.74553645087;5654.886902261932;5659.434056594308;5667.574931880125;5706.914396284059;5611.158326251013;5614.606977511617;5664.065429204217;5669.6314739541795;5671.120976248688;4390;4413.889652758707;4436.556344365602;4444.977775111104;4465.620422264144;3995.62041606047;3997.9205614483926;4031.7984100794843;4035.435039094529;4058.5738497457933;5427.050266203478;5457.5633949283965;5459.945400546023;5460.126379779216;5505.311836020497;5667.206591077253;5717.256756621696;5808.564357673192;5829;5835.503982161479;5504.256925315111;5504.587155963319;5504.80733174337;5505.256790333334;5557.972210138923;5012.824551140692;5013.1227035268685;5057.747112644353;5062.11413002723;5102.719350435687;5873.911891321719;5905.5570832186895;5936.762529498945;6061.272647282354;6069.696515174223;5777.335606405317;5785.334686511105;5827.562932779973;5831.950248955059;5832.50423713989;5979.103134529855;5984.700764961647;5988.760449581968;5993.220881285405;5994.220751302303;5609.102543593006;5619;5648.85877853057;5673.489385955284;5685.260916080883;5762.827115186551;5846.590738648424;5850.707464626837;5863.120531920246;5881.500072493886;5706.80026199081;5710.400407957105;5710.657360558213;5717.228174196596;5717.771289148387;5557.527610153021;5564.77740890376;5665.433456654301;5675.858103547444;5679.858003549779;5599.356074051536;5657.236273103243;5659.688717120598;5701.828945131652;5706.115552089267;5701.657900525982;5709.543236540983;5751.424857514216;5757.798477053283;5760.481556659921;5502.9174562380795;5510.531604813636;5534.05920993424;5558.166275058612;5559.471850174227 21:52:22 INFO - PROCESS | 5123 | |1;ext.html;576.3343338444096;590.2474345209858;591.8490784849865;604.2537466229209;617.7992152550421;33272;44499.77750111249;45094;45192.096158076856;45275.547244527545;48552;48942.51057489424;48950.75524622372;49045;49124.263136052985;9018.09819018098;9160.725178244664;9164.083591640849;9166.679166229183;9169.724908252763;8995.730128096135;9078.727638170865;9080.046595107518;9091.09089091089;9091.681791137311;9132.041135680758;9162.587683554222;9175.311851611123;9182.989871114172;9186.173244408003;9093.590788414538;9137.543122843832;9141.222996045344;9151.267898568098;9154.633814647439;8914.242289405409;8981.775455613597;8987.37088403812;8988.59551320192;8989;43917.78041109806;44235;44245.336319955226;44340.00939915423;44343.11313773706;3599.8200089995657;3763.8870833875026;3796.658300752918;3798.2213646202463;4178.185253875496;33035.504467433006;33067.83466082678;33072.834635826905;33092;33229.50155747642;4322.913541729148;4442.53353397895;4472.508024117345;4479.193745125876;4596.1267359201975;264950.67524662445;265043;265148;265148.0455586336;265692;62341;62683.37316626866;62804.37195628077;62822.057669135;62857.37142628607;57273.14090288649;63015;65179.674101629185;65617.34382656112;65701;15809.762853557206;15842.366305347658;15878.44425445127;15926.60183495422;15990.840091598935;13365;13557.796633050511;13566.389512471962;13568.86431135676;13626.318684065756;15896.602584935237;17600;18040.819591804175;18080.9095954522;18169.818301816813;6266.749330026748;6397.328280530477;6448.677566121675;6450.161479007699;6505.4795616350575;1020.6937918624383;1065.4992153687908;1071.4000159910474;1103.7682086761708;1125.3754166437707;33268.50097248543;34101.8064367746;34166.658333416846;34176.316473670886;34545;1127.0870594818223;1133.699569614053;1180.144395313391;1196.180616277835;1256.6858285428643;17280;17839.910800445916;17915.462536123938;17927.910360448113;17950.640987180446;548551;550363;552763;552949;556391;63246.36753632497;63337;63343;63421.36578634247;63430.68284658547;1323.1862404621088;1366.2895294446832;1395.5743498233137;1399.2793711238517;1401;31115.84442077775;33062.33875322528;33450.83274583612;33627.66372336245;34263.48604770881;55751;56116.71941640429;56151;56258.71870640621;56285.43714562802;473311;474207;474857;476766;477373.58384912723;44122;45837;46501.3024804635;46903.76548117238;46928;50849.237261440365;51388.48611513837;51515.74242128765;52193;52722.736386317825;40825.59174408218;43250.78374608107;43881;45024.32463513115;47900.520994791;43226.567734322256;43719.125617488105;43981.34027989519;44798.51902449695;44826.77586612046;43772;44157;44790.55209447864;44920.32619510645;45290.32064518969;35131;38375.61624383832;39353.80323098366;39622.80188599154;40233;28949;28977.371866135098;29193.85403072971;29570.704292956794;29893.70106298909 21:52:22 INFO - PROCESS | 5123 | |2;jquery.html;384.7326108354695;400.08580393800156;408.71798459063245;411.2946297100472;427.74762889894976;15472.845271547281;19749.605007899834;20332.59334813303;20405.795942040575;20554.89722551387;19412.708809367836;19549.21803127873;19553.804461955377;19659.11533980971;19731.112099955502;5717.942820571793;5819.243498345209;5926.614770039933;6015.368386319439;6055.666938318391;4058.391241313812;5671.06427439472;6130.049842274455;6173.845653858667;6174.10475481055;5918.40815918409;6002.189704389895;6018.849528761794;6020.608660437079;6024.48791852693;5817.127430885359;5984.102384642295;5998.5800993930225;6011.609245399079;6016.639001659914;5844.649321040752;5847.26909136358;5856;5858.443447872445;5859.296884373903;21783.891080544498;23530.529389412288;23907.16324928619;24154.637680434807;24285.392865178335;2276.64711969645;2290.118304452781;2336.299110266913;2382.630692242705;2457.8156638252203;9842.65550705722;10469.214808889365;10637.840432393521;11077.944610276896;11094.1124710023;1892.6593213221615;1897.5730460646382;1938.7901909519794;2002.8898410587412;2069;84203;85686.14313856844;87312;87646;88047.55976220142;11213.887861121284;11495.88504114965;11557.364344960939;11713.824292635618;11722.300266461485;16192.352305907632;16238.512844614681;16265.186740662919;16271.67456650884;16288.42990495327;8440.198181172778;8647.092055334226;8704;8715.956420217984;8927.419717718327;8415.032271288881;8438.620262088221;8466.661333546712;8691.217790398894;8727.738167854974;4947.233178857211;4960.751962401865;5033.949660503348;5057.949420505748;5073.949260507347;2048.6107639548436;2062.5462398272357;2073.2225415469197;2085.937421877346;2108.493961449241;857.8155696525278;914.3964983111248;1050.821360368739;1066.3655125200494;1117.2570240789946;19529.218831246875;20124.698129527776;20132.698009529868;20234.69647955282;20534.897325513277;927.1933417926447;969.1907257440116;989.9901000989898;1006.0291818395137;1045.1586472889246;10704;11056.834147487794;11160.497777600027;11215.383153926448;11241.550337986553;505720;509615;510196;513878;524325;58844.11733823996;77301.6134919333;89230;92879.53560232156;94984;963.7735132243918;981.1513041219293;1001.2840818814437;1002.9469057489607;1003.6236411345745;17136.228869700644;17654.470365889043;18007.2797088115;18021.72967405516;18241.543961401072;43559.56440435555;44181.337279940184;44548.33177502275;44882.326765097896;45348;55661.721691391285;55708;56357.43642563522;62166;62355;12194.31725020494;12587.559435419716;12710.618681439202;13106.73786524245;13111.475540978254;13142.474301027853;13395.933020334836;13802.378892950042;13847.653808654864;14007.299635018413;11852.881471185177;12017.63947081589;12128.514859405524;12197.268163910194;12351.505939762308;11956.521739130338;12128.393580321124;12243.632691019284;12261.448234829275;12834.614961551168;11883.643490695293;12228.69428264265;12247.387630618256;12418.875811242133;12424.503019879465;12614.243145411305;12656;12677.683057923625;12724.1729287596;13031.869681303066;11568.421578921188;11765.411729413667;11797.52809887595;11806.527738890349;11821.645350639494 21:52:22 INFO - PROCESS | 5123 | |3;mootools.html;1371.6707990082384;1480.7852861335107;1543.205249296612;1649.4639242246265;1772.0342413384703;8629.870551941718;9030.187283144523;9064.864027039592;9308.34841561091;9374.296927730413;9181.2654987601;9307.395019323756;9309.487978161198;9322.48726320052;9322.813543729138;2419.213755529451;2461.3723500507435;2466.2477944227003;2467.5064987002584;2468.4075821802726;2401.075585899424;2468.469279104992;2476.6532685424054;2487.6268559716004;2490.7633774791457;2418.7218469875925;2440.8169387296025;2457.594496908017;2461.876906154694;2462.1997850698503;2405.687260656121;2418.504206637635;2420.334408037786;2426.102342133413;2434.4887573609562;2393.581123303415;2412.819038572114;2432.0028788196755;2435.1598698449006;2438.426969662128;9134.90865091354;9275.536223188881;9398.389104708172;9407.29445291606;9409.106134917172;5425.511703946624;5565.415631358689;5574.944250557483;5580.91628625571;5581.665100093966;3667.7799332039895;3728.6830619397383;3736.981315093434;3798.5441746990537;3817.7136714746507;1252.273681264864;1254.7553227120716;1276.2215048820212;1281.3337064726384;1295.4494339905596;12609.74780504394;12731.61805145847;12736.363181840963;12743.299118548475;12743.745125097446;4445.799939002688;4757.571818536279;4789.904201915942;4854.8786280342565;4856.17445034345;9867.506624668738;9870.111689947938;9876.901230987742;9879.110880020833;9881.160101391462;5324.148136298174;5325.4940780625775;5325.760340784596;5333.280007199056;5335;4471.977640111779;4773.188557945157;4902.48523904992;4930.482299358588;4959.925601115986;3899.122697393064;3947.3289540777564;4065.837366505307;4073.368627862686;4083.285425050664;1208.6552088947415;1219.7194645231468;1228.6989687526614;1231.8398608181058;1232.3160645841465;900.5362238447204;906.3519583497699;914.5884352041475;917.4082716647724;951.509972364233;5805.7387417566315;6201.410865967726;6211.81364559064;6428;6509.934900651027;902.9954850225707;924.2605915267893;942.4439580647451;951.8952915179325;1009.8485227215976;3941.664958478504;4286.357046442996;4330.631896288851;4432.911341773211;4442.444694413199;11328.943355283336;11820.23168494045;11833;11864;11907.047436205083;11736.295822250693;11766.058715302755;11777.352245626573;11873.643790686294;11876.287422754662;8094.311983481352;8504.617292221865;8512.829743405096;8767.824643507221;8770.736877893672;6299.716512757029;6302.495800335871;6358.618482891041;6479.8056058318325;6518.543701941008;10798.298110622783;10896.291741036806;10922.563097476012;11028.28316159447;11056.226064175431;10766.46167691628;11134.610288639858;11150.438938548772;11213;11261.718457038312;7493.550386976799;7915.248051435105;8122.878156827771;8191.058028326819;8315.750527484186;9107.863382049407;9168.54157292146;9172.082791720775;9172.174504294378;9181.632734690806;7875.921240787748;8118.431709780495;8145.959270203611;8157.6736930524485;8269.379796515164;7782.533048017137;7843.647035883285;7969.880451793112;7975.202479751978;8182.427230093836;7428.851422971618;7450.590217538087;7521.323080922743;7539.095308563007;7550.093988721169;5249.7112658804135;5402.324709411324;5491.890162196813;5524.364698059774;5634.042212823748;5524.640898341595;5614.410486898817;5639.689817060101;5686.402927692533;5695.003374409379 21:52:22 INFO - PROCESS | 5123 | |4;prototype.html;329.2755936938735;333.74635277189327;339.4399241251935;342.99657003429957;343.8332408781739;5233.371995360557;5298.4436634153435;5300.178472336789;5313.123334649783;5315.920261196085;5166.225066240066;5224.29472021277;5228.29418028566;5231.738413079341;5245.396779370373;3942.9211415771665;3959.148783011652;4087.591240875918;4108.794560271989;4134.545200027995;4092.9181416371653;4104.97947510262;4123.958760412403;4139.358399448075;4143.751374917515;4087.6729861611147;4097.610726980932;4105.856295029675;4110.136871257039;4116.938245926313;3939.1333906540517;3944.8027598620242;4082.632563069308;4086.2031903778775;4110.794460277004;3676.6507181817683;3683.4106542953;3690.3910854708943;3693.2059607184583;3701;5094.694318340911;5101.897962040738;5109.233614957749;5127.89750203708;5131.563817075553;4513.232750432401;4716.292556116576;4721.787519561628;4734.455537613184;4747.029982557269;5406.513413792777;5420.783168673248;5433;5436.782528698847;5444.802143528417;4069.6744260459386;4070.5725898780793;4070.9185816283507;4071.592840715905;4090.6522945549664;5473.2063850741415;5610.438956104357;5614.736684246012;5662.886742265132;5698.857528561819;4141.192467468875;4141.772202528829;4144.129732756155;4144.544100148981;4149.294619914562;4216.262154123016;4221.472315960505;4224.197402493516;4232.756638981345;4235.428217190702;4444.622207112432;4707.458642256116;4731.905361892812;4733.526647335308;4744.383230180055;5514.310711161105;5516.172574113915;5522.66863988162;5525.254090697785;5526.309211348582;1446.2407236200868;1469.9853001470062;1470.2869108482378;1471.0364711114237;1472.0799500312305;913.6939125393068;949.9667511637059;954.5752140297501;956.784723437221;978.5077756421514;5045.672031317952;5151.6908985461;5168.53483186515;5175;5284.286621306152;5566.916496252559;5584.469475399912;5593.888122237532;5604.943950560524;5606.635568688022;5134.743262836843;5226.555742761831;5268.051750684914;5274.287971123926;5292.841214763564;2914.8979785707397;2943.6909124542044;2947.705229477078;2948.6166798316513;2956.763458923281;5762.711864406847;5782.913256301159;5784.219130417424;5793.482332376077;5798.565107616945;5396.568274538027;5629.887402252014;5699.344575373885;5701.429857014348;5709.200711900337;5733.942660573424;5741.224934633771;5766.2503874497725;5770.682612456355;5785.8553536161935;5229.50319719626;5581.860453488695;5582.52548533363;5584.190292407659;5602.13166959122;5532.529734972572;5557.833265002057;5568.192612071146;5585.357683866408;5588.100045984384;5839.124131380327;5847.327557330791;5847.573257098256;5861;5870.53035757147;3919.9608003919598;4046.9595304046584;4048.530396458217;4049.8582549610624;4086.387041943732;5604.674060264944;5624.803131890364;5632.239647647679;5638.774449021993;5639.407862174413;5509.3664228612615;5509.752061157177;5534.090738695305;5554.583406244467;5566.359868615161;5240.5807535397835;5466.562674985912;5496.642718223303;5502.614816962774;5532.3637781654015;5469.945300546943;5477.890442191054;5492.890142197213;5493.434371204187;5494.395616482264;5308.681479111266;5323.3231532067275;5481.437790229925;5528.037546670874;5559.944400555943;5399.757010934439;5401.378841433286;5405.756740946588;5410.242566040836;5413.458654134555 21:52:22 INFO - PROCESS | 5123 | |5;yui.html;755.8715018446863;821.3018933906178;823.3495538524562;825.4758228524885;843.0978852627683;14981.775273370895;15746.527604171864;16159.676806463864;16209.675806483878;16592.08743519109;15699.293531791069;15807.84192158078;15878.920605396957;15907.761383579256;15949.681006379895;807.8424707182087;831.6631764135535;834.8497270491309;835.5446281776447;836.7196989008659;680.1192455769765;804.5735760047185;828.8508068547659;831.1563762780783;831.4429332347312;862.3790870573177;863.0204717645486;864.0452300208242;864.1444969480201;865.3423398217369;855.2644725536034;855.268745222838;856.3448961544424;857.1171693156091;857.781265777229;512.2009664922732;605.8969975104243;618.0327787013355;624.4972796898495;633.284388640833;14933.178675172861;15968.76046859298;15977.680446391123;15992.280347384396;16018.51944441657;13803.102798318178;14218.217998010105;14652.487162949246;14730.337134828958;14864.256787160706;13325.067245292834;13409;13517.776727290762;13588.38852251651;14068.015238933278;4301.171912059177;4305.526392096898;4308.3968244445805;4331.177076355482;4349.956500434987;85449.57275213646;88383.55808220983;89108.55445722795;89109.55445222797;90553;11421.070880182311;11931.224470409396;11933.701657458632;12340.014369595952;12444.315562644142;22985.310440686808;23045.53908921812;23314.883425582764;23320.883395583252;23629;8563.785905352292;8760.080191579793;8772;9109;9131.086891310815;4798.664093513421;4845.53967373099;4886.120498310267;4932.630052746058;4935.185694360391;3497.7026952708798;3608.350496910529;3631.2011357501306;3633.1825339298975;3644.9453258201147;612.2316492801594;615.6213928434071;616.0389791924612;627.3130921640796;636.8439732265532;569.0922977850322;705.2665228162654;722.9277072292729;726.9818254543572;730.5981710059446;8706;9705.466199359103;9977.551010204557;10041.497925103715;10224.488775561193;565.5334349161958;678.716267949673;686.1560280854577;689.3657834792003;731.8536292741474;4574.268117101248;4849.830255941025;4874.244492103659;4898.436679781799;4916.532929371704;74760.87858682124;84624.57687711646;127371;129342.35328823296;138287.30856345655;107803.46098269458;110270;111147.424653564;112242;112577;60524.092138617954;61728.382716172266;78930.60534697404;78945.99999999885;80860.19139808645;9982.301238913496;10004.849927251242;10046.346987445793;10235.385876847413;10455.581776728846;52407.475925241786;53877;54628.453715463926;55267;55855;71956.28043719496;75216.24783752092;77015.61492192504;80082.59958700169;82635.58682206551;13475.124116932368;13547.254900980146;13669.726605468033;13805.585832425044;14230.00389972692;14984.925075374553;15386.538403847902;15659.060456372199;15739.685206296039;16078.365141023363;14449.277536122941;14595.635109122357;14887.776683349543;15647.843521564639;15721.213939303218;14128.222947737973;14129.364178611782;14217.857821421654;14550.85449145495;14696.77954830657;13579.932100339434;14088.58975122605;14131.505397311044;14711.33798979027;14712.779308310597;11086.279391839505;11372.715682108013;11836.58571950012;11990.460429280529;12065.457054432747;11958.402079895797;11999.880001199876;12054;12246;12248.448819803309 21:52:22 INFO - PROCESS | 5123 | __end_tp_report 21:52:22 INFO - PROCESS | 5123 | __start_cc_report 21:52:22 INFO - PROCESS | 5123 | _x_x_mozilla_cycle_collect,982 21:52:22 INFO - PROCESS | 5123 | __end_cc_report 21:52:22 INFO - PROCESS | 5123 | __startTimestamp1470891142601__endTimestamp 21:52:22 INFO - PROCESS | 5123 | 21:52:22 INFO - PROCESS | 5123 | ------- Summary: start ------- 21:52:22 INFO - PROCESS | 5123 | Number of tests: 6 21:52:22 INFO - PROCESS | 5123 | 21:52:22 INFO - PROCESS | 5123 | [#0] dojo.html Cycles:165 Average:5383.96 Median:5574.86 stddev:606.69 (10.9%) stddev-sans-first:580.97 21:52:22 INFO - PROCESS | 5123 | Values: 3078.6 3107.9 3114.8 3129.6 3143.7 5328.4 5335.9 5437.6 5438.4 5483.7 5370.9 5383.5 5403.0 5427.8 5445.3 4800.3 4919.3 4932.1 4935.0 5104.4 5029.7 5041.5 5065.5 5079.8 5081.2 4805.9 4838.0 4852.4 4903.8 5021.3 4868.7 4875.6 4879.3 4879.7 4978.8 4882.2 4893.0 4905.5 4923.2 4953.1 5325.1 5332.5 5376.9 5404.2 5415.5 5810.2 5818.0 5863.6 5870.5 5875.6 5821.9 5861.7 5864.7 5869.8 5909.7 5339.9 5365.4 5418.1 5419.6 5502.9 5989.7 6018.5 6020.2 6024.2 6027.5 5789.5 5790.0 5792.4 5794.1 5798.0 5574.9 5597.7 5602.7 5620.0 5873.7 5654.7 5654.9 5659.4 5667.6 5706.9 5611.2 5614.6 5664.1 5669.6 5671.1 4390.0 4413.9 4436.6 4445.0 4465.6 3995.6 3997.9 4031.8 4035.4 4058.6 5427.1 5457.6 5459.9 5460.1 5505.3 5667.2 5717.3 5808.6 5829.0 5835.5 5504.3 5504.6 5504.8 5505.3 5558.0 5012.8 5013.1 5057.7 5062.1 5102.7 5873.9 5905.6 5936.8 6061.3 6069.7 5777.3 5785.3 5827.6 5832.0 5832.5 5979.1 5984.7 5988.8 5993.2 5994.2 5609.1 5619.0 5648.9 5673.5 5685.3 5762.8 5846.6 5850.7 5863.1 5881.5 5706.8 5710.4 5710.7 5717.2 5717.8 5557.5 5564.8 5665.4 5675.9 5679.9 5599.4 5657.2 5659.7 5701.8 5706.1 5701.7 5709.5 5751.4 5757.8 5760.5 5502.9 5510.5 5534.1 5558.2 5559.5 21:52:22 INFO - PROCESS | 5123 | 21:52:22 INFO - PROCESS | 5123 | [#1] ext.html Cycles:180 Average:61166.53 Median:44794.54 stddev:118857.20 (265.3%) stddev-sans-first:119103.57 21:52:22 INFO - PROCESS | 5123 | Values: 576.3 590.2 591.8 604.3 617.8 33272.0 44499.8 45094.0 45192.1 45275.5 48552.0 48942.5 48950.8 49045.0 49124.3 9018.1 9160.7 9164.1 9166.7 9169.7 8995.7 9078.7 9080.0 9091.1 9091.7 9132.0 9162.6 9175.3 9183.0 9186.2 9093.6 9137.5 9141.2 9151.3 9154.6 8914.2 8981.8 8987.4 8988.6 8989.0 43917.8 44235.0 44245.3 44340.0 44343.1 3599.8 3763.9 3796.7 3798.2 4178.2 33035.5 33067.8 33072.8 33092.0 33229.5 4322.9 4442.5 4472.5 4479.2 4596.1 264950.7 265043.0 265148.0 265148.0 265692.0 62341.0 62683.4 62804.4 62822.1 62857.4 57273.1 63015.0 65179.7 65617.3 65701.0 15809.8 15842.4 15878.4 15926.6 15990.8 13365.0 13557.8 13566.4 13568.9 13626.3 15896.6 17600.0 18040.8 18080.9 18169.8 6266.7 6397.3 6448.7 6450.2 6505.5 1020.7 1065.5 1071.4 1103.8 1125.4 33268.5 34101.8 34166.7 34176.3 34545.0 1127.1 1133.7 1180.1 1196.2 1256.7 17280.0 17839.9 17915.5 17927.9 17950.6 548551.0 550363.0 552763.0 552949.0 556391.0 63246.4 63337.0 63343.0 63421.4 63430.7 1323.2 1366.3 1395.6 1399.3 1401.0 31115.8 33062.3 33450.8 33627.7 34263.5 55751.0 56116.7 56151.0 56258.7 56285.4 473311.0 474207.0 474857.0 476766.0 477373.6 44122.0 45837.0 46501.3 46903.8 46928.0 50849.2 51388.5 51515.7 52193.0 52722.7 40825.6 43250.8 43881.0 45024.3 47900.5 43226.6 43719.1 43981.3 44798.5 44826.8 43772.0 44157.0 44790.6 44920.3 45290.3 35131.0 38375.6 39353.8 39622.8 40233.0 28949.0 28977.4 29193.9 29570.7 29893.7 21:52:22 INFO - PROCESS | 5123 | 21:52:22 INFO - PROCESS | 5123 | [#2] jquery.html Cycles:180 Average:30101.46 Median:2097.22 stddev:84293.75 (4019.3%) stddev-sans-first:84500.69 21:52:22 INFO - PROCESS | 5123 | Values: 384.7 400.1 408.7 411.3 427.7 15472.8 19749.6 20332.6 20405.8 20554.9 19412.7 19549.2 19553.8 19659.1 19731.1 5717.9 5819.2 5926.6 6015.4 6055.7 4058.4 5671.1 6130.0 6173.8 6174.1 5918.4 6002.2 6018.8 6020.6 6024.5 5817.1 5984.1 5998.6 6011.6 6016.6 5844.6 5847.3 5856.0 5858.4 5859.3 21783.9 23530.5 23907.2 24154.6 24285.4 2276.6 2290.1 2336.3 2382.6 2457.8 9842.7 10469.2 10637.8 11077.9 11094.1 1892.7 1897.6 1938.8 2002.9 2069.0 84203.0 85686.1 87312.0 87646.0 88047.6 11213.9 11495.9 11557.4 11713.8 11722.3 16192.4 16238.5 16265.2 16271.7 16288.4 8440.2 8647.1 8704.0 8716.0 8927.4 8415.0 8438.6 8466.7 8691.2 8727.7 4947.2 4960.8 5033.9 5057.9 5073.9 2048.6 2062.5 2073.2 2085.9 2108.5 857.8 914.4 1050.8 1066.4 1117.3 19529.2 20124.7 20132.7 20234.7 20534.9 927.2 969.2 990.0 1006.0 1045.2 10704.0 11056.8 11160.5 11215.4 11241.6 505720.0 509615.0 510196.0 513878.0 524325.0 58844.1 77301.6 89230.0 92879.5 94984.0 963.8 981.2 1001.3 1002.9 1003.6 17136.2 17654.5 18007.3 18021.7 18241.5 43559.6 44181.3 44548.3 44882.3 45348.0 55661.7 55708.0 56357.4 62166.0 62355.0 12194.3 12587.6 12710.6 13106.7 13111.5 13142.5 13395.9 13802.4 13847.7 14007.3 11852.9 12017.6 12128.5 12197.3 12351.5 11956.5 12128.4 12243.6 12261.4 12834.6 11883.6 12228.7 12247.4 12418.9 12424.5 12614.2 12656.0 12677.7 12724.2 13031.9 11568.4 11765.4 11797.5 11806.5 11821.6 21:52:22 INFO - PROCESS | 5123 | 21:52:22 INFO - PROCESS | 5123 | [#3] mootools.html Cycles:180 Average:6096.45 Median:5104.82 stddev:3488.18 (68.3%) stddev-sans-first:3479.90 21:52:22 INFO - PROCESS | 5123 | Values: 1371.7 1480.8 1543.2 1649.5 1772.0 8629.9 9030.2 9064.9 9308.3 9374.3 9181.3 9307.4 9309.5 9322.5 9322.8 2419.2 2461.4 2466.2 2467.5 2468.4 2401.1 2468.5 2476.7 2487.6 2490.8 2418.7 2440.8 2457.6 2461.9 2462.2 2405.7 2418.5 2420.3 2426.1 2434.5 2393.6 2412.8 2432.0 2435.2 2438.4 9134.9 9275.5 9398.4 9407.3 9409.1 5425.5 5565.4 5574.9 5580.9 5581.7 3667.8 3728.7 3737.0 3798.5 3817.7 1252.3 1254.8 1276.2 1281.3 1295.4 12609.7 12731.6 12736.4 12743.3 12743.7 4445.8 4757.6 4789.9 4854.9 4856.2 9867.5 9870.1 9876.9 9879.1 9881.2 5324.1 5325.5 5325.8 5333.3 5335.0 4472.0 4773.2 4902.5 4930.5 4959.9 3899.1 3947.3 4065.8 4073.4 4083.3 1208.7 1219.7 1228.7 1231.8 1232.3 900.5 906.4 914.6 917.4 951.5 5805.7 6201.4 6211.8 6428.0 6509.9 903.0 924.3 942.4 951.9 1009.8 3941.7 4286.4 4330.6 4432.9 4442.4 11328.9 11820.2 11833.0 11864.0 11907.0 11736.3 11766.1 11777.4 11873.6 11876.3 8094.3 8504.6 8512.8 8767.8 8770.7 6299.7 6302.5 6358.6 6479.8 6518.5 10798.3 10896.3 10922.6 11028.3 11056.2 10766.5 11134.6 11150.4 11213.0 11261.7 7493.6 7915.2 8122.9 8191.1 8315.8 9107.9 9168.5 9172.1 9172.2 9181.6 7875.9 8118.4 8146.0 8157.7 8269.4 7782.5 7843.6 7969.9 7975.2 8182.4 7428.9 7450.6 7521.3 7539.1 7550.1 5249.7 5402.3 5491.9 5524.4 5634.0 5524.6 5614.4 5639.7 5686.4 5695.0 21:52:22 INFO - PROCESS | 5123 | 21:52:22 INFO - PROCESS | 5123 | [#4] prototype.html Cycles:180 Average:4632.08 Median:5279.29 stddev:1340.76 (25.4%) stddev-sans-first:1305.04 21:52:22 INFO - PROCESS | 5123 | Values: 329.3 333.7 339.4 343.0 343.8 5233.4 5298.4 5300.2 5313.1 5315.9 5166.2 5224.3 5228.3 5231.7 5245.4 3942.9 3959.1 4087.6 4108.8 4134.5 4092.9 4105.0 4124.0 4139.4 4143.8 4087.7 4097.6 4105.9 4110.1 4116.9 3939.1 3944.8 4082.6 4086.2 4110.8 3676.7 3683.4 3690.4 3693.2 3701.0 5094.7 5101.9 5109.2 5127.9 5131.6 4513.2 4716.3 4721.8 4734.5 4747.0 5406.5 5420.8 5433.0 5436.8 5444.8 4069.7 4070.6 4070.9 4071.6 4090.7 5473.2 5610.4 5614.7 5662.9 5698.9 4141.2 4141.8 4144.1 4144.5 4149.3 4216.3 4221.5 4224.2 4232.8 4235.4 4444.6 4707.5 4731.9 4733.5 4744.4 5514.3 5516.2 5522.7 5525.3 5526.3 1446.2 1470.0 1470.3 1471.0 1472.1 913.7 950.0 954.6 956.8 978.5 5045.7 5151.7 5168.5 5175.0 5284.3 5566.9 5584.5 5593.9 5604.9 5606.6 5134.7 5226.6 5268.1 5274.3 5292.8 2914.9 2943.7 2947.7 2948.6 2956.8 5762.7 5782.9 5784.2 5793.5 5798.6 5396.6 5629.9 5699.3 5701.4 5709.2 5733.9 5741.2 5766.3 5770.7 5785.9 5229.5 5581.9 5582.5 5584.2 5602.1 5532.5 5557.8 5568.2 5585.4 5588.1 5839.1 5847.3 5847.6 5861.0 5870.5 3920.0 4047.0 4048.5 4049.9 4086.4 5604.7 5624.8 5632.2 5638.8 5639.4 5509.4 5509.8 5534.1 5554.6 5566.4 5240.6 5466.6 5496.6 5502.6 5532.4 5469.9 5477.9 5492.9 5493.4 5494.4 5308.7 5323.3 5481.4 5528.0 5559.9 5399.8 5401.4 5405.8 5410.2 5413.5 21:52:22 INFO - PROCESS | 5123 | 21:52:22 INFO - PROCESS | 5123 | [#5] yui.html Cycles:180 Average:21525.28 Median:4306.96 stddev:30911.68 (717.7%) stddev-sans-first:30959.05 21:52:22 INFO - PROCESS | 5123 | Values: 755.9 821.3 823.3 825.5 843.1 14981.8 15746.5 16159.7 16209.7 16592.1 15699.3 15807.8 15878.9 15907.8 15949.7 807.8 831.7 834.8 835.5 836.7 680.1 804.6 828.9 831.2 831.4 862.4 863.0 864.0 864.1 865.3 855.3 855.3 856.3 857.1 857.8 512.2 605.9 618.0 624.5 633.3 14933.2 15968.8 15977.7 15992.3 16018.5 13803.1 14218.2 14652.5 14730.3 14864.3 13325.1 13409.0 13517.8 13588.4 14068.0 4301.2 4305.5 4308.4 4331.2 4350.0 85449.6 88383.6 89108.6 89109.6 90553.0 11421.1 11931.2 11933.7 12340.0 12444.3 22985.3 23045.5 23314.9 23320.9 23629.0 8563.8 8760.1 8772.0 9109.0 9131.1 4798.7 4845.5 4886.1 4932.6 4935.2 3497.7 3608.4 3631.2 3633.2 3644.9 612.2 615.6 616.0 627.3 636.8 569.1 705.3 722.9 727.0 730.6 8706.0 9705.5 9977.6 10041.5 10224.5 565.5 678.7 686.2 689.4 731.9 4574.3 4849.8 4874.2 4898.4 4916.5 74760.9 84624.6 127371.0 129342.4 138287.3 107803.5 110270.0 111147.4 112242.0 112577.0 60524.1 61728.4 78930.6 78946.0 80860.2 9982.3 10004.8 10046.3 10235.4 10455.6 52407.5 53877.0 54628.5 55267.0 55855.0 71956.3 75216.2 77015.6 80082.6 82635.6 13475.1 13547.3 13669.7 13805.6 14230.0 14984.9 15386.5 15659.1 15739.7 16078.4 14449.3 14595.6 14887.8 15647.8 15721.2 14128.2 14129.4 14217.9 14550.9 14696.8 13579.9 14088.6 14131.5 14711.3 14712.8 11086.3 11372.7 11836.6 11990.5 12065.5 11958.4 11999.9 12054.0 12246.0 12248.4 21:52:22 INFO - PROCESS | 5123 | -------- Summary: end -------- 21:52:22 INFO - PROCESS | 5123 | 21:52:23 INFO - TEST-INFO | 5123: exit 0 21:52:23 INFO - TEST-OK | dromaeo_css | took 1115151ms 21:52:23 INFO - TEST-START | kraken 21:52:23 INFO - operating with platform_type : linux_ 21:52:23 INFO - Initialising browser for kraken test... 21:52:23 INFO - TEST-INFO | started process 5318 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpknpLsq/profile http://localhost:34539/getInfo.html) 21:52:27 INFO - PROCESS | 5318 | __metrics Screen width/height:1600/1200 21:52:27 INFO - PROCESS | 5318 | colorDepth:24 21:52:27 INFO - PROCESS | 5318 | Browser inner width/height: 1024/697 21:52:27 INFO - PROCESS | 5318 | __metrics 21:52:28 INFO - PROCESS | 5318 | 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" 21:52:28 INFO - PROCESS | 5318 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:52:29 INFO - TEST-INFO | 5318: exit 0 21:52:29 INFO - Browser initialized. 21:52:29 INFO - Running cycle 1/1 for kraken test... 21:52:29 INFO - TEST-INFO | started process 5399 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpknpLsq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:52:40 INFO - PROCESS | 5399 | RSS: Main: 155856896 21:52:40 INFO - PROCESS | 5399 | 21:52:43 INFO - PROCESS | 5399 | Cycle 1(1): loaded http://localhost:34539/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34539/tests/kraken/driver.html?testName=audio-beat-detection) 21:52:44 INFO - PROCESS | 5399 | RSS: Main: 211681280 21:52:44 INFO - PROCESS | 5399 | 21:52:46 INFO - PROCESS | 5399 | Cycle 1(1): loaded http://localhost:34539/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34539/tests/kraken/driver.html?testName=audio-dft) 21:52:47 INFO - PROCESS | 5399 | RSS: Main: 191221760 21:52:47 INFO - PROCESS | 5399 | 21:52:50 INFO - PROCESS | 5399 | Cycle 1(1): loaded http://localhost:34539/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34539/tests/kraken/driver.html?testName=audio-fft) 21:52:50 INFO - PROCESS | 5399 | RSS: Main: 268607488 21:52:50 INFO - PROCESS | 5399 | 21:52:53 INFO - PROCESS | 5399 | Cycle 1(1): loaded http://localhost:34539/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34539/tests/kraken/driver.html?testName=audio-oscillator) 21:52:53 INFO - PROCESS | 5399 | RSS: Main: 178204672 21:52:53 INFO - PROCESS | 5399 | 21:52:55 INFO - PROCESS | 5399 | Cycle 1(1): loaded http://localhost:34539/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34539/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:52:56 INFO - PROCESS | 5399 | RSS: Main: 182632448 21:52:56 INFO - PROCESS | 5399 | 21:52:59 INFO - PROCESS | 5399 | Cycle 1(1): loaded http://localhost:34539/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34539/tests/kraken/driver.html?testName=imaging-darkroom) 21:53:00 INFO - PROCESS | 5399 | RSS: Main: 218071040 21:53:00 INFO - PROCESS | 5399 | 21:53:03 INFO - PROCESS | 5399 | Cycle 1(1): loaded http://localhost:34539/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34539/tests/kraken/driver.html?testName=imaging-desaturate) 21:53:03 INFO - PROCESS | 5399 | RSS: Main: 218746880 21:53:03 INFO - PROCESS | 5399 | 21:53:06 INFO - PROCESS | 5399 | Cycle 1(1): loaded http://localhost:34539/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34539/tests/kraken/driver.html?testName=json-parse-financial) 21:53:07 INFO - PROCESS | 5399 | RSS: Main: 218759168 21:53:07 INFO - PROCESS | 5399 | 21:53:08 INFO - PROCESS | 5399 | Cycle 1(1): loaded http://localhost:34539/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34539/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:53:09 INFO - PROCESS | 5399 | RSS: Main: 180396032 21:53:09 INFO - PROCESS | 5399 | 21:53:11 INFO - PROCESS | 5399 | Cycle 1(1): loaded http://localhost:34539/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34539/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:53:11 INFO - PROCESS | 5399 | RSS: Main: 188706816 21:53:11 INFO - PROCESS | 5399 | 21:53:13 INFO - PROCESS | 5399 | Cycle 1(1): loaded http://localhost:34539/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34539/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:53:14 INFO - PROCESS | 5399 | RSS: Main: 193146880 21:53:14 INFO - PROCESS | 5399 | 21:53:16 INFO - PROCESS | 5399 | Cycle 1(1): loaded http://localhost:34539/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34539/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:53:17 INFO - PROCESS | 5399 | RSS: Main: 187961344 21:53:17 INFO - PROCESS | 5399 | 21:53:20 INFO - PROCESS | 5399 | Cycle 1(1): loaded http://localhost:34539/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34539/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:53:20 INFO - PROCESS | 5399 | RSS: Main: 191250432 21:53:20 INFO - PROCESS | 5399 | 21:53:22 INFO - PROCESS | 5399 | Cycle 1(1): loaded http://localhost:34539/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34539/tests/kraken/driver.html?testName=ai-astar) 21:53:22 INFO - PROCESS | 5399 | RSS: Main: 242503680 21:53:22 INFO - PROCESS | 5399 | 21:53:22 INFO - PROCESS | 5399 | __start_tp_report 21:53:22 INFO - PROCESS | 5399 | _x_x_mozilla_page_load 21:53:22 INFO - PROCESS | 5399 | _x_x_mozilla_page_load_details 21:53:22 INFO - PROCESS | 5399 | |i|pagename|runs| 21:53:22 INFO - PROCESS | 5399 | |0;ai-astar;101;112;110;110;111;111;110;109;109;110 21:53:22 INFO - PROCESS | 5399 | |1;audio-beat-detection;146;131;118;184;117;120;145;116;148;116 21:53:22 INFO - PROCESS | 5399 | |2;audio-dft;161;160;160;160;159;161;158;161;162;161 21:53:22 INFO - PROCESS | 5399 | |3;audio-fft;75;74;73;112;76;75;73;75;100;76 21:53:22 INFO - PROCESS | 5399 | |4;audio-oscillator;83;83;130;84;84;83;125;84;83;129 21:53:22 INFO - PROCESS | 5399 | |5;imaging-gaussian-blur;117;116;117;116;117;116;117;117;116;117 21:53:22 INFO - PROCESS | 5399 | |6;imaging-darkroom;146;145;146;146;147;145;146;146;147;145 21:53:22 INFO - PROCESS | 5399 | |7;imaging-desaturate;96;96;95;96;95;95;95;95;95;95 21:53:22 INFO - PROCESS | 5399 | |8;json-parse-financial;54;52;51;52;50;51;50;51;51;51 21:53:22 INFO - PROCESS | 5399 | |9;json-stringify-tinderbox;60;62;61;60;61;60;61;61;61;61 21:53:22 INFO - PROCESS | 5399 | |10;stanford-crypto-aes;75;74;75;75;75;76;76;74;75;81 21:53:22 INFO - PROCESS | 5399 | |11;stanford-crypto-ccm;128;120;118;119;115;125;121;132;150;133 21:53:22 INFO - PROCESS | 5399 | |12;stanford-crypto-pbkdf2;155;154;159;155;163;153;156;158;156;158 21:53:22 INFO - PROCESS | 5399 | |13;stanford-crypto-sha256-iterative;62;62;63;62;62;61;61;61;62;60 21:53:22 INFO - PROCESS | 5399 | __end_tp_report 21:53:22 INFO - PROCESS | 5399 | __start_cc_report 21:53:22 INFO - PROCESS | 5399 | _x_x_mozilla_cycle_collect,1650 21:53:22 INFO - PROCESS | 5399 | __end_cc_report 21:53:22 INFO - PROCESS | 5399 | __startTimestamp1470891202803__endTimestamp 21:53:22 INFO - PROCESS | 5399 | 21:53:22 INFO - PROCESS | 5399 | ------- Summary: start ------- 21:53:22 INFO - PROCESS | 5399 | Number of tests: 14 21:53:22 INFO - PROCESS | 5399 | 21:53:22 INFO - PROCESS | 5399 | [#0] ai-astar Cycles:10 Average:109.30 Median:110.00 stddev:3.06 (2.8%) stddev-sans-first:0.97 21:53:22 INFO - PROCESS | 5399 | Values: 101.0 112.0 110.0 110.0 111.0 111.0 110.0 109.0 109.0 110.0 21:53:22 INFO - PROCESS | 5399 | 21:53:22 INFO - PROCESS | 5399 | [#1] audio-beat-detection Cycles:10 Average:134.10 Median:138.00 stddev:22.01 (15.9%) stddev-sans-first:22.92 21:53:22 INFO - PROCESS | 5399 | Values: 146.0 131.0 118.0 184.0 117.0 120.0 145.0 116.0 148.0 116.0 21:53:22 INFO - PROCESS | 5399 | 21:53:22 INFO - PROCESS | 5399 | [#2] audio-dft Cycles:10 Average:160.30 Median:161.00 stddev:1.16 (0.7%) stddev-sans-first:1.20 21:53:22 INFO - PROCESS | 5399 | Values: 161.0 160.0 160.0 160.0 159.0 161.0 158.0 161.0 162.0 161.0 21:53:22 INFO - PROCESS | 5399 | 21:53:22 INFO - PROCESS | 5399 | [#3] audio-fft Cycles:10 Average:80.90 Median:75.00 stddev:13.57 (18.1%) stddev-sans-first:14.22 21:53:22 INFO - PROCESS | 5399 | Values: 75.0 74.0 73.0 112.0 76.0 75.0 73.0 75.0 100.0 76.0 21:53:22 INFO - PROCESS | 5399 | 21:53:22 INFO - PROCESS | 5399 | [#4] audio-oscillator Cycles:10 Average:96.80 Median:83.00 stddev:21.57 (26.0%) stddev-sans-first:22.29 21:53:22 INFO - PROCESS | 5399 | Values: 83.0 83.0 130.0 84.0 84.0 83.0 125.0 84.0 83.0 129.0 21:53:22 INFO - PROCESS | 5399 | 21:53:22 INFO - PROCESS | 5399 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 21:53:22 INFO - PROCESS | 5399 | Values: 117.0 116.0 117.0 116.0 117.0 116.0 117.0 117.0 116.0 117.0 21:53:22 INFO - PROCESS | 5399 | 21:53:22 INFO - PROCESS | 5399 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 21:53:22 INFO - PROCESS | 5399 | Values: 146.0 145.0 146.0 146.0 147.0 145.0 146.0 146.0 147.0 145.0 21:53:22 INFO - PROCESS | 5399 | 21:53:22 INFO - PROCESS | 5399 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 21:53:22 INFO - PROCESS | 5399 | Values: 96.0 96.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 21:53:22 INFO - PROCESS | 5399 | 21:53:22 INFO - PROCESS | 5399 | [#8] json-parse-financial Cycles:10 Average:51.30 Median:51.00 stddev:1.16 (2.3%) stddev-sans-first:0.71 21:53:22 INFO - PROCESS | 5399 | Values: 54.0 52.0 51.0 52.0 50.0 51.0 50.0 51.0 51.0 51.0 21:53:22 INFO - PROCESS | 5399 | 21:53:22 INFO - PROCESS | 5399 | [#9] json-stringify-tinderbox Cycles:10 Average:60.80 Median:61.00 stddev:0.63 (1.0%) stddev-sans-first:0.60 21:53:22 INFO - PROCESS | 5399 | Values: 60.0 62.0 61.0 60.0 61.0 60.0 61.0 61.0 61.0 61.0 21:53:22 INFO - PROCESS | 5399 | 21:53:22 INFO - PROCESS | 5399 | [#10] stanford-crypto-aes Cycles:10 Average:75.60 Median:75.00 stddev:2.01 (2.7%) stddev-sans-first:2.12 21:53:22 INFO - PROCESS | 5399 | Values: 75.0 74.0 75.0 75.0 75.0 76.0 76.0 74.0 75.0 81.0 21:53:22 INFO - PROCESS | 5399 | 21:53:22 INFO - PROCESS | 5399 | [#11] stanford-crypto-ccm Cycles:10 Average:126.10 Median:126.50 stddev:10.33 (8.2%) stddev-sans-first:10.94 21:53:22 INFO - PROCESS | 5399 | Values: 128.0 120.0 118.0 119.0 115.0 125.0 121.0 132.0 150.0 133.0 21:53:22 INFO - PROCESS | 5399 | 21:53:22 INFO - PROCESS | 5399 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.70 Median:157.00 stddev:2.91 (1.9%) stddev-sans-first:3.02 21:53:22 INFO - PROCESS | 5399 | Values: 155.0 154.0 159.0 155.0 163.0 153.0 156.0 158.0 156.0 158.0 21:53:22 INFO - PROCESS | 5399 | 21:53:22 INFO - PROCESS | 5399 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.60 Median:62.00 stddev:0.84 (1.4%) stddev-sans-first:0.88 21:53:22 INFO - PROCESS | 5399 | Values: 62.0 62.0 63.0 62.0 62.0 61.0 61.0 61.0 62.0 60.0 21:53:22 INFO - PROCESS | 5399 | -------- Summary: end -------- 21:53:22 INFO - PROCESS | 5399 | 21:53:23 INFO - TEST-INFO | 5399: exit 0 21:53:23 INFO - TEST-OK | kraken | took 60467ms 21:53:23 INFO - SUITE-END | took 1175s 21:53:23 INFO - Completed test suite (00:19:35) 21:53:23 INFO - javascript benchmark 21:53:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3078.5843911072, 3107.937841243174, 3114.7819652624316, 3129.561861339413, 3143.701348371905, 5328.360596728388, 5335.946640533598, 5437.6465529740535, 5438.428964958682, 5483.698396588191, 5370.892582148354, 5383.515483606475, 5402.9729851350685, 5427.83716488506, 5445.319335083114, 4800.279958006301, 4919.26211068339, 4932.112219800434, 4935.0, 5104.438511763703, 5029.698218106907, 5041.546260836524, 5065.493450654923, 5079.847604571869, 5081.161608334621, 4805.927911081337, 4837.9516204837855, 4852.393450818648, 4903.828366007173, 5021.347224860763, 4868.659193856396, 4875.561199492027, 4879.341288925985, 4879.707217566957, 4978.751062446863, 4882.2432522959, 4893.021395720834, 4905.509449055102, 4923.236898280772, 4953.108440480712, 5325.094733895247, 5332.520073193393, 5376.892462150735, 5404.243405923174, 5415.512603865632, 5810.1865738796605, 5818.0, 5863.58954873159, 5870.471657550798, 5875.559333049996, 5821.854453638651, 5861.70691465425, 5864.7067646617925, 5869.7652093916195, 5909.734061967179, 5339.866503337409, 5365.436629153922, 5418.078926582491, 5419.620626556103, 5502.944970550323, 5989.700514974234, 6018.518518518508, 6020.247469066367, 6024.186734790835, 6027.457528822427, 5789.507891829242, 5790.044642634002, 5792.362840087587, 5794.101914203306, 5798.0, 5574.860628484239, 5597.6641401516035, 5602.663840169603, 5620.044592419298, 5873.735681894325, 5654.74553645087, 5654.886902261932, 5659.434056594308, 5667.574931880125, 5706.914396284059, 5611.158326251013, 5614.606977511617, 5664.065429204217, 5669.6314739541795, 5671.120976248688, 4390.0, 4413.889652758707, 4436.556344365602, 4444.977775111104, 4465.620422264144, 3995.62041606047, 3997.9205614483926, 4031.7984100794843, 4035.435039094529, 4058.5738497457933, 5427.050266203478, 5457.5633949283965, 5459.945400546023, 5460.126379779216, 5505.311836020497, 5667.206591077253, 5717.256756621696, 5808.564357673192, 5829.0, 5835.503982161479, 5504.256925315111, 5504.587155963319, 5504.80733174337, 5505.256790333334, 5557.972210138923, 5012.824551140692, 5013.1227035268685, 5057.747112644353, 5062.11413002723, 5102.719350435687, 5873.911891321719, 5905.5570832186895, 5936.762529498945, 6061.272647282354, 6069.696515174223, 5777.335606405317, 5785.334686511105, 5827.562932779973, 5831.950248955059, 5832.50423713989, 5979.103134529855, 5984.700764961647, 5988.760449581968, 5993.220881285405, 5994.220751302303, 5609.102543593006, 5619.0, 5648.85877853057, 5673.489385955284, 5685.260916080883, 5762.827115186551, 5846.590738648424, 5850.707464626837, 5863.120531920246, 5881.500072493886, 5706.80026199081, 5710.400407957105, 5710.657360558213, 5717.228174196596, 5717.771289148387, 5557.527610153021, 5564.77740890376, 5665.433456654301, 5675.858103547444, 5679.858003549779, 5599.356074051536, 5657.236273103243, 5659.688717120598, 5701.828945131652, 5706.115552089267, 5701.657900525982, 5709.543236540983, 5751.424857514216, 5757.798477053283, 5760.481556659921, 5502.9174562380795, 5510.531604813636, 5534.05920993424, 5558.166275058612, 5559.471850174227], "value": 5343.135320199678, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [576.3343338444096, 590.2474345209858, 591.8490784849865, 604.2537466229209, 617.7992152550421, 33272.0, 44499.77750111249, 45094.0, 45192.096158076856, 45275.547244527545, 48552.0, 48942.51057489424, 48950.75524622372, 49045.0, 49124.263136052985, 9018.09819018098, 9160.725178244664, 9164.083591640849, 9166.679166229183, 9169.724908252763, 8995.730128096135, 9078.727638170865, 9080.046595107518, 9091.09089091089, 9091.681791137311, 9132.041135680758, 9162.587683554222, 9175.311851611123, 9182.989871114172, 9186.173244408003, 9093.590788414538, 9137.543122843832, 9141.222996045344, 9151.267898568098, 9154.633814647439, 8914.242289405409, 8981.775455613597, 8987.37088403812, 8988.59551320192, 8989.0, 43917.78041109806, 44235.0, 44245.336319955226, 44340.00939915423, 44343.11313773706, 3599.8200089995657, 3763.8870833875026, 3796.658300752918, 3798.2213646202463, 4178.185253875496, 33035.504467433006, 33067.83466082678, 33072.834635826905, 33092.0, 33229.50155747642, 4322.913541729148, 4442.53353397895, 4472.508024117345, 4479.193745125876, 4596.1267359201975, 264950.67524662445, 265043.0, 265148.0, 265148.0455586336, 265692.0, 62341.0, 62683.37316626866, 62804.37195628077, 62822.057669135, 62857.37142628607, 57273.14090288649, 63015.0, 65179.674101629185, 65617.34382656112, 65701.0, 15809.762853557206, 15842.366305347658, 15878.44425445127, 15926.60183495422, 15990.840091598935, 13365.0, 13557.796633050511, 13566.389512471962, 13568.86431135676, 13626.318684065756, 15896.602584935237, 17600.0, 18040.819591804175, 18080.9095954522, 18169.818301816813, 6266.749330026748, 6397.328280530477, 6448.677566121675, 6450.161479007699, 6505.4795616350575, 1020.6937918624383, 1065.4992153687908, 1071.4000159910474, 1103.7682086761708, 1125.3754166437707, 33268.50097248543, 34101.8064367746, 34166.658333416846, 34176.316473670886, 34545.0, 1127.0870594818223, 1133.699569614053, 1180.144395313391, 1196.180616277835, 1256.6858285428643, 17280.0, 17839.910800445916, 17915.462536123938, 17927.910360448113, 17950.640987180446, 548551.0, 550363.0, 552763.0, 552949.0, 556391.0, 63246.36753632497, 63337.0, 63343.0, 63421.36578634247, 63430.68284658547, 1323.1862404621088, 1366.2895294446832, 1395.5743498233137, 1399.2793711238517, 1401.0, 31115.84442077775, 33062.33875322528, 33450.83274583612, 33627.66372336245, 34263.48604770881, 55751.0, 56116.71941640429, 56151.0, 56258.71870640621, 56285.43714562802, 473311.0, 474207.0, 474857.0, 476766.0, 477373.58384912723, 44122.0, 45837.0, 46501.3024804635, 46903.76548117238, 46928.0, 50849.237261440365, 51388.48611513837, 51515.74242128765, 52193.0, 52722.736386317825, 40825.59174408218, 43250.78374608107, 43881.0, 45024.32463513115, 47900.520994791, 43226.567734322256, 43719.125617488105, 43981.34027989519, 44798.51902449695, 44826.77586612046, 43772.0, 44157.0, 44790.55209447864, 44920.32619510645, 45290.32064518969, 35131.0, 38375.61624383832, 39353.80323098366, 39622.80188599154, 40233.0, 28949.0, 28977.371866135098, 29193.85403072971, 29570.704292956794, 29893.70106298909], "value": 20972.516356565364, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.7326108354695, 400.08580393800156, 408.71798459063245, 411.2946297100472, 427.74762889894976, 15472.845271547281, 19749.605007899834, 20332.59334813303, 20405.795942040575, 20554.89722551387, 19412.708809367836, 19549.21803127873, 19553.804461955377, 19659.11533980971, 19731.112099955502, 5717.942820571793, 5819.243498345209, 5926.614770039933, 6015.368386319439, 6055.666938318391, 4058.391241313812, 5671.06427439472, 6130.049842274455, 6173.845653858667, 6174.10475481055, 5918.40815918409, 6002.189704389895, 6018.849528761794, 6020.608660437079, 6024.48791852693, 5817.127430885359, 5984.102384642295, 5998.5800993930225, 6011.609245399079, 6016.639001659914, 5844.649321040752, 5847.26909136358, 5856.0, 5858.443447872445, 5859.296884373903, 21783.891080544498, 23530.529389412288, 23907.16324928619, 24154.637680434807, 24285.392865178335, 2276.64711969645, 2290.118304452781, 2336.299110266913, 2382.630692242705, 2457.8156638252203, 9842.65550705722, 10469.214808889365, 10637.840432393521, 11077.944610276896, 11094.1124710023, 1892.6593213221615, 1897.5730460646382, 1938.7901909519794, 2002.8898410587412, 2069.0, 84203.0, 85686.14313856844, 87312.0, 87646.0, 88047.55976220142, 11213.887861121284, 11495.88504114965, 11557.364344960939, 11713.824292635618, 11722.300266461485, 16192.352305907632, 16238.512844614681, 16265.186740662919, 16271.67456650884, 16288.42990495327, 8440.198181172778, 8647.092055334226, 8704.0, 8715.956420217984, 8927.419717718327, 8415.032271288881, 8438.620262088221, 8466.661333546712, 8691.217790398894, 8727.738167854974, 4947.233178857211, 4960.751962401865, 5033.949660503348, 5057.949420505748, 5073.949260507347, 2048.6107639548436, 2062.5462398272357, 2073.2225415469197, 2085.937421877346, 2108.493961449241, 857.8155696525278, 914.3964983111248, 1050.821360368739, 1066.3655125200494, 1117.2570240789946, 19529.218831246875, 20124.698129527776, 20132.698009529868, 20234.69647955282, 20534.897325513277, 927.1933417926447, 969.1907257440116, 989.9901000989898, 1006.0291818395137, 1045.1586472889246, 10704.0, 11056.834147487794, 11160.497777600027, 11215.383153926448, 11241.550337986553, 505720.0, 509615.0, 510196.0, 513878.0, 524325.0, 58844.11733823996, 77301.6134919333, 89230.0, 92879.53560232156, 94984.0, 963.7735132243918, 981.1513041219293, 1001.2840818814437, 1002.9469057489607, 1003.6236411345745, 17136.228869700644, 17654.470365889043, 18007.2797088115, 18021.72967405516, 18241.543961401072, 43559.56440435555, 44181.337279940184, 44548.33177502275, 44882.326765097896, 45348.0, 55661.721691391285, 55708.0, 56357.43642563522, 62166.0, 62355.0, 12194.31725020494, 12587.559435419716, 12710.618681439202, 13106.73786524245, 13111.475540978254, 13142.474301027853, 13395.933020334836, 13802.378892950042, 13847.653808654864, 14007.299635018413, 11852.881471185177, 12017.63947081589, 12128.514859405524, 12197.268163910194, 12351.505939762308, 11956.521739130338, 12128.393580321124, 12243.632691019284, 12261.448234829275, 12834.614961551168, 11883.643490695293, 12228.69428264265, 12247.387630618256, 12418.875811242133, 12424.503019879465, 12614.243145411305, 12656.0, 12677.683057923625, 12724.1729287596, 13031.869681303066, 11568.421578921188, 11765.411729413667, 11797.52809887595, 11806.527738890349, 11821.645350639494], "value": 9685.24897677754, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1371.6707990082384, 1480.7852861335107, 1543.205249296612, 1649.4639242246265, 1772.0342413384703, 8629.870551941718, 9030.187283144523, 9064.864027039592, 9308.34841561091, 9374.296927730413, 9181.2654987601, 9307.395019323756, 9309.487978161198, 9322.48726320052, 9322.813543729138, 2419.213755529451, 2461.3723500507435, 2466.2477944227003, 2467.5064987002584, 2468.4075821802726, 2401.075585899424, 2468.469279104992, 2476.6532685424054, 2487.6268559716004, 2490.7633774791457, 2418.7218469875925, 2440.8169387296025, 2457.594496908017, 2461.876906154694, 2462.1997850698503, 2405.687260656121, 2418.504206637635, 2420.334408037786, 2426.102342133413, 2434.4887573609562, 2393.581123303415, 2412.819038572114, 2432.0028788196755, 2435.1598698449006, 2438.426969662128, 9134.90865091354, 9275.536223188881, 9398.389104708172, 9407.29445291606, 9409.106134917172, 5425.511703946624, 5565.415631358689, 5574.944250557483, 5580.91628625571, 5581.665100093966, 3667.7799332039895, 3728.6830619397383, 3736.981315093434, 3798.5441746990537, 3817.7136714746507, 1252.273681264864, 1254.7553227120716, 1276.2215048820212, 1281.3337064726384, 1295.4494339905596, 12609.74780504394, 12731.61805145847, 12736.363181840963, 12743.299118548475, 12743.745125097446, 4445.799939002688, 4757.571818536279, 4789.904201915942, 4854.8786280342565, 4856.17445034345, 9867.506624668738, 9870.111689947938, 9876.901230987742, 9879.110880020833, 9881.160101391462, 5324.148136298174, 5325.4940780625775, 5325.760340784596, 5333.280007199056, 5335.0, 4471.977640111779, 4773.188557945157, 4902.48523904992, 4930.482299358588, 4959.925601115986, 3899.122697393064, 3947.3289540777564, 4065.837366505307, 4073.368627862686, 4083.285425050664, 1208.6552088947415, 1219.7194645231468, 1228.6989687526614, 1231.8398608181058, 1232.3160645841465, 900.5362238447204, 906.3519583497699, 914.5884352041475, 917.4082716647724, 951.509972364233, 5805.7387417566315, 6201.410865967726, 6211.81364559064, 6428.0, 6509.934900651027, 902.9954850225707, 924.2605915267893, 942.4439580647451, 951.8952915179325, 1009.8485227215976, 3941.664958478504, 4286.357046442996, 4330.631896288851, 4432.911341773211, 4442.444694413199, 11328.943355283336, 11820.23168494045, 11833.0, 11864.0, 11907.047436205083, 11736.295822250693, 11766.058715302755, 11777.352245626573, 11873.643790686294, 11876.287422754662, 8094.311983481352, 8504.617292221865, 8512.829743405096, 8767.824643507221, 8770.736877893672, 6299.716512757029, 6302.495800335871, 6358.618482891041, 6479.8056058318325, 6518.543701941008, 10798.298110622783, 10896.291741036806, 10922.563097476012, 11028.28316159447, 11056.226064175431, 10766.46167691628, 11134.610288639858, 11150.438938548772, 11213.0, 11261.718457038312, 7493.550386976799, 7915.248051435105, 8122.878156827771, 8191.058028326819, 8315.750527484186, 9107.863382049407, 9168.54157292146, 9172.082791720775, 9172.174504294378, 9181.632734690806, 7875.921240787748, 8118.431709780495, 8145.959270203611, 8157.6736930524485, 8269.379796515164, 7782.533048017137, 7843.647035883285, 7969.880451793112, 7975.202479751978, 8182.427230093836, 7428.851422971618, 7450.590217538087, 7521.323080922743, 7539.095308563007, 7550.093988721169, 5249.7112658804135, 5402.324709411324, 5491.890162196813, 5524.364698059774, 5634.042212823748, 5524.640898341595, 5614.410486898817, 5639.689817060101, 5686.402927692533, 5695.003374409379], "value": 4852.319328444749, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.2755936938735, 333.74635277189327, 339.4399241251935, 342.99657003429957, 343.8332408781739, 5233.371995360557, 5298.4436634153435, 5300.178472336789, 5313.123334649783, 5315.920261196085, 5166.225066240066, 5224.29472021277, 5228.29418028566, 5231.738413079341, 5245.396779370373, 3942.9211415771665, 3959.148783011652, 4087.591240875918, 4108.794560271989, 4134.545200027995, 4092.9181416371653, 4104.97947510262, 4123.958760412403, 4139.358399448075, 4143.751374917515, 4087.6729861611147, 4097.610726980932, 4105.856295029675, 4110.136871257039, 4116.938245926313, 3939.1333906540517, 3944.8027598620242, 4082.632563069308, 4086.2031903778775, 4110.794460277004, 3676.6507181817683, 3683.4106542953, 3690.3910854708943, 3693.2059607184583, 3701.0, 5094.694318340911, 5101.897962040738, 5109.233614957749, 5127.89750203708, 5131.563817075553, 4513.232750432401, 4716.292556116576, 4721.787519561628, 4734.455537613184, 4747.029982557269, 5406.513413792777, 5420.783168673248, 5433.0, 5436.782528698847, 5444.802143528417, 4069.6744260459386, 4070.5725898780793, 4070.9185816283507, 4071.592840715905, 4090.6522945549664, 5473.2063850741415, 5610.438956104357, 5614.736684246012, 5662.886742265132, 5698.857528561819, 4141.192467468875, 4141.772202528829, 4144.129732756155, 4144.544100148981, 4149.294619914562, 4216.262154123016, 4221.472315960505, 4224.197402493516, 4232.756638981345, 4235.428217190702, 4444.622207112432, 4707.458642256116, 4731.905361892812, 4733.526647335308, 4744.383230180055, 5514.310711161105, 5516.172574113915, 5522.66863988162, 5525.254090697785, 5526.309211348582, 1446.2407236200868, 1469.9853001470062, 1470.2869108482378, 1471.0364711114237, 1472.0799500312305, 913.6939125393068, 949.9667511637059, 954.5752140297501, 956.784723437221, 978.5077756421514, 5045.672031317952, 5151.6908985461, 5168.53483186515, 5175.0, 5284.286621306152, 5566.916496252559, 5584.469475399912, 5593.888122237532, 5604.943950560524, 5606.635568688022, 5134.743262836843, 5226.555742761831, 5268.051750684914, 5274.287971123926, 5292.841214763564, 2914.8979785707397, 2943.6909124542044, 2947.705229477078, 2948.6166798316513, 2956.763458923281, 5762.711864406847, 5782.913256301159, 5784.219130417424, 5793.482332376077, 5798.565107616945, 5396.568274538027, 5629.887402252014, 5699.344575373885, 5701.429857014348, 5709.200711900337, 5733.942660573424, 5741.224934633771, 5766.2503874497725, 5770.682612456355, 5785.8553536161935, 5229.50319719626, 5581.860453488695, 5582.52548533363, 5584.190292407659, 5602.13166959122, 5532.529734972572, 5557.833265002057, 5568.192612071146, 5585.357683866408, 5588.100045984384, 5839.124131380327, 5847.327557330791, 5847.573257098256, 5861.0, 5870.53035757147, 3919.9608003919598, 4046.9595304046584, 4048.530396458217, 4049.8582549610624, 4086.387041943732, 5604.674060264944, 5624.803131890364, 5632.239647647679, 5638.774449021993, 5639.407862174413, 5509.3664228612615, 5509.752061157177, 5534.090738695305, 5554.583406244467, 5566.359868615161, 5240.5807535397835, 5466.562674985912, 5496.642718223303, 5502.614816962774, 5532.3637781654015, 5469.945300546943, 5477.890442191054, 5492.890142197213, 5493.434371204187, 5494.395616482264, 5308.681479111266, 5323.3231532067275, 5481.437790229925, 5528.037546670874, 5559.944400555943, 5399.757010934439, 5401.378841433286, 5405.756740946588, 5410.242566040836, 5413.458654134555], "value": 4208.980066080104, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [755.8715018446863, 821.3018933906178, 823.3495538524562, 825.4758228524885, 843.0978852627683, 14981.775273370895, 15746.527604171864, 16159.676806463864, 16209.675806483878, 16592.08743519109, 15699.293531791069, 15807.84192158078, 15878.920605396957, 15907.761383579256, 15949.681006379895, 807.8424707182087, 831.6631764135535, 834.8497270491309, 835.5446281776447, 836.7196989008659, 680.1192455769765, 804.5735760047185, 828.8508068547659, 831.1563762780783, 831.4429332347312, 862.3790870573177, 863.0204717645486, 864.0452300208242, 864.1444969480201, 865.3423398217369, 855.2644725536034, 855.268745222838, 856.3448961544424, 857.1171693156091, 857.781265777229, 512.2009664922732, 605.8969975104243, 618.0327787013355, 624.4972796898495, 633.284388640833, 14933.178675172861, 15968.76046859298, 15977.680446391123, 15992.280347384396, 16018.51944441657, 13803.102798318178, 14218.217998010105, 14652.487162949246, 14730.337134828958, 14864.256787160706, 13325.067245292834, 13409.0, 13517.776727290762, 13588.38852251651, 14068.015238933278, 4301.171912059177, 4305.526392096898, 4308.3968244445805, 4331.177076355482, 4349.956500434987, 85449.57275213646, 88383.55808220983, 89108.55445722795, 89109.55445222797, 90553.0, 11421.070880182311, 11931.224470409396, 11933.701657458632, 12340.014369595952, 12444.315562644142, 22985.310440686808, 23045.53908921812, 23314.883425582764, 23320.883395583252, 23629.0, 8563.785905352292, 8760.080191579793, 8772.0, 9109.0, 9131.086891310815, 4798.664093513421, 4845.53967373099, 4886.120498310267, 4932.630052746058, 4935.185694360391, 3497.7026952708798, 3608.350496910529, 3631.2011357501306, 3633.1825339298975, 3644.9453258201147, 612.2316492801594, 615.6213928434071, 616.0389791924612, 627.3130921640796, 636.8439732265532, 569.0922977850322, 705.2665228162654, 722.9277072292729, 726.9818254543572, 730.5981710059446, 8706.0, 9705.466199359103, 9977.551010204557, 10041.497925103715, 10224.488775561193, 565.5334349161958, 678.716267949673, 686.1560280854577, 689.3657834792003, 731.8536292741474, 4574.268117101248, 4849.830255941025, 4874.244492103659, 4898.436679781799, 4916.532929371704, 74760.87858682124, 84624.57687711646, 127371.0, 129342.35328823296, 138287.30856345655, 107803.46098269458, 110270.0, 111147.424653564, 112242.0, 112577.0, 60524.092138617954, 61728.382716172266, 78930.60534697404, 78945.99999999885, 80860.19139808645, 9982.301238913496, 10004.849927251242, 10046.346987445793, 10235.385876847413, 10455.581776728846, 52407.475925241786, 53877.0, 54628.453715463926, 55267.0, 55855.0, 71956.28043719496, 75216.24783752092, 77015.61492192504, 80082.59958700169, 82635.58682206551, 13475.124116932368, 13547.254900980146, 13669.726605468033, 13805.585832425044, 14230.00389972692, 14984.925075374553, 15386.538403847902, 15659.060456372199, 15739.685206296039, 16078.365141023363, 14449.277536122941, 14595.635109122357, 14887.776683349543, 15647.843521564639, 15721.213939303218, 14128.222947737973, 14129.364178611782, 14217.857821421654, 14550.85449145495, 14696.77954830657, 13579.932100339434, 14088.58975122605, 14131.505397311044, 14711.33798979027, 14712.779308310597, 11086.279391839505, 11372.715682108013, 11836.58571950012, 11990.460429280529, 12065.457054432747, 11958.402079895797, 11999.880001199876, 12054.0, 12246.0, 12248.448819803309], "value": 7828.833990982757, "unit": "score"}], "name": "dromaeo_css", "value": 7468.576234849091, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 112.0, 110.0, 110.0, 111.0, 111.0, 110.0, 109.0, 109.0, 110.0], "value": 109.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 131.0, 118.0, 184.0, 117.0, 120.0, 145.0, 116.0, 148.0, 116.0], "value": 134.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 160.0, 159.0, 161.0, 158.0, 161.0, 162.0, 161.0], "value": 160.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 73.0, 112.0, 76.0, 75.0, 73.0, 75.0, 100.0, 76.0], "value": 80.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 130.0, 84.0, 84.0, 83.0, 125.0, 84.0, 83.0, 129.0], "value": 96.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0, 116.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 146.0, 147.0, 145.0, 146.0, 146.0, 147.0, 145.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 51.0, 52.0, 50.0, 51.0, 50.0, 51.0, 51.0, 51.0], "value": 51.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 62.0, 61.0, 60.0, 61.0, 60.0, 61.0, 61.0, 61.0, 61.0], "value": 60.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 75.0, 75.0, 75.0, 76.0, 76.0, 74.0, 75.0, 81.0], "value": 75.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 120.0, 118.0, 119.0, 115.0, 125.0, 121.0, 132.0, 150.0, 133.0], "value": 126.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 154.0, 159.0, 155.0, 163.0, 153.0, 156.0, 158.0, 156.0, 158.0], "value": 156.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 63.0, 62.0, 62.0, 61.0, 61.0, 61.0, 62.0, 60.0], "value": 61.6, "unit": "score"}], "name": "kraken", "value": 1471.2999999999997, "alertThreshold": 2.0}]} 21:53:24 INFO - Return code: 0 21:53:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:53:24 INFO - # TBPL SUCCESS # 21:53:24 INFO - Running post-action listener: _resource_record_post_action 21:53:24 INFO - [mozharness: 2016-08-11 04:53:24.125157Z] Finished run-tests step (success) 21:53:24 INFO - Running post-run listener: _resource_record_post_run 21:53:24 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 28672; Write bytes: 298799104; Read time: 28; Write time: 152700 21:53:24 INFO - TinderboxPrint: CPU usage
13.0% 21:53:24 INFO - TinderboxPrint: I/O read bytes / time
28,672 / 28 21:53:24 INFO - TinderboxPrint: I/O write bytes / time
298,799,104 / 152,700 21:53:24 INFO - TinderboxPrint: CPU idle
8,285.2 (87.0%) 21:53:24 INFO - TinderboxPrint: CPU user
1,193.9 (12.5%) 21:53:24 INFO - TinderboxPrint: Swap in / out
0 / 0 21:53:24 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 10084352; Read time: 0; Write time: 768 21:53:24 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 24576; Write bytes: 288268288; Read time: 16; Write time: 151844 21:53:24 INFO - Running post-run listener: _upload_blobber_files 21:53:24 INFO - Blob upload gear active. 21:53:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:53:24 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 21:53:24 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'] 21:53:24 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 21:53:25 INFO - (blobuploader) - INFO - Open directory for files ... 21:53:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:53:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:53:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:53:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:53:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:53:26 INFO - (blobuploader) - INFO - Done attempting. 21:53:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:53:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:53:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:53:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:53:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:53:26 INFO - (blobuploader) - INFO - Done attempting. 21:53:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:53:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:53:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:53:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:53:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:53:30 INFO - (blobuploader) - INFO - Done attempting. 21:53:30 INFO - (blobuploader) - INFO - Iteration through files over. 21:53:30 INFO - Return code: 0 21:53:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:53:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:53:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4b09a81b07ea8173d3659d679d5643b0215a907e94aaa8f1e235f7ba57b48bd9e234ac3a0f4fb5e57f540eceb442bbb2585caec09e116bcea3e2b7918b03a9f5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/27423b90e23c3e7a8b04d5a5dde4e63eadba954241023722072ea3fad26a587b6ed8730dff54e3e164770a34947b00d5547ce03b4fcaa0b506d5b8c6651f8284", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e56a2995a67a88f007ee7d12296e0fb629d1cc7c0e9010ff683c45bdeaa2ffbf18b42fa496e8793a27464c44c5ca7565adff869a485e590f39b2d370c15aee35"} 21:53:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:53:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:53:30 INFO - Contents: 21:53:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4b09a81b07ea8173d3659d679d5643b0215a907e94aaa8f1e235f7ba57b48bd9e234ac3a0f4fb5e57f540eceb442bbb2585caec09e116bcea3e2b7918b03a9f5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/27423b90e23c3e7a8b04d5a5dde4e63eadba954241023722072ea3fad26a587b6ed8730dff54e3e164770a34947b00d5547ce03b4fcaa0b506d5b8c6651f8284", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e56a2995a67a88f007ee7d12296e0fb629d1cc7c0e9010ff683c45bdeaa2ffbf18b42fa496e8793a27464c44c5ca7565adff869a485e590f39b2d370c15aee35"} 21:53:30 INFO - Running post-run listener: copy_logs_to_upload_dir 21:53:30 INFO - Copying logs to upload dir... 21:53:30 INFO - mkdir: /builds/slave/test/build/upload/logs 21:53:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1230.816545 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2016-08-10 21:53:30.273986) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-10 21:53:30.277677) ========= 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=bb9b54edd4a927dc735d112f000001d9-1470889977.506884-776553730 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/4b09a81b07ea8173d3659d679d5643b0215a907e94aaa8f1e235f7ba57b48bd9e234ac3a0f4fb5e57f540eceb442bbb2585caec09e116bcea3e2b7918b03a9f5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/27423b90e23c3e7a8b04d5a5dde4e63eadba954241023722072ea3fad26a587b6ed8730dff54e3e164770a34947b00d5547ce03b4fcaa0b506d5b8c6651f8284", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e56a2995a67a88f007ee7d12296e0fb629d1cc7c0e9010ff683c45bdeaa2ffbf18b42fa496e8793a27464c44c5ca7565adff869a485e590f39b2d370c15aee35"} build_url:https://queue.taskcluster.net/v1/task/Eo7eWyG2RMi-qtK3nyhBmA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011372 build_url: 'https://queue.taskcluster.net/v1/task/Eo7eWyG2RMi-qtK3nyhBmA/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/4b09a81b07ea8173d3659d679d5643b0215a907e94aaa8f1e235f7ba57b48bd9e234ac3a0f4fb5e57f540eceb442bbb2585caec09e116bcea3e2b7918b03a9f5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/27423b90e23c3e7a8b04d5a5dde4e63eadba954241023722072ea3fad26a587b6ed8730dff54e3e164770a34947b00d5547ce03b4fcaa0b506d5b8c6651f8284", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e56a2995a67a88f007ee7d12296e0fb629d1cc7c0e9010ff683c45bdeaa2ffbf18b42fa496e8793a27464c44c5ca7565adff869a485e590f39b2d370c15aee35"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-10 21:53:30.303075) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 21:53:30.303539) ========= 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=bb9b54edd4a927dc735d112f000001d9-1470889977.506884-776553730 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004419 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 21:53:30.387330) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-10 21:53:30.387672) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-10 21:53:30.388053) ========= ========= Total master_lag: 0.16 =========