builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-025 starttime: 1470460317.25 results: success (0) buildid: 20160805193003 builduid: def840ba1c0b426ca8dec567efe5b95a revision: 731d13870f6a161f47ab8bffe5ee8e2ac92d5195 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-05 22:11:57.253248) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-05 22:11:57.254174) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-05 22:11:57.254507) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-05 22:11:57.343939) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-05 22:11:57.344360) ========= 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=232498196343f89f466a4a9a000001c8-1470458967.27072-1240889926 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-05 22:11:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.5M=0s 2016-08-05 22:11:58 (46.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.095120 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-05 22:11:57.463685) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-05 22:11:57.464031) ========= 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=232498196343f89f466a4a9a000001c8-1470458967.27072-1240889926 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021881 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-05 22:11:57.517403) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-05 22:11:57.517765) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 731d13870f6a161f47ab8bffe5ee8e2ac92d5195 --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 731d13870f6a161f47ab8bffe5ee8e2ac92d5195 --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=232498196343f89f466a4a9a000001c8-1470458967.27072-1240889926 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-05 22:11:58,134 truncating revision to first 12 chars 2016-08-05 22:11:58,134 Setting DEBUG logging. 2016-08-05 22:11:58,134 attempt 1/10 2016-08-05 22:11:58,134 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/731d13870f6a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-05 22:11:58,441 unpacking tar archive at: mozilla-inbound-731d13870f6a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.626445 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-05 22:11:58.165200) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-05 22:11:58.165501) ========= script_repo_revision: 731d13870f6a161f47ab8bffe5ee8e2ac92d5195 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-05 22:11:58.165882) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-05 22:11:58.166147) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-05 22:11:58.199266) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-08-05 22:11:58.199557) ========= /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=232498196343f89f466a4a9a000001c8-1470458967.27072-1240889926 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:11:58 INFO - MultiFileLogger online at 20160805 22:11:58 in /builds/slave/test 22:11:58 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 22:11:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:11:58 INFO - {'append_to_log': False, 22:11:58 INFO - 'base_work_dir': '/builds/slave/test', 22:11:58 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 22:11:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:11:58 INFO - 'branch': 'Mozilla-Inbound', 22:11:58 INFO - 'buildbot_json_path': 'buildprops.json', 22:11:58 INFO - 'config_files': ('talos/linux_config.py',), 22:11:58 INFO - 'default_actions': ('clobber', 22:11:58 INFO - 'read-buildbot-config', 22:11:58 INFO - 'download-and-extract', 22:11:58 INFO - 'populate-webroot', 22:11:58 INFO - 'create-virtualenv', 22:11:58 INFO - 'install', 22:11:58 INFO - 'run-tests'), 22:11:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:11:58 INFO - 'download_minidump_stackwalk': True, 22:11:58 INFO - 'download_symbols': 'ondemand', 22:11:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:11:58 INFO - 'tooltool.py': '/tools/tooltool.py', 22:11:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:11:58 INFO - '/tools/misc-python/virtualenv.py')}, 22:11:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:11:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:11:58 INFO - 'installer_path': 'installer.exe', 22:11:58 INFO - 'log_level': 'info', 22:11:58 INFO - 'log_name': 'talos', 22:11:58 INFO - 'log_to_console': True, 22:11:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:11:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:11:58 INFO - 'opt_config_files': (), 22:11:58 INFO - 'pip_index': False, 22:11:58 INFO - 'sps_profile': False, 22:11:58 INFO - 'sps_profile_interval': 0, 22:11:58 INFO - 'suite': 'dromaeojs', 22:11:58 INFO - 'system_bits': '32', 22:11:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:11:58 INFO - 'title': 'talos-linux64-ix-025', 22:11:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:11:58 INFO - 'use_talos_json': True, 22:11:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:11:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:11:58 INFO - 'work_dir': 'build'} 22:11:58 INFO - [mozharness: 2016-08-06 05:11:58.915908Z] Running clobber step. 22:11:58 INFO - Running pre-action listener: _resource_record_pre_action 22:11:58 INFO - Running main action method: clobber 22:11:58 INFO - rmtree: /builds/slave/test/build 22:11:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:11:59 INFO - Running post-action listener: _resource_record_post_action 22:11:59 INFO - [mozharness: 2016-08-06 05:11:59.881627Z] Finished clobber step (success) 22:11:59 INFO - [mozharness: 2016-08-06 05:11:59.881728Z] Running read-buildbot-config step. 22:11:59 INFO - Running pre-action listener: _resource_record_pre_action 22:11:59 INFO - Running main action method: read_buildbot_config 22:11:59 INFO - Using buildbot properties: 22:11:59 INFO - { 22:11:59 INFO - "project": "", 22:11:59 INFO - "product": "firefox", 22:11:59 INFO - "script_repo_revision": "production", 22:11:59 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 22:11:59 INFO - "repository": "", 22:11:59 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 22:11:59 INFO - "buildid": "20160805193003", 22:11:59 INFO - "pgo_build": "True", 22:11:59 INFO - "basedir": "/builds/slave/test", 22:11:59 INFO - "buildnumber": 2208, 22:11:59 INFO - "slavename": "talos-linux64-ix-025", 22:11:59 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 22:11:59 INFO - "platform": "ubuntu64_hw", 22:11:59 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 22:11:59 INFO - "branch": "mozilla-inbound", 22:11:59 INFO - "repo_path": "integration/mozilla-inbound", 22:11:59 INFO - "revision": "731d13870f6a161f47ab8bffe5ee8e2ac92d5195", 22:11:59 INFO - "stage_platform": "linux64-pgo", 22:11:59 INFO - "builduid": "def840ba1c0b426ca8dec567efe5b95a", 22:11:59 INFO - "slavebuilddir": "test" 22:11:59 INFO - } 22:11:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/SHyhuRHbRwikE1DLSb2Lig/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 22:11:59 INFO - Running post-action listener: _resource_record_post_action 22:11:59 INFO - [mozharness: 2016-08-06 05:11:59.884155Z] Finished read-buildbot-config step (success) 22:11:59 INFO - [mozharness: 2016-08-06 05:11:59.884243Z] Running download-and-extract step. 22:11:59 INFO - Running pre-action listener: _resource_record_pre_action 22:11:59 INFO - Running main action method: download_and_extract 22:11:59 INFO - mkdir: /builds/slave/test/build/tests 22:11:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:11:59 INFO - trying https://queue.taskcluster.net/v1/task/SHyhuRHbRwikE1DLSb2Lig/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 22:11:59 INFO - Downloading https://queue.taskcluster.net/v1/task/SHyhuRHbRwikE1DLSb2Lig/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 22:11:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SHyhuRHbRwikE1DLSb2Lig/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 22:12:00 INFO - Downloaded 1361 bytes. 22:12:00 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 22:12:00 INFO - Using the following test package requirements: 22:12:00 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 22:12:00 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:12:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:12:00 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:12:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:12:00 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:12:00 INFO - u'jsshell-linux-x86_64.zip'], 22:12:00 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:12:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:12:00 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 22:12:00 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:12:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:12:00 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:12:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:12:00 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:12:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:12:00 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:12:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:12:00 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:12: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')]} 22:12:00 INFO - trying https://queue.taskcluster.net/v1/task/SHyhuRHbRwikE1DLSb2Lig/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 22:12:00 INFO - Downloading https://queue.taskcluster.net/v1/task/SHyhuRHbRwikE1DLSb2Lig/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 22:12:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SHyhuRHbRwikE1DLSb2Lig/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 22:12:01 INFO - Downloaded 21827257 bytes. 22:12: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'] 22:12: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 22:12:02 INFO - Return code: 0 22:12: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 22:12: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')]} 22:12:02 INFO - trying https://queue.taskcluster.net/v1/task/SHyhuRHbRwikE1DLSb2Lig/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 22:12:02 INFO - Downloading https://queue.taskcluster.net/v1/task/SHyhuRHbRwikE1DLSb2Lig/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 22:12:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SHyhuRHbRwikE1DLSb2Lig/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 22:12:03 INFO - Downloaded 21827257 bytes. 22:12: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'] 22:12: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 22:12:04 INFO - Return code: 0 22:12:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:12:04 INFO - trying https://queue.taskcluster.net/v1/task/SHyhuRHbRwikE1DLSb2Lig/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 22:12:04 INFO - Downloading https://queue.taskcluster.net/v1/task/SHyhuRHbRwikE1DLSb2Lig/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 22:12:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SHyhuRHbRwikE1DLSb2Lig/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 22:12:05 INFO - Downloaded 14413723 bytes. 22:12:05 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'] 22:12:05 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 22:12:05 INFO - Return code: 0 22:12: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')]} 22:12:05 INFO - trying https://queue.taskcluster.net/v1/task/SHyhuRHbRwikE1DLSb2Lig/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:12:05 INFO - Downloading https://queue.taskcluster.net/v1/task/SHyhuRHbRwikE1DLSb2Lig/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 22:12:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SHyhuRHbRwikE1DLSb2Lig/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 22:12:08 INFO - Downloaded 59969378 bytes. 22:12:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SHyhuRHbRwikE1DLSb2Lig/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:12:08 INFO - mkdir: /builds/slave/test/properties 22:12:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:12:08 INFO - Writing to file /builds/slave/test/properties/build_url 22:12:08 INFO - Contents: 22:12:08 INFO - build_url:https://queue.taskcluster.net/v1/task/SHyhuRHbRwikE1DLSb2Lig/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:12:09 INFO - Running post-action listener: _resource_record_post_action 22:12:09 INFO - Running post-action listener: set_extra_try_arguments 22:12:09 INFO - [mozharness: 2016-08-06 05:12:09.583645Z] Finished download-and-extract step (success) 22:12:09 INFO - [mozharness: 2016-08-06 05:12:09.583880Z] Running populate-webroot step. 22:12:09 INFO - Running pre-action listener: _resource_record_pre_action 22:12:09 INFO - Running main action method: populate_webroot 22:12:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:12:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:12:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:12:09 INFO - '--tppagecycles', 22:12:09 INFO - '7'], 22:12:09 INFO - 'tests': ['tsvgm']}}, 22:12:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:12:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:12:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:12:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:12:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:12:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:12:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:12:09 INFO - 'talos_options': ['--disable-e10s'], 22:12:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:12:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:12:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:12:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:12:09 INFO - 'talos_options': ['--disable-e10s'], 22:12:09 INFO - 'tests': ['damp', 'tps']}, 22:12:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:12:09 INFO - 'tests': ['damp', 'tps']}, 22:12:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:12:09 INFO - 'tests': ['dromaeo_dom']}, 22:12:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:12:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:12:09 INFO - 'tests': ['basic_compositor_video']}, 22:12:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:12:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:12:09 INFO - 'tests': ['a11yr', 22:12:09 INFO - 'ts_paint', 22:12:09 INFO - 'tpaint', 22:12:09 INFO - 'sessionrestore', 22:12:09 INFO - 'sessionrestore_no_auto_restore', 22:12:09 INFO - 'tabpaint']}, 22:12:09 INFO - 'other-e10s': {'tests': ['a11yr', 22:12:09 INFO - 'ts_paint', 22:12:09 INFO - 'tpaint', 22:12:09 INFO - 'sessionrestore', 22:12:09 INFO - 'sessionrestore_no_auto_restore', 22:12:09 INFO - 'tabpaint']}, 22:12:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:12:09 INFO - 'ts_paint', 22:12:09 INFO - 'tpaint', 22:12:09 INFO - 'sessionrestore', 22:12:09 INFO - 'sessionrestore_no_auto_restore', 22:12:09 INFO - 'tabpaint']}, 22:12:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:12:09 INFO - 'ts_paint', 22:12:09 INFO - 'tpaint', 22:12:09 INFO - 'sessionrestore', 22:12:09 INFO - 'sessionrestore_no_auto_restore', 22:12:09 INFO - 'tabpaint']}, 22:12:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:12:09 INFO - 'tests': ['a11yr', 22:12:09 INFO - 'ts_paint', 22:12:09 INFO - 'tpaint', 22:12:09 INFO - 'sessionrestore', 22:12:09 INFO - 'sessionrestore_no_auto_restore', 22:12:09 INFO - 'tabpaint']}, 22:12:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:12:09 INFO - 'tests': ['a11yr', 22:12:09 INFO - 'ts_paint', 22:12:09 INFO - 'tpaint', 22:12:09 INFO - 'sessionrestore', 22:12:09 INFO - 'sessionrestore_no_auto_restore', 22:12:09 INFO - 'tabpaint']}, 22:12:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:12:09 INFO - 'tests': ['tsvgx', 22:12:09 INFO - 'tsvgr_opacity', 22:12:09 INFO - 'tart', 22:12:09 INFO - 'tscrollx', 22:12:09 INFO - 'cart']}, 22:12:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:12:09 INFO - 'tsvgr_opacity', 22:12:09 INFO - 'tart', 22:12:09 INFO - 'tscrollx', 22:12:09 INFO - 'cart']}, 22:12:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:12:09 INFO - 'talos_options': ['--disable-e10s'], 22:12:09 INFO - 'tests': ['tp5o']}, 22:12:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:12:09 INFO - 'tests': ['tp5o']}, 22:12:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:12:09 INFO - 'talos_options': ['--disable-e10s', 22:12:09 INFO - '--xperf_path', 22:12:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:12:09 INFO - 'tests': ['tp5n']}, 22:12:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:12:09 INFO - 'talos_options': ['--xperf_path', 22:12:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:12:09 INFO - 'tests': ['tp5n']}}, 22:12:09 INFO - 'talos.zip': {'path': '', 22:12:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:12:09 INFO - Running post-action listener: _resource_record_post_action 22:12:09 INFO - [mozharness: 2016-08-06 05:12:09.603114Z] Finished populate-webroot step (success) 22:12:09 INFO - [mozharness: 2016-08-06 05:12:09.603202Z] Running create-virtualenv step. 22:12:09 INFO - Running pre-action listener: _resource_record_pre_action 22:12:09 INFO - Running main action method: create_virtualenv 22:12:09 INFO - Creating virtualenv /builds/slave/test/build/venv 22:12:09 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 22:12:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:12:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:12:09 INFO - Using real prefix '/usr' 22:12:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:12:10 INFO - Installing distribute.............................................................................................................................................................................................done. 22:12:12 INFO - Installing pip.................done. 22:12:12 INFO - Return code: 0 22:12:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:12:12 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')]} 22:12:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:12:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12:12 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')]} 22:12:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:12:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12:12 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 0xf30030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11f1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11ef3b0>, '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 0x11f02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11f0740>, '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': '232498196343f89f466a4a9a000001c8-1470458967.27072-1240889926', '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 22:12:12 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 22:12:12 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 22:12:12 INFO - Using env: {'DISPLAY': ':0', 22:12:12 INFO - 'HOME': '/home/cltbld', 22:12:12 INFO - 'LANG': 'en_US.UTF-8', 22:12:12 INFO - 'LANGUAGE': 'en_US:en', 22:12:12 INFO - 'LOGNAME': 'cltbld', 22:12:12 INFO - 'MAIL': '/var/mail/cltbld', 22:12:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:12:12 INFO - 'MOZ_NO_REMOTE': '1', 22:12:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:12:12 INFO - 'NO_EM_RESTART': '1', 22:12:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:12:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:12:12 INFO - 'PWD': '/builds/slave/test', 22:12:12 INFO - 'SHELL': '/bin/bash', 22:12:12 INFO - 'SHLVL': '1', 22:12:12 INFO - 'TERM': 'linux', 22:12:12 INFO - 'TMOUT': '86400', 22:12:12 INFO - 'USER': 'cltbld', 22:12:12 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1470458967.27072-1240889926', 22:12:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:12:12 INFO - '_': '/tools/buildbot/bin/python'} 22:12:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:12:12 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 22:12:12 INFO - Cleaning up... 22:12:12 INFO - Return code: 0 22:12:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:12:12 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')]} 22:12:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:12:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12:12 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')]} 22:12:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:12:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12:12 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 0xf30030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11f1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11ef3b0>, '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 0x11f02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11f0740>, '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': '232498196343f89f466a4a9a000001c8-1470458967.27072-1240889926', '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 22:12:12 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 22:12:12 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 22:12:12 INFO - Using env: {'DISPLAY': ':0', 22:12:12 INFO - 'HOME': '/home/cltbld', 22:12:12 INFO - 'LANG': 'en_US.UTF-8', 22:12:12 INFO - 'LANGUAGE': 'en_US:en', 22:12:12 INFO - 'LOGNAME': 'cltbld', 22:12:12 INFO - 'MAIL': '/var/mail/cltbld', 22:12:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:12:12 INFO - 'MOZ_NO_REMOTE': '1', 22:12:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:12:12 INFO - 'NO_EM_RESTART': '1', 22:12:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:12:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:12:12 INFO - 'PWD': '/builds/slave/test', 22:12:12 INFO - 'SHELL': '/bin/bash', 22:12:12 INFO - 'SHLVL': '1', 22:12:12 INFO - 'TERM': 'linux', 22:12:12 INFO - 'TMOUT': '86400', 22:12:12 INFO - 'USER': 'cltbld', 22:12:12 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1470458967.27072-1240889926', 22:12:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:12:12 INFO - '_': '/tools/buildbot/bin/python'} 22:12:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:12:12 INFO - Downloading/unpacking psutil>=3.1.1 22:12:12 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 22:12:12 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 22:12:12 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 22:12:12 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 22:12:12 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 22:12:12 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 22:12:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:12:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:12:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:12:14 INFO - Installing collected packages: psutil 22:12:14 INFO - Running setup.py install for psutil 22:12:14 INFO - building 'psutil._psutil_linux' extension 22:12:14 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 22:12:14 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 22:12:14 INFO - building 'psutil._psutil_posix' extension 22:12:14 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 22:12:14 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 22:12:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:12:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:12:15 INFO - Successfully installed psutil 22:12:15 INFO - Cleaning up... 22:12:15 INFO - Return code: 0 22:12:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:12:15 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')]} 22:12:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:12:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12:15 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')]} 22:12:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:12:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12:15 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 0xf30030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11f1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11ef3b0>, '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 0x11f02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11f0740>, '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': '232498196343f89f466a4a9a000001c8-1470458967.27072-1240889926', '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 22:12:15 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 22:12:15 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 22:12:15 INFO - Using env: {'DISPLAY': ':0', 22:12:15 INFO - 'HOME': '/home/cltbld', 22:12:15 INFO - 'LANG': 'en_US.UTF-8', 22:12:15 INFO - 'LANGUAGE': 'en_US:en', 22:12:15 INFO - 'LOGNAME': 'cltbld', 22:12:15 INFO - 'MAIL': '/var/mail/cltbld', 22:12:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:12:15 INFO - 'MOZ_NO_REMOTE': '1', 22:12:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:12:15 INFO - 'NO_EM_RESTART': '1', 22:12:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:12:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:12:15 INFO - 'PWD': '/builds/slave/test', 22:12:15 INFO - 'SHELL': '/bin/bash', 22:12:15 INFO - 'SHLVL': '1', 22:12:15 INFO - 'TERM': 'linux', 22:12:15 INFO - 'TMOUT': '86400', 22:12:15 INFO - 'USER': 'cltbld', 22:12:15 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1470458967.27072-1240889926', 22:12:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:12:15 INFO - '_': '/tools/buildbot/bin/python'} 22:12:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:12:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:12:15 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 22:12:15 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 22:12:15 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 22:12:15 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 22:12:15 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 22:12:15 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 22:12:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:12:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:12:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:12:17 INFO - Installing collected packages: mozsystemmonitor 22:12:17 INFO - Running setup.py install for mozsystemmonitor 22:12:17 INFO - Successfully installed mozsystemmonitor 22:12:17 INFO - Cleaning up... 22:12:17 INFO - Return code: 0 22:12:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:12: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')]} 22:12:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:12:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12: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')]} 22:12:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:12:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12: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 0xf30030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11f1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11ef3b0>, '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 0x11f02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11f0740>, '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': '232498196343f89f466a4a9a000001c8-1470458967.27072-1240889926', '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 22:12: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 22:12: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 22:12:17 INFO - Using env: {'DISPLAY': ':0', 22:12:17 INFO - 'HOME': '/home/cltbld', 22:12:17 INFO - 'LANG': 'en_US.UTF-8', 22:12:17 INFO - 'LANGUAGE': 'en_US:en', 22:12:17 INFO - 'LOGNAME': 'cltbld', 22:12:17 INFO - 'MAIL': '/var/mail/cltbld', 22:12:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:12:17 INFO - 'MOZ_NO_REMOTE': '1', 22:12:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:12:17 INFO - 'NO_EM_RESTART': '1', 22:12:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:12:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:12:17 INFO - 'PWD': '/builds/slave/test', 22:12:17 INFO - 'SHELL': '/bin/bash', 22:12:17 INFO - 'SHLVL': '1', 22:12:17 INFO - 'TERM': 'linux', 22:12:17 INFO - 'TMOUT': '86400', 22:12:17 INFO - 'USER': 'cltbld', 22:12:17 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1470458967.27072-1240889926', 22:12:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:12:17 INFO - '_': '/tools/buildbot/bin/python'} 22:12:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:12:17 INFO - Downloading/unpacking jsonschema==2.5.1 22:12: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 22:12: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 22:12: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 22:12: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 22:12: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 22:12: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 22:12:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:12:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:12: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 22:12: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 22:12: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 22:12: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 22:12: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 22:12: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 22:12:19 INFO - Downloading functools32-3.2.3-2.tar.gz 22:12:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:12:19 INFO - warning: no files found matching '*.txt' 22:12:19 INFO - no previously-included directories found matching 'build' 22:12:19 INFO - no previously-included directories found matching 'dist' 22:12:19 INFO - no previously-included directories found matching '.git*' 22:12:19 INFO - Installing collected packages: jsonschema, functools32 22:12:19 INFO - Running setup.py install for functools32 22:12:19 INFO - warning: no files found matching '*.txt' 22:12:19 INFO - no previously-included directories found matching 'build' 22:12:19 INFO - no previously-included directories found matching 'dist' 22:12:19 INFO - no previously-included directories found matching '.git*' 22:12:19 INFO - Successfully installed jsonschema functools32 22:12:19 INFO - Cleaning up... 22:12:19 INFO - Return code: 0 22:12:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:12: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')]} 22:12:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:12:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12:20 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')]} 22:12:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:12:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12:20 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 0xf30030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11f1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11ef3b0>, '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 0x11f02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11f0740>, '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': '232498196343f89f466a4a9a000001c8-1470458967.27072-1240889926', '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 22:12:20 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 22:12:20 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 22:12:20 INFO - Using env: {'DISPLAY': ':0', 22:12:20 INFO - 'HOME': '/home/cltbld', 22:12:20 INFO - 'LANG': 'en_US.UTF-8', 22:12:20 INFO - 'LANGUAGE': 'en_US:en', 22:12:20 INFO - 'LOGNAME': 'cltbld', 22:12:20 INFO - 'MAIL': '/var/mail/cltbld', 22:12:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:12:20 INFO - 'MOZ_NO_REMOTE': '1', 22:12:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:12:20 INFO - 'NO_EM_RESTART': '1', 22:12:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:12:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:12:20 INFO - 'PWD': '/builds/slave/test', 22:12:20 INFO - 'SHELL': '/bin/bash', 22:12:20 INFO - 'SHLVL': '1', 22:12:20 INFO - 'TERM': 'linux', 22:12:20 INFO - 'TMOUT': '86400', 22:12:20 INFO - 'USER': 'cltbld', 22:12:20 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1470458967.27072-1240889926', 22:12:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:12:20 INFO - '_': '/tools/buildbot/bin/python'} 22:12:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:12:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:12:20 INFO - Cleaning up... 22:12:20 INFO - Return code: 0 22:12:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:12:20 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')]} 22:12:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:12:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12:20 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')]} 22:12:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:12:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12:20 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 0xf30030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11f1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11ef3b0>, '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 0x11f02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11f0740>, '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': '232498196343f89f466a4a9a000001c8-1470458967.27072-1240889926', '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 22:12:20 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 22:12:20 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 22:12:20 INFO - Using env: {'DISPLAY': ':0', 22:12:20 INFO - 'HOME': '/home/cltbld', 22:12:20 INFO - 'LANG': 'en_US.UTF-8', 22:12:20 INFO - 'LANGUAGE': 'en_US:en', 22:12:20 INFO - 'LOGNAME': 'cltbld', 22:12:20 INFO - 'MAIL': '/var/mail/cltbld', 22:12:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:12:20 INFO - 'MOZ_NO_REMOTE': '1', 22:12:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:12:20 INFO - 'NO_EM_RESTART': '1', 22:12:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:12:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:12:20 INFO - 'PWD': '/builds/slave/test', 22:12:20 INFO - 'SHELL': '/bin/bash', 22:12:20 INFO - 'SHLVL': '1', 22:12:20 INFO - 'TERM': 'linux', 22:12:20 INFO - 'TMOUT': '86400', 22:12:20 INFO - 'USER': 'cltbld', 22:12:20 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1470458967.27072-1240889926', 22:12:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:12:20 INFO - '_': '/tools/buildbot/bin/python'} 22:12:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:12:20 INFO - Downloading/unpacking blobuploader==1.2.4 22:12: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 22:12: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 22:12: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 22:12: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 22:12: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 22:12: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 22:12:22 INFO - Downloading blobuploader-1.2.4.tar.gz 22:12:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:12:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:12: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 22:12: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 22:12: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 22:12: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 22:12: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 22:12: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 22:12:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:12:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:12: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 22:12: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 22:12: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 22:12: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 22:12: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 22:12: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 22:12:22 INFO - Downloading docopt-0.6.1.tar.gz 22:12:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:12:23 INFO - Installing collected packages: blobuploader, requests, docopt 22:12:23 INFO - Running setup.py install for blobuploader 22:12:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:12:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:12:23 INFO - Running setup.py install for requests 22:12:23 INFO - Running setup.py install for docopt 22:12:23 INFO - Successfully installed blobuploader requests docopt 22:12:23 INFO - Cleaning up... 22:12:23 INFO - Return code: 0 22:12:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:12: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')]} 22:12:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:12:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12: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')]} 22:12:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:12:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12: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 0xf30030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11f1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11ef3b0>, '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 0x11f02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11f0740>, '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': '232498196343f89f466a4a9a000001c8-1470458967.27072-1240889926', '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 22:12: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 22:12: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 22:12:23 INFO - Using env: {'DISPLAY': ':0', 22:12:23 INFO - 'HOME': '/home/cltbld', 22:12:23 INFO - 'LANG': 'en_US.UTF-8', 22:12:23 INFO - 'LANGUAGE': 'en_US:en', 22:12:23 INFO - 'LOGNAME': 'cltbld', 22:12:23 INFO - 'MAIL': '/var/mail/cltbld', 22:12:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:12:23 INFO - 'MOZ_NO_REMOTE': '1', 22:12:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:12:23 INFO - 'NO_EM_RESTART': '1', 22:12:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:12:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:12:23 INFO - 'PWD': '/builds/slave/test', 22:12:23 INFO - 'SHELL': '/bin/bash', 22:12:23 INFO - 'SHLVL': '1', 22:12:23 INFO - 'TERM': 'linux', 22:12:23 INFO - 'TMOUT': '86400', 22:12:23 INFO - 'USER': 'cltbld', 22:12:23 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1470458967.27072-1240889926', 22:12:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:12:23 INFO - '_': '/tools/buildbot/bin/python'} 22:12:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:12:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:12:23 INFO - Running setup.py (path:/tmp/pip-kyfJrO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:12:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:12:23 INFO - Running setup.py (path:/tmp/pip-WWv_ly-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:12:24 INFO - Running setup.py (path:/tmp/pip-MgWjT4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:12:24 INFO - Running setup.py (path:/tmp/pip-QKkCKB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:12:24 INFO - Running setup.py (path:/tmp/pip-wSeMqD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:12:24 INFO - Running setup.py (path:/tmp/pip-28HDDf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:12:24 INFO - Running setup.py (path:/tmp/pip-89h2bg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:12:24 INFO - Running setup.py (path:/tmp/pip-e5Gisy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:12:24 INFO - Running setup.py (path:/tmp/pip-lxU_IY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:12:24 INFO - Running setup.py (path:/tmp/pip-7u8XX_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:12:24 INFO - Running setup.py (path:/tmp/pip-uExSSd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:12:24 INFO - Running setup.py (path:/tmp/pip-pqf9tS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:12:24 INFO - Running setup.py (path:/tmp/pip-jGL2qX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:12:24 INFO - Running setup.py (path:/tmp/pip-lGEXF8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:12:24 INFO - Running setup.py (path:/tmp/pip-qdhtQt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:12:24 INFO - Running setup.py (path:/tmp/pip-ESi5R0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:12:25 INFO - Running setup.py (path:/tmp/pip-MptwJI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:12:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:12:25 INFO - Running setup.py install for manifestparser 22:12:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:12:25 INFO - Running setup.py install for mozcrash 22:12:25 INFO - Running setup.py install for mozdebug 22:12:25 INFO - Running setup.py install for mozdevice 22:12:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:12:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:12:25 INFO - Running setup.py install for mozfile 22:12:25 INFO - Running setup.py install for mozhttpd 22:12:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:12:25 INFO - Running setup.py install for mozinfo 22:12:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:12:25 INFO - Running setup.py install for mozInstall 22:12:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:12:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:12:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:12:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:12:25 INFO - Running setup.py install for mozleak 22:12:26 INFO - Running setup.py install for mozlog 22:12:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:12:26 INFO - Running setup.py install for moznetwork 22:12:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:12:26 INFO - Running setup.py install for mozprocess 22:12:26 INFO - Running setup.py install for mozprofile 22:12:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:12:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:12:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:12:26 INFO - Running setup.py install for mozrunner 22:12:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:12:26 INFO - Running setup.py install for mozscreenshot 22:12:26 INFO - Running setup.py install for moztest 22:12:26 INFO - Running setup.py install for mozversion 22:12:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:12:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:12:26 INFO - Cleaning up... 22:12:26 INFO - Return code: 0 22:12:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:12: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')]} 22:12:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:12:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12: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')]} 22:12:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:12:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12: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 0xf30030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11f1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11ef3b0>, '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 0x11f02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11f0740>, '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': '232498196343f89f466a4a9a000001c8-1470458967.27072-1240889926', '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 22:12: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 22:12: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 22:12:26 INFO - Using env: {'DISPLAY': ':0', 22:12:26 INFO - 'HOME': '/home/cltbld', 22:12:26 INFO - 'LANG': 'en_US.UTF-8', 22:12:26 INFO - 'LANGUAGE': 'en_US:en', 22:12:26 INFO - 'LOGNAME': 'cltbld', 22:12:26 INFO - 'MAIL': '/var/mail/cltbld', 22:12:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:12:26 INFO - 'MOZ_NO_REMOTE': '1', 22:12:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:12:26 INFO - 'NO_EM_RESTART': '1', 22:12:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:12:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:12:26 INFO - 'PWD': '/builds/slave/test', 22:12:26 INFO - 'SHELL': '/bin/bash', 22:12:26 INFO - 'SHLVL': '1', 22:12:26 INFO - 'TERM': 'linux', 22:12:26 INFO - 'TMOUT': '86400', 22:12:26 INFO - 'USER': 'cltbld', 22:12:26 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1470458967.27072-1240889926', 22:12:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:12:26 INFO - '_': '/tools/buildbot/bin/python'} 22:12:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:12:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:12:27 INFO - Running setup.py (path:/tmp/pip-1nTpbG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:12:27 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)) 22:12:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:12:27 INFO - Running setup.py (path:/tmp/pip-Ose8Q1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:12:27 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)) 22:12:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:12:27 INFO - Running setup.py (path:/tmp/pip-s089Ph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:12:27 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)) 22:12:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:12:27 INFO - Running setup.py (path:/tmp/pip-jh2ZYM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:12: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)) 22:12:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:12:27 INFO - Running setup.py (path:/tmp/pip-GxkOqS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:12: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)) 22:12:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:12:27 INFO - Running setup.py (path:/tmp/pip-NNMlWS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:12: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)) 22:12:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:12:27 INFO - Running setup.py (path:/tmp/pip-R65oz_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:12: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)) 22:12:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:12:27 INFO - Running setup.py (path:/tmp/pip-5uSkbH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:12: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)) 22:12:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:12:27 INFO - Running setup.py (path:/tmp/pip-uaE0TU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:12: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)) 22:12:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:12:27 INFO - Running setup.py (path:/tmp/pip-_qQftv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:12: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)) 22:12:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:12:27 INFO - Running setup.py (path:/tmp/pip-e0keMm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:12: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)) 22:12:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:12:27 INFO - Running setup.py (path:/tmp/pip-RhM1PH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:12:28 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)) 22:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:12:28 INFO - Running setup.py (path:/tmp/pip-fiEtpX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:12:28 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)) 22:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:12:28 INFO - Running setup.py (path:/tmp/pip-LvzFwV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:12:28 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)) 22:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:12:28 INFO - Running setup.py (path:/tmp/pip-lCh7w_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:12:28 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)) 22:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:12:28 INFO - Running setup.py (path:/tmp/pip-084Qoa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:12:28 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)) 22:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:12:28 INFO - Running setup.py (path:/tmp/pip-zzUCed-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:12:28 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)) 22:12:28 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)) 22:12:28 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)) 22:12:28 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)) 22:12:28 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)) 22:12:28 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)) 22:12:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:12:28 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 22:12:28 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 22:12:28 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 22:12:28 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 22:12:28 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 22:12:28 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 22:12:30 INFO - Downloading blessings-1.6.tar.gz 22:12:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:12:30 INFO - Installing collected packages: blessings 22:12:30 INFO - Running setup.py install for blessings 22:12:30 INFO - Successfully installed blessings 22:12:30 INFO - Cleaning up... 22:12:30 INFO - Return code: 0 22:12:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:12:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:12:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:12:30 INFO - Reading from file tmpfile_stdout 22:12:30 INFO - Current package versions: 22:12:30 INFO - argparse == 1.2.1 22:12:30 INFO - blessings == 1.6 22:12:30 INFO - blobuploader == 1.2.4 22:12:30 INFO - docopt == 0.6.1 22:12:30 INFO - functools32 == 3.2.3-2 22:12:30 INFO - jsonschema == 2.5.1 22:12:30 INFO - manifestparser == 1.1 22:12:30 INFO - mozInstall == 1.12 22:12:30 INFO - mozcrash == 0.17 22:12:30 INFO - mozdebug == 0.1 22:12:30 INFO - mozdevice == 0.48 22:12:30 INFO - mozfile == 1.2 22:12:30 INFO - mozhttpd == 0.7 22:12:30 INFO - mozinfo == 0.9 22:12:30 INFO - mozleak == 0.1 22:12:30 INFO - mozlog == 3.3 22:12:30 INFO - moznetwork == 0.27 22:12:30 INFO - mozprocess == 0.23 22:12:30 INFO - mozprofile == 0.28 22:12:30 INFO - mozrunner == 6.12 22:12:30 INFO - mozscreenshot == 0.1 22:12:30 INFO - mozsystemmonitor == 0.3 22:12:30 INFO - moztest == 0.7 22:12:30 INFO - mozversion == 1.4 22:12:30 INFO - psutil == 3.1.1 22:12:30 INFO - requests == 1.2.3 22:12:30 INFO - wsgiref == 0.1.2 22:12:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:12: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')]} 22:12:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:12:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12: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')]} 22:12:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:12:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12: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 0xf30030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11f1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11ef3b0>, '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 0x11f02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11f0740>, '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': '232498196343f89f466a4a9a000001c8-1470458967.27072-1240889926', '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 22:12: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 22:12: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 22:12:30 INFO - Using env: {'DISPLAY': ':0', 22:12:30 INFO - 'HOME': '/home/cltbld', 22:12:30 INFO - 'LANG': 'en_US.UTF-8', 22:12:30 INFO - 'LANGUAGE': 'en_US:en', 22:12:30 INFO - 'LOGNAME': 'cltbld', 22:12:30 INFO - 'MAIL': '/var/mail/cltbld', 22:12:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:12:30 INFO - 'MOZ_NO_REMOTE': '1', 22:12:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:12:30 INFO - 'NO_EM_RESTART': '1', 22:12:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:12:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:12:30 INFO - 'PWD': '/builds/slave/test', 22:12:30 INFO - 'SHELL': '/bin/bash', 22:12:30 INFO - 'SHLVL': '1', 22:12:30 INFO - 'TERM': 'linux', 22:12:30 INFO - 'TMOUT': '86400', 22:12:30 INFO - 'USER': 'cltbld', 22:12:30 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1470458967.27072-1240889926', 22:12:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:12:30 INFO - '_': '/tools/buildbot/bin/python'} 22:12:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:12: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)) 22:12: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)) 22:12: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)) 22:12: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)) 22:12: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)) 22:12: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)) 22:12: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)) 22:12: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)) 22:12: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)) 22:12: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)) 22:12: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)) 22:12:30 INFO - Cleaning up... 22:12:30 INFO - Return code: 0 22:12:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:12: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')]} 22:12:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:12:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12: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')]} 22:12:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:12:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12: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 0xf30030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11f1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11ef3b0>, '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 0x11f02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11f0740>, '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': '232498196343f89f466a4a9a000001c8-1470458967.27072-1240889926', '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 22:12: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 22:12: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 22:12:30 INFO - Using env: {'DISPLAY': ':0', 22:12:30 INFO - 'HOME': '/home/cltbld', 22:12:30 INFO - 'LANG': 'en_US.UTF-8', 22:12:30 INFO - 'LANGUAGE': 'en_US:en', 22:12:30 INFO - 'LOGNAME': 'cltbld', 22:12:30 INFO - 'MAIL': '/var/mail/cltbld', 22:12:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:12:30 INFO - 'MOZ_NO_REMOTE': '1', 22:12:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:12:30 INFO - 'NO_EM_RESTART': '1', 22:12:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:12:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:12:30 INFO - 'PWD': '/builds/slave/test', 22:12:30 INFO - 'SHELL': '/bin/bash', 22:12:30 INFO - 'SHLVL': '1', 22:12:30 INFO - 'TERM': 'linux', 22:12:30 INFO - 'TMOUT': '86400', 22:12:30 INFO - 'USER': 'cltbld', 22:12:30 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1470458967.27072-1240889926', 22:12:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:12:30 INFO - '_': '/tools/buildbot/bin/python'} 22:12:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:12:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:12:31 INFO - Cleaning up... 22:12:31 INFO - Return code: 0 22:12:31 INFO - Running post-action listener: _resource_record_post_action 22:12:31 INFO - Running post-action listener: _start_resource_monitoring 22:12:31 INFO - Starting resource monitoring. 22:12:31 INFO - [mozharness: 2016-08-06 05:12:31.200541Z] Finished create-virtualenv step (success) 22:12:31 INFO - [mozharness: 2016-08-06 05:12:31.200985Z] Running install step. 22:12:31 INFO - Running pre-action listener: _resource_record_pre_action 22:12:31 INFO - Running main action method: install 22:12:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:12:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:12:31 INFO - Reading from file tmpfile_stdout 22:12:31 INFO - Detecting whether we're running mozinstall >=1.0... 22:12:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:12:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:12:31 INFO - Reading from file tmpfile_stdout 22:12:31 INFO - Output received: 22:12:31 INFO - Usage: mozinstall [options] installer 22:12:31 INFO - Options: 22:12:31 INFO - -h, --help show this help message and exit 22:12:31 INFO - -d DEST, --destination=DEST 22:12:31 INFO - Directory to install application into. [default: 22:12:31 INFO - "/builds/slave/test"] 22:12:31 INFO - --app=APP Application being installed. [default: firefox] 22:12:31 INFO - mkdir: /builds/slave/test/build/application 22:12: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'] 22:12: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 22:12:48 INFO - Reading from file tmpfile_stdout 22:12:48 INFO - Output received: 22:12:48 INFO - /builds/slave/test/build/application/firefox/firefox 22:12:48 INFO - Running post-action listener: _resource_record_post_action 22:12:48 INFO - [mozharness: 2016-08-06 05:12:48.966931Z] Finished install step (success) 22:12:48 INFO - [mozharness: 2016-08-06 05:12:48.967183Z] Running run-tests step. 22:12:48 INFO - Running pre-action listener: _resource_record_pre_action 22:12:48 INFO - Running main action method: run_tests 22:12:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:12:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:12:48 INFO - Python 2.7.3 22:12:48 INFO - Return code: 0 22:12:48 INFO - grabbing minidump binary from tooltool 22:12:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:12:48 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 0x11ef3b0>, '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 0x11f02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11f0740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:12:48 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 22:12:48 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 22:12:48 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 22:12:49 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:12:49 INFO - Return code: 0 22:12:49 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:12:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:12:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:12:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:12:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:12:49 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/SHyhuRHbRwikE1DLSb2Lig/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-025', '--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 22:12:49 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/SHyhuRHbRwikE1DLSb2Lig/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-025 --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 22:12:49 INFO - Using env: {'DISPLAY': ':0', 22:12:49 INFO - 'HOME': '/home/cltbld', 22:12:49 INFO - 'LANG': 'en_US.UTF-8', 22:12:49 INFO - 'LANGUAGE': 'en_US:en', 22:12:49 INFO - 'LOGNAME': 'cltbld', 22:12:49 INFO - 'MAIL': '/var/mail/cltbld', 22:12:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:12:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:12:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:12:49 INFO - 'MOZ_NO_REMOTE': '1', 22:12:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:12:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:12:49 INFO - 'NO_EM_RESTART': '1', 22:12:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:12:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:12:49 INFO - 'PWD': '/builds/slave/test', 22:12:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:12:49 INFO - 'SHELL': '/bin/bash', 22:12:49 INFO - 'SHLVL': '1', 22:12:49 INFO - 'TERM': 'linux', 22:12:49 INFO - 'TMOUT': '86400', 22:12:49 INFO - 'USER': 'cltbld', 22:12:49 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1470458967.27072-1240889926', 22:12:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:12:49 INFO - '_': '/tools/buildbot/bin/python'} 22:12:49 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/SHyhuRHbRwikE1DLSb2Lig/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-025', '--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 22:12:49 INFO - mozversion application_buildid: 20160805193003 22:12:49 INFO - mozversion application_changeset: 731d13870f6a161f47ab8bffe5ee8e2ac92d5195 22:12:49 INFO - mozversion application_display_name: Nightly 22:12:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:12:49 INFO - mozversion application_name: Firefox 22:12:49 INFO - mozversion application_remotingname: firefox 22:12:49 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:12:49 INFO - mozversion application_vendor: Mozilla 22:12:49 INFO - mozversion application_version: 51.0a1 22:12:49 INFO - mozversion platform_buildid: 20160805193003 22:12:49 INFO - mozversion platform_changeset: 731d13870f6a161f47ab8bffe5ee8e2ac92d5195 22:12:49 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:12:49 INFO - mozversion platform_version: 51.0a1 22:12:49 INFO - using testdate: 1470460369 22:12:49 INFO - actual date: 1470460369 22:12:49 INFO - starting webserver on 'localhost:53380' 22:12:49 INFO - SUITE-START | Running 2 tests 22:12:49 INFO - TEST-START | dromaeo_css 22:12:49 INFO - operating with platform_type : linux_ 22:12:49 INFO - Initialising browser for dromaeo_css test... 22:12:49 INFO - TEST-INFO | started process 22829 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpM6Ryrz/profile http://localhost:53380/getInfo.html) 22:12:53 INFO - PROCESS | 22829 | __metrics Screen width/height:1600/1200 22:12:53 INFO - PROCESS | 22829 | colorDepth:24 22:12:53 INFO - PROCESS | 22829 | Browser inner width/height: 1024/697 22:12:53 INFO - PROCESS | 22829 | __metrics 22:12:54 INFO - PROCESS | 22829 | 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" 22:12:54 INFO - PROCESS | 22829 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:12:55 INFO - TEST-INFO | 22829: exit 0 22:12:55 INFO - Browser initialized. 22:12:55 INFO - Running cycle 1/1 for dromaeo_css test... 22:12:55 INFO - TEST-INFO | started process 22912 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpM6Ryrz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:13:06 INFO - PROCESS | 22912 | RSS: Main: 156545024 22:13:06 INFO - PROCESS | 22912 | 22:13:58 INFO - PROCESS | 22912 | 1470460438381 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] 22:16:07 INFO - PROCESS | 22912 | Cycle 1(1): loaded http://localhost:53380/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53380/tests/dromaeo/cssquery-ext.html) 22:16:08 INFO - PROCESS | 22912 | RSS: Main: 288305152 22:16:08 INFO - PROCESS | 22912 | 22:19:11 INFO - PROCESS | 22912 | Cycle 1(1): loaded http://localhost:53380/tests/dromaeo/cssquery-ext.html (next: http://localhost:53380/tests/dromaeo/cssquery-jquery.html) 22:19:11 INFO - PROCESS | 22912 | RSS: Main: 246431744 22:19:11 INFO - PROCESS | 22912 | 22:22:13 INFO - PROCESS | 22912 | Cycle 1(1): loaded http://localhost:53380/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53380/tests/dromaeo/cssquery-mootools.html) 22:22:14 INFO - PROCESS | 22912 | RSS: Main: 184512512 22:22:14 INFO - PROCESS | 22912 | 22:25:16 INFO - PROCESS | 22912 | Cycle 1(1): loaded http://localhost:53380/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53380/tests/dromaeo/cssquery-prototype.html) 22:25:16 INFO - PROCESS | 22912 | RSS: Main: 224780288 22:25:16 INFO - PROCESS | 22912 | 22:28:19 INFO - PROCESS | 22912 | Cycle 1(1): loaded http://localhost:53380/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53380/tests/dromaeo/cssquery-yui.html) 22:28:20 INFO - PROCESS | 22912 | RSS: Main: 186490880 22:28:20 INFO - PROCESS | 22912 | 22:31:23 INFO - PROCESS | 22912 | Cycle 1(1): loaded http://localhost:53380/tests/dromaeo/cssquery-yui.html (next: http://localhost:53380/tests/dromaeo/cssquery-dojo.html) 22:31:23 INFO - PROCESS | 22912 | RSS: Main: 280887296 22:31:23 INFO - PROCESS | 22912 | 22:31:23 INFO - PROCESS | 22912 | __start_tp_report 22:31:23 INFO - PROCESS | 22912 | _x_x_mozilla_page_load 22:31:23 INFO - PROCESS | 22912 | _x_x_mozilla_page_load_details 22:31:23 INFO - PROCESS | 22912 | |i|pagename|runs| 22:31:23 INFO - PROCESS | 22912 | |0;dojo.html;2959.201015725754;2988.1035689293208;3000.564918086877;3008.789384743066;3040.939181216376;5160.535551800337;5172.431032586422;5215.713135777535;5244.213367994803;5296.073187192241;5327.227552004957;5328.227407025979;5369.919451208235;5394.91907621386;5426.104692725696;4804.855854324367;4836.685615434985;4844.370231869852;4856.902861942759;5027.648064635477;4933.481984391641;4962.15643340633;4995.750212489379;5007.273945277932;5026.371703537058;4778.498257682945;4817.494163112858;4830.130576496229;4882.023595280958;4947.851564453073;4849.248366503198;4859.295402166701;4868.586170175504;4927.48261432548;4956.87607809804;4868.9756551217015;4883.194272944996;4887.902241955177;4922.704637721748;4952.207646776535;5223.973880130575;5338.386085600167;5366.382865970385;5386.380566234894;5405.540529055035;5696.145578163268;5699.68651724155;5767.913481297784;5769.394213607553;5769.9711501442225;5771.942280577183;5782.624129431573;5792.768289268424;5794.884102317972;5795;5216.452272511369;5289.47105289468;5345.946540534623;5346.278252435949;5371.973140134274;5905.320888097838;5911.586188966731;5914.763409463573;5916.674582897896;5919.437653422918;5681.971590142023;5693.715314234271;5696.11710184922;5697.715114244271;5698.629589076697;5503.972480137573;5615.017371959891;5678.772849086073;5679;5769;5658.34928983164;5661.943380566224;5673.035583950738;5677.914831277534;5718.828435146952;5623;5664.150377443417;5665.858353541194;5676.432356764373;5690.260266165372;4309.245881970643;4329.09089091288;4331.870043898688;4377.474703035656;4392.05570802279;3940.4877365942243;3961.2473630010204;3963.841446342172;3963.920721585552;3964.365701487748;5364.8122315718765;5387.111126664056;5395.163749618815;5453.400125986138;5458.5087342139395;5766.538676905838;5775.8556036099435;5777.508911742465;5780.075187969905;5826.213461182686;5407.405185429599;5433.049216387117;5450.482204190595;5453.182022696548;5464.781408743605;4825.517448255217;4924.285978533093;4988.152014157529;4992.525710057539;4998.725070121105;5969.701514924236;5975.940240597538;5976;5988.700564971821;6032.426919442646;5648.887022259614;5742.856428589319;5763.8559036024435;5764.913526297025;5771.2208851806145;5803.448672376117;5838.211841401527;5906.881862362815;5965.373635768182;5974.9402505974385;5618.2415373924;5633.8591535211945;5634.6337488063145;5668.121441176624;5684.7157642118555;5733.455321744427;5735.36910939788;5735.541156707537;5736.397678243891;5740.052891272893;5476.698781567052;5564.4713752193475;5569.693666848362;5599.636023658449;5610.270664813548;5643.492085712305;5644.379118297066;5660.88678226425;5697.458741419724;5713.600047996601;5472.72636368188;5550.722463876871;5551.916721249266;5570.5265052470995;5574.721263936706;5454.400015998158;5578.3584887738425;5633.88732225345;5698.173764803997;5698.772049117989;5536.7231638417115;5537.8061767837935;5541.3904470509015;5576.498115169654;5581.888362232814 22:31:23 INFO - PROCESS | 22912 | |1;ext.html;567.8239745678837;571.1119209629028;596.1742985964439;596.5853731656495;616.0051516800369;44905;46175.53824461754;46253.76873115634;46302;48956.75521622396;46803;47110.057798844005;47154.52845471553;47280;47412.28881566771;9020.233280171176;9139;9165.725028249164;9173.357864949456;9174.036726143759;8962.283017358595;9044.819103617923;9052.8642070369;9053.547322633842;9059.59231834569;9111.49886756228;9135.862962055575;9143.13140251675;9148.085191480832;9148.222401095914;8789.033206347296;8814.382993190478;8827.337949653802;8836.823263534758;8842.381033327638;9003.819923601557;9068.229200517964;9071.410358326753;9074.183323500916;9090.727278181665;43590.56409435897;43840.3423948641;43912;43945.78027109876;43981.34027989583;3567.126054116758;3710.5361829771277;3713.665770080706;3930.037140900498;4066.1867626474527;32803.83598082018;32831.50752738711;33259.83370083158;33277.50083748746;33545.832270838735;4153.709240353177;4199.181159673864;4205.15896820634;4205.894852628679;4359.17175736609;252550;254560.81756729708;254762;255082;255240;57218.7139064302;57515;57535.479162396805;57648.42351576514;57667.13499297514;48934.265986010236;53874;59333.40666593365;59440.405595943485;59603.701981489816;13318.600441986757;13788.310584470737;13847.792283115761;13874.445022199201;13906.374213160432;11436.771264574661;11466.2546934449;11474.426278686033;11475.54097836094;11483.311001339946;16075.35698572044;16087.356505739874;16135.193240337936;16191.595210119605;16255.431059912846;5407.997602217933;6504.699060187982;6544.672766361663;6659.234188068434;6679.7662081827875;995.9103680668629;1018.9133923616428;1102.7518808268076;1119.675294164683;1135.5741596901162;33321.42538179883;33469.330613387596;33568.160795979806;33655.663443365745;33820.661793381754;1012.9240306977006;1013.1843865688191;1058.4496062047692;1063.5852017713098;1092.6012005617893;16791;17183.828161718222;17225.7416138758;17363.887728511498;17506.21222045011;542522;543303;546297;547491;547563;59697.40302597005;59783.40216597865;59805;59945.70027149836;59959.40040599625;1162.91859569831;1169.181572898974;1193.1230545549015;1362.5844117544045;1387.965300867466;30422.239444014078;34248.31503369869;34340.48489272713;34390.82804585961;35158.8945083827;52351.47648523466;53325.200121998976;53425;53464.46535534751;53515.73242133764;504703;505479;506639;507197;507841;43104;43623.34564981464;43855.561444386425;45848.770756146005;46347.30479042749;48996;49060.26409603788;49321.50678493169;49603.75198123986;49793.75103124461;40763.38854917119;41831.954201145214;42588.57411425846;42611;43781.78109109434;41567.79216104021;41631.58368416277;42760.358594620484;43128;43614.56385436105;42188.578114219694;42588;43341;43597.128057438036;43715.12569748651;35343.29313413769;37195.44206836846;37439.06402339963;37589.43615845819;38042;28607.28481787972;28643.427131457673;28650.85674571697;28735.856320718263;28967.855160724062 22:31:23 INFO - PROCESS | 22912 | |2;jquery.html;374.7713894524338;380.9657130858224;383.74864463776225;392.21361170852447;400.64743026136995;14913.701725965475;18898.716519252204;19600.11799469022;20024.49938751532;21198;17520.474385768415;20250.29123980661;20339.694904576445;20407.59184816303;20577.382678519632;5380.704061276629;5606.55147588192;5615.971920140393;5616.63491873029;5619.381867994517;5399;5642.125470552071;5685.886282274373;5744.3394009689;5856.648601083949;5686.2039314496005;5687.943120568803;5710.743016564244;5721.885562288751;5732.283464566929;5731.254936858202;6121.602095863755;6140.263168419819;6159.568830181889;6162.845928851792;5372.301600791911;5510.476504732084;5530;5531.446855314477;5531.751071201806;13159.276239806806;13369.933150334284;13371.33143342829;13374.130681505769;15740;2178.4771654802894;2269.795718385337;2313.849599776009;2557.744225577446;2721.727827217282;9544.761380965532;9901.801963960752;9991;10158.085772280456;14108.647283817883;1917.2810196176433;1936.8353689936373;1944.8526664508017;1967.5179581002603;1991.6415045291842;62227;79350.60324698397;79853.60073199654;80250.59874700647;87250.56374718086;10970.670879873456;11104.44477776108;11226.21416500837;11295.54817807295;11393.886061139448;15587.220638968007;15600;15603.60990975212;15604.29780659873;15611.843881561039;7506.924930750732;7736.6518506667335;7929.286364227247;8073.959630201929;8093.231143041402;7190.496665233488;7279.6360181990685;7308.5614863108385;7511.399088073051;7534.171241163577;4668.183067963093;4806;4832.758362081881;4855.271709243641;4960.156773348539;1928.4118343904963;1941.2526177421512;2020.5857799151138;2026.1388909713312;2033.786452422504;943.5753910740194;1026.1380440430075;1030.7680771826278;1036.4506811389974;1083.268793564341;19626.313079042164;20262.40377030665;20277.49306267355;20547.897260513902;20735.27426540094;961.2214106573698;985.9556319965619;991.8859331176862;1011.7976404719086;1020.4285600063988;10504.422256775797;11352.54589816398;11371.772564548663;11387.20289579738;11413.086953043736;461762;470680;471297;488940;489248;91602.54198729097;92326;92445;92692;93165.5341723287;979.588572799426;987.9851802222972;1016.044917777287;1029.7683021320142;1032.1123833503182;10414.687559373231;10923.617673381712;11573.88426115728;17152.914235428743;17813;42336.78831605822;43021.13957720891;43359.34960975651;43739.78130109329;44248.336274955254;59603;60312.69843650753;60959;61304;62987.37012629815;12573.559925402567;12589.307588082744;12702.428390722625;13064.934675326942;13105.475780968654;14034.78947815804;14036.719265614835;14231.644208894446;14242;14293.356798943865;11743.823842642196;12156.088293378209;12395.442205100973;12407;12839.743205136032;11627.6511704649;11679.532818687157;11985.460654270404;12285.815712764137;12655.620331390073;6260.561760676891;7898.447108702336;12279.973804322186;12444.94658381357;12601.495940162658;11802.173847830569;12526.812097818358;12792.296423696785;12878.549250776177;13218.867811321763;12185.147039707135;12211.450484728386;12294.81557776652;12393.814092788796;12488.438020288928 22:31:23 INFO - PROCESS | 22912 | |3;mootools.html;1136.9658910232695;1399.1395291895485;1488.5459934719906;1629.519291808917;1785.669651114544;8419.957900210498;8897.91102088979;8935.91064089359;9112.589933452993;9177.954110229457;8936.687215947442;9047.095290470967;9053.81892362154;9056.230220431271;9057.456552606849;2451.3013791069548;2471.369800700828;2475.084218839032;2477.182529765173;2478;2389.581823180945;2441.3286346254745;2443.218170185541;2443.9755602444015;2457.8156638252112;2360.433495960974;2365.184011516033;2366.1954935321987;2390.1873363056557;2391.9043238270447;2379.0721618568773;2395.197608801054;2400.7359190489033;2402.483466054789;2421.9636705449434;2394.245812569035;2421.878906054708;2422.987885060581;2424.381782645432;2424.4302588891596;8431.114732953012;8898.37711360205;9076.63693452261;9087.454752714859;9088.50013249271;5275.182346736262;5447.591430642718;5482.424345443711;5488.9725551371985;5506.284183056217;3468.5837699476224;3469.9653003469894;3515.4726790981304;3527.6472352764777;3562.6615471530163;1191.4578866615668;1197.485081414991;1208.3776854919724;1230.341767154577;2119.2582596091397;12279.570215042431;12295.200811947285;12425.62723118308;12432.37838108091;12433.502659893593;4509.391232183613;4559.908801824011;4572.337011133367;4659.347691323217;4891.804327826847;9821.508924553744;9992.400455972665;10001.649942252132;10006.749831254132;10011.749706257257;5411.134218525017;5418.83743487696;5421.430749771296;5423.996560636295;5429.728513574306;4500.392447019676;4848.733319667452;4932.778024988884;4995.8001679932395;5023.648344615842;3900.239453306578;3956.6241207085855;4016.7991600420446;4043.555208927016;4047.817848196838;1160.6460029691025;1165.906727461801;1176.9529218831321;1176.9941150294364;1187.2876274235562;971.582219645545;984.842425211977;989.5497548615363;991.4200192887098;1031.6028329093353;5800.7099645018425;5813.912791308134;5895.469407753323;5927.8221653350465;6038.879222415527;983.2969426859829;1006.3006210683504;1006.9949650251701;1008.3294609085023;1056.984145237822;4095.24238015968;4180.790960452026;4270.380794784801;4296.334068219431;4401.163778882003;11087;11421.314721116594;11502.88497115018;11523;11533.826992595117;11241.831372529256;11346.205765596495;11355.886441135648;11448.54205831774;11493.367864767351;8173.67305307793;8316;8322.793194986692;8332.875006874901;8335.083140854622;6175.69121543912;6221.471174949999;6275.654838983717;6276.968615156895;6292.182016337847;10071.395716257048;10144.847827282745;10191.541380638037;10223.233257505568;10358.223133264884;10506.789864202521;10565.841512377474;10577.100946419332;10674.679759607225;10787.784244315228;6874.931250687429;7410.592417417094;7584.203658615766;7878.487898286593;8203.876941845996;8900.154485323885;8957.328200385127;8965.686200982935;8966.641334346554;8971.596278167368;7385.778426647209;7533.585652789149;7610.96194519046;7932.524048557105;8205.876911846208;7550.282723141293;7650.502717323357;7657.770266892002;7665.156832748505;7667.616619169131;7308.780736577911;7405.703771849281;7411.370033547209;7412.481126321106;7422;4969.527894849984;5007.824726134713;5111.718855462986;5132.8460146195675;5277.8416647500635;4695.718256904596;4791.377120974251;4834.395700537433;4964.4539100698175;5171.456997015259 22:31:23 INFO - PROCESS | 22912 | |4;prototype.html;311.9064280715786;323.917401062729;329.4021351247487;331.6948407465132;333.6946693775195;4947.678400903938;5008.749562521877;5091.770870310836;5147.922781158286;5189.455107213745;4999.825006124786;5036.5897548686335;5064.949350506494;5082.593392528598;5114.28400023996;4085.5914408559056;4096.303628383182;4098.569650186725;4099.118689481761;4101.671866250707;3853.0945227871566;3965.1363858986374;4080.0411903202726;4094.382718826062;4101.548829628753;4006.8797936061965;4024.738392004525;4043.454133691958;4048.3927410888455;4062.2078694654556;4003.3586229645853;4007.3788562772816;4010.037590978144;4018.2967980603275;4021.819018144176;3580.856765729368;3586.659267369622;3587.8744243951337;3607.026102952188;3611.2485444559366;4835.259306649603;4955.925661115086;4974.885673588747;4981.676191047606;4981.875453113665;4578.816847326103;4581.839635612738;4586.449626044862;4586.908261834778;4593.379893714339;5273.815416460406;5274.314339135888;5284.2866213061125;5285.8635393390605;5318.361796584396;3638.4178531434845;3815.8664446744233;3833.8274777635074;3835.1562656215588;3843.5579908310624;5467.305135408036;5502.312210973629;5506.32057222547;5515.310586176728;5524.14375771752;3946.506686664167;3971.3050216212046;3979.6816254700134;3982.7809470478824;3983.900402489961;3908.609139086069;3950.664193543581;3964.266610677034;3979.1245925896255;4033.2941735196223;4512;4517.819287228474;4522.706024108423;4541.613962813198;4549.863504094817;5332.7067011313975;5348.197770334403;5369.597280203922;5378.767811358242;5382.811601594004;1309.5874799438147;1315.302889468583;1339.9263040532865;1358.4294596269392;1370.2532119994628;910.2763303173992;936.8501039833731;940.1773448232797;942.1520631431766;950.7955789505289;5191.143461328914;5195.506426889439;5201.1678131498775;5207.739613019334;5221.686698798084;5266.815661451831;5407.351117865881;5497.120460726264;5499.707568721347;5503.229547863302;5100.158473851847;5101.20931255656;5114.6931184128325;5115.104856650071;5117.360329958755;2512.4221429071417;2542.453372524916;2546.0197823837957;2577.3814284571945;2583.114326541635;4772.284157376422;4945.629077819178;4973.278874563241;4974.37820272466;4980.9252861207115;4872.439669437989;4877.000429823755;4882.292067650171;4886.389201349832;4893.584045356113;4956.826511072095;4962.6774259673;4965.379327584052;4970.633075904156;4978.551930326288;4487.6185524230805;4793.352897358812;4796.832110876103;4806.831760888352;4812.51874812516;4760.666753327234;4765.880852978703;4777.665563410528;4796.37647105874;4806.068298337941;4898.359049718384;4929.827456039022;4941.925871111865;4957.487966170208;4961.70229786214;3572.857085716646;3597.748157628941;3604.603493615648;3711.183539621279;3964.8414063438295;5455.672659640434;5455.918161227505;5459.535939445192;5466.945330546644;5467.39858615544;5338.519533242027;5355.919661205007;5356.303680521507;5367.141257398797;5372.543333816513;5118.488151185001;5323.387810401853;5361.490658387447;5367.731613419392;5376.973115134399;5096.311997880387;5193.558547523352;5289.814856480005;5299.330710826078;5326.9733651333045;5382;5386.973065134649;5389.251481744261;5429.728513574384;5430.266913966564;5156.2523434102595;5229.50319719626;5250.868728281824;5280.7556788365555;5290.518654776669 22:31:23 INFO - PROCESS | 22912 | |5;yui.html;764.3961270596229;828.7306625346764;837.7486753973807;871.0244526130734;883.7658020624534;14816.259187040645;15356.923215383935;15521.379144834193;15558;15595.1422671753;14112.364943577539;14161.079529830553;14405.42378304869;14466.132032078083;14668.266586670677;808.0707186735258;826.0624191542612;830.393812516863;831.8585840407145;840.0297656207091;553.2531083037907;570.2017175953656;571.0064487791234;571.6570057965208;674.6390680985673;874.9825003499926;880.0583375787877;880.2869675562782;880.344143613006;880.4673172730502;874.7069731639908;874.8643960186213;875.8029443375221;875.8598624220126;876.2464280718577;514.0361821584529;614.8432149801816;632.9272133704637;638.9073584330292;639.7792761497323;14153.716925661533;14186;14232.928835355757;14256.643583910381;14258.2157981311;14250.714985700331;14535.360106470102;14847.706931242685;15018.249087545688;15342.309596068204;13330.933345333307;13456.798148027787;13605.659858503517;13617;13628.727425451534;4490.640748740093;4562.064776720765;4574.679772415932;4728.4798672146035;4761.2143996240575;79682.20317796864;80559.5972020142;80577;80784.59607701983;80844.59577702191;11515.654530364103;11838.785928530275;11979.520819167135;12021;12073.818892716616;21710.34868953934;22152.778472215396;22175.223867164572;22213.666794998087;22256.109755609596;8485.872711909326;8514.872276915852;9014.278857691368;9105.180533751862;9123.77190570241;4868.878278043077;5000.824971125993;5098.490150984871;5161.974190129025;5169.844904652867;3384.526166336715;3501.8774342897877;3523.8414271357337;3530.329237444929;3533.0813988362697;571.7655761137913;587;595.7051259626512;598.1924402057275;598.8921994041115;630.8399663815746;640.5044716333845;678.8913773796169;694.4062826283556;727.276355038562;9662.95168524167;9745.223882829876;9826.16477599412;9860.211183105333;10148.543315550818;559.8236555485009;560.3024234827584;652.360686527206;691.9550229235084;722.6675729164538;4714.787834547454;4739.454962679267;4902.2156454967035;4907.631927605373;4911.901761964741;75119;76054;76120;98000.50999744955;125786.37106814407;103240.22458091147;103920;109187;109647.65100671153;115604;62842.68578657169;75550.24449755432;76300.23699763042;87057.5647121773;91080.34736700135;10121.797564048531;10149.441780701836;10217.233707472118;10370.11853992389;10705.732356691145;51431.485685144165;51990;52253;54047.72976135094;54361.45638543564;43104.78447607742;47903.76048119737;48020.27969580529;55095;71234;13129.934350328187;13335.799963000756;13574.93212533931;13804.17174969505;13900.51348202808;13975.580732578039;14108.365123569669;14402.783958240845;14558.41766329335;14630.268486575842;13495;13784.517541886387;13793.172409655454;14379.496717614833;14467.276636167939;13630.318484075558;13961.650958726113;13967.30163491801;14137.858621413654;14602.196879171748;13840.653983650489;14193.503227386991;14310.998230124207;14524.27378631043;14527.709445811235;10653.360798352123;11152.832707509557;11173;11184.664460066211;11453;9825.606975720892;10326.845097323696;10581.31221470602;10816.161876147733;11324.207305488537 22:31:23 INFO - PROCESS | 22912 | __end_tp_report 22:31:23 INFO - PROCESS | 22912 | __start_cc_report 22:31:23 INFO - PROCESS | 22912 | _x_x_mozilla_cycle_collect,1069 22:31:23 INFO - PROCESS | 22912 | __end_cc_report 22:31:23 INFO - PROCESS | 22912 | __startTimestamp1470461483438__endTimestamp 22:31:23 INFO - PROCESS | 22912 | 22:31:23 INFO - PROCESS | 22912 | ------- Summary: start ------- 22:31:23 INFO - PROCESS | 22912 | Number of tests: 6 22:31:23 INFO - PROCESS | 22912 | 22:31:23 INFO - PROCESS | 22912 | [#0] dojo.html Cycles:165 Average:5324.99 Median:5550.72 stddev:614.35 (11.1%) stddev-sans-first:587.53 22:31:23 INFO - PROCESS | 22912 | Values: 2959.2 2988.1 3000.6 3008.8 3040.9 5160.5 5172.4 5215.7 5244.2 5296.1 5327.2 5328.2 5369.9 5394.9 5426.1 4804.9 4836.7 4844.4 4856.9 5027.6 4933.5 4962.2 4995.8 5007.3 5026.4 4778.5 4817.5 4830.1 4882.0 4947.9 4849.2 4859.3 4868.6 4927.5 4956.9 4869.0 4883.2 4887.9 4922.7 4952.2 5224.0 5338.4 5366.4 5386.4 5405.5 5696.1 5699.7 5767.9 5769.4 5770.0 5771.9 5782.6 5792.8 5794.9 5795.0 5216.5 5289.5 5345.9 5346.3 5372.0 5905.3 5911.6 5914.8 5916.7 5919.4 5682.0 5693.7 5696.1 5697.7 5698.6 5504.0 5615.0 5678.8 5679.0 5769.0 5658.3 5661.9 5673.0 5677.9 5718.8 5623.0 5664.2 5665.9 5676.4 5690.3 4309.2 4329.1 4331.9 4377.5 4392.1 3940.5 3961.2 3963.8 3963.9 3964.4 5364.8 5387.1 5395.2 5453.4 5458.5 5766.5 5775.9 5777.5 5780.1 5826.2 5407.4 5433.0 5450.5 5453.2 5464.8 4825.5 4924.3 4988.2 4992.5 4998.7 5969.7 5975.9 5976.0 5988.7 6032.4 5648.9 5742.9 5763.9 5764.9 5771.2 5803.4 5838.2 5906.9 5965.4 5974.9 5618.2 5633.9 5634.6 5668.1 5684.7 5733.5 5735.4 5735.5 5736.4 5740.1 5476.7 5564.5 5569.7 5599.6 5610.3 5643.5 5644.4 5660.9 5697.5 5713.6 5472.7 5550.7 5551.9 5570.5 5574.7 5454.4 5578.4 5633.9 5698.2 5698.8 5536.7 5537.8 5541.4 5576.5 5581.9 22:31:23 INFO - PROCESS | 22912 | 22:31:23 INFO - PROCESS | 22912 | [#1] ext.html Cycles:180 Average:60578.17 Median:43748.45 stddev:120818.39 (276.2%) stddev-sans-first:121073.30 22:31:23 INFO - PROCESS | 22912 | Values: 567.8 571.1 596.2 596.6 616.0 44905.0 46175.5 46253.8 46302.0 48956.8 46803.0 47110.1 47154.5 47280.0 47412.3 9020.2 9139.0 9165.7 9173.4 9174.0 8962.3 9044.8 9052.9 9053.5 9059.6 9111.5 9135.9 9143.1 9148.1 9148.2 8789.0 8814.4 8827.3 8836.8 8842.4 9003.8 9068.2 9071.4 9074.2 9090.7 43590.6 43840.3 43912.0 43945.8 43981.3 3567.1 3710.5 3713.7 3930.0 4066.2 32803.8 32831.5 33259.8 33277.5 33545.8 4153.7 4199.2 4205.2 4205.9 4359.2 252550.0 254560.8 254762.0 255082.0 255240.0 57218.7 57515.0 57535.5 57648.4 57667.1 48934.3 53874.0 59333.4 59440.4 59603.7 13318.6 13788.3 13847.8 13874.4 13906.4 11436.8 11466.3 11474.4 11475.5 11483.3 16075.4 16087.4 16135.2 16191.6 16255.4 5408.0 6504.7 6544.7 6659.2 6679.8 995.9 1018.9 1102.8 1119.7 1135.6 33321.4 33469.3 33568.2 33655.7 33820.7 1012.9 1013.2 1058.4 1063.6 1092.6 16791.0 17183.8 17225.7 17363.9 17506.2 542522.0 543303.0 546297.0 547491.0 547563.0 59697.4 59783.4 59805.0 59945.7 59959.4 1162.9 1169.2 1193.1 1362.6 1388.0 30422.2 34248.3 34340.5 34390.8 35158.9 52351.5 53325.2 53425.0 53464.5 53515.7 504703.0 505479.0 506639.0 507197.0 507841.0 43104.0 43623.3 43855.6 45848.8 46347.3 48996.0 49060.3 49321.5 49603.8 49793.8 40763.4 41832.0 42588.6 42611.0 43781.8 41567.8 41631.6 42760.4 43128.0 43614.6 42188.6 42588.0 43341.0 43597.1 43715.1 35343.3 37195.4 37439.1 37589.4 38042.0 28607.3 28643.4 28650.9 28735.9 28967.9 22:31:23 INFO - PROCESS | 22912 | 22:31:23 INFO - PROCESS | 22912 | [#2] jquery.html Cycles:180 Average:28640.54 Median:20562.64 stddev:78636.56 (382.4%) stddev-sans-first:78828.52 22:31:23 INFO - PROCESS | 22912 | Values: 374.8 381.0 383.7 392.2 400.6 14913.7 18898.7 19600.1 20024.5 21198.0 17520.5 20250.3 20339.7 20407.6 20577.4 5380.7 5606.6 5616.0 5616.6 5619.4 5399.0 5642.1 5685.9 5744.3 5856.6 5686.2 5687.9 5710.7 5721.9 5732.3 5731.3 6121.6 6140.3 6159.6 6162.8 5372.3 5510.5 5530.0 5531.4 5531.8 13159.3 13369.9 13371.3 13374.1 15740.0 2178.5 2269.8 2313.8 2557.7 2721.7 9544.8 9901.8 9991.0 10158.1 14108.6 1917.3 1936.8 1944.9 1967.5 1991.6 62227.0 79350.6 79853.6 80250.6 87250.6 10970.7 11104.4 11226.2 11295.5 11393.9 15587.2 15600.0 15603.6 15604.3 15611.8 7506.9 7736.7 7929.3 8074.0 8093.2 7190.5 7279.6 7308.6 7511.4 7534.2 4668.2 4806.0 4832.8 4855.3 4960.2 1928.4 1941.3 2020.6 2026.1 2033.8 943.6 1026.1 1030.8 1036.5 1083.3 19626.3 20262.4 20277.5 20547.9 20735.3 961.2 986.0 991.9 1011.8 1020.4 10504.4 11352.5 11371.8 11387.2 11413.1 461762.0 470680.0 471297.0 488940.0 489248.0 91602.5 92326.0 92445.0 92692.0 93165.5 979.6 988.0 1016.0 1029.8 1032.1 10414.7 10923.6 11573.9 17152.9 17813.0 42336.8 43021.1 43359.3 43739.8 44248.3 59603.0 60312.7 60959.0 61304.0 62987.4 12573.6 12589.3 12702.4 13064.9 13105.5 14034.8 14036.7 14231.6 14242.0 14293.4 11743.8 12156.1 12395.4 12407.0 12839.7 11627.7 11679.5 11985.5 12285.8 12655.6 6260.6 7898.4 12280.0 12444.9 12601.5 11802.2 12526.8 12792.3 12878.5 13218.9 12185.1 12211.5 12294.8 12393.8 12488.4 22:31:23 INFO - PROCESS | 22912 | 22:31:23 INFO - PROCESS | 22912 | [#3] mootools.html Cycles:180 Average:5911.40 Median:4566.12 stddev:3353.26 (73.4%) stddev-sans-first:3343.46 22:31:23 INFO - PROCESS | 22912 | Values: 1137.0 1399.1 1488.5 1629.5 1785.7 8420.0 8897.9 8935.9 9112.6 9178.0 8936.7 9047.1 9053.8 9056.2 9057.5 2451.3 2471.4 2475.1 2477.2 2478.0 2389.6 2441.3 2443.2 2444.0 2457.8 2360.4 2365.2 2366.2 2390.2 2391.9 2379.1 2395.2 2400.7 2402.5 2422.0 2394.2 2421.9 2423.0 2424.4 2424.4 8431.1 8898.4 9076.6 9087.5 9088.5 5275.2 5447.6 5482.4 5489.0 5506.3 3468.6 3470.0 3515.5 3527.6 3562.7 1191.5 1197.5 1208.4 1230.3 2119.3 12279.6 12295.2 12425.6 12432.4 12433.5 4509.4 4559.9 4572.3 4659.3 4891.8 9821.5 9992.4 10001.6 10006.7 10011.7 5411.1 5418.8 5421.4 5424.0 5429.7 4500.4 4848.7 4932.8 4995.8 5023.6 3900.2 3956.6 4016.8 4043.6 4047.8 1160.6 1165.9 1177.0 1177.0 1187.3 971.6 984.8 989.5 991.4 1031.6 5800.7 5813.9 5895.5 5927.8 6038.9 983.3 1006.3 1007.0 1008.3 1057.0 4095.2 4180.8 4270.4 4296.3 4401.2 11087.0 11421.3 11502.9 11523.0 11533.8 11241.8 11346.2 11355.9 11448.5 11493.4 8173.7 8316.0 8322.8 8332.9 8335.1 6175.7 6221.5 6275.7 6277.0 6292.2 10071.4 10144.8 10191.5 10223.2 10358.2 10506.8 10565.8 10577.1 10674.7 10787.8 6874.9 7410.6 7584.2 7878.5 8203.9 8900.2 8957.3 8965.7 8966.6 8971.6 7385.8 7533.6 7611.0 7932.5 8205.9 7550.3 7650.5 7657.8 7665.2 7667.6 7308.8 7405.7 7411.4 7412.5 7422.0 4969.5 5007.8 5111.7 5132.8 5277.8 4695.7 4791.4 4834.4 4964.5 5171.5 22:31:23 INFO - PROCESS | 22912 | 22:31:23 INFO - PROCESS | 22912 | [#4] prototype.html Cycles:180 Average:4380.17 Median:4946.65 stddev:1261.30 (25.5%) stddev-sans-first:1227.32 22:31:23 INFO - PROCESS | 22912 | Values: 311.9 323.9 329.4 331.7 333.7 4947.7 5008.7 5091.8 5147.9 5189.5 4999.8 5036.6 5064.9 5082.6 5114.3 4085.6 4096.3 4098.6 4099.1 4101.7 3853.1 3965.1 4080.0 4094.4 4101.5 4006.9 4024.7 4043.5 4048.4 4062.2 4003.4 4007.4 4010.0 4018.3 4021.8 3580.9 3586.7 3587.9 3607.0 3611.2 4835.3 4955.9 4974.9 4981.7 4981.9 4578.8 4581.8 4586.4 4586.9 4593.4 5273.8 5274.3 5284.3 5285.9 5318.4 3638.4 3815.9 3833.8 3835.2 3843.6 5467.3 5502.3 5506.3 5515.3 5524.1 3946.5 3971.3 3979.7 3982.8 3983.9 3908.6 3950.7 3964.3 3979.1 4033.3 4512.0 4517.8 4522.7 4541.6 4549.9 5332.7 5348.2 5369.6 5378.8 5382.8 1309.6 1315.3 1339.9 1358.4 1370.3 910.3 936.9 940.2 942.2 950.8 5191.1 5195.5 5201.2 5207.7 5221.7 5266.8 5407.4 5497.1 5499.7 5503.2 5100.2 5101.2 5114.7 5115.1 5117.4 2512.4 2542.5 2546.0 2577.4 2583.1 4772.3 4945.6 4973.3 4974.4 4980.9 4872.4 4877.0 4882.3 4886.4 4893.6 4956.8 4962.7 4965.4 4970.6 4978.6 4487.6 4793.4 4796.8 4806.8 4812.5 4760.7 4765.9 4777.7 4796.4 4806.1 4898.4 4929.8 4941.9 4957.5 4961.7 3572.9 3597.7 3604.6 3711.2 3964.8 5455.7 5455.9 5459.5 5466.9 5467.4 5338.5 5355.9 5356.3 5367.1 5372.5 5118.5 5323.4 5361.5 5367.7 5377.0 5096.3 5193.6 5289.8 5299.3 5327.0 5382.0 5387.0 5389.3 5429.7 5430.3 5156.3 5229.5 5250.9 5280.8 5290.5 22:31:23 INFO - PROCESS | 22912 | 22:31:23 INFO - PROCESS | 22912 | [#5] yui.html Cycles:180 Average:19866.40 Median:4721.63 stddev:27865.03 (590.2%) stddev-sans-first:27906.29 22:31:23 INFO - PROCESS | 22912 | Values: 764.4 828.7 837.7 871.0 883.8 14816.3 15356.9 15521.4 15558.0 15595.1 14112.4 14161.1 14405.4 14466.1 14668.3 808.1 826.1 830.4 831.9 840.0 553.3 570.2 571.0 571.7 674.6 875.0 880.1 880.3 880.3 880.5 874.7 874.9 875.8 875.9 876.2 514.0 614.8 632.9 638.9 639.8 14153.7 14186.0 14232.9 14256.6 14258.2 14250.7 14535.4 14847.7 15018.2 15342.3 13330.9 13456.8 13605.7 13617.0 13628.7 4490.6 4562.1 4574.7 4728.5 4761.2 79682.2 80559.6 80577.0 80784.6 80844.6 11515.7 11838.8 11979.5 12021.0 12073.8 21710.3 22152.8 22175.2 22213.7 22256.1 8485.9 8514.9 9014.3 9105.2 9123.8 4868.9 5000.8 5098.5 5162.0 5169.8 3384.5 3501.9 3523.8 3530.3 3533.1 571.8 587.0 595.7 598.2 598.9 630.8 640.5 678.9 694.4 727.3 9663.0 9745.2 9826.2 9860.2 10148.5 559.8 560.3 652.4 692.0 722.7 4714.8 4739.5 4902.2 4907.6 4911.9 75119.0 76054.0 76120.0 98000.5 125786.4 103240.2 103920.0 109187.0 109647.7 115604.0 62842.7 75550.2 76300.2 87057.6 91080.3 10121.8 10149.4 10217.2 10370.1 10705.7 51431.5 51990.0 52253.0 54047.7 54361.5 43104.8 47903.8 48020.3 55095.0 71234.0 13129.9 13335.8 13574.9 13804.2 13900.5 13975.6 14108.4 14402.8 14558.4 14630.3 13495.0 13784.5 13793.2 14379.5 14467.3 13630.3 13961.7 13967.3 14137.9 14602.2 13840.7 14193.5 14311.0 14524.3 14527.7 10653.4 11152.8 11173.0 11184.7 11453.0 9825.6 10326.8 10581.3 10816.2 11324.2 22:31:23 INFO - PROCESS | 22912 | -------- Summary: end -------- 22:31:23 INFO - PROCESS | 22912 | 22:31:24 INFO - TEST-INFO | 22912: exit 0 22:31:24 INFO - TEST-OK | dromaeo_css | took 1115009ms 22:31:24 INFO - TEST-START | kraken 22:31:24 INFO - operating with platform_type : linux_ 22:31:24 INFO - Initialising browser for kraken test... 22:31:24 INFO - TEST-INFO | started process 23110 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpS9IIOs/profile http://localhost:53380/getInfo.html) 22:31:28 INFO - PROCESS | 23110 | __metrics Screen width/height:1600/1200 22:31:28 INFO - PROCESS | 23110 | colorDepth:24 22:31:28 INFO - PROCESS | 23110 | Browser inner width/height: 1024/697 22:31:28 INFO - PROCESS | 23110 | __metrics 22:31:29 INFO - PROCESS | 23110 | 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" 22:31:29 INFO - PROCESS | 23110 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:31:30 INFO - TEST-INFO | 23110: exit 0 22:31:30 INFO - Browser initialized. 22:31:30 INFO - Running cycle 1/1 for kraken test... 22:31:30 INFO - TEST-INFO | started process 23190 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpS9IIOs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:31:41 INFO - PROCESS | 23190 | RSS: Main: 156307456 22:31:41 INFO - PROCESS | 23190 | 22:31:44 INFO - PROCESS | 23190 | Cycle 1(1): loaded http://localhost:53380/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53380/tests/kraken/driver.html?testName=audio-beat-detection) 22:31:45 INFO - PROCESS | 23190 | RSS: Main: 213217280 22:31:45 INFO - PROCESS | 23190 | 22:31:47 INFO - PROCESS | 23190 | Cycle 1(1): loaded http://localhost:53380/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53380/tests/kraken/driver.html?testName=audio-dft) 22:31:48 INFO - PROCESS | 23190 | RSS: Main: 186396672 22:31:48 INFO - PROCESS | 23190 | 22:31:51 INFO - PROCESS | 23190 | Cycle 1(1): loaded http://localhost:53380/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53380/tests/kraken/driver.html?testName=audio-fft) 22:31:52 INFO - PROCESS | 23190 | RSS: Main: 269496320 22:31:52 INFO - PROCESS | 23190 | 22:31:54 INFO - PROCESS | 23190 | Cycle 1(1): loaded http://localhost:53380/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53380/tests/kraken/driver.html?testName=audio-oscillator) 22:31:54 INFO - PROCESS | 23190 | RSS: Main: 179834880 22:31:54 INFO - PROCESS | 23190 | 22:31:57 INFO - PROCESS | 23190 | Cycle 1(1): loaded http://localhost:53380/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53380/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:31:57 INFO - PROCESS | 23190 | RSS: Main: 184209408 22:31:57 INFO - PROCESS | 23190 | 22:32:00 INFO - PROCESS | 23190 | Cycle 1(1): loaded http://localhost:53380/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53380/tests/kraken/driver.html?testName=imaging-darkroom) 22:32:01 INFO - PROCESS | 23190 | RSS: Main: 219570176 22:32:01 INFO - PROCESS | 23190 | 22:32:04 INFO - PROCESS | 23190 | Cycle 1(1): loaded http://localhost:53380/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53380/tests/kraken/driver.html?testName=imaging-desaturate) 22:32:05 INFO - PROCESS | 23190 | RSS: Main: 219758592 22:32:05 INFO - PROCESS | 23190 | 22:32:08 INFO - PROCESS | 23190 | Cycle 1(1): loaded http://localhost:53380/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53380/tests/kraken/driver.html?testName=json-parse-financial) 22:32:08 INFO - PROCESS | 23190 | RSS: Main: 219709440 22:32:08 INFO - PROCESS | 23190 | 22:32:10 INFO - PROCESS | 23190 | Cycle 1(1): loaded http://localhost:53380/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53380/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:32:10 INFO - PROCESS | 23190 | RSS: Main: 184156160 22:32:10 INFO - PROCESS | 23190 | 22:32:12 INFO - PROCESS | 23190 | Cycle 1(1): loaded http://localhost:53380/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53380/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:32:13 INFO - PROCESS | 23190 | RSS: Main: 190238720 22:32:13 INFO - PROCESS | 23190 | 22:32:15 INFO - PROCESS | 23190 | Cycle 1(1): loaded http://localhost:53380/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53380/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:32:15 INFO - PROCESS | 23190 | RSS: Main: 194093056 22:32:15 INFO - PROCESS | 23190 | 22:32:18 INFO - PROCESS | 23190 | Cycle 1(1): loaded http://localhost:53380/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53380/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:32:18 INFO - PROCESS | 23190 | RSS: Main: 193036288 22:32:18 INFO - PROCESS | 23190 | 22:32:21 INFO - PROCESS | 23190 | Cycle 1(1): loaded http://localhost:53380/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53380/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:32:22 INFO - PROCESS | 23190 | RSS: Main: 195891200 22:32:22 INFO - PROCESS | 23190 | 22:32:23 INFO - PROCESS | 23190 | Cycle 1(1): loaded http://localhost:53380/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53380/tests/kraken/driver.html?testName=ai-astar) 22:32:24 INFO - PROCESS | 23190 | RSS: Main: 243576832 22:32:24 INFO - PROCESS | 23190 | 22:32:24 INFO - PROCESS | 23190 | __start_tp_report 22:32:24 INFO - PROCESS | 23190 | _x_x_mozilla_page_load 22:32:24 INFO - PROCESS | 23190 | _x_x_mozilla_page_load_details 22:32:24 INFO - PROCESS | 23190 | |i|pagename|runs| 22:32:24 INFO - PROCESS | 23190 | |0;ai-astar;104;112;112;110;112;111;111;112;110;111 22:32:24 INFO - PROCESS | 23190 | |1;audio-beat-detection;147;119;120;183;118;122;146;117;156;128 22:32:24 INFO - PROCESS | 23190 | |2;audio-dft;161;161;161;161;161;160;161;160;163;160 22:32:24 INFO - PROCESS | 23190 | |3;audio-fft;74;76;74;112;78;76;74;74;97;74 22:32:24 INFO - PROCESS | 23190 | |4;audio-oscillator;83;84;125;84;84;84;121;84;83;122 22:32:24 INFO - PROCESS | 23190 | |5;imaging-gaussian-blur;117;117;117;117;116;117;117;117;116;117 22:32:24 INFO - PROCESS | 23190 | |6;imaging-darkroom;145;145;149;146;146;146;147;146;146;146 22:32:24 INFO - PROCESS | 23190 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;97 22:32:24 INFO - PROCESS | 23190 | |8;json-parse-financial;51;60;49;50;55;49;51;50;49;50 22:32:24 INFO - PROCESS | 23190 | |9;json-stringify-tinderbox;60;60;60;60;61;61;61;61;61;61 22:32:24 INFO - PROCESS | 23190 | |10;stanford-crypto-aes;77;77;77;79;77;76;76;77;77;76 22:32:24 INFO - PROCESS | 23190 | |11;stanford-crypto-ccm;121;121;120;123;119;119;118;119;191;126 22:32:24 INFO - PROCESS | 23190 | |12;stanford-crypto-pbkdf2;158;158;156;159;159;163;163;160;155;158 22:32:24 INFO - PROCESS | 23190 | |13;stanford-crypto-sha256-iterative;64;62;66;62;66;63;68;65;71;61 22:32:24 INFO - PROCESS | 23190 | __end_tp_report 22:32:24 INFO - PROCESS | 23190 | __start_cc_report 22:32:24 INFO - PROCESS | 23190 | _x_x_mozilla_cycle_collect,1676 22:32:24 INFO - PROCESS | 23190 | __end_cc_report 22:32:24 INFO - PROCESS | 23190 | __startTimestamp1470461544217__endTimestamp 22:32:24 INFO - PROCESS | 23190 | 22:32:24 INFO - PROCESS | 23190 | ------- Summary: start ------- 22:32:24 INFO - PROCESS | 23190 | Number of tests: 14 22:32:24 INFO - PROCESS | 23190 | 22:32:24 INFO - PROCESS | 23190 | [#0] ai-astar Cycles:10 Average:110.50 Median:111.50 stddev:2.42 (2.2%) stddev-sans-first:0.83 22:32:24 INFO - PROCESS | 23190 | Values: 104.0 112.0 112.0 110.0 112.0 111.0 111.0 112.0 110.0 111.0 22:32:24 INFO - PROCESS | 23190 | 22:32:24 INFO - PROCESS | 23190 | [#1] audio-beat-detection Cycles:10 Average:135.60 Median:137.00 stddev:21.90 (16.0%) stddev-sans-first:22.84 22:32:24 INFO - PROCESS | 23190 | Values: 147.0 119.0 120.0 183.0 118.0 122.0 146.0 117.0 156.0 128.0 22:32:24 INFO - PROCESS | 23190 | 22:32:24 INFO - PROCESS | 23190 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:0.88 (0.5%) stddev-sans-first:0.93 22:32:24 INFO - PROCESS | 23190 | Values: 161.0 161.0 161.0 161.0 161.0 160.0 161.0 160.0 163.0 160.0 22:32:24 INFO - PROCESS | 23190 | 22:32:24 INFO - PROCESS | 23190 | [#3] audio-fft Cycles:10 Average:80.90 Median:75.00 stddev:13.00 (17.3%) stddev-sans-first:13.55 22:32:24 INFO - PROCESS | 23190 | Values: 74.0 76.0 74.0 112.0 78.0 76.0 74.0 74.0 97.0 74.0 22:32:24 INFO - PROCESS | 23190 | 22:32:24 INFO - PROCESS | 23190 | [#4] audio-oscillator Cycles:10 Average:95.40 Median:84.00 stddev:18.85 (22.4%) stddev-sans-first:19.45 22:32:24 INFO - PROCESS | 23190 | Values: 83.0 84.0 125.0 84.0 84.0 84.0 121.0 84.0 83.0 122.0 22:32:24 INFO - PROCESS | 23190 | 22:32:24 INFO - PROCESS | 23190 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 22:32:24 INFO - PROCESS | 23190 | Values: 117.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 116.0 117.0 22:32:24 INFO - PROCESS | 23190 | 22:32:24 INFO - PROCESS | 23190 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:1.14 (0.8%) stddev-sans-first:1.12 22:32:24 INFO - PROCESS | 23190 | Values: 145.0 145.0 149.0 146.0 146.0 146.0 147.0 146.0 146.0 146.0 22:32:24 INFO - PROCESS | 23190 | 22:32:24 INFO - PROCESS | 23190 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 22:32:24 INFO - PROCESS | 23190 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 97.0 22:32:24 INFO - PROCESS | 23190 | 22:32:24 INFO - PROCESS | 23190 | [#8] json-parse-financial Cycles:10 Average:51.40 Median:50.50 stddev:3.50 (6.9%) stddev-sans-first:3.71 22:32:24 INFO - PROCESS | 23190 | Values: 51.0 60.0 49.0 50.0 55.0 49.0 51.0 50.0 49.0 50.0 22:32:24 INFO - PROCESS | 23190 | 22:32:24 INFO - PROCESS | 23190 | [#9] json-stringify-tinderbox Cycles:10 Average:60.60 Median:61.00 stddev:0.52 (0.8%) stddev-sans-first:0.50 22:32:24 INFO - PROCESS | 23190 | Values: 60.0 60.0 60.0 60.0 61.0 61.0 61.0 61.0 61.0 61.0 22:32:24 INFO - PROCESS | 23190 | 22:32:24 INFO - PROCESS | 23190 | [#10] stanford-crypto-aes Cycles:10 Average:76.90 Median:77.00 stddev:0.88 (1.1%) stddev-sans-first:0.93 22:32:24 INFO - PROCESS | 23190 | Values: 77.0 77.0 77.0 79.0 77.0 76.0 76.0 77.0 77.0 76.0 22:32:24 INFO - PROCESS | 23190 | 22:32:24 INFO - PROCESS | 23190 | [#11] stanford-crypto-ccm Cycles:10 Average:127.70 Median:121.00 stddev:22.37 (18.5%) stddev-sans-first:23.59 22:32:24 INFO - PROCESS | 23190 | Values: 121.0 121.0 120.0 123.0 119.0 119.0 118.0 119.0 191.0 126.0 22:32:24 INFO - PROCESS | 23190 | 22:32:24 INFO - PROCESS | 23190 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.90 Median:159.00 stddev:2.60 (1.6%) stddev-sans-first:2.74 22:32:24 INFO - PROCESS | 23190 | Values: 158.0 158.0 156.0 159.0 159.0 163.0 163.0 160.0 155.0 158.0 22:32:24 INFO - PROCESS | 23190 | 22:32:24 INFO - PROCESS | 23190 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.80 Median:65.50 stddev:3.08 (4.7%) stddev-sans-first:3.26 22:32:24 INFO - PROCESS | 23190 | Values: 64.0 62.0 66.0 62.0 66.0 63.0 68.0 65.0 71.0 61.0 22:32:24 INFO - PROCESS | 23190 | -------- Summary: end -------- 22:32:24 INFO - PROCESS | 23190 | 22:32:25 INFO - TEST-INFO | 23190: exit 0 22:32:25 INFO - TEST-OK | kraken | took 60943ms 22:32:25 INFO - SUITE-END | took 1175s 22:32:25 INFO - Completed test suite (00:19:36) 22:32:25 INFO - javascript benchmark 22:32:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2959.201015725754, 2988.1035689293208, 3000.564918086877, 3008.789384743066, 3040.939181216376, 5160.535551800337, 5172.431032586422, 5215.713135777535, 5244.213367994803, 5296.073187192241, 5327.227552004957, 5328.227407025979, 5369.919451208235, 5394.91907621386, 5426.104692725696, 4804.855854324367, 4836.685615434985, 4844.370231869852, 4856.902861942759, 5027.648064635477, 4933.481984391641, 4962.15643340633, 4995.750212489379, 5007.273945277932, 5026.371703537058, 4778.498257682945, 4817.494163112858, 4830.130576496229, 4882.023595280958, 4947.851564453073, 4849.248366503198, 4859.295402166701, 4868.586170175504, 4927.48261432548, 4956.87607809804, 4868.9756551217015, 4883.194272944996, 4887.902241955177, 4922.704637721748, 4952.207646776535, 5223.973880130575, 5338.386085600167, 5366.382865970385, 5386.380566234894, 5405.540529055035, 5696.145578163268, 5699.68651724155, 5767.913481297784, 5769.394213607553, 5769.9711501442225, 5771.942280577183, 5782.624129431573, 5792.768289268424, 5794.884102317972, 5795.0, 5216.452272511369, 5289.47105289468, 5345.946540534623, 5346.278252435949, 5371.973140134274, 5905.320888097838, 5911.586188966731, 5914.763409463573, 5916.674582897896, 5919.437653422918, 5681.971590142023, 5693.715314234271, 5696.11710184922, 5697.715114244271, 5698.629589076697, 5503.972480137573, 5615.017371959891, 5678.772849086073, 5679.0, 5769.0, 5658.34928983164, 5661.943380566224, 5673.035583950738, 5677.914831277534, 5718.828435146952, 5623.0, 5664.150377443417, 5665.858353541194, 5676.432356764373, 5690.260266165372, 4309.245881970643, 4329.09089091288, 4331.870043898688, 4377.474703035656, 4392.05570802279, 3940.4877365942243, 3961.2473630010204, 3963.841446342172, 3963.920721585552, 3964.365701487748, 5364.8122315718765, 5387.111126664056, 5395.163749618815, 5453.400125986138, 5458.5087342139395, 5766.538676905838, 5775.8556036099435, 5777.508911742465, 5780.075187969905, 5826.213461182686, 5407.405185429599, 5433.049216387117, 5450.482204190595, 5453.182022696548, 5464.781408743605, 4825.517448255217, 4924.285978533093, 4988.152014157529, 4992.525710057539, 4998.725070121105, 5969.701514924236, 5975.940240597538, 5976.0, 5988.700564971821, 6032.426919442646, 5648.887022259614, 5742.856428589319, 5763.8559036024435, 5764.913526297025, 5771.2208851806145, 5803.448672376117, 5838.211841401527, 5906.881862362815, 5965.373635768182, 5974.9402505974385, 5618.2415373924, 5633.8591535211945, 5634.6337488063145, 5668.121441176624, 5684.7157642118555, 5733.455321744427, 5735.36910939788, 5735.541156707537, 5736.397678243891, 5740.052891272893, 5476.698781567052, 5564.4713752193475, 5569.693666848362, 5599.636023658449, 5610.270664813548, 5643.492085712305, 5644.379118297066, 5660.88678226425, 5697.458741419724, 5713.600047996601, 5472.72636368188, 5550.722463876871, 5551.916721249266, 5570.5265052470995, 5574.721263936706, 5454.400015998158, 5578.3584887738425, 5633.88732225345, 5698.173764803997, 5698.772049117989, 5536.7231638417115, 5537.8061767837935, 5541.3904470509015, 5576.498115169654, 5581.888362232814], "value": 5282.150505004939, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [567.8239745678837, 571.1119209629028, 596.1742985964439, 596.5853731656495, 616.0051516800369, 44905.0, 46175.53824461754, 46253.76873115634, 46302.0, 48956.75521622396, 46803.0, 47110.057798844005, 47154.52845471553, 47280.0, 47412.28881566771, 9020.233280171176, 9139.0, 9165.725028249164, 9173.357864949456, 9174.036726143759, 8962.283017358595, 9044.819103617923, 9052.8642070369, 9053.547322633842, 9059.59231834569, 9111.49886756228, 9135.862962055575, 9143.13140251675, 9148.085191480832, 9148.222401095914, 8789.033206347296, 8814.382993190478, 8827.337949653802, 8836.823263534758, 8842.381033327638, 9003.819923601557, 9068.229200517964, 9071.410358326753, 9074.183323500916, 9090.727278181665, 43590.56409435897, 43840.3423948641, 43912.0, 43945.78027109876, 43981.34027989583, 3567.126054116758, 3710.5361829771277, 3713.665770080706, 3930.037140900498, 4066.1867626474527, 32803.83598082018, 32831.50752738711, 33259.83370083158, 33277.50083748746, 33545.832270838735, 4153.709240353177, 4199.181159673864, 4205.15896820634, 4205.894852628679, 4359.17175736609, 252550.0, 254560.81756729708, 254762.0, 255082.0, 255240.0, 57218.7139064302, 57515.0, 57535.479162396805, 57648.42351576514, 57667.13499297514, 48934.265986010236, 53874.0, 59333.40666593365, 59440.405595943485, 59603.701981489816, 13318.600441986757, 13788.310584470737, 13847.792283115761, 13874.445022199201, 13906.374213160432, 11436.771264574661, 11466.2546934449, 11474.426278686033, 11475.54097836094, 11483.311001339946, 16075.35698572044, 16087.356505739874, 16135.193240337936, 16191.595210119605, 16255.431059912846, 5407.997602217933, 6504.699060187982, 6544.672766361663, 6659.234188068434, 6679.7662081827875, 995.9103680668629, 1018.9133923616428, 1102.7518808268076, 1119.675294164683, 1135.5741596901162, 33321.42538179883, 33469.330613387596, 33568.160795979806, 33655.663443365745, 33820.661793381754, 1012.9240306977006, 1013.1843865688191, 1058.4496062047692, 1063.5852017713098, 1092.6012005617893, 16791.0, 17183.828161718222, 17225.7416138758, 17363.887728511498, 17506.21222045011, 542522.0, 543303.0, 546297.0, 547491.0, 547563.0, 59697.40302597005, 59783.40216597865, 59805.0, 59945.70027149836, 59959.40040599625, 1162.91859569831, 1169.181572898974, 1193.1230545549015, 1362.5844117544045, 1387.965300867466, 30422.239444014078, 34248.31503369869, 34340.48489272713, 34390.82804585961, 35158.8945083827, 52351.47648523466, 53325.200121998976, 53425.0, 53464.46535534751, 53515.73242133764, 504703.0, 505479.0, 506639.0, 507197.0, 507841.0, 43104.0, 43623.34564981464, 43855.561444386425, 45848.770756146005, 46347.30479042749, 48996.0, 49060.26409603788, 49321.50678493169, 49603.75198123986, 49793.75103124461, 40763.38854917119, 41831.954201145214, 42588.57411425846, 42611.0, 43781.78109109434, 41567.79216104021, 41631.58368416277, 42760.358594620484, 43128.0, 43614.56385436105, 42188.578114219694, 42588.0, 43341.0, 43597.128057438036, 43715.12569748651, 35343.29313413769, 37195.44206836846, 37439.06402339963, 37589.43615845819, 38042.0, 28607.28481787972, 28643.427131457673, 28650.85674571697, 28735.856320718263, 28967.855160724062], "value": 20248.2907138724, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [374.7713894524338, 380.9657130858224, 383.74864463776225, 392.21361170852447, 400.64743026136995, 14913.701725965475, 18898.716519252204, 19600.11799469022, 20024.49938751532, 21198.0, 17520.474385768415, 20250.29123980661, 20339.694904576445, 20407.59184816303, 20577.382678519632, 5380.704061276629, 5606.55147588192, 5615.971920140393, 5616.63491873029, 5619.381867994517, 5399.0, 5642.125470552071, 5685.886282274373, 5744.3394009689, 5856.648601083949, 5686.2039314496005, 5687.943120568803, 5710.743016564244, 5721.885562288751, 5732.283464566929, 5731.254936858202, 6121.602095863755, 6140.263168419819, 6159.568830181889, 6162.845928851792, 5372.301600791911, 5510.476504732084, 5530.0, 5531.446855314477, 5531.751071201806, 13159.276239806806, 13369.933150334284, 13371.33143342829, 13374.130681505769, 15740.0, 2178.4771654802894, 2269.795718385337, 2313.849599776009, 2557.744225577446, 2721.727827217282, 9544.761380965532, 9901.801963960752, 9991.0, 10158.085772280456, 14108.647283817883, 1917.2810196176433, 1936.8353689936373, 1944.8526664508017, 1967.5179581002603, 1991.6415045291842, 62227.0, 79350.60324698397, 79853.60073199654, 80250.59874700647, 87250.56374718086, 10970.670879873456, 11104.44477776108, 11226.21416500837, 11295.54817807295, 11393.886061139448, 15587.220638968007, 15600.0, 15603.60990975212, 15604.29780659873, 15611.843881561039, 7506.924930750732, 7736.6518506667335, 7929.286364227247, 8073.959630201929, 8093.231143041402, 7190.496665233488, 7279.6360181990685, 7308.5614863108385, 7511.399088073051, 7534.171241163577, 4668.183067963093, 4806.0, 4832.758362081881, 4855.271709243641, 4960.156773348539, 1928.4118343904963, 1941.2526177421512, 2020.5857799151138, 2026.1388909713312, 2033.786452422504, 943.5753910740194, 1026.1380440430075, 1030.7680771826278, 1036.4506811389974, 1083.268793564341, 19626.313079042164, 20262.40377030665, 20277.49306267355, 20547.897260513902, 20735.27426540094, 961.2214106573698, 985.9556319965619, 991.8859331176862, 1011.7976404719086, 1020.4285600063988, 10504.422256775797, 11352.54589816398, 11371.772564548663, 11387.20289579738, 11413.086953043736, 461762.0, 470680.0, 471297.0, 488940.0, 489248.0, 91602.54198729097, 92326.0, 92445.0, 92692.0, 93165.5341723287, 979.588572799426, 987.9851802222972, 1016.044917777287, 1029.7683021320142, 1032.1123833503182, 10414.687559373231, 10923.617673381712, 11573.88426115728, 17152.914235428743, 17813.0, 42336.78831605822, 43021.13957720891, 43359.34960975651, 43739.78130109329, 44248.336274955254, 59603.0, 60312.69843650753, 60959.0, 61304.0, 62987.37012629815, 12573.559925402567, 12589.307588082744, 12702.428390722625, 13064.934675326942, 13105.475780968654, 14034.78947815804, 14036.719265614835, 14231.644208894446, 14242.0, 14293.356798943865, 11743.823842642196, 12156.088293378209, 12395.442205100973, 12407.0, 12839.743205136032, 11627.6511704649, 11679.532818687157, 11985.460654270404, 12285.815712764137, 12655.620331390073, 6260.561760676891, 7898.447108702336, 12279.973804322186, 12444.94658381357, 12601.495940162658, 11802.173847830569, 12526.812097818358, 12792.296423696785, 12878.549250776177, 13218.867811321763, 12185.147039707135, 12211.450484728386, 12294.81557776652, 12393.814092788796, 12488.438020288928], "value": 9300.313692649834, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1136.9658910232695, 1399.1395291895485, 1488.5459934719906, 1629.519291808917, 1785.669651114544, 8419.957900210498, 8897.91102088979, 8935.91064089359, 9112.589933452993, 9177.954110229457, 8936.687215947442, 9047.095290470967, 9053.81892362154, 9056.230220431271, 9057.456552606849, 2451.3013791069548, 2471.369800700828, 2475.084218839032, 2477.182529765173, 2478.0, 2389.581823180945, 2441.3286346254745, 2443.218170185541, 2443.9755602444015, 2457.8156638252112, 2360.433495960974, 2365.184011516033, 2366.1954935321987, 2390.1873363056557, 2391.9043238270447, 2379.0721618568773, 2395.197608801054, 2400.7359190489033, 2402.483466054789, 2421.9636705449434, 2394.245812569035, 2421.878906054708, 2422.987885060581, 2424.381782645432, 2424.4302588891596, 8431.114732953012, 8898.37711360205, 9076.63693452261, 9087.454752714859, 9088.50013249271, 5275.182346736262, 5447.591430642718, 5482.424345443711, 5488.9725551371985, 5506.284183056217, 3468.5837699476224, 3469.9653003469894, 3515.4726790981304, 3527.6472352764777, 3562.6615471530163, 1191.4578866615668, 1197.485081414991, 1208.3776854919724, 1230.341767154577, 2119.2582596091397, 12279.570215042431, 12295.200811947285, 12425.62723118308, 12432.37838108091, 12433.502659893593, 4509.391232183613, 4559.908801824011, 4572.337011133367, 4659.347691323217, 4891.804327826847, 9821.508924553744, 9992.400455972665, 10001.649942252132, 10006.749831254132, 10011.749706257257, 5411.134218525017, 5418.83743487696, 5421.430749771296, 5423.996560636295, 5429.728513574306, 4500.392447019676, 4848.733319667452, 4932.778024988884, 4995.8001679932395, 5023.648344615842, 3900.239453306578, 3956.6241207085855, 4016.7991600420446, 4043.555208927016, 4047.817848196838, 1160.6460029691025, 1165.906727461801, 1176.9529218831321, 1176.9941150294364, 1187.2876274235562, 971.582219645545, 984.842425211977, 989.5497548615363, 991.4200192887098, 1031.6028329093353, 5800.7099645018425, 5813.912791308134, 5895.469407753323, 5927.8221653350465, 6038.879222415527, 983.2969426859829, 1006.3006210683504, 1006.9949650251701, 1008.3294609085023, 1056.984145237822, 4095.24238015968, 4180.790960452026, 4270.380794784801, 4296.334068219431, 4401.163778882003, 11087.0, 11421.314721116594, 11502.88497115018, 11523.0, 11533.826992595117, 11241.831372529256, 11346.205765596495, 11355.886441135648, 11448.54205831774, 11493.367864767351, 8173.67305307793, 8316.0, 8322.793194986692, 8332.875006874901, 8335.083140854622, 6175.69121543912, 6221.471174949999, 6275.654838983717, 6276.968615156895, 6292.182016337847, 10071.395716257048, 10144.847827282745, 10191.541380638037, 10223.233257505568, 10358.223133264884, 10506.789864202521, 10565.841512377474, 10577.100946419332, 10674.679759607225, 10787.784244315228, 6874.931250687429, 7410.592417417094, 7584.203658615766, 7878.487898286593, 8203.876941845996, 8900.154485323885, 8957.328200385127, 8965.686200982935, 8966.641334346554, 8971.596278167368, 7385.778426647209, 7533.585652789149, 7610.96194519046, 7932.524048557105, 8205.876911846208, 7550.282723141293, 7650.502717323357, 7657.770266892002, 7665.156832748505, 7667.616619169131, 7308.780736577911, 7405.703771849281, 7411.370033547209, 7412.481126321106, 7422.0, 4969.527894849984, 5007.824726134713, 5111.718855462986, 5132.8460146195675, 5277.8416647500635, 4695.718256904596, 4791.377120974251, 4834.395700537433, 4964.4539100698175, 5171.456997015259], "value": 4747.9309885690045, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [311.9064280715786, 323.917401062729, 329.4021351247487, 331.6948407465132, 333.6946693775195, 4947.678400903938, 5008.749562521877, 5091.770870310836, 5147.922781158286, 5189.455107213745, 4999.825006124786, 5036.5897548686335, 5064.949350506494, 5082.593392528598, 5114.28400023996, 4085.5914408559056, 4096.303628383182, 4098.569650186725, 4099.118689481761, 4101.671866250707, 3853.0945227871566, 3965.1363858986374, 4080.0411903202726, 4094.382718826062, 4101.548829628753, 4006.8797936061965, 4024.738392004525, 4043.454133691958, 4048.3927410888455, 4062.2078694654556, 4003.3586229645853, 4007.3788562772816, 4010.037590978144, 4018.2967980603275, 4021.819018144176, 3580.856765729368, 3586.659267369622, 3587.8744243951337, 3607.026102952188, 3611.2485444559366, 4835.259306649603, 4955.925661115086, 4974.885673588747, 4981.676191047606, 4981.875453113665, 4578.816847326103, 4581.839635612738, 4586.449626044862, 4586.908261834778, 4593.379893714339, 5273.815416460406, 5274.314339135888, 5284.2866213061125, 5285.8635393390605, 5318.361796584396, 3638.4178531434845, 3815.8664446744233, 3833.8274777635074, 3835.1562656215588, 3843.5579908310624, 5467.305135408036, 5502.312210973629, 5506.32057222547, 5515.310586176728, 5524.14375771752, 3946.506686664167, 3971.3050216212046, 3979.6816254700134, 3982.7809470478824, 3983.900402489961, 3908.609139086069, 3950.664193543581, 3964.266610677034, 3979.1245925896255, 4033.2941735196223, 4512.0, 4517.819287228474, 4522.706024108423, 4541.613962813198, 4549.863504094817, 5332.7067011313975, 5348.197770334403, 5369.597280203922, 5378.767811358242, 5382.811601594004, 1309.5874799438147, 1315.302889468583, 1339.9263040532865, 1358.4294596269392, 1370.2532119994628, 910.2763303173992, 936.8501039833731, 940.1773448232797, 942.1520631431766, 950.7955789505289, 5191.143461328914, 5195.506426889439, 5201.1678131498775, 5207.739613019334, 5221.686698798084, 5266.815661451831, 5407.351117865881, 5497.120460726264, 5499.707568721347, 5503.229547863302, 5100.158473851847, 5101.20931255656, 5114.6931184128325, 5115.104856650071, 5117.360329958755, 2512.4221429071417, 2542.453372524916, 2546.0197823837957, 2577.3814284571945, 2583.114326541635, 4772.284157376422, 4945.629077819178, 4973.278874563241, 4974.37820272466, 4980.9252861207115, 4872.439669437989, 4877.000429823755, 4882.292067650171, 4886.389201349832, 4893.584045356113, 4956.826511072095, 4962.6774259673, 4965.379327584052, 4970.633075904156, 4978.551930326288, 4487.6185524230805, 4793.352897358812, 4796.832110876103, 4806.831760888352, 4812.51874812516, 4760.666753327234, 4765.880852978703, 4777.665563410528, 4796.37647105874, 4806.068298337941, 4898.359049718384, 4929.827456039022, 4941.925871111865, 4957.487966170208, 4961.70229786214, 3572.857085716646, 3597.748157628941, 3604.603493615648, 3711.183539621279, 3964.8414063438295, 5455.672659640434, 5455.918161227505, 5459.535939445192, 5466.945330546644, 5467.39858615544, 5338.519533242027, 5355.919661205007, 5356.303680521507, 5367.141257398797, 5372.543333816513, 5118.488151185001, 5323.387810401853, 5361.490658387447, 5367.731613419392, 5376.973115134399, 5096.311997880387, 5193.558547523352, 5289.814856480005, 5299.330710826078, 5326.9733651333045, 5382.0, 5386.973065134649, 5389.251481744261, 5429.728513574384, 5430.266913966564, 5156.2523434102595, 5229.50319719626, 5250.868728281824, 5280.7556788365555, 5290.518654776669], "value": 3983.2513463124246, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [764.3961270596229, 828.7306625346764, 837.7486753973807, 871.0244526130734, 883.7658020624534, 14816.259187040645, 15356.923215383935, 15521.379144834193, 15558.0, 15595.1422671753, 14112.364943577539, 14161.079529830553, 14405.42378304869, 14466.132032078083, 14668.266586670677, 808.0707186735258, 826.0624191542612, 830.393812516863, 831.8585840407145, 840.0297656207091, 553.2531083037907, 570.2017175953656, 571.0064487791234, 571.6570057965208, 674.6390680985673, 874.9825003499926, 880.0583375787877, 880.2869675562782, 880.344143613006, 880.4673172730502, 874.7069731639908, 874.8643960186213, 875.8029443375221, 875.8598624220126, 876.2464280718577, 514.0361821584529, 614.8432149801816, 632.9272133704637, 638.9073584330292, 639.7792761497323, 14153.716925661533, 14186.0, 14232.928835355757, 14256.643583910381, 14258.2157981311, 14250.714985700331, 14535.360106470102, 14847.706931242685, 15018.249087545688, 15342.309596068204, 13330.933345333307, 13456.798148027787, 13605.659858503517, 13617.0, 13628.727425451534, 4490.640748740093, 4562.064776720765, 4574.679772415932, 4728.4798672146035, 4761.2143996240575, 79682.20317796864, 80559.5972020142, 80577.0, 80784.59607701983, 80844.59577702191, 11515.654530364103, 11838.785928530275, 11979.520819167135, 12021.0, 12073.818892716616, 21710.34868953934, 22152.778472215396, 22175.223867164572, 22213.666794998087, 22256.109755609596, 8485.872711909326, 8514.872276915852, 9014.278857691368, 9105.180533751862, 9123.77190570241, 4868.878278043077, 5000.824971125993, 5098.490150984871, 5161.974190129025, 5169.844904652867, 3384.526166336715, 3501.8774342897877, 3523.8414271357337, 3530.329237444929, 3533.0813988362697, 571.7655761137913, 587.0, 595.7051259626512, 598.1924402057275, 598.8921994041115, 630.8399663815746, 640.5044716333845, 678.8913773796169, 694.4062826283556, 727.276355038562, 9662.95168524167, 9745.223882829876, 9826.16477599412, 9860.211183105333, 10148.543315550818, 559.8236555485009, 560.3024234827584, 652.360686527206, 691.9550229235084, 722.6675729164538, 4714.787834547454, 4739.454962679267, 4902.2156454967035, 4907.631927605373, 4911.901761964741, 75119.0, 76054.0, 76120.0, 98000.50999744955, 125786.37106814407, 103240.22458091147, 103920.0, 109187.0, 109647.65100671153, 115604.0, 62842.68578657169, 75550.24449755432, 76300.23699763042, 87057.5647121773, 91080.34736700135, 10121.797564048531, 10149.441780701836, 10217.233707472118, 10370.11853992389, 10705.732356691145, 51431.485685144165, 51990.0, 52253.0, 54047.72976135094, 54361.45638543564, 43104.78447607742, 47903.76048119737, 48020.27969580529, 55095.0, 71234.0, 13129.934350328187, 13335.799963000756, 13574.93212533931, 13804.17174969505, 13900.51348202808, 13975.580732578039, 14108.365123569669, 14402.783958240845, 14558.41766329335, 14630.268486575842, 13495.0, 13784.517541886387, 13793.172409655454, 14379.496717614833, 14467.276636167939, 13630.318484075558, 13961.650958726113, 13967.30163491801, 14137.858621413654, 14602.196879171748, 13840.653983650489, 14193.503227386991, 14310.998230124207, 14524.27378631043, 14527.709445811235, 10653.360798352123, 11152.832707509557, 11173.0, 11184.664460066211, 11453.0, 9825.606975720892, 10326.845097323696, 10581.31221470602, 10816.161876147733, 11324.207305488537], "value": 7500.42661492095, "unit": "score"}], "name": "dromaeo_css", "value": 7215.427567650773, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 112.0, 112.0, 110.0, 112.0, 111.0, 111.0, 112.0, 110.0, 111.0], "value": 110.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 119.0, 120.0, 183.0, 118.0, 122.0, 146.0, 117.0, 156.0, 128.0], "value": 135.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 161.0, 161.0, 160.0, 161.0, 160.0, 163.0, 160.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 74.0, 112.0, 78.0, 76.0, 74.0, 74.0, 97.0, 74.0], "value": 80.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 125.0, 84.0, 84.0, 84.0, 121.0, 84.0, 83.0, 122.0], "value": 95.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 149.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 97.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 60.0, 49.0, 50.0, 55.0, 49.0, 51.0, 50.0, 49.0, 50.0], "value": 51.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 60.0, 60.0, 61.0, 61.0, 61.0, 61.0, 61.0, 61.0], "value": 60.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 77.0, 79.0, 77.0, 76.0, 76.0, 77.0, 77.0, 76.0], "value": 76.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 121.0, 120.0, 123.0, 119.0, 119.0, 118.0, 119.0, 191.0, 126.0], "value": 127.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 158.0, 156.0, 159.0, 159.0, 163.0, 163.0, 160.0, 155.0, 158.0], "value": 158.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 66.0, 62.0, 66.0, 63.0, 68.0, 65.0, 71.0, 61.0], "value": 64.8, "unit": "score"}], "name": "kraken", "value": 1481.8000000000002, "alertThreshold": 2.0}]} 22:32:25 INFO - Return code: 0 22:32:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:32:25 INFO - # TBPL SUCCESS # 22:32:25 INFO - Running post-action listener: _resource_record_post_action 22:32:25 INFO - [mozharness: 2016-08-06 05:32:25.505889Z] Finished run-tests step (success) 22:32:25 INFO - Running post-run listener: _resource_record_post_run 22:32:25 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 4096; Write bytes: 560693248; Read time: 4; Write time: 590172 22:32:25 INFO - TinderboxPrint: CPU usage
13.0% 22:32:25 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 4 22:32:25 INFO - TinderboxPrint: I/O write bytes / time
560,693,248 / 590,172 22:32:25 INFO - TinderboxPrint: CPU idle
8,289.3 (87.0%) 22:32:25 INFO - TinderboxPrint: CPU user
1,192.0 (12.5%) 22:32:25 INFO - TinderboxPrint: Swap in / out
0 / 0 22:32:25 INFO - install - Wall time: 18s; CPU: 15.0%; Read bytes: 0; Write bytes: 267239424; Read time: 0; Write time: 334580 22:32:25 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 4096; Write bytes: 293453824; Read time: 4; Write time: 255592 22:32:26 INFO - Running post-run listener: _upload_blobber_files 22:32:26 INFO - Blob upload gear active. 22:32:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:32:26 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 22:32:26 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'] 22:32:26 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 22:32:26 INFO - (blobuploader) - INFO - Open directory for files ... 22:32:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:32:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:32:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:32:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:32:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:32:27 INFO - (blobuploader) - INFO - Done attempting. 22:32:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:32:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:32:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:32:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:32:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:32:30 INFO - (blobuploader) - INFO - Done attempting. 22:32:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:32:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:32:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:32:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:32:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:32:31 INFO - (blobuploader) - INFO - Done attempting. 22:32:31 INFO - (blobuploader) - INFO - Iteration through files over. 22:32:31 INFO - Return code: 0 22:32:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:32:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:32:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/99963bb812bbd77d1092b4c976ac11432700de3572d56cfc5d8ded9f215bf93db6a43dfa964495c6368dcc274850f874b8fee8657003cab30ca526bd726e3290", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/15bfcf1ab03b4efed018977e0ecaae5cfdfc5eeb3c7040dbe970c503e645d4cc49fe6d01e10807b825ad623a7cd95366da2d95c84cd1d74e4391267e5b26d443", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/76fc8cf0d0d45a1c73722e99340b23abf342e068debb81aaf6b9fa966ce5d4150255f8a2441a3ce106c2d02da4d30d10a76f7d0b90a4db4ce7891fb447c9f0e8"} 22:32:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:32:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:32:31 INFO - Contents: 22:32:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/99963bb812bbd77d1092b4c976ac11432700de3572d56cfc5d8ded9f215bf93db6a43dfa964495c6368dcc274850f874b8fee8657003cab30ca526bd726e3290", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/15bfcf1ab03b4efed018977e0ecaae5cfdfc5eeb3c7040dbe970c503e645d4cc49fe6d01e10807b825ad623a7cd95366da2d95c84cd1d74e4391267e5b26d443", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/76fc8cf0d0d45a1c73722e99340b23abf342e068debb81aaf6b9fa966ce5d4150255f8a2441a3ce106c2d02da4d30d10a76f7d0b90a4db4ce7891fb447c9f0e8"} 22:32:31 INFO - Running post-run listener: copy_logs_to_upload_dir 22:32:31 INFO - Copying logs to upload dir... 22:32:31 INFO - mkdir: /builds/slave/test/build/upload/logs 22:32:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1232.970140 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-08-05 22:32:31.189497) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-05 22:32:31.196761) ========= 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=232498196343f89f466a4a9a000001c8-1470458967.27072-1240889926 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/99963bb812bbd77d1092b4c976ac11432700de3572d56cfc5d8ded9f215bf93db6a43dfa964495c6368dcc274850f874b8fee8657003cab30ca526bd726e3290", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/15bfcf1ab03b4efed018977e0ecaae5cfdfc5eeb3c7040dbe970c503e645d4cc49fe6d01e10807b825ad623a7cd95366da2d95c84cd1d74e4391267e5b26d443", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/76fc8cf0d0d45a1c73722e99340b23abf342e068debb81aaf6b9fa966ce5d4150255f8a2441a3ce106c2d02da4d30d10a76f7d0b90a4db4ce7891fb447c9f0e8"} build_url:https://queue.taskcluster.net/v1/task/SHyhuRHbRwikE1DLSb2Lig/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011304 build_url: 'https://queue.taskcluster.net/v1/task/SHyhuRHbRwikE1DLSb2Lig/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/99963bb812bbd77d1092b4c976ac11432700de3572d56cfc5d8ded9f215bf93db6a43dfa964495c6368dcc274850f874b8fee8657003cab30ca526bd726e3290", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/15bfcf1ab03b4efed018977e0ecaae5cfdfc5eeb3c7040dbe970c503e645d4cc49fe6d01e10807b825ad623a7cd95366da2d95c84cd1d74e4391267e5b26d443", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/76fc8cf0d0d45a1c73722e99340b23abf342e068debb81aaf6b9fa966ce5d4150255f8a2441a3ce106c2d02da4d30d10a76f7d0b90a4db4ce7891fb447c9f0e8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-05 22:32:31.225062) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-05 22:32:31.225444) ========= 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=232498196343f89f466a4a9a000001c8-1470458967.27072-1240889926 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004570 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-05 22:32:31.307034) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-05 22:32:31.310377) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-05 22:32:31.310695) ========= ========= Total master_lag: 0.19 =========